Merge branch 'perf/core' into perf/probes

Conflicts:
	tools/perf/Makefile

Merge reason:

 - fix the conflict
 - pick up the pr_*() infrastructure to queue up dependent patch

Signed-off-by: Ingo Molnar <mingo@elte.hu>

7 files changed, 136 insertions, 75 deletions

diff --git a/include/trace/events/bkl.h b/include/trace/events/bkl.h
new file mode 100644
index 000000000000..1af72dc24278
--- /dev/null
+++ b/include/trace/events/bkl.h
@@ -0,0 +1,61 @@
+#undef TRACE_SYSTEM
+#define TRACE_SYSTEM bkl
+
+#if !defined(_TRACE_BKL_H) || defined(TRACE_HEADER_MULTI_READ)
+#define _TRACE_BKL_H
+
+#include <linux/tracepoint.h>
+
+TRACE_EVENT(lock_kernel,
+
+        TP_PROTO(const char *func, const char *file, int line),
+
+        TP_ARGS(func, file, line),
+
+        TP_STRUCT__entry(
+                __field(        int,            depth                   )
+                __field_ext(    const char *,   func, FILTER_PTR_STRING )
+                __field_ext(    const char *,   file, FILTER_PTR_STRING )
+                __field(        int,            line                    )
+        ),
+
+        TP_fast_assign(
+                /* We want to record the lock_depth after lock is acquired */
+                __entry->depth = current->lock_depth + 1;
+                __entry->func = func;
+                __entry->file = file;
+                __entry->line = line;
+        ),
+
+        TP_printk("depth=%d file:line=%s:%d func=%s()", __entry->depth,
+                  __entry->file, __entry->line, __entry->func)
+);
+
+TRACE_EVENT(unlock_kernel,
+
+        TP_PROTO(const char *func, const char *file, int line),
+
+        TP_ARGS(func, file, line),
+
+        TP_STRUCT__entry(
+                __field(int,            depth           )
+                __field(const char *,   func            )
+                __field(const char *,   file            )
+                __field(int,            line            )
+        ),
+
+        TP_fast_assign(
+                __entry->depth = current->lock_depth;
+                __entry->func = func;
+                __entry->file = file;
+                __entry->line = line;
+        ),
+
+        TP_printk("depth=%d file:line=%s:%d func=%s()", __entry->depth,
+                  __entry->file, __entry->line, __entry->func)
+);
+
+#endif /* _TRACE_BKL_H */
+
+/* This part must be outside protection */
+#include <trace/define_trace.h>
diff --git a/include/trace/events/irq.h b/include/trace/events/irq.h
index b89f9db4a404..dcfcd4407623 100644
--- a/include/trace/events/irq.h
+++ b/include/trace/events/irq.h
@@ -48,7 +48,7 @@ TRACE_EVENT(irq_handler_entry,
                 __assign_str(name, action->name);
         ),
 
