2009-08-12 02:03:49 -06:00
|
|
|
/* For general debugging purposes */
|
|
|
|
|
|
|
|
#include "../perf.h"
|
2009-08-16 14:05:48 -06:00
|
|
|
|
2009-08-12 02:03:49 -06:00
|
|
|
#include <string.h>
|
|
|
|
#include <stdarg.h>
|
|
|
|
#include <stdio.h>
|
2016-02-14 09:03:45 -07:00
|
|
|
#include <api/debug.h>
|
2009-08-12 02:03:49 -06:00
|
|
|
|
2010-03-11 16:12:44 -07:00
|
|
|
#include "cache.h"
|
2009-08-16 14:05:48 -06:00
|
|
|
#include "color.h"
|
|
|
|
#include "event.h"
|
|
|
|
#include "debug.h"
|
2009-12-27 16:37:00 -07:00
|
|
|
#include "util.h"
|
2012-05-06 23:09:02 -06:00
|
|
|
#include "target.h"
|
2009-08-16 14:05:48 -06:00
|
|
|
|
2014-07-11 06:49:54 -06:00
|
|
|
#define NSECS_PER_SEC 1000000000ULL
|
|
|
|
#define NSECS_PER_USEC 1000ULL
|
|
|
|
|
2010-10-26 11:20:09 -06:00
|
|
|
int verbose;
|
|
|
|
bool dump_trace = false, quiet = false;
|
2014-07-11 06:49:54 -06:00
|
|
|
int debug_ordered_events;
|
2014-11-21 14:38:00 -07:00
|
|
|
static int redirect_to_stderr;
|
perf data: Add perf data to CTF conversion support
Adding 'perf data convert' to convert perf data file into different
format. This patch adds support for CTF format conversion.
To convert perf.data into CTF run:
$ perf data convert --to-ctf=./ctf-data/
[ perf data convert: Converted 'perf.data' into CTF data './ctf-data/' ]
[ perf data convert: Converted and wrote 11.268 MB (100230 samples) ]
The command will create CTF metadata out of perf.data file (or one
specified via -i option) and then convert all sample events into single
CTF stream.
Each sample_type bit is translated into separated CTF event field apart
from following exceptions:
PERF_SAMPLE_RAW - added in next patch
PERF_SAMPLE_READ - TODO
PERF_SAMPLE_CALLCHAIN - TODO
PERF_SAMPLE_BRANCH_STACK - TODO
PERF_SAMPLE_REGS_USER - TODO
PERF_SAMPLE_STACK_USER - TODO
$ perf --debug=data-convert=2 data convert ...
The converted CTF data could be analyzed by CTF tools, like babletrace
or tracecompass [1].
$ babeltrace ./ctf-data/
[03:19:13.962125533] (+?.?????????) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 1 }
[03:19:13.962130001] (+0.000004468) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 1 }
[03:19:13.962131936] (+0.000001935) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 8 }
[03:19:13.962133732] (+0.000001796) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 114 }
[03:19:13.962135557] (+0.000001825) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 2087 }
[03:19:13.962137627] (+0.000002070) cycles: { }, { ip = 0xFFFFFFFF81361938, tid = 20714, pid = 20714, period = 37582 }
[03:19:13.962161091] (+0.000023464) cycles: { }, { ip = 0xFFFFFFFF8124218F, tid = 20714, pid = 20714, period = 600246 }
[03:19:13.962517569] (+0.000356478) cycles: { }, { ip = 0xFFFFFFFF811A75DB, tid = 20714, pid = 20714, period = 1325731 }
[03:19:13.969518008] (+0.007000439) cycles: { }, { ip = 0x34080917B2, tid = 20714, pid = 20714, period = 1144298 }
The following members to the ctf-environment were decided to be added to
distinguish and specify perf CTF data:
- domain
It says "kernel" because it contains a kernel trace (not to be
confused with a user space like lttng-ust does)
- tracer_name
It says perf. This can be used to distinguish between lttng and perf
CTF based trace.
- version
The kernel version from stream. In addition to release, this is what
it looks like on a Debian kernel:
release = "3.14-1-amd64";
version = "3.14.0";
[1] http://projects.eclipse.org/projects/tools.tracecompass
Signed-off-by: Jiri Olsa <jolsa@kernel.org>
Acked-by: Namhyung Kim <namhyung@kernel.org>
Reviewed-by: David Ahern <dsahern@gmail.com>
Tested-by: Arnaldo Carvalho de Melo <acme@redhat.com>
Cc: Frederic Weisbecker <fweisbec@gmail.com>
Cc: Jeremie Galarneau <jgalar@efficios.com>
Cc: Paul Mackerras <paulus@samba.org>
Cc: Peter Zijlstra <peterz@infradead.org>
Cc: Sebastian Andrzej Siewior <bigeasy@linutronix.de>
Cc: Tom Zanussi <tzanussi@gmail.com>
Cc: Wang Nan <wangnan0@huawei.com>
Link: http://lkml.kernel.org/r/1424470628-5969-4-git-send-email-jolsa@kernel.org
Signed-off-by: Sebastian Andrzej Siewior <bigeasy@linutronix.de>
Signed-off-by: Arnaldo Carvalho de Melo <acme@redhat.com>
2015-02-20 15:17:00 -07:00
|
|
|
int debug_data_convert;
|
2009-08-12 02:03:49 -06:00
|
|
|
|
2016-02-16 07:48:38 -07:00
|
|
|
int veprintf(int level, int var, const char *fmt, va_list args)
|
2009-08-12 02:03:49 -06:00
|
|
|
{
|
|
|
|
int ret = 0;
|
|
|
|
|
2014-07-14 15:46:49 -06:00
|
|
|
if (var >= level) {
|
2014-11-21 14:38:00 -07:00
|
|
|
if (use_browser >= 1 && !redirect_to_stderr)
|
2012-11-14 09:47:41 -07:00
|
|
|
ui_helpline__vshow(fmt, args);
|
2010-03-11 16:12:44 -07:00
|
|
|
else
|
|
|
|
ret = vfprintf(stderr, fmt, args);
|
2009-08-12 02:03:49 -06:00
|
|
|
}
|
|
|
|
|
|
|
|
return ret;
|
|
|
|
}
|
2009-08-16 11:24:21 -06:00
|
|
|
|
2014-07-14 15:46:49 -06:00
|
|
|
int eprintf(int level, int var, const char *fmt, ...)
|
2013-12-03 06:09:25 -07:00
|
|
|
{
|
|
|
|
va_list args;
|
|
|
|
int ret;
|
|
|
|
|
|
|
|
va_start(args, fmt);
|
2016-02-16 07:48:38 -07:00
|
|
|
ret = veprintf(level, var, fmt, args);
|
2013-12-03 06:09:25 -07:00
|
|
|
va_end(args);
|
|
|
|
|
|
|
|
return ret;
|
|
|
|
}
|
|
|
|
|
2016-02-16 07:48:38 -07:00
|
|
|
static int veprintf_time(u64 t, const char *fmt, va_list args)
|
2014-07-11 06:49:54 -06:00
|
|
|
{
|
|
|
|
int ret = 0;
|
|
|
|
u64 secs, usecs, nsecs = t;
|
|
|
|
|
|
|
|
secs = nsecs / NSECS_PER_SEC;
|
|
|
|
nsecs -= secs * NSECS_PER_SEC;
|
|
|
|
usecs = nsecs / NSECS_PER_USEC;
|
|
|
|
|
|
|
|
ret = fprintf(stderr, "[%13" PRIu64 ".%06" PRIu64 "] ",
|
|
|
|
secs, usecs);
|
|
|
|
ret += vfprintf(stderr, fmt, args);
|
|
|
|
return ret;
|
|
|
|
}
|
|
|
|
|
|
|
|
int eprintf_time(int level, int var, u64 t, const char *fmt, ...)
|
|
|
|
{
|
|
|
|
int ret = 0;
|
|
|
|
va_list args;
|
|
|
|
|
|
|
|
if (var >= level) {
|
|
|
|
va_start(args, fmt);
|
2016-02-16 07:48:38 -07:00
|
|
|
ret = veprintf_time(t, fmt, args);
|
2014-07-11 06:49:54 -06:00
|
|
|
va_end(args);
|
|
|
|
}
|
|
|
|
|
|
|
|
return ret;
|
|
|
|
}
|
|
|
|
|
2013-12-03 06:09:25 -07:00
|
|
|
/*
|
|
|
|
* Overloading libtraceevent standard info print
|
|
|
|
* function, display with -v in perf.
|
|
|
|
*/
|
|
|
|
void pr_stat(const char *fmt, ...)
|
|
|
|
{
|
|
|
|
va_list args;
|
|
|
|
|
|
|
|
va_start(args, fmt);
|
2016-02-16 07:48:38 -07:00
|
|
|
veprintf(1, verbose, fmt, args);
|
2013-12-03 06:09:25 -07:00
|
|
|
va_end(args);
|
2014-07-14 15:46:49 -06:00
|
|
|
eprintf(1, verbose, "\n");
|
2013-12-03 06:09:25 -07:00
|
|
|
}
|
|
|
|
|
2009-08-16 11:24:21 -06:00
|
|
|
int dump_printf(const char *fmt, ...)
|
|
|
|
{
|
|
|
|
va_list args;
|
|
|
|
int ret = 0;
|
|
|
|
|
|
|
|
if (dump_trace) {
|
|
|
|
va_start(args, fmt);
|
|
|
|
ret = vprintf(fmt, args);
|
|
|
|
va_end(args);
|
|
|
|
}
|
|
|
|
|
|
|
|
return ret;
|
|
|
|
}
|
2009-08-16 14:05:48 -06:00
|
|
|
|
2016-02-24 04:20:44 -07:00
|
|
|
static void trace_event_printer(enum binary_printer_ops op,
|
|
|
|
unsigned int val, void *extra)
|
|
|
|
{
|
|
|
|
const char *color = PERF_COLOR_BLUE;
|
|
|
|
union perf_event *event = (union perf_event *)extra;
|
|
|
|
unsigned char ch = (unsigned char)val;
|
|
|
|
|
|
|
|
switch (op) {
|
|
|
|
case BINARY_PRINT_DATA_BEGIN:
|
|
|
|
printf(".");
|
|
|
|
color_fprintf(stdout, color, "\n. ... raw event: size %d bytes\n",
|
|
|
|
event->header.size);
|
|
|
|
break;
|
|
|
|
case BINARY_PRINT_LINE_BEGIN:
|
|
|
|
printf(".");
|
|
|
|
break;
|
|
|
|
case BINARY_PRINT_ADDR:
|
|
|
|
color_fprintf(stdout, color, " %04x: ", val);
|
|
|
|
break;
|
|
|
|
case BINARY_PRINT_NUM_DATA:
|
|
|
|
color_fprintf(stdout, color, " %02x", val);
|
|
|
|
break;
|
|
|
|
case BINARY_PRINT_NUM_PAD:
|
|
|
|
color_fprintf(stdout, color, " ");
|
|
|
|
break;
|
|
|
|
case BINARY_PRINT_SEP:
|
|
|
|
color_fprintf(stdout, color, " ");
|
|
|
|
break;
|
|
|
|
case BINARY_PRINT_CHAR_DATA:
|
|
|
|
color_fprintf(stdout, color, "%c",
|
|
|
|
isprint(ch) ? ch : '.');
|
|
|
|
break;
|
|
|
|
case BINARY_PRINT_CHAR_PAD:
|
|
|
|
color_fprintf(stdout, color, " ");
|
|
|
|
break;
|
|
|
|
case BINARY_PRINT_LINE_END:
|
|
|
|
color_fprintf(stdout, color, "\n");
|
|
|
|
break;
|
|
|
|
case BINARY_PRINT_DATA_END:
|
|
|
|
printf("\n");
|
|
|
|
break;
|
|
|
|
default:
|
|
|
|
break;
|
|
|
|
}
|
|
|
|
}
|
|
|
|
|
2011-01-29 09:01:45 -07:00
|
|
|
void trace_event(union perf_event *event)
|
2009-08-16 14:05:48 -06:00
|
|
|
{
|
|
|
|
unsigned char *raw_event = (void *)event;
|
|
|
|
|
|
|
|
if (!dump_trace)
|
|
|
|
return;
|
|
|
|
|
2016-02-24 04:20:44 -07:00
|
|
|
print_binary(raw_event, event->header.size, 16,
|
|
|
|
trace_event_printer, event);
|
2009-08-16 14:05:48 -06:00
|
|
|
}
|
2014-07-17 04:55:00 -06:00
|
|
|
|
|
|
|
static struct debug_variable {
|
|
|
|
const char *name;
|
|
|
|
int *ptr;
|
|
|
|
} debug_variables[] = {
|
2014-07-11 06:49:54 -06:00
|
|
|
{ .name = "verbose", .ptr = &verbose },
|
|
|
|
{ .name = "ordered-events", .ptr = &debug_ordered_events},
|
2014-11-21 14:38:00 -07:00
|
|
|
{ .name = "stderr", .ptr = &redirect_to_stderr},
|
perf data: Add perf data to CTF conversion support
Adding 'perf data convert' to convert perf data file into different
format. This patch adds support for CTF format conversion.
To convert perf.data into CTF run:
$ perf data convert --to-ctf=./ctf-data/
[ perf data convert: Converted 'perf.data' into CTF data './ctf-data/' ]
[ perf data convert: Converted and wrote 11.268 MB (100230 samples) ]
The command will create CTF metadata out of perf.data file (or one
specified via -i option) and then convert all sample events into single
CTF stream.
Each sample_type bit is translated into separated CTF event field apart
from following exceptions:
PERF_SAMPLE_RAW - added in next patch
PERF_SAMPLE_READ - TODO
PERF_SAMPLE_CALLCHAIN - TODO
PERF_SAMPLE_BRANCH_STACK - TODO
PERF_SAMPLE_REGS_USER - TODO
PERF_SAMPLE_STACK_USER - TODO
$ perf --debug=data-convert=2 data convert ...
The converted CTF data could be analyzed by CTF tools, like babletrace
or tracecompass [1].
$ babeltrace ./ctf-data/
[03:19:13.962125533] (+?.?????????) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 1 }
[03:19:13.962130001] (+0.000004468) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 1 }
[03:19:13.962131936] (+0.000001935) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 8 }
[03:19:13.962133732] (+0.000001796) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 114 }
[03:19:13.962135557] (+0.000001825) cycles: { }, { ip = 0xFFFFFFFF8105443A, tid = 20714, pid = 20714, period = 2087 }
[03:19:13.962137627] (+0.000002070) cycles: { }, { ip = 0xFFFFFFFF81361938, tid = 20714, pid = 20714, period = 37582 }
[03:19:13.962161091] (+0.000023464) cycles: { }, { ip = 0xFFFFFFFF8124218F, tid = 20714, pid = 20714, period = 600246 }
[03:19:13.962517569] (+0.000356478) cycles: { }, { ip = 0xFFFFFFFF811A75DB, tid = 20714, pid = 20714, period = 1325731 }
[03:19:13.969518008] (+0.007000439) cycles: { }, { ip = 0x34080917B2, tid = 20714, pid = 20714, period = 1144298 }
The following members to the ctf-environment were decided to be added to
distinguish and specify perf CTF data:
- domain
It says "kernel" because it contains a kernel trace (not to be
confused with a user space like lttng-ust does)
- tracer_name
It says perf. This can be used to distinguish between lttng and perf
CTF based trace.
- version
The kernel version from stream. In addition to release, this is what
it looks like on a Debian kernel:
release = "3.14-1-amd64";
version = "3.14.0";
[1] http://projects.eclipse.org/projects/tools.tracecompass
Signed-off-by: Jiri Olsa <jolsa@kernel.org>
Acked-by: Namhyung Kim <namhyung@kernel.org>
Reviewed-by: David Ahern <dsahern@gmail.com>
Tested-by: Arnaldo Carvalho de Melo <acme@redhat.com>
Cc: Frederic Weisbecker <fweisbec@gmail.com>
Cc: Jeremie Galarneau <jgalar@efficios.com>
Cc: Paul Mackerras <paulus@samba.org>
Cc: Peter Zijlstra <peterz@infradead.org>
Cc: Sebastian Andrzej Siewior <bigeasy@linutronix.de>
Cc: Tom Zanussi <tzanussi@gmail.com>
Cc: Wang Nan <wangnan0@huawei.com>
Link: http://lkml.kernel.org/r/1424470628-5969-4-git-send-email-jolsa@kernel.org
Signed-off-by: Sebastian Andrzej Siewior <bigeasy@linutronix.de>
Signed-off-by: Arnaldo Carvalho de Melo <acme@redhat.com>
2015-02-20 15:17:00 -07:00
|
|
|
{ .name = "data-convert", .ptr = &debug_data_convert },
|
2014-07-17 04:55:00 -06:00
|
|
|
{ .name = NULL, }
|
|
|
|
};
|
|
|
|
|
|
|
|
int perf_debug_option(const char *str)
|
|
|
|
{
|
|
|
|
struct debug_variable *var = &debug_variables[0];
|
|
|
|
char *vstr, *s = strdup(str);
|
|
|
|
int v = 1;
|
|
|
|
|
|
|
|
vstr = strchr(s, '=');
|
|
|
|
if (vstr)
|
|
|
|
*vstr++ = 0;
|
|
|
|
|
|
|
|
while (var->name) {
|
|
|
|
if (!strcmp(s, var->name))
|
|
|
|
break;
|
|
|
|
var++;
|
|
|
|
}
|
|
|
|
|
|
|
|
if (!var->name) {
|
|
|
|
pr_err("Unknown debug variable name '%s'\n", s);
|
|
|
|
free(s);
|
|
|
|
return -1;
|
|
|
|
}
|
|
|
|
|
|
|
|
if (vstr) {
|
|
|
|
v = atoi(vstr);
|
|
|
|
/*
|
|
|
|
* Allow only values in range (0, 10),
|
|
|
|
* otherwise set 0.
|
|
|
|
*/
|
|
|
|
v = (v < 0) || (v > 10) ? 0 : v;
|
|
|
|
}
|
|
|
|
|
|
|
|
*var->ptr = v;
|
|
|
|
free(s);
|
|
|
|
return 0;
|
|
|
|
}
|
2016-02-14 09:03:45 -07:00
|
|
|
|
|
|
|
#define DEBUG_WRAPPER(__n, __l) \
|
|
|
|
static int pr_ ## __n ## _wrapper(const char *fmt, ...) \
|
|
|
|
{ \
|
|
|
|
va_list args; \
|
|
|
|
int ret; \
|
|
|
|
\
|
|
|
|
va_start(args, fmt); \
|
|
|
|
ret = veprintf(__l, verbose, fmt, args); \
|
|
|
|
va_end(args); \
|
|
|
|
return ret; \
|
|
|
|
}
|
|
|
|
|
|
|
|
DEBUG_WRAPPER(warning, 0);
|
|
|
|
DEBUG_WRAPPER(debug, 1);
|
|
|
|
|
|
|
|
void perf_debug_setup(void)
|
|
|
|
{
|
|
|
|
libapi_set_print(pr_warning_wrapper, pr_warning_wrapper, pr_debug_wrapper);
|
|
|
|
}
|