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