kernel/trace/trace_output.c

   1 /*
   2  * trace_output.c
   3  *
   4  * Copyright (C) 2008 Red Hat Inc, Steven Rostedt <srostedt@redhat.com>
   5  *
   6  */
   7
   8 #include <linux/module.h>
   9 #include <linux/mutex.h>
  10 #include <linux/ftrace.h>
  11
  12 #include "trace_output.h"
  13
  14 /* must be a power of 2 */
  15 #define EVENT_HASHSIZE  128
  16
  17 DECLARE_RWSEM(trace_event_sem);
  18
  19 static struct hlist_head event_hash[EVENT_HASHSIZE] __read_mostly;
  20
  21 static int next_event_type = __TRACE_LAST_TYPE + 1;
  22
  23 int trace_print_seq(struct seq_file *m, struct trace_seq *s)
  24 {
  25         int len = s->len >= PAGE_SIZE ? PAGE_SIZE - 1 : s->len;
  26         int ret;
  27
  28         ret = seq_write(m, s->buffer, len);
  29
  30         /*
  31          * Only reset this buffer if we successfully wrote to the
  32          * seq_file buffer.
  33          */
  34         if (!ret)
  35                 trace_seq_init(s);
  36
  37         return ret;
  38 }
  39
  40 enum print_line_t trace_print_bputs_msg_only(struct trace_iterator *iter)
  41 {
  42         struct trace_seq *s = &iter->seq;
  43         struct trace_entry *entry = iter->ent;
  44         struct bputs_entry *field;
  45         int ret;
  46
  47         trace_assign_type(field, entry);
  48
  49         ret = trace_seq_puts(s, field->str);
  50         if (!ret)
  51                 return TRACE_TYPE_PARTIAL_LINE;
  52
  53         return TRACE_TYPE_HANDLED;
  54 }
  55
  56 enum print_line_t trace_print_bprintk_msg_only(struct trace_iterator *iter)
  57 {
  58         struct trace_seq *s = &iter->seq;
  59         struct trace_entry *entry = iter->ent;
  60         struct bprint_entry *field;
  61         int ret;
  62
  63         trace_assign_type(field, entry);
  64
  65         ret = trace_seq_bprintf(s, field->fmt, field->buf);
  66         if (!ret)
  67                 return TRACE_TYPE_PARTIAL_LINE;
  68
  69         return TRACE_TYPE_HANDLED;
  70 }
  71
  72 enum print_line_t trace_print_printk_msg_only(struct trace_iterator *iter)
  73 {
  74         struct trace_seq *s = &iter->seq;
  75         struct trace_entry *entry = iter->ent;
  76         struct print_entry *field;
  77         int ret;
  78
  79         trace_assign_type(field, entry);
  80
  81         ret = trace_seq_puts(s, field->buf);
  82         if (!ret)
  83                 return TRACE_TYPE_PARTIAL_LINE;
  84
  85         return TRACE_TYPE_HANDLED;
  86 }
  87
  88 /**
  89  * trace_seq_printf - sequence printing of trace information
  90  * @s: trace sequence descriptor
  91  * @fmt: printf format string
  92  *
  93  * It returns 0 if the trace oversizes the buffer's free
  94  * space, 1 otherwise.
  95  *
  96  * The tracer may use either sequence operations or its own
  97  * copy to user routines. To simplify formating of a trace
  98  * trace_seq_printf is used to store strings into a special
  99  * buffer (@s). Then the output may be either used by
 100  * the sequencer or pulled into another buffer.
 101  */
 102 int
 103 trace_seq_printf(struct trace_seq *s, const char *fmt, ...)
 104 {
 105         int len = (PAGE_SIZE - 1) - s->len;
 106         va_list ap;
 107         int ret;
 108
 109         if (s->full || !len)
 110                 return 0;
 111
 112         va_start(ap, fmt);
 113         ret = vsnprintf(s->buffer + s->len, len, fmt, ap);
 114         va_end(ap);
 115
 116         /* If we can't write it all, don't bother writing anything */
 117         if (ret >= len) {
 118                 s->full = 1;
 119                 return 0;
 120         }
 121
 122         s->len += ret;
 123
 124         return 1;
 125 }
 126 EXPORT_SYMBOL_GPL(trace_seq_printf);
 127
 128 /**
 129  * trace_seq_vprintf - sequence printing of trace information
 130  * @s: trace sequence descriptor
 131  * @fmt: printf format string
 132  *
 133  * The tracer may use either sequence operations or its own
 134  * copy to user routines. To simplify formating of a trace
 135  * trace_seq_printf is used to store strings into a special
 136  * buffer (@s). Then the output may be either used by
 137  * the sequencer or pulled into another buffer.
 138  */
 139 int
 140 trace_seq_vprintf(struct trace_seq *s, const char *fmt, va_list args)
 141 {
 142         int len = (PAGE_SIZE - 1) - s->len;
 143         int ret;
 144
 145         if (s->full || !len)
 146                 return 0;
 147
 148         ret = vsnprintf(s->buffer + s->len, len, fmt, args);
 149
 150         /* If we can't write it all, don't bother writing anything */
 151         if (ret >= len) {
 152                 s->full = 1;
 153                 return 0;
 154         }
 155
 156         s->len += ret;
 157
 158         return len;
 159 }
 160 EXPORT_SYMBOL_GPL(trace_seq_vprintf);
 161
 162 int trace_seq_bprintf(struct trace_seq *s, const char *fmt, const u32 *binary)
 163 {
 164         int len = (PAGE_SIZE - 1) - s->len;
 165         int ret;
 166
 167         if (s->full || !len)
 168                 return 0;
 169
 170         ret = bstr_printf(s->buffer + s->len, len, fmt, binary);
 171
 172         /* If we can't write it all, don't bother writing anything */
 173         if (ret >= len) {
 174                 s->full = 1;
 175                 return 0;
 176         }
 177
 178         s->len += ret;
 179
 180         return len;
 181 }
 182
 183 /**
 184  * trace_seq_puts - trace sequence printing of simple string
 185  * @s: trace sequence descriptor
 186  * @str: simple string to record
 187  *
 188  * The tracer may use either the sequence operations or its own
 189  * copy to user routines. This function records a simple string
 190  * into a special buffer (@s) for later retrieval by a sequencer
 191  * or other mechanism.
 192  */
 193 int trace_seq_puts(struct trace_seq *s, const char *str)
 194 {
 195         int len = strlen(str);
 196
 197         if (s->full)
 198                 return 0;
 199
 200         if (len > ((PAGE_SIZE - 1) - s->len)) {
 201                 s->full = 1;
 202                 return 0;
 203         }
 204
 205         memcpy(s->buffer + s->len, str, len);
 206         s->len += len;
 207
 208         return len;
 209 }
 210
 211 int trace_seq_putc(struct trace_seq *s, unsigned char c)
 212 {
 213         if (s->full)
 214                 return 0;
 215
 216         if (s->len >= (PAGE_SIZE - 1)) {
 217                 s->full = 1;
 218                 return 0;
 219         }
 220
 221         s->buffer[s->len++] = c;
 222
 223         return 1;
 224 }
 225 EXPORT_SYMBOL(trace_seq_putc);
 226
 227 int trace_seq_putmem(struct trace_seq *s, const void *mem, size_t len)
 228 {
 229         if (s->full)
 230                 return 0;
 231
 232         if (len > ((PAGE_SIZE - 1) - s->len)) {
 233                 s->full = 1;
 234                 return 0;
 235         }
 236
 237         memcpy(s->buffer + s->len, mem, len);
 238         s->len += len;
 239
 240         return len;
 241 }
 242
 243 int trace_seq_putmem_hex(struct trace_seq *s, const void *mem, size_t len)
 244 {
 245         unsigned char hex[HEX_CHARS];
 246         const unsigned char *data = mem;
 247         int i, j;
 248
 249         if (s->full)
 250                 return 0;
 251
 252 #ifdef __BIG_ENDIAN
 253         for (i = 0, j = 0; i < len; i++) {
 254 #else
 255         for (i = len-1, j = 0; i >= 0; i--) {
 256 #endif
 257                 hex[j++] = hex_asc_hi(data[i]);
 258                 hex[j++] = hex_asc_lo(data[i]);
 259         }
 260         hex[j++] = ' ';
 261
 262         return trace_seq_putmem(s, hex, j);
 263 }
 264
 265 void *trace_seq_reserve(struct trace_seq *s, size_t len)
 266 {
 267         void *ret;
 268
 269         if (s->full)
 270                 return NULL;
 271
 272         if (len > ((PAGE_SIZE - 1) - s->len)) {
 273                 s->full = 1;
 274                 return NULL;
 275         }
 276
 277         ret = s->buffer + s->len;
 278         s->len += len;
 279
 280         return ret;
 281 }
 282
 283 int trace_seq_path(struct trace_seq *s, const struct path *path)
 284 {
 285         unsigned char *p;
 286
 287         if (s->full)
 288                 return 0;
 289
 290         if (s->len >= (PAGE_SIZE - 1)) {
 291                 s->full = 1;
 292                 return 0;
 293         }
 294
 295         p = d_path(path, s->buffer + s->len, PAGE_SIZE - s->len);
 296         if (!IS_ERR(p)) {
 297                 p = mangle_path(s->buffer + s->len, p, "\n");
 298                 if (p) {
 299                         s->len = p - s->buffer;
 300                         return 1;
 301                 }
 302         } else {
 303                 s->buffer[s->len++] = '?';
 304                 return 1;
 305         }
 306
 307         s->full = 1;
 308         return 0;
 309 }
 310
 311 const char *
 312 ftrace_print_flags_seq(struct trace_seq *p, const char *delim,
 313                        unsigned long flags,
 314                        const struct trace_print_flags *flag_array)
 315 {
 316         unsigned long mask;
 317         const char *str;
 318         const char *ret = p->buffer + p->len;
 319         int i, first = 1;
 320
 321         for (i = 0;  flag_array[i].name && flags; i++) {
 322
 323                 mask = flag_array[i].mask;
 324                 if ((flags & mask) != mask)
 325                         continue;
 326
 327                 str = flag_array[i].name;
 328                 flags &= ~mask;
 329                 if (!first && delim)
 330                         trace_seq_puts(p, delim);
 331                 else
 332                         first = 0;
 333                 trace_seq_puts(p, str);
 334         }
 335
 336         /* check for left over flags */
 337         if (flags) {
 338                 if (!first && delim)
 339                         trace_seq_puts(p, delim);
 340                 trace_seq_printf(p, "0x%lx", flags);
 341         }
 342
 343         trace_seq_putc(p, 0);
 344
 345         return ret;
 346 }
 347 EXPORT_SYMBOL(ftrace_print_flags_seq);
 348
 349 const char *
 350 ftrace_print_symbols_seq(struct trace_seq *p, unsigned long val,
 351                          const struct trace_print_flags *symbol_array)
 352 {
 353         int i;
 354         const char *ret = p->buffer + p->len;
 355
 356         for (i = 0;  symbol_array[i].name; i++) {
 357
 358                 if (val != symbol_array[i].mask)
 359                         continue;
 360
 361                 trace_seq_puts(p, symbol_array[i].name);
 362                 break;
 363         }
 364
 365         if (ret == (const char *)(p->buffer + p->len))
 366                 trace_seq_printf(p, "0x%lx", val);
 367
 368         trace_seq_putc(p, 0);
 369
 370         return ret;
 371 }
 372 EXPORT_SYMBOL(ftrace_print_symbols_seq);
 373
 374 #if BITS_PER_LONG == 32
 375 const char *
 376 ftrace_print_symbols_seq_u64(struct trace_seq *p, unsigned long long val,
 377                          const struct trace_print_flags_u64 *symbol_array)
 378 {
 379         int i;
 380         const char *ret = p->buffer + p->len;
 381
 382         for (i = 0;  symbol_array[i].name; i++) {
 383
 384                 if (val != symbol_array[i].mask)
 385                         continue;
 386
 387                 trace_seq_puts(p, symbol_array[i].name);
 388                 break;
 389         }
 390
 391         if (ret == (const char *)(p->buffer + p->len))
 392                 trace_seq_printf(p, "0x%llx", val);
 393
 394         trace_seq_putc(p, 0);
 395
 396         return ret;
 397 }
 398 EXPORT_SYMBOL(ftrace_print_symbols_seq_u64);
 399 #endif
 400
 401 const char *
 402 ftrace_print_hex_seq(struct trace_seq *p, const unsigned char *buf, int buf_len)
 403 {
 404         int i;
 405         const char *ret = p->buffer + p->len;
 406
 407         for (i = 0; i < buf_len; i++)
 408                 trace_seq_printf(p, "%s%2.2x", i == 0 ? "" : " ", buf[i]);
 409
 410         trace_seq_putc(p, 0);
 411
 412         return ret;
 413 }
 414 EXPORT_SYMBOL(ftrace_print_hex_seq);
 415
 416 int ftrace_raw_output_prep(struct trace_iterator *iter,
 417                            struct trace_event *trace_event)
 418 {
 419         struct ftrace_event_call *event;
 420         struct trace_seq *s = &iter->seq;
 421         struct trace_seq *p = &iter->tmp_seq;
 422         struct trace_entry *entry;
 423         int ret;
 424
 425         event = container_of(trace_event, struct ftrace_event_call, event);
 426         entry = iter->ent;
 427
 428         if (entry->type != event->event.type) {
 429                 WARN_ON_ONCE(1);
 430                 return TRACE_TYPE_UNHANDLED;
 431         }
 432
 433         trace_seq_init(p);
 434         ret = trace_seq_printf(s, "%s: ", ftrace_event_name(event));
 435         if (!ret)
 436                 return TRACE_TYPE_PARTIAL_LINE;
 437
 438         return 0;
 439 }
 440 EXPORT_SYMBOL(ftrace_raw_output_prep);
 441
 442 static int ftrace_output_raw(struct trace_iterator *iter, char *name,
 443                              char *fmt, va_list ap)
 444 {
 445         struct trace_seq *s = &iter->seq;
 446         int ret;
 447
 448         ret = trace_seq_printf(s, "%s: ", name);
 449         if (!ret)
 450                 return TRACE_TYPE_PARTIAL_LINE;
 451
 452         ret = trace_seq_vprintf(s, fmt, ap);
 453
 454         if (!ret)
 455                 return TRACE_TYPE_PARTIAL_LINE;
 456
 457         return TRACE_TYPE_HANDLED;
 458 }
 459
 460 int ftrace_output_call(struct trace_iterator *iter, char *name, char *fmt, ...)
 461 {
 462         va_list ap;
 463         int ret;
 464
 465         va_start(ap, fmt);
 466         ret = ftrace_output_raw(iter, name, fmt, ap);
 467         va_end(ap);
 468
 469         return ret;
 470 }
 471 EXPORT_SYMBOL_GPL(ftrace_output_call);
 472
 473 #ifdef CONFIG_KRETPROBES
 474 static inline const char *kretprobed(const char *name)
 475 {
 476         static const char tramp_name[] = "kretprobe_trampoline";
 477         int size = sizeof(tramp_name);
 478
 479         if (strncmp(tramp_name, name, size) == 0)
 480                 return "[unknown/kretprobe'd]";
 481         return name;
 482 }
 483 #else
 484 static inline const char *kretprobed(const char *name)
 485 {
 486         return name;
 487 }
 488 #endif /* CONFIG_KRETPROBES */
 489
 490 static int
 491 seq_print_sym_short(struct trace_seq *s, const char *fmt, unsigned long address)
 492 {
 493 #ifdef CONFIG_KALLSYMS
 494         char str[KSYM_SYMBOL_LEN];
 495         const char *name;
 496
 497         kallsyms_lookup(address, NULL, NULL, NULL, str);
 498
 499         name = kretprobed(str);
 500
 501         return trace_seq_printf(s, fmt, name);
 502 #endif
 503         return 1;
 504 }
 505
 506 static int
 507 seq_print_sym_offset(struct trace_seq *s, const char *fmt,
 508                      unsigned long address)
 509 {
 510 #ifdef CONFIG_KALLSYMS
 511         char str[KSYM_SYMBOL_LEN];
 512         const char *name;
 513
 514         sprint_symbol(str, address);
 515         name = kretprobed(str);
 516
 517         return trace_seq_printf(s, fmt, name);
 518 #endif
 519         return 1;
 520 }
 521
 522 #ifndef CONFIG_64BIT
 523 # define IP_FMT "%08lx"
 524 #else
 525 # define IP_FMT "%016lx"
 526 #endif
 527
 528 int seq_print_user_ip(struct trace_seq *s, struct mm_struct *mm,
 529                       unsigned long ip, unsigned long sym_flags)
 530 {
 531         struct file *file = NULL;
 532         unsigned long vmstart = 0;
 533         int ret = 1;
 534
 535         if (s->full)
 536                 return 0;
 537
 538         if (mm) {
 539                 const struct vm_area_struct *vma;
 540
 541                 down_read(&mm->mmap_sem);
 542                 vma = find_vma(mm, ip);
 543                 if (vma) {
 544                         file = vma->vm_file;
 545                         vmstart = vma->vm_start;
 546                 }
 547                 if (file) {
 548                         ret = trace_seq_path(s, &file->f_path);
 549                         if (ret)
 550                                 ret = trace_seq_printf(s, "[+0x%lx]",
 551                                                        ip - vmstart);
 552                 }
 553                 up_read(&mm->mmap_sem);
 554         }
 555         if (ret && ((sym_flags & TRACE_ITER_SYM_ADDR) || !file))
 556                 ret = trace_seq_printf(s, " <" IP_FMT ">", ip);
 557         return ret;
 558 }
 559
 560 int
 561 seq_print_userip_objs(const struct userstack_entry *entry, struct trace_seq *s,
 562                       unsigned long sym_flags)
 563 {
 564         struct mm_struct *mm = NULL;
 565         int ret = 1;
 566         unsigned int i;
 567
 568         if (trace_flags & TRACE_ITER_SYM_USEROBJ) {
 569                 struct task_struct *task;
 570                 /*
 571                  * we do the lookup on the thread group leader,
 572                  * since individual threads might have already quit!
 573                  */
 574                 rcu_read_lock();
 575                 task = find_task_by_vpid(entry->tgid);
 576                 if (task)
 577                         mm = get_task_mm(task);
 578                 rcu_read_unlock();
 579         }
 580
 581         for (i = 0; i < FTRACE_STACK_ENTRIES; i++) {
 582                 unsigned long ip = entry->caller[i];
 583
 584                 if (ip == ULONG_MAX || !ret)
 585                         break;
 586                 if (ret)
 587                         ret = trace_seq_puts(s, " => ");
 588                 if (!ip) {
 589                         if (ret)
 590                                 ret = trace_seq_puts(s, "??");
 591                         if (ret)
 592                                 ret = trace_seq_putc(s, '\n');
 593                         continue;
 594                 }
 595                 if (!ret)
 596                         break;
 597                 if (ret)
 598                         ret = seq_print_user_ip(s, mm, ip, sym_flags);
 599                 ret = trace_seq_putc(s, '\n');
 600         }
 601
 602         if (mm)
 603                 mmput(mm);
 604         return ret;
 605 }
 606
 607 int
 608 seq_print_ip_sym(struct trace_seq *s, unsigned long ip, unsigned long sym_flags)
 609 {
 610         int ret;
 611
 612         if (!ip)
 613                 return trace_seq_putc(s, '0');
 614
 615         if (sym_flags & TRACE_ITER_SYM_OFFSET)
 616                 ret = seq_print_sym_offset(s, "%s", ip);
 617         else
 618                 ret = seq_print_sym_short(s, "%s", ip);
 619
 620         if (!ret)
 621                 return 0;
 622
 623         if (sym_flags & TRACE_ITER_SYM_ADDR)
 624                 ret = trace_seq_printf(s, " <" IP_FMT ">", ip);
 625         return ret;
 626 }
 627
 628 /**
 629  * trace_print_lat_fmt - print the irq, preempt and lockdep fields
 630  * @s: trace seq struct to write to
 631  * @entry: The trace entry field from the ring buffer
 632  *
 633  * Prints the generic fields of irqs off, in hard or softirq, preempt
 634  * count.
 635  */
 636 int trace_print_lat_fmt(struct trace_seq *s, struct trace_entry *entry)
 637 {
 638         char hardsoft_irq;
 639         char need_resched;
 640         char irqs_off;
 641         int hardirq;
 642         int softirq;
 643         int ret;
 644
 645         hardirq = entry->flags & TRACE_FLAG_HARDIRQ;
 646         softirq = entry->flags & TRACE_FLAG_SOFTIRQ;
 647
 648         irqs_off =
 649                 (entry->flags & TRACE_FLAG_IRQS_OFF) ? 'd' :
 650                 (entry->flags & TRACE_FLAG_IRQS_NOSUPPORT) ? 'X' :
 651                 '.';
 652
 653         switch (entry->flags & (TRACE_FLAG_NEED_RESCHED |
 654                                 TRACE_FLAG_PREEMPT_RESCHED)) {
 655         case TRACE_FLAG_NEED_RESCHED | TRACE_FLAG_PREEMPT_RESCHED:
 656                 need_resched = 'N';
 657                 break;
 658         case TRACE_FLAG_NEED_RESCHED:
 659                 need_resched = 'n';
 660                 break;
 661         case TRACE_FLAG_PREEMPT_RESCHED:
 662                 need_resched = 'p';
 663                 break;
 664         default:
 665                 need_resched = '.';
 666                 break;
 667         }
 668
 669         hardsoft_irq =
 670                 (hardirq && softirq) ? 'H' :
 671                 hardirq ? 'h' :
 672                 softirq ? 's' :
 673                 '.';
 674
 675         if (!trace_seq_printf(s, "%c%c%c",
 676                               irqs_off, need_resched, hardsoft_irq))
 677                 return 0;
 678
 679         if (entry->preempt_count)
 680                 ret = trace_seq_printf(s, "%x", entry->preempt_count);
 681         else
 682                 ret = trace_seq_putc(s, '.');
 683
 684         return ret;
 685 }
 686
 687 static int
 688 lat_print_generic(struct trace_seq *s, struct trace_entry *entry, int cpu)
 689 {
 690         char comm[TASK_COMM_LEN];
 691
 692         trace_find_cmdline(entry->pid, comm);
 693
 694         if (!trace_seq_printf(s, "%8.8s-%-5d %3d",
 695                               comm, entry->pid, cpu))
 696                 return 0;
 697
 698         return trace_print_lat_fmt(s, entry);
 699 }
 700
 701 static unsigned long preempt_mark_thresh_us = 100;
 702
 703 static int
 704 lat_print_timestamp(struct trace_iterator *iter, u64 next_ts)
 705 {
 706         unsigned long verbose = trace_flags & TRACE_ITER_VERBOSE;
 707         unsigned long in_ns = iter->iter_flags & TRACE_FILE_TIME_IN_NS;
 708         unsigned long long abs_ts = iter->ts - iter->trace_buffer->time_start;
 709         unsigned long long rel_ts = next_ts - iter->ts;
 710         struct trace_seq *s = &iter->seq;
 711
 712         if (in_ns) {
 713                 abs_ts = ns2usecs(abs_ts);
 714                 rel_ts = ns2usecs(rel_ts);
 715         }
 716
 717         if (verbose && in_ns) {
 718                 unsigned long abs_usec = do_div(abs_ts, USEC_PER_MSEC);
 719                 unsigned long abs_msec = (unsigned long)abs_ts;
 720                 unsigned long rel_usec = do_div(rel_ts, USEC_PER_MSEC);
 721                 unsigned long rel_msec = (unsigned long)rel_ts;
 722
 723                 return trace_seq_printf(
 724                                 s, "[%08llx] %ld.%03ldms (+%ld.%03ldms): ",
 725                                 ns2usecs(iter->ts),
 726                                 abs_msec, abs_usec,
 727                                 rel_msec, rel_usec);
 728         } else if (verbose && !in_ns) {
 729                 return trace_seq_printf(
 730                                 s, "[%016llx] %lld (+%lld): ",
 731                                 iter->ts, abs_ts, rel_ts);
 732         } else if (!verbose && in_ns) {
 733                 return trace_seq_printf(
 734                                 s, " %4lldus%c: ",
 735                                 abs_ts,
 736                                 rel_ts > preempt_mark_thresh_us ? '!' :
 737                                   rel_ts > 1 ? '+' : ' ');
 738         } else { /* !verbose && !in_ns */
 739                 return trace_seq_printf(s, " %4lld: ", abs_ts);
 740         }
 741 }
 742
 743 int trace_print_context(struct trace_iterator *iter)
 744 {
 745         struct trace_seq *s = &iter->seq;
 746         struct trace_entry *entry = iter->ent;
 747         unsigned long long t;
 748         unsigned long secs, usec_rem;
 749         char comm[TASK_COMM_LEN];
 750         int ret;
 751
 752         trace_find_cmdline(entry->pid, comm);
 753
 754         ret = trace_seq_printf(s, "%16s-%-5d [%03d] ",
 755                                comm, entry->pid, iter->cpu);
 756         if (!ret)
 757                 return 0;
 758
 759         if (trace_flags & TRACE_ITER_IRQ_INFO) {
 760                 ret = trace_print_lat_fmt(s, entry);
 761                 if (!ret)
 762                         return 0;
 763         }
 764
 765         if (iter->iter_flags & TRACE_FILE_TIME_IN_NS) {
 766                 t = ns2usecs(iter->ts);
 767                 usec_rem = do_div(t, USEC_PER_SEC);
 768                 secs = (unsigned long)t;
 769                 return trace_seq_printf(s, " %5lu.%06lu: ", secs, usec_rem);
 770         } else
 771                 return trace_seq_printf(s, " %12llu: ", iter->ts);
 772 }
 773
 774 int trace_print_lat_context(struct trace_iterator *iter)
 775 {
 776         u64 next_ts;
 777         int ret;
 778         /* trace_find_next_entry will reset ent_size */
 779         int ent_size = iter->ent_size;
 780         struct trace_seq *s = &iter->seq;
 781         struct trace_entry *entry = iter->ent,
 782                            *next_entry = trace_find_next_entry(iter, NULL,
 783                                                                &next_ts);
 784         unsigned long verbose = (trace_flags & TRACE_ITER_VERBOSE);
 785
 786         /* Restore the original ent_size */
 787         iter->ent_size = ent_size;
 788
 789         if (!next_entry)
 790                 next_ts = iter->ts;
 791
 792         if (verbose) {
 793                 char comm[TASK_COMM_LEN];
 794
 795                 trace_find_cmdline(entry->pid, comm);
 796
 797                 ret = trace_seq_printf(
 798                                 s, "%16s %5d %3d %d %08x %08lx ",
 799                                 comm, entry->pid, iter->cpu, entry->flags,
 800                                 entry->preempt_count, iter->idx);
 801         } else {
 802                 ret = lat_print_generic(s, entry, iter->cpu);
 803         }
 804
 805         if (ret)
 806                 ret = lat_print_timestamp(iter, next_ts);
 807
 808         return ret;
 809 }
 810
 811 static const char state_to_char[] = TASK_STATE_TO_CHAR_STR;
 812
 813 static int task_state_char(unsigned long state)
 814 {
 815         int bit = state ? __ffs(state) + 1 : 0;
 816
 817         return bit < sizeof(state_to_char) - 1 ? state_to_char[bit] : '?';
 818 }
 819
 820 /**
 821  * ftrace_find_event - find a registered event
 822  * @type: the type of event to look for
 823  *
 824  * Returns an event of type @type otherwise NULL
 825  * Called with trace_event_read_lock() held.
 826  */
 827 struct trace_event *ftrace_find_event(int type)
 828 {
 829         struct trace_event *event;
 830         unsigned key;
 831
 832         key = type & (EVENT_HASHSIZE - 1);
 833
 834         hlist_for_each_entry(event, &event_hash[key], node) {
 835                 if (event->type == type)
 836                         return event;
 837         }
 838
 839         return NULL;
 840 }
 841
 842 static LIST_HEAD(ftrace_event_list);
 843
 844 static int trace_search_list(struct list_head **list)
 845 {
 846         struct trace_event *e;
 847         int last = __TRACE_LAST_TYPE;
 848
 849         if (list_empty(&ftrace_event_list)) {
 850                 *list = &ftrace_event_list;
 851                 return last + 1;
 852         }
 853
 854         /*
 855          * We used up all possible max events,
 856          * lets see if somebody freed one.
 857          */
 858         list_for_each_entry(e, &ftrace_event_list, list) {
 859                 if (e->type != last + 1)
 860                         break;
 861                 last++;
 862         }
 863
 864         /* Did we used up all 65 thousand events??? */
 865         if ((last + 1) > FTRACE_MAX_EVENT)
 866                 return 0;
 867
 868         *list = &e->list;
 869         return last + 1;
 870 }
 871
 872 void trace_event_read_lock(void)
 873 {
 874         down_read(&trace_event_sem);
 875 }
 876
 877 void trace_event_read_unlock(void)
 878 {
 879         up_read(&trace_event_sem);
 880 }
 881
 882 /**
 883  * register_ftrace_event - register output for an event type
 884  * @event: the event type to register
 885  *
 886  * Event types are stored in a hash and this hash is used to
 887  * find a way to print an event. If the @event->type is set
 888  * then it will use that type, otherwise it will assign a
 889  * type to use.
 890  *
 891  * If you assign your own type, please make sure it is added
 892  * to the trace_type enum in trace.h, to avoid collisions
 893  * with the dynamic types.
 894  *
 895  * Returns the event type number or zero on error.
 896  */
 897 int register_ftrace_event(struct trace_event *event)
 898 {
 899         unsigned key;
 900         int ret = 0;
 901
 902         down_write(&trace_event_sem);
 903
 904         if (WARN_ON(!event))
 905                 goto out;
 906
 907         if (WARN_ON(!event->funcs))
 908                 goto out;
 909
 910         INIT_LIST_HEAD(&event->list);
 911
 912         if (!event->type) {
 913                 struct list_head *list = NULL;
 914
 915                 if (next_event_type > FTRACE_MAX_EVENT) {
 916
 917                         event->type = trace_search_list(&list);
 918                         if (!event->type)
 919                                 goto out;
 920
 921                 } else {
 922
 923                         event->type = next_event_type++;
 924                         list = &ftrace_event_list;
 925                 }
 926
 927                 if (WARN_ON(ftrace_find_event(event->type)))
 928                         goto out;
 929
 930                 list_add_tail(&event->list, list);
 931
 932         } else if (event->type > __TRACE_LAST_TYPE) {
 933                 printk(KERN_WARNING "Need to add type to trace.h\n");
 934                 WARN_ON(1);
 935                 goto out;
 936         } else {
 937                 /* Is this event already used */
 938                 if (ftrace_find_event(event->type))
 939                         goto out;
 940         }
 941
 942         if (event->funcs->trace == NULL)
 943                 event->funcs->trace = trace_nop_print;
 944         if (event->funcs->raw == NULL)
 945                 event->funcs->raw = trace_nop_print;
 946         if (event->funcs->hex == NULL)
 947                 event->funcs->hex = trace_nop_print;
 948         if (event->funcs->binary == NULL)
 949                 event->funcs->binary = trace_nop_print;
 950
 951         key = event->type & (EVENT_HASHSIZE - 1);
 952
 953         hlist_add_head(&event->node, &event_hash[key]);
 954
 955         ret = event->type;
 956  out:
 957         up_write(&trace_event_sem);
 958
 959         return ret;
 960 }
 961 EXPORT_SYMBOL_GPL(register_ftrace_event);
 962
 963 /*
 964  * Used by module code with the trace_event_sem held for write.
 965  */
 966 int __unregister_ftrace_event(struct trace_event *event)
 967 {
 968         hlist_del(&event->node);
 969         list_del(&event->list);
 970         return 0;
 971 }
 972
 973 /**
 974  * unregister_ftrace_event - remove a no longer used event
 975  * @event: the event to remove
 976  */
 977 int unregister_ftrace_event(struct trace_event *event)
 978 {
 979         down_write(&trace_event_sem);
 980         __unregister_ftrace_event(event);
 981         up_write(&trace_event_sem);
 982
 983         return 0;
 984 }
 985 EXPORT_SYMBOL_GPL(unregister_ftrace_event);
 986
 987 /*
 988  * Standard events
 989  */
 990
 991 enum print_line_t trace_nop_print(struct trace_iterator *iter, int flags,
 992                                   struct trace_event *event)
 993 {
 994         if (!trace_seq_printf(&iter->seq, "type: %d\n", iter->ent->type))
 995                 return TRACE_TYPE_PARTIAL_LINE;
 996
 997         return TRACE_TYPE_HANDLED;
 998 }
 999
1000 /* TRACE_FN */
1001 static enum print_line_t trace_fn_trace(struct trace_iterator *iter, int flags,
1002                                         struct trace_event *event)
1003 {
1004         struct ftrace_entry *field;
1005         struct trace_seq *s = &iter->seq;
1006
1007         trace_assign_type(field, iter->ent);
1008
1009         if (!seq_print_ip_sym(s, field->ip, flags))
1010                 goto partial;
1011
1012         if ((flags & TRACE_ITER_PRINT_PARENT) && field->parent_ip) {
1013                 if (!trace_seq_puts(s, " <-"))
1014                         goto partial;
1015                 if (!seq_print_ip_sym(s,
1016                                       field->parent_ip,
1017                                       flags))
1018                         goto partial;
1019         }
1020         if (!trace_seq_putc(s, '\n'))
1021                 goto partial;
1022
1023         return TRACE_TYPE_HANDLED;
1024
1025  partial:
1026         return TRACE_TYPE_PARTIAL_LINE;
1027 }
1028
1029 static enum print_line_t trace_fn_raw(struct trace_iterator *iter, int flags,
1030                                       struct trace_event *event)
1031 {
1032         struct ftrace_entry *field;
1033
1034         trace_assign_type(field, iter->ent);
1035
1036         if (!trace_seq_printf(&iter->seq, "%lx %lx\n",
1037                               field->ip,
1038                               field->parent_ip))
1039                 return TRACE_TYPE_PARTIAL_LINE;
1040
1041         return TRACE_TYPE_HANDLED;
1042 }
1043
1044 static enum print_line_t trace_fn_hex(struct trace_iterator *iter, int flags,
1045                                       struct trace_event *event)
1046 {
1047         struct ftrace_entry *field;
1048         struct trace_seq *s = &iter->seq;
1049
1050         trace_assign_type(field, iter->ent);
1051
1052         SEQ_PUT_HEX_FIELD_RET(s, field->ip);
1053         SEQ_PUT_HEX_FIELD_RET(s, field->parent_ip);
1054
1055         return TRACE_TYPE_HANDLED;
1056 }
1057
1058 static enum print_line_t trace_fn_bin(struct trace_iterator *iter, int flags,
1059                                       struct trace_event *event)
1060 {
1061         struct ftrace_entry *field;
1062         struct trace_seq *s = &iter->seq;
1063
1064         trace_assign_type(field, iter->ent);
1065
1066         SEQ_PUT_FIELD_RET(s, field->ip);
1067         SEQ_PUT_FIELD_RET(s, field->parent_ip);
1068
1069         return TRACE_TYPE_HANDLED;
1070 }
1071
1072 static struct trace_event_functions trace_fn_funcs = {
1073         .trace          = trace_fn_trace,
1074         .raw            = trace_fn_raw,
1075         .hex            = trace_fn_hex,
1076         .binary         = trace_fn_bin,
1077 };
1078
1079 static struct trace_event trace_fn_event = {
1080         .type           = TRACE_FN,
1081         .funcs          = &trace_fn_funcs,
1082 };
1083
1084 /* TRACE_CTX an TRACE_WAKE */
1085 static enum print_line_t trace_ctxwake_print(struct trace_iterator *iter,
1086                                              char *delim)
1087 {
1088         struct ctx_switch_entry *field;
1089         char comm[TASK_COMM_LEN];
1090         int S, T;
1091
1092
1093         trace_assign_type(field, iter->ent);
1094
1095         T = task_state_char(field->next_state);
1096         S = task_state_char(field->prev_state);
1097         trace_find_cmdline(field->next_pid, comm);
1098         if (!trace_seq_printf(&iter->seq,
1099                               " %5d:%3d:%c %s [%03d] %5d:%3d:%c %s\n",
1100                               field->prev_pid,
1101                               field->prev_prio,
1102                               S, delim,
1103                               field->next_cpu,
1104                               field->next_pid,
1105                               field->next_prio,
1106                               T, comm))
1107                 return TRACE_TYPE_PARTIAL_LINE;
1108
1109         return TRACE_TYPE_HANDLED;
1110 }
1111
1112 static enum print_line_t trace_ctx_print(struct trace_iterator *iter, int flags,
1113                                          struct trace_event *event)
1114 {
1115         return trace_ctxwake_print(iter, "==>");
1116 }
1117
1118 static enum print_line_t trace_wake_print(struct trace_iterator *iter,
1119                                           int flags, struct trace_event *event)
1120 {
1121         return trace_ctxwake_print(iter, "  +");
1122 }
1123
1124 static int trace_ctxwake_raw(struct trace_iterator *iter, char S)
1125 {
1126         struct ctx_switch_entry *field;
1127         int T;
1128
1129         trace_assign_type(field, iter->ent);
1130
1131         if (!S)
1132                 S = task_state_char(field->prev_state);
1133         T = task_state_char(field->next_state);
1134         if (!trace_seq_printf(&iter->seq, "%d %d %c %d %d %d %c\n",
1135                               field->prev_pid,
1136                               field->prev_prio,
1137                               S,
1138                               field->next_cpu,
1139                               field->next_pid,
1140                               field->next_prio,
1141                               T))
1142                 return TRACE_TYPE_PARTIAL_LINE;
1143
1144         return TRACE_TYPE_HANDLED;
1145 }
1146
1147 static enum print_line_t trace_ctx_raw(struct trace_iterator *iter, int flags,
1148                                        struct trace_event *event)
1149 {
1150         return trace_ctxwake_raw(iter, 0);
1151 }
1152
1153 static enum print_line_t trace_wake_raw(struct trace_iterator *iter, int flags,
1154                                         struct trace_event *event)
1155 {
1156         return trace_ctxwake_raw(iter, '+');
1157 }
1158
1159
1160 static int trace_ctxwake_hex(struct trace_iterator *iter, char S)
1161 {
1162         struct ctx_switch_entry *field;
1163         struct trace_seq *s = &iter->seq;
1164         int T;
1165
1166         trace_assign_type(field, iter->ent);
1167
1168         if (!S)
1169                 S = task_state_char(field->prev_state);
1170         T = task_state_char(field->next_state);
1171
1172         SEQ_PUT_HEX_FIELD_RET(s, field->prev_pid);
1173         SEQ_PUT_HEX_FIELD_RET(s, field->prev_prio);
1174         SEQ_PUT_HEX_FIELD_RET(s, S);
1175         SEQ_PUT_HEX_FIELD_RET(s, field->next_cpu);
1176         SEQ_PUT_HEX_FIELD_RET(s, field->next_pid);
1177         SEQ_PUT_HEX_FIELD_RET(s, field->next_prio);
1178         SEQ_PUT_HEX_FIELD_RET(s, T);
1179
1180         return TRACE_TYPE_HANDLED;
1181 }
1182
1183 static enum print_line_t trace_ctx_hex(struct trace_iterator *iter, int flags,
1184                                        struct trace_event *event)
1185 {
1186         return trace_ctxwake_hex(iter, 0);
1187 }
1188
1189 static enum print_line_t trace_wake_hex(struct trace_iterator *iter, int flags,
1190                                         struct trace_event *event)
1191 {
1192         return trace_ctxwake_hex(iter, '+');
1193 }
1194
1195 static enum print_line_t trace_ctxwake_bin(struct trace_iterator *iter,
1196                                            int flags, struct trace_event *event)
1197 {
1198         struct ctx_switch_entry *field;
1199         struct trace_seq *s = &iter->seq;
1200
1201         trace_assign_type(field, iter->ent);
1202
1203         SEQ_PUT_FIELD_RET(s, field->prev_pid);
1204         SEQ_PUT_FIELD_RET(s, field->prev_prio);
1205         SEQ_PUT_FIELD_RET(s, field->prev_state);
1206         SEQ_PUT_FIELD_RET(s, field->next_pid);
1207         SEQ_PUT_FIELD_RET(s, field->next_prio);
1208         SEQ_PUT_FIELD_RET(s, field->next_state);
1209
1210         return TRACE_TYPE_HANDLED;
1211 }
1212
1213 static struct trace_event_functions trace_ctx_funcs = {
1214         .trace          = trace_ctx_print,
1215         .raw            = trace_ctx_raw,
1216         .hex            = trace_ctx_hex,
1217         .binary         = trace_ctxwake_bin,
1218 };
1219
1220 static struct trace_event trace_ctx_event = {
1221         .type           = TRACE_CTX,
1222         .funcs          = &trace_ctx_funcs,
1223 };
1224
1225 static struct trace_event_functions trace_wake_funcs = {
1226         .trace          = trace_wake_print,
1227         .raw            = trace_wake_raw,
1228         .hex            = trace_wake_hex,
1229         .binary         = trace_ctxwake_bin,
1230 };
1231
1232 static struct trace_event trace_wake_event = {
1233         .type           = TRACE_WAKE,
1234         .funcs          = &trace_wake_funcs,
1235 };
1236
1237 /* TRACE_STACK */
1238
1239 static enum print_line_t trace_stack_print(struct trace_iterator *iter,
1240                                            int flags, struct trace_event *event)
1241 {
1242         struct stack_entry *field;
1243         struct trace_seq *s = &iter->seq;
1244         unsigned long *p;
1245         unsigned long *end;
1246
1247         trace_assign_type(field, iter->ent);
1248         end = (unsigned long *)((long)iter->ent + iter->ent_size);
1249
1250         if (!trace_seq_puts(s, "<stack trace>\n"))
1251                 goto partial;
1252
1253         for (p = field->caller; p && *p != ULONG_MAX && p < end; p++) {
1254                 if (!trace_seq_puts(s, " => "))
1255                         goto partial;
1256
1257                 if (!seq_print_ip_sym(s, *p, flags))
1258                         goto partial;
1259                 if (!trace_seq_putc(s, '\n'))
1260                         goto partial;
1261         }
1262
1263         return TRACE_TYPE_HANDLED;
1264
1265  partial:
1266         return TRACE_TYPE_PARTIAL_LINE;
1267 }
1268
1269 static struct trace_event_functions trace_stack_funcs = {
1270         .trace          = trace_stack_print,
1271 };
1272
1273 static struct trace_event trace_stack_event = {
1274         .type           = TRACE_STACK,
1275         .funcs          = &trace_stack_funcs,
1276 };
1277
1278 /* TRACE_USER_STACK */
1279 static enum print_line_t trace_user_stack_print(struct trace_iterator *iter,
1280                                                 int flags, struct trace_event *event)
1281 {
1282         struct userstack_entry *field;
1283         struct trace_seq *s = &iter->seq;
1284
1285         trace_assign_type(field, iter->ent);
1286
1287         if (!trace_seq_puts(s, "<user stack trace>\n"))
1288                 goto partial;
1289
1290         if (!seq_print_userip_objs(field, s, flags))
1291                 goto partial;
1292
1293         return TRACE_TYPE_HANDLED;
1294
1295  partial:
1296         return TRACE_TYPE_PARTIAL_LINE;
1297 }
1298
1299 static struct trace_event_functions trace_user_stack_funcs = {
1300         .trace          = trace_user_stack_print,
1301 };
1302
1303 static struct trace_event trace_user_stack_event = {
1304         .type           = TRACE_USER_STACK,
1305         .funcs          = &trace_user_stack_funcs,
1306 };
1307
1308 /* TRACE_BPUTS */
1309 static enum print_line_t
1310 trace_bputs_print(struct trace_iterator *iter, int flags,
1311                    struct trace_event *event)
1312 {
1313         struct trace_entry *entry = iter->ent;
1314         struct trace_seq *s = &iter->seq;
1315         struct bputs_entry *field;
1316
1317         trace_assign_type(field, entry);
1318
1319         if (!seq_print_ip_sym(s, field->ip, flags))
1320                 goto partial;
1321
1322         if (!trace_seq_puts(s, ": "))
1323                 goto partial;
1324
1325         if (!trace_seq_puts(s, field->str))
1326                 goto partial;
1327
1328         return TRACE_TYPE_HANDLED;
1329
1330  partial:
1331         return TRACE_TYPE_PARTIAL_LINE;
1332 }
1333
1334
1335 static enum print_line_t
1336 trace_bputs_raw(struct trace_iterator *iter, int flags,
1337                 struct trace_event *event)
1338 {
1339         struct bputs_entry *field;
1340         struct trace_seq *s = &iter->seq;
1341
1342         trace_assign_type(field, iter->ent);
1343
1344         if (!trace_seq_printf(s, ": %lx : ", field->ip))
1345                 goto partial;
1346
1347         if (!trace_seq_puts(s, field->str))
1348                 goto partial;
1349
1350         return TRACE_TYPE_HANDLED;
1351
1352  partial:
1353         return TRACE_TYPE_PARTIAL_LINE;
1354 }
1355
1356 static struct trace_event_functions trace_bputs_funcs = {
1357         .trace          = trace_bputs_print,
1358         .raw            = trace_bputs_raw,
1359 };
1360
1361 static struct trace_event trace_bputs_event = {
1362         .type           = TRACE_BPUTS,
1363         .funcs          = &trace_bputs_funcs,
1364 };
1365
1366 /* TRACE_BPRINT */
1367 static enum print_line_t
1368 trace_bprint_print(struct trace_iterator *iter, int flags,
1369                    struct trace_event *event)
1370 {
1371         struct trace_entry *entry = iter->ent;
1372         struct trace_seq *s = &iter->seq;
1373         struct bprint_entry *field;
1374
1375         trace_assign_type(field, entry);
1376
1377         if (!seq_print_ip_sym(s, field->ip, flags))
1378                 goto partial;
1379
1380         if (!trace_seq_puts(s, ": "))
1381                 goto partial;
1382
1383         if (!trace_seq_bprintf(s, field->fmt, field->buf))
1384                 goto partial;
1385
1386         return TRACE_TYPE_HANDLED;
1387
1388  partial:
1389         return TRACE_TYPE_PARTIAL_LINE;
1390 }
1391
1392
1393 static enum print_line_t
1394 trace_bprint_raw(struct trace_iterator *iter, int flags,
1395                  struct trace_event *event)
1396 {
1397         struct bprint_entry *field;
1398         struct trace_seq *s = &iter->seq;
1399
1400         trace_assign_type(field, iter->ent);
1401
1402         if (!trace_seq_printf(s, ": %lx : ", field->ip))
1403                 goto partial;
1404
1405         if (!trace_seq_bprintf(s, field->fmt, field->buf))
1406                 goto partial;
1407
1408         return TRACE_TYPE_HANDLED;
1409
1410  partial:
1411         return TRACE_TYPE_PARTIAL_LINE;
1412 }
1413
1414 static struct trace_event_functions trace_bprint_funcs = {
1415         .trace          = trace_bprint_print,
1416         .raw            = trace_bprint_raw,
1417 };
1418
1419 static struct trace_event trace_bprint_event = {
1420         .type           = TRACE_BPRINT,
1421         .funcs          = &trace_bprint_funcs,
1422 };
1423
1424 /* TRACE_PRINT */
1425 static enum print_line_t trace_print_print(struct trace_iterator *iter,
1426                                            int flags, struct trace_event *event)
1427 {
1428         struct print_entry *field;
1429         struct trace_seq *s = &iter->seq;
1430
1431         trace_assign_type(field, iter->ent);
1432
1433         if (!seq_print_ip_sym(s, field->ip, flags))
1434                 goto partial;
1435
1436         if (!trace_seq_printf(s, ": %s", field->buf))
1437                 goto partial;
1438
1439         return TRACE_TYPE_HANDLED;
1440
1441  partial:
1442         return TRACE_TYPE_PARTIAL_LINE;
1443 }
1444
1445 static enum print_line_t trace_print_raw(struct trace_iterator *iter, int flags,
1446                                          struct trace_event *event)
1447 {
1448         struct print_entry *field;
1449
1450         trace_assign_type(field, iter->ent);
1451
1452         if (!trace_seq_printf(&iter->seq, "# %lx %s", field->ip, field->buf))
1453                 goto partial;
1454
1455         return TRACE_TYPE_HANDLED;
1456
1457  partial:
1458         return TRACE_TYPE_PARTIAL_LINE;
1459 }
1460
1461 static struct trace_event_functions trace_print_funcs = {
1462         .trace          = trace_print_print,
1463         .raw            = trace_print_raw,
1464 };
1465
1466 static struct trace_event trace_print_event = {
1467         .type           = TRACE_PRINT,
1468         .funcs          = &trace_print_funcs,
1469 };
1470
1471
1472 static struct trace_event *events[] __initdata = {
1473         &trace_fn_event,
1474         &trace_ctx_event,
1475         &trace_wake_event,
1476         &trace_stack_event,
1477         &trace_user_stack_event,
1478         &trace_bputs_event,
1479         &trace_bprint_event,
1480         &trace_print_event,
1481         NULL
1482 };
1483
1484 __init static int init_events(void)
1485 {
1486         struct trace_event *event;
1487         int i, ret;
1488
1489         for (i = 0; events[i]; i++) {
1490                 event = events[i];
1491
1492                 ret = register_ftrace_event(event);
1493                 if (!ret) {
1494                         printk(KERN_WARNING "event %d failed to register\n",
1495                                event->type);
1496                         WARN_ON_ONCE(1);
1497                 }
1498         }
1499
1500         return 0;
1501 }
1502 early_initcall(init_events);