Re: [RFC PATCH v8 3/7] perf stat: Fork and launch perf record when perf stat needs to get retire latency value for a metric.

From: Ian Rogers
Date: Thu May 16 2024 - 12:43:48 EST


On Tue, May 14, 2024 at 10:44 PM <weilin.wang@xxxxxxxxx> wrote:
>
> From: Weilin Wang <weilin.wang@xxxxxxxxx>
>
> When retire_latency value is used in a metric formula, perf stat would fork a
> perf record process with "-e" and "-W" options. Perf record will collect
> required retire_latency values in parallel while perf stat is collecting
> counting values.
>
> At the point of time that perf stat stops counting, it would send sigterm signal
> to perf record process and receiving sampling data back from perf record from a
> pipe. Perf stat will then process the received data to get retire latency data
> and calculate metric result.
>
> Another thread is required to synchronize between perf stat and perf record
> when we pass data through pipe.
>
> Signed-off-by: Weilin Wang <weilin.wang@xxxxxxxxx>
> Reviewed-by: Ian Rogers <irogers@xxxxxxxxxx>

So I don't think this is the correct way to add this. My reviewed-by
was based on the fact that I was going to refactor this after landing.
It didn't land and I already sent out:
"Retirement latency perf stat support"
https://lore.kernel.org/lkml/20240428053616.1125891-1-irogers@xxxxxxxxxx/
v3 to just land the tool portion:
https://lore.kernel.org/lkml/20240503232849.17752-1-irogers@xxxxxxxxxx/
So my reviewed-by no longer stands. The changes I've sent out haven't
been reviewed. Given you're trying to land this, can we work on
reviewing those changes? The v3 was specifically done just so that we
can have the cleaner basis for adding tpebs to the evsel.

Let me point to specific issues below:

> ---
> tools/perf/builtin-stat.c | 19 +++
> tools/perf/util/Build | 1 +
> tools/perf/util/intel-tpebs.c | 285 ++++++++++++++++++++++++++++++++++
> tools/perf/util/intel-tpebs.h | 29 ++++
> tools/perf/util/stat.h | 3 +
> 5 files changed, 337 insertions(+)
> create mode 100644 tools/perf/util/intel-tpebs.c
> create mode 100644 tools/perf/util/intel-tpebs.h
>
> diff --git a/tools/perf/builtin-stat.c b/tools/perf/builtin-stat.c
> index 428e9721b908..85927cf45adb 100644
> --- a/tools/perf/builtin-stat.c
> +++ b/tools/perf/builtin-stat.c
> @@ -70,6 +70,7 @@
> #include "util/bpf_counter.h"
> #include "util/iostat.h"
> #include "util/util.h"
> +#include "util/intel-tpebs.h"
> #include "asm/bug.h"
>
> #include <linux/time64.h>
> @@ -94,6 +95,7 @@
> #include <perf/evlist.h>
> #include <internal/threadmap.h>
>
> +
> #define DEFAULT_SEPARATOR " "
> #define FREEZE_ON_SMI_PATH "devices/cpu/freeze_on_smi"
>
> @@ -162,6 +164,8 @@ static struct perf_stat_config stat_config = {
> .ctl_fd = -1,
> .ctl_fd_ack = -1,
> .iostat_run = false,
> + .tpebs_results = LIST_HEAD_INIT(stat_config.tpebs_results),
> + .tpebs_pid = -1,

Here we're adding state to the stat_config for the sake of tpebs
events. Were the state in the evsel, as in my changes, we'd not need
to carry new global state for tpebs.

> };
>
> static void evlist__check_cpu_maps(struct evlist *evlist)
> @@ -653,6 +657,8 @@ static enum counter_recovery stat_handle_error(struct evsel *counter)
>
> if (child_pid != -1)
> kill(child_pid, SIGTERM);
> + if (stat_config.tpebs_pid != -1)
> + stop_tpebs();

This logic is builtin-stat but what about other commands that could be
using TPEBS for counters?

