|
@@ -31,6 +31,7 @@
|
|
|
#include "util/intlist.h"
|
|
|
#include "util/thread_map.h"
|
|
|
#include "util/stat.h"
|
|
|
+#include "trace/beauty/beauty.h"
|
|
|
#include "trace-event.h"
|
|
|
#include "util/parse-events.h"
|
|
|
#include "util/bpf-loader.h"
|
|
@@ -267,15 +268,6 @@ out_delete:
|
|
|
({ struct syscall_tp *fields = evsel->priv; \
|
|
|
fields->name.pointer(&fields->name, sample); })
|
|
|
|
|
|
-struct syscall_arg {
|
|
|
- unsigned long val;
|
|
|
- struct thread *thread;
|
|
|
- struct trace *trace;
|
|
|
- void *parm;
|
|
|
- u8 idx;
|
|
|
- u8 mask;
|
|
|
-};
|
|
|
-
|
|
|
struct strarray {
|
|
|
int offset;
|
|
|
int nr_entries;
|
|
@@ -771,6 +763,10 @@ static struct syscall_fmt {
|
|
|
.arg_parm = { [0] = &strarray__socket_families, /* family */ }, },
|
|
|
{ .name = "stat", .errmsg = true, .alias = "newstat", },
|
|
|
{ .name = "statfs", .errmsg = true, },
|
|
|
+ { .name = "statx", .errmsg = true,
|
|
|
+ .arg_scnprintf = { [0] = SCA_FDAT, /* flags */
|
|
|
+ [2] = SCA_STATX_FLAGS, /* flags */
|
|
|
+ [3] = SCA_STATX_MASK, /* mask */ }, },
|
|
|
{ .name = "swapoff", .errmsg = true,
|
|
|
.arg_scnprintf = { [0] = SCA_FILENAME, /* specialfile */ }, },
|
|
|
{ .name = "swapon", .errmsg = true,
|
|
@@ -821,12 +817,21 @@ struct syscall {
|
|
|
void **arg_parm;
|
|
|
};
|
|
|
|
|
|
-static size_t fprintf_duration(unsigned long t, FILE *fp)
|
|
|
+/*
|
|
|
+ * We need to have this 'calculated' boolean because in some cases we really
|
|
|
+ * don't know what is the duration of a syscall, for instance, when we start
|
|
|
+ * a session and some threads are waiting for a syscall to finish, say 'poll',
|
|
|
+ * in which case all we can do is to print "( ? ) for duration and for the
|
|
|
+ * start timestamp.
|
|
|
+ */
|
|
|
+static size_t fprintf_duration(unsigned long t, bool calculated, FILE *fp)
|
|
|
{
|
|
|
double duration = (double)t / NSEC_PER_MSEC;
|
|
|
size_t printed = fprintf(fp, "(");
|
|
|
|
|
|
- if (duration >= 1.0)
|
|
|
+ if (!calculated)
|
|
|
+ printed += fprintf(fp, " ? ");
|
|
|
+ else if (duration >= 1.0)
|
|
|
printed += color_fprintf(fp, PERF_COLOR_RED, "%6.3f ms", duration);
|
|
|
else if (duration >= 0.01)
|
|
|
printed += color_fprintf(fp, PERF_COLOR_YELLOW, "%6.3f ms", duration);
|
|
@@ -1028,13 +1033,27 @@ static bool trace__filter_duration(struct trace *trace, double t)
|
|
|
return t < (trace->duration_filter * NSEC_PER_MSEC);
|
|
|
}
|
|
|
|
|
|
-static size_t trace__fprintf_tstamp(struct trace *trace, u64 tstamp, FILE *fp)
|
|
|
+static size_t __trace__fprintf_tstamp(struct trace *trace, u64 tstamp, FILE *fp)
|
|
|
{
|
|
|
double ts = (double)(tstamp - trace->base_time) / NSEC_PER_MSEC;
|
|
|
|
|
|
return fprintf(fp, "%10.3f ", ts);
|
|
|
}
|
|
|
|
|
|
+/*
|
|
|
+ * We're handling tstamp=0 as an undefined tstamp, i.e. like when we are
|
|
|
+ * using ttrace->entry_time for a thread that receives a sys_exit without
|
|
|
+ * first having received a sys_enter ("poll" issued before tracing session
|
|
|
+ * starts, lost sys_enter exit due to ring buffer overflow).
|
|
|
+ */
|
|
|
+static size_t trace__fprintf_tstamp(struct trace *trace, u64 tstamp, FILE *fp)
|
|
|
+{
|
|
|
+ if (tstamp > 0)
|
|
|
+ return __trace__fprintf_tstamp(trace, tstamp, fp);
|
|
|
+
|
|
|
+ return fprintf(fp, " ? ");
|
|
|
+}
|
|
|
+
|
|
|
static bool done = false;
|
|
|
static bool interrupted = false;
|
|
|
|
|
@@ -1045,10 +1064,10 @@ static void sig_handler(int sig)
|
|
|
}
|
|
|
|
|
|
static size_t trace__fprintf_entry_head(struct trace *trace, struct thread *thread,
|
|
|
- u64 duration, u64 tstamp, FILE *fp)
|
|
|
+ u64 duration, bool duration_calculated, u64 tstamp, FILE *fp)
|
|
|
{
|
|
|
size_t printed = trace__fprintf_tstamp(trace, tstamp, fp);
|
|
|
- printed += fprintf_duration(duration, fp);
|
|
|
+ printed += fprintf_duration(duration, duration_calculated, fp);
|
|
|
|
|
|
if (trace->multiple_threads) {
|
|
|
if (trace->show_comm)
|
|
@@ -1450,7 +1469,7 @@ static int trace__printf_interrupted_entry(struct trace *trace, struct perf_samp
|
|
|
|
|
|
duration = sample->time - ttrace->entry_time;
|
|
|
|
|
|
- printed = trace__fprintf_entry_head(trace, trace->current, duration, ttrace->entry_time, trace->output);
|
|
|
+ printed = trace__fprintf_entry_head(trace, trace->current, duration, true, ttrace->entry_time, trace->output);
|
|
|
printed += fprintf(trace->output, "%-70s) ...\n", ttrace->entry_str);
|
|
|
ttrace->entry_pending = false;
|
|
|
|
|
@@ -1497,7 +1516,7 @@ static int trace__sys_enter(struct trace *trace, struct perf_evsel *evsel,
|
|
|
|
|
|
if (sc->is_exit) {
|
|
|
if (!(trace->duration_filter || trace->summary_only || trace->min_stack)) {
|
|
|
- trace__fprintf_entry_head(trace, thread, 1, ttrace->entry_time, trace->output);
|
|
|
+ trace__fprintf_entry_head(trace, thread, 0, false, ttrace->entry_time, trace->output);
|
|
|
fprintf(trace->output, "%-70s)\n", ttrace->entry_str);
|
|
|
}
|
|
|
} else {
|
|
@@ -1545,6 +1564,7 @@ static int trace__sys_exit(struct trace *trace, struct perf_evsel *evsel,
|
|
|
{
|
|
|
long ret;
|
|
|
u64 duration = 0;
|
|
|
+ bool duration_calculated = false;
|
|
|
struct thread *thread;
|
|
|
int id = perf_evsel__sc_tp_uint(evsel, id, sample), err = -1, callchain_ret = 0;
|
|
|
struct syscall *sc = trace__syscall_info(trace, evsel, id);
|
|
@@ -1573,6 +1593,7 @@ static int trace__sys_exit(struct trace *trace, struct perf_evsel *evsel,
|
|
|
duration = sample->time - ttrace->entry_time;
|
|
|
if (trace__filter_duration(trace, duration))
|
|
|
goto out;
|
|
|
+ duration_calculated = true;
|
|
|
} else if (trace->duration_filter)
|
|
|
goto out;
|
|
|
|
|
@@ -1588,7 +1609,7 @@ static int trace__sys_exit(struct trace *trace, struct perf_evsel *evsel,
|
|
|
if (trace->summary_only)
|
|
|
goto out;
|
|
|
|
|
|
- trace__fprintf_entry_head(trace, thread, duration, ttrace->entry_time, trace->output);
|
|
|
+ trace__fprintf_entry_head(trace, thread, duration, duration_calculated, ttrace->entry_time, trace->output);
|
|
|
|
|
|
if (ttrace->entry_pending) {
|
|
|
fprintf(trace->output, "%-70s", ttrace->entry_str);
|
|
@@ -1855,7 +1876,7 @@ static int trace__pgfault(struct trace *trace,
|
|
|
thread__find_addr_location(thread, sample->cpumode, MAP__FUNCTION,
|
|
|
sample->ip, &al);
|
|
|
|
|
|
- trace__fprintf_entry_head(trace, thread, 0, sample->time, trace->output);
|
|
|
+ trace__fprintf_entry_head(trace, thread, 0, true, sample->time, trace->output);
|
|
|
|
|
|
fprintf(trace->output, "%sfault [",
|
|
|
evsel->attr.config == PERF_COUNT_SW_PAGE_FAULTS_MAJ ?
|