mirror of
https://github.com/edk2-porting/linux-next.git
synced 2024-12-17 09:43:59 +08:00
08efcb4a63
libtraceevent has added more levels of debug printout and with changes like: https://lore.kernel.org/linux-trace-devel/20210507095022.1079364-3-tz.stoyanov@gmail.com previously generated output like "registering plugin" is no longer displayed. This change makes it so that if perf's verbose debug output is enabled then the debug and info libtraceevent messages can be displayed. The code is conditionally enabled based on the libtraceevent version as discussed in the RFC: https://lore.kernel.org/lkml/20210610060643.595673-1-irogers@google.com/ v2. Is a rebase and handles the case of building without LIBTRACEEVENT_DYNAMIC. Signed-off-by: Ian Rogers <irogers@google.com> Acked-by: Jiri Olsa <jolsa@redhat.com> Cc: Alexander Shishkin <alexander.shishkin@linux.intel.com> Cc: Mark Rutland <mark.rutland@arm.com> Cc: Namhyung Kim <namhyung@kernel.org> Cc: Peter Zijlstra <peterz@infradead.org> Cc: Stephane Eranian <eranian@google.com> Cc: Steven Rostedt (VMware) <rostedt@goodmis.org> Cc: Tzvetomir Stoyanov (VMware) <tz.stoyanov@gmail.com> Cc: linux-trace-devel@vger.kernel.org Link: http://lore.kernel.org/lkml/20210923001024.550263-4-irogers@google.com Signed-off-by: Arnaldo Carvalho de Melo <acme@redhat.com>
314 lines
6.4 KiB
C
314 lines
6.4 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>
|
|
#include <sys/time.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 "util/parse-sublevel-options.h"
|
|
|
|
#include <linux/ctype.h>
|
|
#include <traceevent/event-parse.h>
|
|
|
|
#define MAKE_LIBTRACEEVENT_VERSION(a, b, c) ((a)*255*255+(b)*255+(c))
|
|
#ifndef LIBTRACEEVENT_VERSION
|
|
/*
|
|
* If LIBTRACEEVENT_VERSION wasn't computed then set to version 1.1.0 that ships
|
|
* with the Linux kernel tools.
|
|
*/
|
|
#define LIBTRACEEVENT_VERSION MAKE_LIBTRACEEVENT_VERSION(1, 1, 0)
|
|
#endif
|
|
|
|
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;
|
|
static FILE *debug_file;
|
|
bool debug_display_time;
|
|
|
|
void debug_set_file(FILE *file)
|
|
{
|
|
debug_file = file;
|
|
}
|
|
|
|
void debug_set_display_time(bool set)
|
|
{
|
|
debug_display_time = set;
|
|
}
|
|
|
|
static int fprintf_time(FILE *file)
|
|
{
|
|
struct timeval tod;
|
|
struct tm ltime;
|
|
char date[64];
|
|
|
|
if (!debug_display_time)
|
|
return 0;
|
|
|
|
if (gettimeofday(&tod, NULL) != 0)
|
|
return 0;
|
|
|
|
if (localtime_r(&tod.tv_sec, <ime) == NULL)
|
|
return 0;
|
|
|
|
strftime(date, sizeof(date), "%F %H:%M:%S", <ime);
|
|
return fprintf(file, "[%s.%06lu] ", date, (long)tod.tv_usec);
|
|
}
|
|
|
|
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 = fprintf_time(debug_file);
|
|
ret += vfprintf(debug_file, 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 sublevel_option debug_opts[] = {
|
|
{ .name = "verbose", .value_ptr = &verbose },
|
|
{ .name = "ordered-events", .value_ptr = &debug_ordered_events},
|
|
{ .name = "stderr", .value_ptr = &redirect_to_stderr},
|
|
{ .name = "data-convert", .value_ptr = &debug_data_convert },
|
|
{ .name = "perf-event-open", .value_ptr = &debug_peo_args },
|
|
{ .name = NULL, }
|
|
};
|
|
|
|
int perf_debug_option(const char *str)
|
|
{
|
|
int ret;
|
|
|
|
ret = perf_parse_sublevel_options(str, debug_opts);
|
|
if (ret)
|
|
return ret;
|
|
|
|
/* Allow only verbose value in range (0, 10), otherwise set 0. */
|
|
verbose = (verbose < 0) || (verbose > 10) ? 0 : verbose;
|
|
|
|
#if MAKE_LIBTRACEEVENT_VERSION(1, 3, 0) <= LIBTRACEEVENT_VERSION
|
|
if (verbose == 1)
|
|
tep_set_loglevel(TEP_LOG_INFO);
|
|
else if (verbose == 2)
|
|
tep_set_loglevel(TEP_LOG_DEBUG);
|
|
else if (verbose >= 3)
|
|
tep_set_loglevel(TEP_LOG_ALL);
|
|
#endif
|
|
|
|
return 0;
|
|
}
|
|
|
|
int perf_quiet_option(void)
|
|
{
|
|
struct sublevel_option *opt = &debug_opts[0];
|
|
|
|
/* disable all debug messages */
|
|
while (opt->name) {
|
|
*opt->value_ptr = -1;
|
|
opt++;
|
|
}
|
|
|
|
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)
|
|
{
|
|
debug_set_file(stderr);
|
|
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);
|
|
}
|