> return COUNTER_FATAL;
> }
>
> @@ -673,6 +679,10 @@ static int __run_perf_stat(int argc, const char **argv, int run_idx)
> int err;
> bool second_pass = false;
>
> + err = start_tpebs(&stat_config, evsel_list);
> + if (err < 0)
> + return err;
> +
> if (forks) {
> if (evlist__prepare_workload(evsel_list, &target, argv, is_pipe, workload_exec_failed_signal) < 0) {
> perror("failed to prepare workload");
> @@ -878,6 +888,10 @@ static int __run_perf_stat(int argc, const char **argv, int run_idx)
>
> t1 = rdclock();
>
> + err = stop_tpebs();
> + if (err < 0)
> + return err;
> +
> if (stat_config.walltime_run_table)
> stat_config.walltime_run[run_idx] = t1 - t0;
>
> @@ -985,6 +999,9 @@ static void sig_atexit(void)
> if (child_pid != -1)
> kill(child_pid, SIGTERM);
>
> + if (stat_config.tpebs_pid != -1)
> + kill(stat_config.tpebs_pid, SIGTERM);
> +
> sigprocmask(SIG_SETMASK, &oset, NULL);
>
> if (signr == -1)
> @@ -2918,5 +2935,7 @@ int cmd_stat(int argc, const char **argv)
> metricgroup__rblist_exit(&stat_config.metric_events);
> evlist__close_control(stat_config.ctl_fd, stat_config.ctl_fd_ack, &stat_config.ctl_fd_close);
>
> + tpebs_data__delete();
> +
> return status;
> }
> diff --git a/tools/perf/util/Build b/tools/perf/util/Build
> index 292170a99ab6..c9f1d0bb6bf8 100644
> --- a/tools/perf/util/Build
> +++ b/tools/perf/util/Build
> @@ -153,6 +153,7 @@ perf-y += clockid.o
> perf-y += list_sort.o
> perf-y += mutex.o
> perf-y += sharded_mutex.o
> +perf-y += intel-tpebs.o
>
> perf-$(CONFIG_LIBBPF) += bpf_map.o
> perf-$(CONFIG_PERF_BPF_SKEL) += bpf_counter.o
> diff --git a/tools/perf/util/intel-tpebs.c b/tools/perf/util/intel-tpebs.c
> new file mode 100644
> index 000000000000..4b7a98794fae
> --- /dev/null
> +++ b/tools/perf/util/intel-tpebs.c
> @@ -0,0 +1,285 @@
> +// SPDX-License-Identifier: GPL-2.0-only
> +/*
> + * intel_pt.c: Intel Processor Trace support
> + * Copyright (c) 2013-2015, Intel Corporation.
> + */
> +
> +
> +#include <sys/param.h>
> +#include <subcmd/run-command.h>
> +#include <thread.h>
> +#include "intel-tpebs.h"
> +#include <linux/list.h>
> +#include <linux/zalloc.h>
> +#include <linux/err.h>
> +#include "sample.h"
> +#include "debug.h"
> +#include "evlist.h"
> +#include "evsel.h"
> +#include "session.h"
> +#include "tool.h"
> +#include "metricgroup.h"
> +#include <sys/stat.h>
> +#include <sys/file.h>
> +
> +
> +
> +#define PERF_DATA "-"
> +#define CONTROL "/tmp/control"
> +#define ACK "/tmp/ack"
> +pthread_t reader_thread;
> +struct child_process *cmd;
> +struct perf_stat_config *stat_config;
> +
> +static int get_perf_record_args(const char **record_argv)
> +{
> + int i = 0;
> + struct tpebs_retire_lat *e;
> +
> + pr_debug("Prepare perf record for retire_latency\n");
> +
> + record_argv[i++] = "perf";
> + record_argv[i++] = "record";
> + record_argv[i++] = "-W";
> + record_argv[i++] = "--synth=no";

There should be more things to disable, like BPF events, we don't need
the dummy, etc.

> + record_argv[i++] = "--control=fifo:/tmp/control,/tmp/ack";
> +
> + if (stat_config->user_requested_cpu_list) {
> + record_argv[i++] = "-C";
> + record_argv[i++] = stat_config->user_requested_cpu_list;
> + }
> +
> + if (stat_config->system_wide)
> + record_argv[i++] = "-a";
> +
> + if (!stat_config->system_wide && !stat_config->user_requested_cpu_list) {
> + pr_err("Require -a or -C option to run sampling.\n");
> + return -ECANCELED;
> + }
> +
> + list_for_each_entry(e, &stat_config->tpebs_results, nd) {
> + record_argv[i++] = "-e";
> + record_argv[i++] = e->name;
> + }
> +
> + record_argv[i++] = "-o";
> + record_argv[i++] = PERF_DATA;
> +
> + return 0;
> +}
> +
> +static int prepare_run_command(const char **argv)
> +{
> + cmd = zalloc(sizeof(struct child_process));
> + if (!cmd)
> + return -ENOMEM;
> + cmd->argv = argv;
> + cmd->out = -1;
> + return 0;
> +}
> +
> +static int prepare_perf_record(int tpebs_event_size)
> +{
> + const char **record_argv;
> + int ret;
> +
> + /*Create control and ack fd for --control*/
> + if (mkfifo(CONTROL, 0600)) {
> + pr_err("Failed to create control fifo");
> + return -1;
> + }
> + if (mkfifo(ACK, 0600)) {
> + pr_err("Failed to create control fifo");
> + return -1;
> + }
> +
> + record_argv = calloc(10 + 2 * tpebs_event_size, sizeof(char *));
> + if (!record_argv)
> + return -ENOMEM;
> +
> +
> + ret = get_perf_record_args(record_argv);
> + if (ret)
> + goto out;
> +
> + ret = prepare_run_command(record_argv);
> + if (ret)
> + goto out;
> + ret = start_command(cmd);
> +out:
> + free(record_argv);
> + return ret;
> +}
> +struct sample_data_reader {
> + struct perf_tool tool;
> + struct perf_session *session;
> +};
> +
> +static void tpebs_retire_lat__delete(struct tpebs_retire_lat *r)
> +{
> + zfree(&r->name);
> + zfree(&r->tpebs_name);
> + free(r);
> +}
> +
> +void tpebs_data__delete(void)
> +{
> + struct tpebs_retire_lat *r, *rtmp;
> +
> + list_for_each_entry_safe(r, rtmp, &stat_config->tpebs_results, nd) {
> + list_del_init(&r->nd);
> + tpebs_retire_lat__delete(r);
> + }
> + free(cmd);
> +}
> +
> +static int process_sample_event(struct perf_tool *tool __maybe_unused,
> + union perf_event *event __maybe_unused,
> + struct perf_sample *sample,
> + struct evsel *evsel,
> + struct machine *machine __maybe_unused)
> +{
> + int ret = 0;
> + const char *evname;
> + struct tpebs_retire_lat *t;
> +
> + evname = evsel__name(evsel);
> +
> + /*
> + * Need to handle per core results? We are assuming average retire
> + * latency value will be used. Save the number of samples and the sum of
> + * retire latency value for each event.
> + */
> + list_for_each_entry(t, &stat_config->tpebs_results, nd) {
> + if (!strcmp(evname, t->name)) {
> + t->count += 1;
> + t->sum += sample->retire_lat;
> + t->val = (double) t->sum / t->count;
> + break;
> + }
> + }
> +
> + return ret;
> +}
> +
> +static int process_feature_event(struct perf_session *session,
> + union perf_event *event)
> +{
> + if (event->feat.feat_id < HEADER_LAST_FEATURE)
> + return perf_event__process_feature(session, event);
> + return 0;
> +}
> +
> +static void *__sample_reader(void *arg)
> +{
> + struct child_process *child = arg;
> + struct perf_session *session;
> + struct perf_data data = {
> + .mode = PERF_DATA_MODE_READ,
> + .path = PERF_DATA,
> + .file.fd = child->out,
> + };
> + struct sample_data_reader reader = {
> + .tool = {
> + .sample = process_sample_event,
> + .feature = process_feature_event,
> + .attr = perf_event__process_attr,
> + },
> + };

I prefer the reading approach here over what I did here:
https://lore.kernel.org/lkml/CAM9d7cgzTsfk3C+dTN80f5FhB1rmfturjuUUwvSTeUvny5eWKw@xxxxxxxxxxxxxx/
I'd done that as Namhyung had commented on using perf report:
https://lore.kernel.org/lkml/CAM9d7cgdQQn5GYB7t++xuoMdeqPXiEkkcop69+rD06RAnu9-EQ@xxxxxxxxxxxxxx/
It was also less work. I don't see why we can't move the reading logic
into a function like evsel__read_retire_latency that's in my change. I
also think that in the first instance all reading logic should be
implemented to return 0 and we only do the forking, etc. when a
command line flag is passed.

> +
> + session = perf_session__new(&data, &reader.tool);
> + if (IS_ERR(session))
> + return NULL;
> + reader.session = session;
> + perf_session__process_events(session);
> + perf_session__delete(session);
> +
> + return NULL;
> +}
> +
> +
> +int start_tpebs(struct perf_stat_config *perf_stat_config, struct evlist *evsel_list)
> +{
> + int ret = 0;
> + struct evsel *evsel;
> + int control = -1, ack = -1;
> +
> + stat_config = perf_stat_config;
> + /*
> + * Prepare perf record for sampling event retire_latency before fork and
> + * prepare workload
> + */
> + evlist__for_each_entry(evsel_list, evsel) {
> + if (evsel->retire_lat) {
> + struct tpebs_retire_lat *new = malloc(sizeof(struct tpebs_retire_lat));
> + int i;
> + char *name;
> +
> + pr_debug("perf stat retire latency %s required\n", evsel->name);
> + if (!new)
> + return -1;
> + for (i = strlen(evsel->name) - 1; i > 0; i--) {
> + if (evsel->name[i] == 'R')
> + break;
> + }
> + if (i <= 0 || evsel->name[i] != 'R')
> + return -1;
> +
> + name = strdup(evsel->name);
> + if (!name)
> + return -ENOMEM;
> + name[i] = 'p';
> + new->name = strdup(name);
> + free(name);
> + new->tpebs_name = strdup(evsel->name);
> + if (!new->tpebs_name)
> + return -ENOMEM;
> + new->count = 0;
> + new->sum = 0;
> + list_add_tail(&new->nd, &stat_config->tpebs_results);
> + stat_config->tpebs_event_size += 1;
> + }
> + }
> +
> + if (stat_config->tpebs_event_size > 0) {
> + ret = prepare_perf_record(stat_config->tpebs_event_size);
> + if (ret)
> + return ret;
> + if (pthread_create(&reader_thread, NULL, __sample_reader, cmd)) {
> + kill(cmd->pid, SIGTERM);
> + close(cmd->out);
> + pr_err("Could not create thread to process sample data.\n");
> + return -1;
> + }
> + /* Wait for perf record initialization a little bit.*/
> + control = open(CONTROL, O_RDONLY, O_NONBLOCK);
> + if (!control)
> + return -1;
> + close(control);
> + ack = open(ACK, O_RDONLY, O_NONBLOCK);
> + if (!ack)
> + return -1;
> + close(ack);
> + pr_debug("Received ack from perf record\n");
> + }
> +
> + return ret;
> +}
> +
> +
> +int stop_tpebs(void)
> +{
> + int ret = 0;
> +
> + if (stat_config->tpebs_event_size > 0) {
> + kill(cmd->pid, SIGTERM);
> + pthread_join(reader_thread, NULL);
> + close(cmd->out);
> + ret = finish_command(cmd);
> + if (ret == -ERR_RUN_COMMAND_WAITPID_SIGNAL)
> + ret = 0;
> + remove(CONTROL);
> + remove(ACK);
> + }
> + return ret;
> +}
> diff --git a/tools/perf/util/intel-tpebs.h b/tools/perf/util/intel-tpebs.h
> new file mode 100644
> index 000000000000..e8e2bb2f479b
> --- /dev/null
> +++ b/tools/perf/util/intel-tpebs.h
> @@ -0,0 +1,29 @@
> +/* SPDX-License-Identifier: GPL-2.0-only */
> +/*
> + * intel_pt.h: Intel Processor Trace support
> + * Copyright (c) 2013-2015, Intel Corporation.
> + */
> +#include "stat.h"
> +
> +#ifndef INCLUDE__PERF_INTEL_TPEBS_H__
> +#define INCLUDE__PERF_INTEL_TPEBS_H__
> +
> +struct tpebs_retire_lat {
> + struct list_head nd;
> + /* Event name */
> + const char *name;
> + /* Event name with the TPEBS modifier R */
> + const char *tpebs_name;
> + /* Count of retire_latency values found in sample data */
> + size_t count;
> + /* Sum of all the retire_latency values in sample data */
> + int sum;
> + /* Average of retire_latency, val = sum / count */
> + double val;
> +};

An evsel has a pretty much all of this and so we're duplicating in
particular the counting logic which then needs later work to integrate
and is why I'd prefer we went the evsel route.

Thanks,
Ian

> +
> +int start_tpebs(struct perf_stat_config *perf_stat_config, struct evlist *evsel_list);
> +int stop_tpebs(void);
> +void tpebs_data__delete(void);
> +
> +#endif
> diff --git a/tools/perf/util/stat.h b/tools/perf/util/stat.h
> index fd7a187551bd..c6c2aa43030f 100644
> --- a/tools/perf/util/stat.h
> +++ b/tools/perf/util/stat.h
> @@ -110,6 +110,9 @@ struct perf_stat_config {
> struct cpu_aggr_map *cpus_aggr_map;
> u64 *walltime_run;
> struct rblist metric_events;
> + size_t tpebs_event_size;
> + struct list_head tpebs_results;
> + pid_t tpebs_pid;
> int ctl_fd;
> int ctl_fd_ack;
> bool ctl_fd_close;
> --
> 2.43.0
>