Commit 45604710 authored by Namhyung Kim's avatar Namhyung Kim Committed by Jiri Olsa

perf record: Propagate exit status of a command line workload

Currently perf record doesn't propagate the exit status of a workload
given by the command line.  But sometimes it'd useful if it's
propagated so that a monitoring script can handle errors
appropriately.

To do that, it moves most of logic out of the exit handlers and run
them directly in the __cmd_record().  The only thing needs to be done
in the handler is propagating terminating signal so that the shell can
terminate its loop properly when Ctrl-C was pressed.  Also it cleaned
up the resource management code in record__exit().

With this change, perf record returns the child exit status in case of
normal termination and send signal to itself when terminated by signal.

Example run of Stephane's case:

  $ perf record true && echo yes || echo no
  [ perf record: Woken up 1 times to write data ]
  [ perf record: Captured and wrote 0.013 MB perf.data (~589 samples) ]
  yes

  $ perf record false && echo yes || echo no
  [ perf record: Woken up 1 times to write data ]
  [ perf record: Captured and wrote 0.013 MB perf.data (~589 samples) ]
  no

Jiri's case (error in parent):

  $ perf record -m 10G true && echo yes || echo no
  rounding mmap pages size to 17179869184 bytes (4194304 pages)
  failed to mmap with 12 (Cannot allocate memory)
  no

  $ ulimit -n 6
  $ perf record sleep 1 && echo yes || echo no
  failed to create 'go' pipe: Too many open files
  Couldn't run the workload!
  no

And Peter's case (interrupted by signal):

  $ while :; do perf record sleep 1; done
  ^C[ perf record: Woken up 1 times to write data ]
  [ perf record: Captured and wrote 0.014 MB perf.data (~593 samples) ]
