xref: /linux/tools/perf/util/debug.c (revision 87c9c16317882dd6dbbc07e349bc3223e14f3244)
1 // SPDX-License-Identifier: GPL-2.0
2 /* For general debugging purposes */
3 
4 #include <inttypes.h>
5 #include <string.h>
6 #include <stdarg.h>
7 #include <stdio.h>
8 #include <stdlib.h>
9 #include <sys/wait.h>
10 #include <api/debug.h>
11 #include <linux/kernel.h>
12 #include <linux/time64.h>
13 #include <sys/time.h>
14 #ifdef HAVE_BACKTRACE_SUPPORT
15 #include <execinfo.h>
16 #endif
17 #include "color.h"
18 #include "event.h"
19 #include "debug.h"
20 #include "print_binary.h"
21 #include "target.h"
22 #include "ui/helpline.h"
23 #include "ui/ui.h"
24 #include "util/parse-sublevel-options.h"
25 
26 #include <linux/ctype.h>
27 
28 int verbose;
29 int debug_peo_args;
30 bool dump_trace = false, quiet = false;
31 int debug_ordered_events;
32 static int redirect_to_stderr;
33 int debug_data_convert;
34 static FILE *debug_file;
35 bool debug_display_time;
36 
37 void debug_set_file(FILE *file)
38 {
39 	debug_file = file;
40 }
41 
42 void debug_set_display_time(bool set)
43 {
44 	debug_display_time = set;
45 }
46 
47 static int fprintf_time(FILE *file)
48 {
49 	struct timeval tod;
50 	struct tm ltime;
51 	char date[64];
52 
53 	if (!debug_display_time)
54 		return 0;
55 
56 	if (gettimeofday(&tod, NULL) != 0)
57 		return 0;
58 
59 	if (localtime_r(&tod.tv_sec, &ltime) == NULL)
60 		return 0;
61 
62 	strftime(date, sizeof(date),  "%F %H:%M:%S", &ltime);
63 	return fprintf(file, "[%s.%06lu] ", date, (long)tod.tv_usec);
64 }
65 
66 int veprintf(int level, int var, const char *fmt, va_list args)
67 {
68 	int ret = 0;
69 
70 	if (var >= level) {
71 		if (use_browser >= 1 && !redirect_to_stderr) {
72 			ui_helpline__vshow(fmt, args);
73 		} else {
74 			ret = fprintf_time(debug_file);
75 			ret += vfprintf(debug_file, fmt, args);
76 		}
77 	}
78 
79 	return ret;
80 }
81 
82 int eprintf(int level, int var, const char *fmt, ...)
83 {
84 	va_list args;
85 	int ret;
86 
87 	va_start(args, fmt);
88 	ret = veprintf(level, var, fmt, args);
89 	va_end(args);
90 
91 	return ret;
92 }
93 
94 static int veprintf_time(u64 t, const char *fmt, va_list args)
95 {
96 	int ret = 0;
97 	u64 secs, usecs, nsecs = t;
98 
99 	secs   = nsecs / NSEC_PER_SEC;
100 	nsecs -= secs  * NSEC_PER_SEC;
101 	usecs  = nsecs / NSEC_PER_USEC;
102 
103 	ret = fprintf(stderr, "[%13" PRIu64 ".%06" PRIu64 "] ",
104 		      secs, usecs);
105 	ret += vfprintf(stderr, fmt, args);
106 	return ret;
107 }
108 
109 int eprintf_time(int level, int var, u64 t, const char *fmt, ...)
110 {
111 	int ret = 0;
112 	va_list args;
113 
114 	if (var >= level) {
115 		va_start(args, fmt);
116 		ret = veprintf_time(t, fmt, args);
117 		va_end(args);
118 	}
119 
120 	return ret;
121 }
122 
123 /*
124  * Overloading libtraceevent standard info print
125  * function, display with -v in perf.
126  */
127 void pr_stat(const char *fmt, ...)
128 {
129 	va_list args;
130 
131 	va_start(args, fmt);
132 	veprintf(1, verbose, fmt, args);
133 	va_end(args);
134 	eprintf(1, verbose, "\n");
135 }
136 
137 int dump_printf(const char *fmt, ...)
138 {
139 	va_list args;
140 	int ret = 0;
141 
142 	if (dump_trace) {
143 		va_start(args, fmt);
144 		ret = vprintf(fmt, args);
145 		va_end(args);
146 	}
147 
148 	return ret;
149 }
150 
151 static int trace_event_printer(enum binary_printer_ops op,
152 			       unsigned int val, void *extra, FILE *fp)
153 {
154 	const char *color = PERF_COLOR_BLUE;
155 	union perf_event *event = (union perf_event *)extra;
156 	unsigned char ch = (unsigned char)val;
157 	int printed = 0;
158 
159 	switch (op) {
160 	case BINARY_PRINT_DATA_BEGIN:
161 		printed += fprintf(fp, ".");
162 		printed += color_fprintf(fp, color, "\n. ... raw event: size %d bytes\n",
163 					 event->header.size);
164 		break;
165 	case BINARY_PRINT_LINE_BEGIN:
166 		printed += fprintf(fp, ".");
167 		break;
168 	case BINARY_PRINT_ADDR:
169 		printed += color_fprintf(fp, color, "  %04x: ", val);
170 		break;
171 	case BINARY_PRINT_NUM_DATA:
172 		printed += color_fprintf(fp, color, " %02x", val);
173 		break;
174 	case BINARY_PRINT_NUM_PAD:
175 		printed += color_fprintf(fp, color, "   ");
176 		break;
177 	case BINARY_PRINT_SEP:
178 		printed += color_fprintf(fp, color, "  ");
179 		break;
180 	case BINARY_PRINT_CHAR_DATA:
181 		printed += color_fprintf(fp, color, "%c",
182 			      isprint(ch) ? ch : '.');
183 		break;
184 	case BINARY_PRINT_CHAR_PAD:
185 		printed += color_fprintf(fp, color, " ");
186 		break;
187 	case BINARY_PRINT_LINE_END:
188 		printed += color_fprintf(fp, color, "\n");
189 		break;
190 	case BINARY_PRINT_DATA_END:
191 		printed += fprintf(fp, "\n");
192 		break;
193 	default:
194 		break;
195 	}
196 
197 	return printed;
198 }
199 
200 void trace_event(union perf_event *event)
201 {
202 	unsigned char *raw_event = (void *)event;
203 
204 	if (!dump_trace)
205 		return;
206 
207 	print_binary(raw_event, event->header.size, 16,
208 		     trace_event_printer, event);
209 }
210 
211 static struct sublevel_option debug_opts[] = {
212 	{ .name = "verbose",		.value_ptr = &verbose },
213 	{ .name = "ordered-events",	.value_ptr = &debug_ordered_events},
214 	{ .name = "stderr",		.value_ptr = &redirect_to_stderr},
215 	{ .name = "data-convert",	.value_ptr = &debug_data_convert },
216 	{ .name = "perf-event-open",	.value_ptr = &debug_peo_args },
217 	{ .name = NULL, }
218 };
219 
220 int perf_debug_option(const char *str)
221 {
222 	int ret;
223 
224 	ret = perf_parse_sublevel_options(str, debug_opts);
225 	if (ret)
226 		return ret;
227 
228 	/* Allow only verbose value in range (0, 10), otherwise set 0. */
229 	verbose = (verbose < 0) || (verbose > 10) ? 0 : verbose;
230 
231 	return 0;
232 }
233 
234 int perf_quiet_option(void)
235 {
236 	struct sublevel_option *opt = &debug_opts[0];
237 
238 	/* disable all debug messages */
239 	while (opt->name) {
240 		*opt->value_ptr = -1;
241 		opt++;
242 	}
243 
244 	return 0;
245 }
246 
247 #define DEBUG_WRAPPER(__n, __l)				\
248 static int pr_ ## __n ## _wrapper(const char *fmt, ...)	\
249 {							\
250 	va_list args;					\
251 	int ret;					\
252 							\
253 	va_start(args, fmt);				\
254 	ret = veprintf(__l, verbose, fmt, args);	\
255 	va_end(args);					\
256 	return ret;					\
257 }
258 
259 DEBUG_WRAPPER(warning, 0);
260 DEBUG_WRAPPER(debug, 1);
261 
262 void perf_debug_setup(void)
263 {
264 	debug_set_file(stderr);
265 	libapi_set_print(pr_warning_wrapper, pr_warning_wrapper, pr_debug_wrapper);
266 }
267 
268 /* Obtain a backtrace and print it to stdout. */
269 #ifdef HAVE_BACKTRACE_SUPPORT
270 void dump_stack(void)
271 {
272 	void *array[16];
273 	size_t size = backtrace(array, ARRAY_SIZE(array));
274 	char **strings = backtrace_symbols(array, size);
275 	size_t i;
276 
277 	printf("Obtained %zd stack frames.\n", size);
278 
279 	for (i = 0; i < size; i++)
280 		printf("%s\n", strings[i]);
281 
282 	free(strings);
283 }
284 #else
285 void dump_stack(void) {}
286 #endif
287 
288 void sighandler_dump_stack(int sig)
289 {
290 	psignal(sig, "perf");
291 	dump_stack();
292 	signal(sig, SIG_DFL);
293 	raise(sig);
294 }
295