0bdfbd04c6
Dump kmaps if using 'perf --debug kmaps' or verbose > 2 (e.g. -vvv) for tools 'perf script' and 'perf report' if there is no browser. Example: $ perf --debug kmaps script 2>&1 >/dev/null | grep kvm.intel build id event received for /lib/modules/6.7.2-local/kernel/arch/x86/kvm/kvm-intel.ko: 0691d75e10e72ebbbd45a44c59f6d00a5604badf [20] Map: 0-3a3 4f5d8 [kvm_intel].modinfo Map: 0-5240 5f280 [kvm_intel]__versions Map: 0-30 64 [kvm_intel].note.Linux Map: 0-14 644c0 [kvm_intel].orc_header Map: 0-5297 43680 [kvm_intel].rodata Map: 0-5bee 3b837 [kvm_intel].text.unlikely Map: 0-7e0 41430 [kvm_intel].noinstr.text Map: 0-2080 713c0 [kvm_intel].bss Map: 0-26 705c8 [kvm_intel].data..read_mostly Map: 0-5888 6a4c0 [kvm_intel].data Map: 0-22 70220 [kvm_intel].data.once Map: 0-40 705f0 [kvm_intel].data..percpu Map: 0-1685 41d20 [kvm_intel].init.text Map: 0-4b8 6fd60 [kvm_intel].init.data Map: 0-380 70248 [kvm_intel]__dyndbg Map: 0-8 70218 [kvm_intel].exit.data Map: 0-438 4f980 [kvm_intel]__param Map: 0-5f5 4ca0f [kvm_intel].rodata.str1.1 Map: 0-3657 493b8 [kvm_intel].rodata.str1.8 Map: 0-e0 70640 [kvm_intel].data..ro_after_init Map: 0-500 70ec0 [kvm_intel].gnu.linkonce.this_module Map: ffffffffc13a7000-ffffffffc1421000 a0 /lib/modules/6.7.2-local/kernel/arch/x86/kvm/kvm-intel.ko The example above shows how the module section mappings are all wrong except for the main .text mapping at 0xffffffffc13a7000. Signed-off-by: Adrian Hunter <adrian.hunter@intel.com> Acked-by: Namhyung Kim <namhyung@kernel.org> Cc: Like Xu <like.xu.linux@gmail.com> Signed-off-by: Namhyung Kim <namhyung@kernel.org> Link: https://lore.kernel.org/r/20240208085326.13432-2-adrian.hunter@intel.com
325 lines
6.5 KiB
C
325 lines
6.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>
|
|
#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 "trace-event.h"
|
|
#include "ui/helpline.h"
|
|
#include "ui/ui.h"
|
|
#include "util/parse-sublevel-options.h"
|
|
|
|
#include <linux/ctype.h>
|
|
|
|
#ifdef HAVE_LIBTRACEEVENT
|
|
#include <traceevent/event-parse.h>
|
|
#else
|
|
#define LIBTRACEEVENT_VERSION 0
|
|
#endif
|
|
|
|
int verbose;
|
|
int debug_kmaps;
|
|
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;
|
|
|
|
FILE *debug_file(void)
|
|
{
|
|
if (!_debug_file) {
|
|
pr_warning_once("debug_file not set");
|
|
debug_set_file(stderr);
|
|
}
|
|
return _debug_file;
|
|
}
|
|
|
|
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(debug_file(), "[%13" PRIu64 ".%06" PRIu64 "] ", secs, usecs);
|
|
ret += vfprintf(debug_file(), 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) && isascii(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 = "kmaps", .value_ptr = &debug_kmaps },
|
|
{ .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 LIBTRACEEVENT_VERSION >= MAKE_LIBTRACEEVENT_VERSION(1, 3, 0)
|
|
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++;
|
|
}
|
|
|
|
/* For debug variables that are used as bool types, set to 0. */
|
|
redirect_to_stderr = 0;
|
|
debug_peo_args = 0;
|
|
debug_kmaps = 0;
|
|
|
|
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);
|
|
}
|