mirror of
				https://github.com/torvalds/linux.git
				synced 2025-11-04 02:30:34 +02:00 
			
		
		
		
	Perf record with verbose=2 already prints this information along with
whole lot of other traces which requires lot of scrolling. Introduce
an option to print only perf_event_open() arguments and return value.
Sample o/p:
  $ perf --debug perf-event-open=1 record -- ls > /dev/null
  ------------------------------------------------------------
  perf_event_attr:
    size                             112
    { sample_period, sample_freq }   4000
    sample_type                      IP|TID|TIME|PERIOD
    read_format                      ID
    disabled                         1
    inherit                          1
    exclude_kernel                   1
    mmap                             1
    comm                             1
    freq                             1
    enable_on_exec                   1
    task                             1
    precise_ip                       3
    sample_id_all                    1
    exclude_guest                    1
    mmap2                            1
    comm_exec                        1
    ksymbol                          1
    bpf_event                        1
  ------------------------------------------------------------
  sys_perf_event_open: pid 4308  cpu 0  group_fd -1  flags 0x8 = 4
  sys_perf_event_open: pid 4308  cpu 1  group_fd -1  flags 0x8 = 5
  sys_perf_event_open: pid 4308  cpu 2  group_fd -1  flags 0x8 = 6
  sys_perf_event_open: pid 4308  cpu 3  group_fd -1  flags 0x8 = 8
  sys_perf_event_open: pid 4308  cpu 4  group_fd -1  flags 0x8 = 9
  sys_perf_event_open: pid 4308  cpu 5  group_fd -1  flags 0x8 = 10
  sys_perf_event_open: pid 4308  cpu 6  group_fd -1  flags 0x8 = 11
  sys_perf_event_open: pid 4308  cpu 7  group_fd -1  flags 0x8 = 12
  ------------------------------------------------------------
  perf_event_attr:
    type                             1
    size                             112
    config                           0x9
    watermark                        1
    sample_id_all                    1
    bpf_event                        1
    { wakeup_events, wakeup_watermark } 1
  ------------------------------------------------------------
  sys_perf_event_open: pid -1  cpu 0  group_fd -1  flags 0x8
  sys_perf_event_open failed, error -13
  [ perf record: Woken up 1 times to write data ]
  [ perf record: Captured and wrote 0.002 MB perf.data (9 samples) ]
Committer notes:
Just like the 'verbose' variable this new 'debug_peo_args' needs to be
added to util/python.c, since we don't link the debug.o file in the
python binding, which ended up making 'perf test python' fail with:
  # perf test -v python
  18: 'import perf' in python                               :
  --- start ---
  test child forked, pid 19237
  Traceback (most recent call last):
    File "<stdin>", line 1, in <module>
  ImportError: /tmp/build/perf/python/perf.so: undefined symbol: debug_peo_args
  test child finished with -1
  ---- end ----
  'import perf' in python: FAILED!
  #
After adding that new variable to util/python.c:
  # perf test -v python
  18: 'import perf' in python                               :
  --- start ---
  test child forked, pid 22364
  test child finished with 0
  ---- end ----
  'import perf' in python: Ok
  #
Signed-off-by: Ravi Bangoria <ravi.bangoria@linux.ibm.com>
Tested-by: Arnaldo Carvalho de Melo <acme@redhat.com>
Acked-by: Jiri Olsa <jolsa@kernel.org>
Link: http://lore.kernel.org/lkml/20191108094128.28769-1-ravi.bangoria@linux.ibm.com
Signed-off-by: Arnaldo Carvalho de Melo <acme@redhat.com>
		
	
			
		
			
				
	
	
		
			286 lines
		
	
	
	
		
			5.5 KiB
		
	
	
	
		
			C
		
	
	
	
	
	
			
		
		
	
	
			286 lines
		
	
	
	
		
			5.5 KiB
		
	
	
	
		
			C
		
	
	
	
	
	
// SPDX-License-Identifier: GPL-2.0
 | 
						|
/* For general debugging purposes */
 | 
						|
 | 
						|
#include <inttypes.h>
 | 
						|
#include <string.h>
 | 
						|
#include <stdarg.h>
 | 
						|
#include <stdio.h>
 | 
						|
#include <stdlib.h>
 | 
						|
#include <sys/wait.h>
 | 
						|
#include <api/debug.h>
 | 
						|