Reported-by: default avatarStephane Eranian <eranian@google.com>
Signed-off-by: default avatarNamhyung Kim <namhyung@kernel.org>
Acked-by: default avatarStephane Eranian <eranian@google.com>
Acked-by: default avatarPeter Zijlstra <a.p.zijlstra@chello.nl>
Acked-by: default avatarIngo Molnar <mingo@kernel.org>
Link: http://lkml.kernel.org/r/1399855645-25815-1-git-send-email-namhyung@kernel.orgSigned-off-by: default avatarJiri Olsa <jolsa@kernel.org>
parent 6bcab4e1
...@@ -147,29 +147,19 @@ static void sig_handler(int sig) ...@@ -147,29 +147,19 @@ static void sig_handler(int sig)
{ {
if (sig == SIGCHLD) if (sig == SIGCHLD)
child_finished = 1; child_finished = 1;
else
signr = sig;
done = 1; done = 1;
signr = sig;
} }
static void record__sig_exit(int exit_status __maybe_unused, void *arg) static void record__sig_exit(void)
{ {
struct record *rec = arg; if (signr == -1)
int status;
if (rec->evlist->workload.pid > 0) {
if (!child_finished)
kill(rec->evlist->workload.pid, SIGTERM);
wait(&status);
if (WIFSIGNALED(status))
psignal(WTERMSIG(status), rec->progname);
}
if (signr == -1 || signr == SIGUSR1)
return; return;
signal(signr, SIG_DFL); signal(signr, SIG_DFL);
raise(signr);
} }
static int record__open(struct record *rec) static int record__open(struct record *rec)
...@@ -243,27 +233,6 @@ static int process_buildids(struct record *rec) ...@@ -243,27 +233,6 @@ static int process_buildids(struct record *rec)
size, &build_id__mark_dso_hit_ops); size, &build_id__mark_dso_hit_ops);
} }
static void record__exit(int status, void *arg)
{
struct record *rec = arg;
struct perf_data_file *file = &rec->file;
if (status != 0)
return;
if (!file->is_pipe) {
rec->session->header.data_size += rec->bytes_written;
if (!rec->no_buildid)
process_buildids(rec);
perf_session__write_header(rec->session, rec->evlist,
file->fd, true);
perf_session__delete(rec->session);
perf_evlist__delete(rec->evlist);
symbol__exit();
}
}
static void perf_event__synthesize_guest_os(struct machine *machine, void *data) static void perf_event__synthesize_guest_os(struct machine *machine, void *data)
{ {
int err; int err;
...@@ -344,18 +313,19 @@ static volatile int workload_exec_errno; ...@@ -344,18 +313,19 @@ static volatile int workload_exec_errno;
* if the fork fails, since we asked by setting its * if the fork fails, since we asked by setting its
* want_signal to true. * want_signal to true.
*/ */
static void workload_exec_failed_signal(int signo, siginfo_t *info, static void workload_exec_failed_signal(int signo __maybe_unused,
siginfo_t *info,
void *ucontext __maybe_unused) void *ucontext __maybe_unused)
{ {
workload_exec_errno = info->si_value.sival_int; workload_exec_errno = info->si_value.sival_int;
done = 1; done = 1;
signr = signo;
child_finished = 1; child_finished = 1;
} }
static int __cmd_record(struct record *rec, int argc, const char **argv) static int __cmd_record(struct record *rec, int argc, const char **argv)
{ {
int err; int err;
int status = 0;
unsigned long waking = 0; unsigned long waking = 0;
const bool forks = argc > 0; const bool forks = argc > 0;
struct machine *machine; struct machine *machine;
...@@ -367,7 +337,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv) ...@@ -367,7 +337,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv)
rec->progname = argv[0]; rec->progname = argv[0];
on_exit(record__sig_exit, rec); atexit(record__sig_exit);
signal(SIGCHLD, sig_handler); signal(SIGCHLD, sig_handler);
signal(SIGINT, sig_handler); signal(SIGINT, sig_handler);
signal(SIGTERM, sig_handler); signal(SIGTERM, sig_handler);
...@@ -388,32 +358,28 @@ static int __cmd_record(struct record *rec, int argc, const char **argv) ...@@ -388,32 +358,28 @@ static int __cmd_record(struct record *rec, int argc, const char **argv)
workload_exec_failed_signal); workload_exec_failed_signal);
if (err < 0) { if (err < 0) {
pr_err("Couldn't run the workload!\n"); pr_err("Couldn't run the workload!\n");
status = err;
goto out_delete_session; goto out_delete_session;
} }
} }
if (record__open(rec) != 0) { if (record__open(rec) != 0) {
err = -1; err = -1;
goto out_delete_session; goto out_child;
} }
if (!rec->evlist->nr_groups) if (!rec->evlist->nr_groups)
perf_header__clear_feat(&session->header, HEADER_GROUP_DESC); perf_header__clear_feat(&session->header, HEADER_GROUP_DESC);
/*
* perf_session__delete(session) will be called at record__exit()
*/
on_exit(record__exit, rec);
if (file->is_pipe) { if (file->is_pipe) {
err = perf_header__write_pipe(file->fd); err = perf_header__write_pipe(file->fd);
if (err < 0) if (err < 0)
goto out_delete_session; goto out_child;
} else { } else {
err = perf_session__write_header(session, rec->evlist, err = perf_session__write_header(session, rec->evlist,
file->fd, false); file->fd, false);
if (err < 0) if (err < 0)
goto out_delete_session; goto out_child;
} }
if (!rec->no_buildid if (!rec->no_buildid
...@@ -421,7 +387,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv) ...@@ -421,7 +387,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv)
pr_err("Couldn't generate buildids. " pr_err("Couldn't generate buildids. "
"Use --no-buildid to profile anyway.\n"); "Use --no-buildid to profile anyway.\n");
err = -1; err = -1;
goto out_delete_session; goto out_child;
} }
machine = &session->machines.host; machine = &session->machines.host;
...@@ -431,7 +397,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv) ...@@ -431,7 +397,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv)
process_synthesized_event); process_synthesized_event);
if (err < 0) { if (err < 0) {
pr_err("Couldn't synthesize attrs.\n"); pr_err("Couldn't synthesize attrs.\n");
goto out_delete_session; goto out_child;
} }
if (have_tracepoints(&rec->evlist->entries)) { if (have_tracepoints(&rec->evlist->entries)) {
...@@ -447,7 +413,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv) ...@@ -447,7 +413,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv)
process_synthesized_event); process_synthesized_event);
if (err <= 0) { if (err <= 0) {
pr_err("Couldn't record tracing data.\n"); pr_err("Couldn't record tracing data.\n");
goto out_delete_session; goto out_child;
} }
rec->bytes_written += err; rec->bytes_written += err;
} }
...@@ -475,7 +441,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv) ...@@ -475,7 +441,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv)
err = __machine__synthesize_threads(machine, tool, &opts->target, rec->evlist->threads, err = __machine__synthesize_threads(machine, tool, &opts->target, rec->evlist->threads,
process_synthesized_event, opts->sample_address); process_synthesized_event, opts->sample_address);
if (err != 0) if (err != 0)
goto out_delete_session; goto out_child;
if (rec->realtime_prio) { if (rec->realtime_prio) {
struct sched_param param; struct sched_param param;
...@@ -484,7 +450,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv) ...@@ -484,7 +450,7 @@ static int __cmd_record(struct record *rec, int argc, const char **argv)
if (sched_setscheduler(0, SCHED_FIFO, &param)) { if (sched_setscheduler(0, SCHED_FIFO, &param)) {
pr_err("Could not set realtime priority.\n"); pr_err("Could not set realtime priority.\n");
err = -1; err = -1;
goto out_delete_session; goto out_child;
} }
} }
...@@ -512,13 +478,15 @@ static int __cmd_record(struct record *rec, int argc, const char **argv) ...@@ -512,13 +478,15 @@ static int __cmd_record(struct record *rec, int argc, const char **argv)
if (record__mmap_read_all(rec) < 0) { if (record__mmap_read_all(rec) < 0) {
err = -1; err = -1;
goto out_delete_session; goto out_child;
} }
if (hits == rec->samples) { if (hits == rec->samples) {
if (done) if (done)
break; break;
err = poll(rec->evlist->pollfd, rec->evlist->nr_fds, -1); err = poll(rec->evlist->pollfd, rec->evlist->nr_fds, -1);
if (err < 0 && errno == EINTR)
err = 0;
waking++; waking++;
} }
...@@ -538,28 +506,52 @@ static int __cmd_record(struct record *rec, int argc, const char **argv) ...@@ -538,28 +506,52 @@ static int __cmd_record(struct record *rec, int argc, const char **argv)
const char *emsg = strerror_r(workload_exec_errno, msg, sizeof(msg)); const char *emsg = strerror_r(workload_exec_errno, msg, sizeof(msg));
pr_err("Workload failed: %s\n", emsg); pr_err("Workload failed: %s\n", emsg);
err = -1; err = -1;
goto out_delete_session; goto out_child;
} }
if (quiet || signr == SIGUSR1) if (!quiet) {
return 0; fprintf(stderr, "[ perf record: Woken up %ld times to write data ]\n", waking);
fprintf(stderr, "[ perf record: Woken up %ld times to write data ]\n", waking); /*
* Approximate RIP event size: 24 bytes.
*/
fprintf(stderr,
"[ perf record: Captured and wrote %.3f MB %s (~%" PRIu64 " samples) ]\n",
(double)rec->bytes_written / 1024.0 / 1024.0,
file->path,
rec->bytes_written / 24);
}
/* out_child:
* Approximate RIP event size: 24 bytes. if (forks) {
*/ int exit_status;
fprintf(stderr,
"[ perf record: Captured and wrote %.3f MB %s (~%" PRIu64 " samples) ]\n",
(double)rec->bytes_written / 1024.0 / 1024.0,
file->path,
rec->bytes_written / 24);
return 0; if (!child_finished)
kill(rec->evlist->workload.pid, SIGTERM);
wait(&exit_status);
if (err < 0)
status = err;
else if (WIFEXITED(exit_status))
status = WEXITSTATUS(exit_status);
else if (WIFSIGNALED(exit_status))
signr = WTERMSIG(exit_status);
} else
status = err;
if (!err && !file->is_pipe) {
rec->session->header.data_size += rec->bytes_written;
if (!rec->no_buildid)
process_buildids(rec);
perf_session__write_header(rec->session, rec->evlist,
file->fd, true);
}
out_delete_session: out_delete_session:
perf_session__delete(session); perf_session__delete(session);
return err; return status;
} }
#define BRANCH_OPT(n, m) \ #define BRANCH_OPT(n, m) \
...@@ -988,6 +980,7 @@ int cmd_record(int argc, const char **argv, const char *prefix __maybe_unused) ...@@ -988,6 +980,7 @@ int cmd_record(int argc, const char **argv, const char *prefix __maybe_unused)
err = __cmd_record(&record, argc, argv); err = __cmd_record(&record, argc, argv);
out_symbol_exit: out_symbol_exit:
perf_evlist__delete(rec->evlist);
symbol__exit(); symbol__exit();
return err; return err;
} }
Markdown is supported
0%
or
You are about to add 0 people to the discussion. Proceed with caution.
Finish editing this message first!
Please register or to comment