-        TP_printk("irq=%d handler=%s", __entry->irq, __get_str(name))
+        TP_printk("irq=%d name=%s", __entry->irq, __get_str(name))
 );
 
 /**
@@ -78,7 +78,7 @@ TRACE_EVENT(irq_handler_exit,
                 __entry->ret    = ret;
         ),
 
-        TP_printk("irq=%d return=%s",
+        TP_printk("irq=%d ret=%s",
                   __entry->irq, __entry->ret ? "handled" : "unhandled")
 );
 
@@ -107,7 +107,7 @@ TRACE_EVENT(softirq_entry,
                 __entry->vec = (int)(h - vec);
         ),
 
-        TP_printk("softirq=%d action=%s", __entry->vec,
+        TP_printk("vec=%d [action=%s]", __entry->vec,
                   show_softirq_name(__entry->vec))
 );
 
@@ -136,7 +136,7 @@ TRACE_EVENT(softirq_exit,
                 __entry->vec = (int)(h - vec);
         ),
 
-        TP_printk("softirq=%d action=%s", __entry->vec,
+        TP_printk("vec=%d [action=%s]", __entry->vec,
                   show_softirq_name(__entry->vec))
 );
 
diff --git a/include/trace/events/power.h b/include/trace/events/power.h
index ea6d579261ad..9bb96e5a2848 100644
--- a/include/trace/events/power.h
+++ b/include/trace/events/power.h
@@ -16,8 +16,6 @@ enum {
 };
 #endif
 
-
-
 TRACE_EVENT(power_start,
 
         TP_PROTO(unsigned int type, unsigned int state),
diff --git a/include/trace/events/sched.h b/include/trace/events/sched.h
index 4069c43f4187..b50b9856c59f 100644
--- a/include/trace/events/sched.h
+++ b/include/trace/events/sched.h
@@ -26,7 +26,7 @@ TRACE_EVENT(sched_kthread_stop,
                 __entry->pid    = t->pid;
         ),
 
-        TP_printk("task %s:%d", __entry->comm, __entry->pid)
+        TP_printk("comm=%s pid=%d", __entry->comm, __entry->pid)
 );
 
 /*
@@ -46,7 +46,7 @@ TRACE_EVENT(sched_kthread_stop_ret,
                 __entry->ret    = ret;
         ),
 
-        TP_printk("ret %d", __entry->ret)
+        TP_printk("ret=%d", __entry->ret)
 );
 
 /*
@@ -73,7 +73,7 @@ TRACE_EVENT(sched_wait_task,
                 __entry->prio   = p->prio;
         ),
 
-        TP_printk("task %s:%d [%d]",
+        TP_printk("comm=%s pid=%d prio=%d",
                   __entry->comm, __entry->pid, __entry->prio)
 );
 
@@ -94,7 +94,7 @@ TRACE_EVENT(sched_wakeup,
                 __field(        pid_t,  pid                     )
                 __field(        int,    prio                    )
                 __field(        int,    success                 )
-                __field(        int,    cpu                     )
+                __field(        int,    target_cpu              )
         ),
 
         TP_fast_assign(
@@ -102,12 +102,12 @@ TRACE_EVENT(sched_wakeup,
                 __entry->pid            = p->pid;
                 __entry->prio           = p->prio;
                 __entry->success        = success;
-                __entry->cpu            = task_cpu(p);
+                __entry->target_cpu     = task_cpu(p);
         ),
 
-        TP_printk("task %s:%d [%d] success=%d [%03d]",
+        TP_printk("comm=%s pid=%d prio=%d success=%d target_cpu=%03d",
                   __entry->comm, __entry->pid, __entry->prio,
-                  __entry->success, __entry->cpu)
+                  __entry->success, __entry->target_cpu)
 );
 
 /*
@@ -127,7 +127,7 @@ TRACE_EVENT(sched_wakeup_new,
                 __field(        pid_t,  pid                     )
                 __field(        int,    prio                    )
                 __field(        int,    success                 )
-                __field(        int,    cpu                     )
+                __field(        int,    target_cpu              )
         ),
 
         TP_fast_assign(
@@ -135,12 +135,12 @@ TRACE_EVENT(sched_wakeup_new,
                 __entry->pid            = p->pid;
                 __entry->prio           = p->prio;
                 __entry->success        = success;
-                __entry->cpu            = task_cpu(p);
+                __entry->target_cpu     = task_cpu(p);
         ),
 
-        TP_printk("task %s:%d [%d] success=%d [%03d]",
+        TP_printk("comm=%s pid=%d prio=%d success=%d target_cpu=%03d",
                   __entry->comm, __entry->pid, __entry->prio,
-                  __entry->success, __entry->cpu)
+                  __entry->success, __entry->target_cpu)
 );
 
 /*
@@ -176,7 +176,7 @@ TRACE_EVENT(sched_switch,
                 __entry->next_prio      = next->prio;
         ),
 
-        TP_printk("task %s:%d [%d] (%s) ==> %s:%d [%d]",
+        TP_printk("prev_comm=%s prev_pid=%d prev_prio=%d prev_state=%s ==> next_comm=%s next_pid=%d next_prio=%d",
                 __entry->prev_comm, __entry->prev_pid, __entry->prev_prio,
                 __entry->prev_state ?
                   __print_flags(__entry->prev_state, "|",
@@ -211,7 +211,7 @@ TRACE_EVENT(sched_migrate_task,
                 __entry->dest_cpu       = dest_cpu;
         ),
 
-        TP_printk("task %s:%d [%d] from: %d  to: %d",
+        TP_printk("comm=%s pid=%d prio=%d orig_cpu=%d dest_cpu=%d",
                   __entry->comm, __entry->pid, __entry->prio,
                   __entry->orig_cpu, __entry->dest_cpu)
 );
@@ -237,7 +237,7 @@ TRACE_EVENT(sched_process_free,
                 __entry->prio           = p->prio;
         ),
 
-        TP_printk("task %s:%d [%d]",
+        TP_printk("comm=%s pid=%d prio=%d",
                   __entry->comm, __entry->pid, __entry->prio)
 );
 
@@ -262,7 +262,7 @@ TRACE_EVENT(sched_process_exit,
                 __entry->prio           = p->prio;
         ),
 
-        TP_printk("task %s:%d [%d]",
+        TP_printk("comm=%s pid=%d prio=%d",
                   __entry->comm, __entry->pid, __entry->prio)
 );
 
@@ -287,7 +287,7 @@ TRACE_EVENT(sched_process_wait,
                 __entry->prio           = current->prio;
         ),
 
-        TP_printk("task %s:%d [%d]",
+        TP_printk("comm=%s pid=%d prio=%d",
                   __entry->comm, __entry->pid, __entry->prio)
 );
 
@@ -314,7 +314,7 @@ TRACE_EVENT(sched_process_fork,
                 __entry->child_pid      = child->pid;
         ),
 
-        TP_printk("parent %s:%d  child %s:%d",
+        TP_printk("comm=%s pid=%d child_comm=%s child_pid=%d",
                 __entry->parent_comm, __entry->parent_pid,
                 __entry->child_comm, __entry->child_pid)
 );
@@ -340,7 +340,7 @@ TRACE_EVENT(sched_signal_send,
                 __entry->sig    = sig;
         ),
 
-        TP_printk("sig: %d  task %s:%d",
+        TP_printk("sig=%d comm=%s pid=%d",
                   __entry->sig, __entry->comm, __entry->pid)
 );
 
@@ -374,7 +374,7 @@ TRACE_EVENT(sched_stat_wait,
                 __perf_count(delay);
         ),
 
-        TP_printk("task: %s:%d wait: %Lu [ns]",
+        TP_printk("comm=%s pid=%d delay=%Lu [ns]",
                         __entry->comm, __entry->pid,
                         (unsigned long long)__entry->delay)
 );
@@ -406,7 +406,7 @@ TRACE_EVENT(sched_stat_runtime,
                 __perf_count(runtime);
         ),
 
-        TP_printk("task: %s:%d runtime: %Lu [ns], vruntime: %Lu [ns]",
+        TP_printk("comm=%s pid=%d runtime=%Lu [ns] vruntime=%Lu [ns]",
                         __entry->comm, __entry->pid,
                         (unsigned long long)__entry->runtime,
                         (unsigned long long)__entry->vruntime)
@@ -437,7 +437,7 @@ TRACE_EVENT(sched_stat_sleep,
                 __perf_count(delay);
         ),
 
-        TP_printk("task: %s:%d sleep: %Lu [ns]",
+        TP_printk("comm=%s pid=%d delay=%Lu [ns]",
                         __entry->comm, __entry->pid,
                         (unsigned long long)__entry->delay)
 );
@@ -467,7 +467,7 @@ TRACE_EVENT(sched_stat_iowait,
                 __perf_count(delay);
         ),
 
-        TP_printk("task: %s:%d iowait: %Lu [ns]",
+        TP_printk("comm=%s pid=%d delay=%Lu [ns]",
                         __entry->comm, __entry->pid,
                         (unsigned long long)__entry->delay)
 );
diff --git a/include/trace/events/timer.h b/include/trace/events/timer.h
index 1844c48d640e..e5ce87a0498d 100644
--- a/include/trace/events/timer.h
+++ b/include/trace/events/timer.h
@@ -26,7 +26,7 @@ TRACE_EVENT(timer_init,
                 __entry->timer  = timer;
         ),
 
-        TP_printk("timer %p", __entry->timer)
+        TP_printk("timer=%p", __entry->timer)
 );
 
 /**
@@ -54,7 +54,7 @@ TRACE_EVENT(timer_start,
                 __entry->now            = jiffies;
         ),
 
-        TP_printk("timer %p: func %pf, expires %lu, timeout %ld",
+        TP_printk("timer=%p function=%pf expires=%lu [timeout=%ld]",
                   __entry->timer, __entry->function, __entry->expires,
                   (long)__entry->expires - __entry->now)
 );
@@ -81,7 +81,7 @@ TRACE_EVENT(timer_expire_entry,
                 __entry->now            = jiffies;
         ),
 
-        TP_printk("timer %p: now %lu", __entry->timer, __entry->now)
+        TP_printk("timer=%p now=%lu", __entry->timer, __entry->now)
 );
 
 /**
@@ -108,7 +108,7 @@ TRACE_EVENT(timer_expire_exit,
                 __entry->timer  = timer;
         ),
 
-        TP_printk("timer %p", __entry->timer)
+        TP_printk("timer=%p", __entry->timer)
 );
 
 /**
@@ -129,7 +129,7 @@ TRACE_EVENT(timer_cancel,
                 __entry->timer  = timer;
         ),
 
-        TP_printk("timer %p", __entry->timer)
+        TP_printk("timer=%p", __entry->timer)
 );
 
 /**
@@ -140,24 +140,24 @@ TRACE_EVENT(timer_cancel,
  */
 TRACE_EVENT(hrtimer_init,
 
-        TP_PROTO(struct hrtimer *timer, clockid_t clockid,
+        TP_PROTO(struct hrtimer *hrtimer, clockid_t clockid,
                  enum hrtimer_mode mode),
 
-        TP_ARGS(timer, clockid, mode),
+        TP_ARGS(hrtimer, clockid, mode),
 
         TP_STRUCT__entry(
-                __field( void *,                timer           )
+                __field( void *,                hrtimer         )
                 __field( clockid_t,             clockid         )
                 __field( enum hrtimer_mode,     mode            )
         ),
 
         TP_fast_assign(
-                __entry->timer          = timer;
+                __entry->hrtimer        = hrtimer;
                 __entry->clockid        = clockid;
                 __entry->mode           = mode;
         ),
 
-        TP_printk("hrtimer %p, clockid %s, mode %s", __entry->timer,
+        TP_printk("hrtimer=%p clockid=%s mode=%s", __entry->hrtimer,
                   __entry->clockid == CLOCK_REALTIME ?
                         "CLOCK_REALTIME" : "CLOCK_MONOTONIC",
                   __entry->mode == HRTIMER_MODE_ABS ?
@@ -170,26 +170,26 @@ TRACE_EVENT(hrtimer_init,
  */
 TRACE_EVENT(hrtimer_start,
 
-        TP_PROTO(struct hrtimer *timer),
+        TP_PROTO(struct hrtimer *hrtimer),
 
-        TP_ARGS(timer),
+        TP_ARGS(hrtimer),
 
         TP_STRUCT__entry(
-                __field( void *,        timer           )
+                __field( void *,        hrtimer         )
                 __field( void *,        function        )
                 __field( s64,           expires         )
                 __field( s64,           softexpires     )
         ),
 
         TP_fast_assign(
-                __entry->timer          = timer;
-                __entry->function       = timer->function;
-                __entry->expires        = hrtimer_get_expires(timer).tv64;
-                __entry->softexpires    = hrtimer_get_softexpires(timer).tv64;
+                __entry->hrtimer        = hrtimer;
+                __entry->function       = hrtimer->function;
+                __entry->expires        = hrtimer_get_expires(hrtimer).tv64;
+                __entry->softexpires    = hrtimer_get_softexpires(hrtimer).tv64;
         ),
 
-        TP_printk("hrtimer %p, func %pf, expires %llu, softexpires %llu",
-                  __entry->timer, __entry->function,
+        TP_printk("hrtimer=%p function=%pf expires=%llu softexpires=%llu",
+                  __entry->hrtimer, __entry->function,
                   (unsigned long long)ktime_to_ns((ktime_t) {
                                   .tv64 = __entry->expires }),
                   (unsigned long long)ktime_to_ns((ktime_t) {
@@ -206,23 +206,22 @@ TRACE_EVENT(hrtimer_start,
  */
 TRACE_EVENT(hrtimer_expire_entry,
 
-        TP_PROTO(struct hrtimer *timer, ktime_t *now),
+        TP_PROTO(struct hrtimer *hrtimer, ktime_t *now),
 
-        TP_ARGS(timer, now),
+        TP_ARGS(hrtimer, now),
 
         TP_STRUCT__entry(
-                __field( void *,        timer   )
+                __field( void *,        hrtimer )
                 __field( s64,           now     )
         ),
 
         TP_fast_assign(
-                __entry->timer  = timer;
-                __entry->now    = now->tv64;
+                __entry->hrtimer        = hrtimer;
+                __entry->now            = now->tv64;
         ),
 
-        TP_printk("hrtimer %p, now %llu", __entry->timer,
-                  (unsigned long long)ktime_to_ns((ktime_t) {
-                                  .tv64 = __entry->now }))
+        TP_printk("hrtimer=%p now=%llu", __entry->hrtimer,
+                  (unsigned long long)ktime_to_ns((ktime_t) { .tv64 = __entry->now }))
  );
 
 /**
@@ -234,40 +233,40 @@ TRACE_EVENT(hrtimer_expire_entry,
  */
 TRACE_EVENT(hrtimer_expire_exit,
 
-        TP_PROTO(struct hrtimer *timer),
+        TP_PROTO(struct hrtimer *hrtimer),
 
-        TP_ARGS(timer),
+        TP_ARGS(hrtimer),
 
         TP_STRUCT__entry(
-                __field( void *,        timer   )
+                __field( void *,        hrtimer )
         ),
 
         TP_fast_assign(
-                __entry->timer  = timer;
+                __entry->hrtimer        = hrtimer;
         ),
 
-        TP_printk("hrtimer %p", __entry->timer)
+        TP_printk("hrtimer=%p", __entry->hrtimer)
 );
 
 /**
  * hrtimer_cancel - called when the hrtimer is canceled
- * @timer:      pointer to struct hrtimer
+ * @hrtimer:    pointer to struct hrtimer
  */
 TRACE_EVENT(hrtimer_cancel,
 
-        TP_PROTO(struct hrtimer *timer),
+        TP_PROTO(struct hrtimer *hrtimer),
 
-        TP_ARGS(timer),
+        TP_ARGS(hrtimer),
 
         TP_STRUCT__entry(
-                __field( void *,        timer   )
+                __field( void *,        hrtimer )
         ),
 
         TP_fast_assign(
-                __entry->timer  = timer;
+                __entry->hrtimer        = hrtimer;
         ),
 
-        TP_printk("hrtimer %p", __entry->timer)
+        TP_printk("hrtimer=%p", __entry->hrtimer)
 );
 
 /**
@@ -302,7 +301,7 @@ TRACE_EVENT(itimer_state,
                 __entry->interval_usec  = value->it_interval.tv_usec;
         ),
 
-        TP_printk("which %d, expires %lu, it_value %lu.%lu, it_interval %lu.%lu",
+        TP_printk("which=%d expires=%lu it_value=%lu.%lu it_interval=%lu.%lu",
                   __entry->which, __entry->expires,
                   __entry->value_sec, __entry->value_usec,
                   __entry->interval_sec, __entry->interval_usec)
@@ -332,7 +331,7 @@ TRACE_EVENT(itimer_expire,
                 __entry->pid    = pid_nr(pid);
         ),
 
-            TP_printk("which %d, pid %d, now %lu", __entry->which,
+            TP_printk("which=%d pid=%d now=%lu", __entry->which,
                       (int) __entry->pid, __entry->now)
 );
 
diff --git a/include/trace/ftrace.h b/include/trace/ftrace.h
index 54d02c06ae7e..a7f946094128 100644
--- a/include/trace/ftrace.h
+++ b/include/trace/ftrace.h
@@ -120,9 +120,10 @@
 #undef __field
 #define __field(type, item)                                     \
         ret = trace_seq_printf(s, "\tfield:" #type " " #item ";\t"      \
-                               "offset:%u;\tsize:%u;\n",                \
+                               "offset:%u;\tsize:%u;\tsigned:%u;\n",    \
                                (unsigned int)offsetof(typeof(field), item), \
-                               (unsigned int)sizeof(field.item));       \
+                               (unsigned int)sizeof(field.item),        \
+                               (unsigned int)is_signed_type(type));     \
         if (!ret)                                                       \
                 return 0;
 
@@ -132,19 +133,21 @@
 #undef __array
 #define __array(type, item, len)                                                \
         ret = trace_seq_printf(s, "\tfield:" #type " " #item "[" #len "];\t"    \
-                               "offset:%u;\tsize:%u;\n",                \
+                               "offset:%u;\tsize:%u;\tsigned:%u;\n",    \
                                (unsigned int)offsetof(typeof(field), item), \
-                               (unsigned int)sizeof(field.item));       \
+                               (unsigned int)sizeof(field.item),        \
+                               (unsigned int)is_signed_type(type));     \
         if (!ret)                                                       \
                 return 0;
 
 #undef __dynamic_array
 #define __dynamic_array(type, item, len)                                       \
         ret = trace_seq_printf(s, "\tfield:__data_loc " #type "[] " #item ";\t"\
-                               "offset:%u;\tsize:%u;\n",                       \
+                               "offset:%u;\tsize:%u;\tsigned:%u;\n",           \
                                (unsigned int)offsetof(typeof(field),           \
                                         __data_loc_##item),                    \
-                               (unsigned int)sizeof(field.__data_loc_##item)); \
+                               (unsigned int)sizeof(field.__data_loc_##item), \
+                               (unsigned int)is_signed_type(type));     \
         if (!ret)                                                              \
                 return 0;
 
diff --git a/include/trace/syscall.h b/include/trace/syscall.h
index e290b86f6167..51ee17d3632a 100644
--- a/include/trace/syscall.h
+++ b/include/trace/syscall.h
@@ -33,7 +33,7 @@ struct syscall_metadata {
 };
 
 #ifdef CONFIG_FTRACE_SYSCALLS
-extern struct syscall_metadata *syscall_nr_to_meta(int nr);
+extern unsigned long arch_syscall_addr(int nr);
 extern int syscall_name_to_nr(char *name);
 void set_syscall_enter_id(int num, int id);
 void set_syscall_exit_id(int num, int id);