#include <linux/kernel.h>
 | 
						|
#include <linux/time64.h>
 | 
						|
#ifdef HAVE_BACKTRACE_SUPPORT
 | 
						|
#include <execinfo.h>
 | 
						|
#endif
 | 
						|
#include "color.h"
 | 
						|
#include "event.h"
 | 
						|
#include "debug.h"
 | 
						|
#include "print_binary.h"
 | 
						|
#include "target.h"
 | 
						|
#include "ui/helpline.h"
 | 
						|
#include "ui/ui.h"
 | 
						|
 | 
						|
#include <linux/ctype.h>
 | 
						|
 | 
						|
int verbose;
 | 
						|
int debug_peo_args;
 | 
						|
bool dump_trace = false, quiet = false;
 | 
						|
int debug_ordered_events;
 | 
						|
static int redirect_to_stderr;
 | 
						|
int debug_data_convert;
 | 
						|
 | 
						|
int veprintf(int level, int var, const char *fmt, va_list args)
 | 
						|
{
 | 
						|
	int ret = 0;
 | 
						|
 | 
						|
	if (var >= level) {
 | 
						|
		if (use_browser >= 1 && !redirect_to_stderr)
 | 
						|
			ui_helpline__vshow(fmt, args);
 | 
						|
		else
 | 
						|
			ret = vfprintf(stderr, fmt, args);
 | 
						|
	}
 | 
						|
 | 
						|
	return ret;
 | 
						|
}
 | 
						|
 | 
						|
int eprintf(int level, int var, const char *fmt, ...)
 | 
						|
{
 | 
						|
	va_list args;
 | 
						|
	int ret;
 | 
						|
 | 
						|
	va_start(args, fmt);
 | 
						|
	ret = veprintf(level, var, fmt, args);
 | 
						|
	va_end(args);
 | 
						|
 | 
						|
	return ret;
 | 
						|
}
 | 
						|
 | 
						|
static int veprintf_time(u64 t, const char *fmt, va_list args)
 | 
						|
{
 | 
						|
	int ret = 0;
 | 
						|
	u64 secs, usecs, nsecs = t;
 | 
						|
 | 
						|
	secs   = nsecs / NSEC_PER_SEC;
 | 
						|
	nsecs -= secs  * NSEC_PER_SEC;
 | 
						|
	usecs  = nsecs / NSEC_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);
 | 
						|
		ret = veprintf_time(t, fmt, args);
 | 
						|
		va_end(args);
 | 
						|
	}
 | 
						|
 | 
						|
	return ret;
 | 
						|
}
 | 
						|
 | 
						|
/*
 | 
						|
 * Overloading libtraceevent standard info print
 | 
						|
 * function, display with -v in perf.
 | 
						|
 */
 | 
						|
void pr_stat(const char *fmt, ...)
 | 
						|
{
 | 
						|
	va_list args;
 | 
						|
 | 
						|
	va_start(args, fmt);
 | 
						|
	veprintf(1, verbose, fmt, args);
 | 
						|
	va_end(args);
 | 
						|
	eprintf(1, verbose, "\n");
 | 
						|
}
 | 
						|
 | 
						|
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;
 | 
						|
}
 | 
						|
 | 
						|
static int trace_event_printer(enum binary_printer_ops op,
 | 
						|
			       unsigned int val, void *extra, FILE *fp)
 | 
						|
{
 | 
						|
	const char *color = PERF_COLOR_BLUE;
 | 
						|
	union perf_event *event = (union perf_event *)extra;
 | 
						|
	unsigned char ch = (unsigned char)val;
 | 
						|
	int printed = 0;
 | 
						|
 | 
						|
	switch (op) {
 | 
						|
	case BINARY_PRINT_DATA_BEGIN:
 | 
						|
		printed += fprintf(fp, ".");
 | 
						|
		printed += color_fprintf(fp, color, "\n. ... raw event: size %d bytes\n",
 | 
						|
					 event->header.size);
 | 
						|
		break;
 | 
						|
	case BINARY_PRINT_LINE_BEGIN:
 | 
						|
		printed += fprintf(fp, ".");
 | 
						|
		break;
 | 
						|
	case BINARY_PRINT_ADDR:
 | 
						|
		printed += color_fprintf(fp, color, "  %04x: ", val);
 | 
						|
		break;
 | 
						|
	case BINARY_PRINT_NUM_DATA:
 | 
						|
		printed += color_fprintf(fp, color, " %02x", val);
 | 
						|
		break;
 | 
						|
	case BINARY_PRINT_NUM_PAD:
 | 
						|
		printed += color_fprintf(fp, color, "   ");
 | 
						|
		break;
 | 
						|
	case BINARY_PRINT_SEP:
 | 
						|
		printed += color_fprintf(fp, color, "  ");
 | 
						|
		break;
 | 
						|
	case BINARY_PRINT_CHAR_DATA:
 | 
						|
		printed += color_fprintf(fp, color, "%c",
 | 
						|
			      isprint(ch) ? ch : '.');
 | 
						|
		break;
 | 
						|
	case BINARY_PRINT_CHAR_PAD:
 | 
						|
		printed += color_fprintf(fp, color, " ");
 | 
						|
		break;
 | 
						|
	case BINARY_PRINT_LINE_END:
 | 
						|
		printed += color_fprintf(fp, color, "\n");
 | 
						|
		break;
 | 
						|
	case BINARY_PRINT_DATA_END:
 | 
						|
		printed += fprintf(fp, "\n");
 | 
						|
		break;
 | 
						|
	default:
 | 
						|
		break;
 | 
						|
	}
 | 
						|
 | 
						|
	return printed;
 | 
						|
}
 | 
						|
 | 
						|
void trace_event(union perf_event *event)
 | 
						|
{
 | 
						|
	unsigned char *raw_event = (void *)event;
 | 
						|
 | 
						|
	if (!dump_trace)
 | 
						|
		return;
 | 
						|
 | 
						|
	print_binary(raw_event, event->header.size, 16,
 | 
						|
		     trace_event_printer, event);
 | 
						|
}
 | 
						|
 | 
						|
static struct debug_variable {
 | 
						|
	const char *name;
 | 
						|
	int *ptr;
 | 
						|
} debug_variables[] = {
 | 
						|
	{ .name = "verbose",		.ptr = &verbose },
 | 
						|
	{ .name = "ordered-events",	.ptr = &debug_ordered_events},
 | 
						|
	{ .name = "stderr",		.ptr = &redirect_to_stderr},
 | 
						|
	{ .name = "data-convert",	.ptr = &debug_data_convert },
 | 
						|
	{ .name = "perf-event-open",	.ptr = &debug_peo_args },
 | 
						|
	{ .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;
 | 
						|
	}
 | 
						|
 | 
						|
	if (quiet)
 | 
						|
		v = -1;
 | 
						|
 | 
						|
	*var->ptr = v;
 | 
						|
	free(s);
 | 
						|
	return 0;
 | 
						|
}
 | 
						|
 | 
						|
int perf_quiet_option(void)
 | 
						|
{
 | 
						|
	struct debug_variable *var = &debug_variables[0];
 | 
						|
 | 
						|
	/* disable all debug messages */
 | 
						|
	while (var->name) {
 | 
						|
		*var->ptr = -1;
 | 
						|
		var++;
 | 
						|
	}
 | 
						|
 | 
						|
	return 0;
 | 
						|
}
 | 
						|
 | 
						|
#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);
 | 
						|
}
 | 
						|
 | 
						|
/* Obtain a backtrace and print it to stdout. */
 | 
						|
#ifdef HAVE_BACKTRACE_SUPPORT
 | 
						|
void dump_stack(void)
 | 
						|
{
 | 
						|
	void *array[16];
 | 
						|
	size_t size = backtrace(array, ARRAY_SIZE(array));
 | 
						|
	char **strings = backtrace_symbols(array, size);
 | 
						|
	size_t i;
 | 
						|
 | 
						|
	printf("Obtained %zd stack frames.\n", size);
 | 
						|
 | 
						|
	for (i = 0; i < size; i++)
 | 
						|
		printf("%s\n", strings[i]);
 | 
						|
 | 
						|
	free(strings);
 | 
						|
}
 | 
						|
#else
 | 
						|
void dump_stack(void) {}
 | 
						|
#endif
 | 
						|
 | 
						|
void sighandler_dump_stack(int sig)
 | 
						|
{
 | 
						|
	psignal(sig, "perf");
 | 
						|
	dump_stack();
 | 
						|
	signal(sig, SIG_DFL);
 | 
						|
	raise(sig);
 | 
						|
}
 |