2008-05-12 15:20:42 -04:00
|
|
|
/*
|
|
|
|
* trace task wakeup timings
|
|
|
|
*
|
|
|
|
* Copyright (C) 2007-2008 Steven Rostedt <srostedt@redhat.com>
|
|
|
|
* Copyright (C) 2008 Ingo Molnar <mingo@redhat.com>
|
|
|
|
*
|
|
|
|
* Based on code from the latency_tracer, that is:
|
|
|
|
*
|
|
|
|
* Copyright (C) 2004-2006 Ingo Molnar
|
|
|
|
* Copyright (C) 2004 William Lee Irwin III
|
|
|
|
*/
|
|
|
|
#include <linux/module.h>
|
|
|
|
#include <linux/fs.h>
|
|
|
|
#include <linux/debugfs.h>
|
|
|
|
#include <linux/kallsyms.h>
|
|
|
|
#include <linux/uaccess.h>
|
|
|
|
#include <linux/ftrace.h>
|
2009-04-14 19:39:12 -04:00
|
|
|
#include <trace/events/sched.h>
|
2008-05-12 15:20:42 -04:00
|
|
|
|
|
|
|
#include "trace.h"
|
|
|
|
|
|
|
|
static struct trace_array *wakeup_trace;
|
|
|
|
static int __read_mostly tracer_enabled;
|
|
|
|
|
|
|
|
static struct task_struct *wakeup_task;
|
|
|
|
static int wakeup_cpu;
|
tracing: do not grab lock in wakeup latency function tracing
The wakeup tracer, when enabled, has its own function tracer.
It only traces the functions on the CPU where the task it is following
is on. If a task is woken on one CPU but then migrates to another CPU
before it wakes up, the latency tracer will then start tracing functions
on the other CPU.
To find which CPU the task is on, the wakeup function tracer performs
a task_cpu(wakeup_task). But to make sure the task does not disappear
it grabs the wakeup_lock, which is also taken when the task wakes up.
By taking this lock, the function tracer does not need to worry about
the task being freed as it checks its cpu.
Jan Blunck found a problem with this approach on his 32 CPU box. When
a task is being traced by the wakeup tracer, all functions take this
lock. That means that on all 32 CPUs, each function call is taking
this one lock to see if the task is on that CPU. This lock has just
serialized all functions on all 32 CPUs. Needless to say, this caused
major issues on that box. It would even lockup.
This patch changes the wakeup latency to insert a probe on the migrate task
tracepoint. When a task changes its CPU that it will run on, the
probe will take note. Now the wakeup function tracer no longer needs
to take the lock. It only compares the current CPU with a variable that
holds the current CPU the task is on. We don't worry about races since
it is OK to add or miss a function trace.
Reported-by: Jan Blunck <jblunck@suse.de>
Tested-by: Jan Blunck <jblunck@suse.de>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
2009-09-09 10:36:01 -04:00
|
|
|
static int wakeup_current_cpu;
|
2008-05-12 15:20:42 -04:00
|
|
|
static unsigned wakeup_prio = -1;
|
2009-01-21 16:24:46 -05:00
|
|
|
static int wakeup_rt;
|
2008-05-12 15:20:42 -04:00
|
|
|
|
2009-12-02 13:49:50 -05:00
|
|
|
static arch_spinlock_t wakeup_lock =
|
2009-12-03 06:38:57 -05:00
|
|
|
(arch_spinlock_t)__ARCH_SPIN_LOCK_UNLOCKED;
|
2008-05-12 15:20:42 -04:00
|
|
|
|
2008-05-12 15:20:51 -04:00
|
|
|
static void __wakeup_reset(struct trace_array *tr);
|
2008-05-12 15:20:42 -04:00
|
|
|
|
2009-03-04 22:15:30 -05:00
|
|
|
static int save_lat_flag;
|
|
|
|
|
2008-10-06 19:06:12 -04:00
|
|
|
#ifdef CONFIG_FUNCTION_TRACER
|
2008-05-22 00:22:19 -04:00
|
|
|
/*
|
|
|
|
* irqsoff uses its own tracer function to keep the overhead down:
|
|
|
|
*/
|
|
|
|
static void
|
|
|
|
wakeup_tracer_call(unsigned long ip, unsigned long parent_ip)
|
|
|
|
{
|
|
|
|
struct trace_array *tr = wakeup_trace;
|
|
|
|
struct trace_array_cpu *data;
|
|
|
|
unsigned long flags;
|
|
|
|
long disabled;
|
|
|
|
int resched;
|
|
|
|
int cpu;
|
2008-10-01 13:14:09 -04:00
|
|
|
int pc;
|
2008-05-22 00:22:19 -04:00
|
|
|
|
|
|
|
if (likely(!wakeup_task))
|
|
|
|
return;
|
|
|
|
|
2008-10-01 13:14:09 -04:00
|
|
|
pc = preempt_count();
|
2008-11-03 23:15:56 -05:00
|
|
|
resched = ftrace_preempt_disable();
|
2008-05-22 00:22:19 -04:00
|
|
|
|
|
|
|
cpu = raw_smp_processor_id();
|
tracing: do not grab lock in wakeup latency function tracing
The wakeup tracer, when enabled, has its own function tracer.
It only traces the functions on the CPU where the task it is following
is on. If a task is woken on one CPU but then migrates to another CPU
before it wakes up, the latency tracer will then start tracing functions
on the other CPU.
To find which CPU the task is on, the wakeup function tracer performs
a task_cpu(wakeup_task). But to make sure the task does not disappear
it grabs the wakeup_lock, which is also taken when the task wakes up.
By taking this lock, the function tracer does not need to worry about
the task being freed as it checks its cpu.
Jan Blunck found a problem with this approach on his 32 CPU box. When
a task is being traced by the wakeup tracer, all functions take this
lock. That means that on all 32 CPUs, each function call is taking
this one lock to see if the task is on that CPU. This lock has just
serialized all functions on all 32 CPUs. Needless to say, this caused
major issues on that box. It would even lockup.
This patch changes the wakeup latency to insert a probe on the migrate task
tracepoint. When a task changes its CPU that it will run on, the
probe will take note. Now the wakeup function tracer no longer needs
to take the lock. It only compares the current CPU with a variable that
holds the current CPU the task is on. We don't worry about races since
it is OK to add or miss a function trace.
Reported-by: Jan Blunck <jblunck@suse.de>
Tested-by: Jan Blunck <jblunck@suse.de>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
2009-09-09 10:36:01 -04:00
|
|
|
if (cpu != wakeup_current_cpu)
|
|
|
|
goto out_enable;
|
|
|
|
|
2008-05-22 00:22:19 -04:00
|
|
|
data = tr->data[cpu];
|
|
|
|
disabled = atomic_inc_return(&data->disabled);
|
|
|
|
if (unlikely(disabled != 1))
|
|
|
|
goto out;
|
|
|
|
|
2008-07-16 00:13:45 -04:00
|
|
|
local_irq_save(flags);
|
2008-05-22 00:22:19 -04:00
|
|
|
|
2009-02-05 01:13:37 -05:00
|
|
|
trace_function(tr, ip, parent_ip, flags, pc);
|
2008-05-22 00:22:19 -04:00
|
|
|
|
2008-07-16 00:13:45 -04:00
|
|
|
local_irq_restore(flags);
|
2008-05-22 00:22:19 -04:00
|
|
|
|
|
|
|
out:
|
|
|
|
atomic_dec(&data->disabled);
|
tracing: do not grab lock in wakeup latency function tracing
The wakeup tracer, when enabled, has its own function tracer.
It only traces the functions on the CPU where the task it is following
is on. If a task is woken on one CPU but then migrates to another CPU
before it wakes up, the latency tracer will then start tracing functions
on the other CPU.
To find which CPU the task is on, the wakeup function tracer performs
a task_cpu(wakeup_task). But to make sure the task does not disappear
it grabs the wakeup_lock, which is also taken when the task wakes up.
By taking this lock, the function tracer does not need to worry about
the task being freed as it checks its cpu.
Jan Blunck found a problem with this approach on his 32 CPU box. When
a task is being traced by the wakeup tracer, all functions take this
lock. That means that on all 32 CPUs, each function call is taking
this one lock to see if the task is on that CPU. This lock has just
serialized all functions on all 32 CPUs. Needless to say, this caused
major issues on that box. It would even lockup.
This patch changes the wakeup latency to insert a probe on the migrate task
tracepoint. When a task changes its CPU that it will run on, the
probe will take note. Now the wakeup function tracer no longer needs
to take the lock. It only compares the current CPU with a variable that
holds the current CPU the task is on. We don't worry about races since
it is OK to add or miss a function trace.
Reported-by: Jan Blunck <jblunck@suse.de>
Tested-by: Jan Blunck <jblunck@suse.de>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
2009-09-09 10:36:01 -04:00
|
|
|
out_enable:
|
2008-11-03 23:15:56 -05:00
|
|
|
ftrace_preempt_enable(resched);
|
2008-05-22 00:22:19 -04:00
|
|
|
}
|
|
|
|
|
|
|
|
static struct ftrace_ops trace_ops __read_mostly =
|
|
|
|
{
|
|
|
|
.func = wakeup_tracer_call,
|
|
|
|
};
|
2008-10-06 19:06:12 -04:00
|
|
|
#endif /* CONFIG_FUNCTION_TRACER */
|
2008-05-22 00:22:19 -04:00
|
|
|
|
2008-05-12 15:20:42 -04:00
|
|
|
/*
|
|
|
|
* Should this new latency be reported/recorded?
|
|
|
|
*/
|
2008-05-12 15:20:51 -04:00
|
|
|
static int report_latency(cycle_t delta)
|
2008-05-12 15:20:42 -04:00
|
|
|
{
|
|
|
|
if (tracing_thresh) {
|
|
|
|
if (delta < tracing_thresh)
|
|
|
|
return 0;
|
|
|
|
} else {
|
|
|
|
if (delta <= tracing_max_latency)
|
|
|
|
return 0;
|
|
|
|
}
|
|
|
|
return 1;
|
|
|
|
}
|
|
|
|
|
tracing: do not grab lock in wakeup latency function tracing
The wakeup tracer, when enabled, has its own function tracer.
It only traces the functions on the CPU where the task it is following
is on. If a task is woken on one CPU but then migrates to another CPU
before it wakes up, the latency tracer will then start tracing functions
on the other CPU.
To find which CPU the task is on, the wakeup function tracer performs
a task_cpu(wakeup_task). But to make sure the task does not disappear
it grabs the wakeup_lock, which is also taken when the task wakes up.
By taking this lock, the function tracer does not need to worry about
the task being freed as it checks its cpu.
Jan Blunck found a problem with this approach on his 32 CPU box. When
a task is being traced by the wakeup tracer, all functions take this
lock. That means that on all 32 CPUs, each function call is taking
this one lock to see if the task is on that CPU. This lock has just
serialized all functions on all 32 CPUs. Needless to say, this caused
major issues on that box. It would even lockup.
This patch changes the wakeup latency to insert a probe on the migrate task
tracepoint. When a task changes its CPU that it will run on, the
probe will take note. Now the wakeup function tracer no longer needs
to take the lock. It only compares the current CPU with a variable that
holds the current CPU the task is on. We don't worry about races since
it is OK to add or miss a function trace.
Reported-by: Jan Blunck <jblunck@suse.de>
Tested-by: Jan Blunck <jblunck@suse.de>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
2009-09-09 10:36:01 -04:00
|
|
|
static void probe_wakeup_migrate_task(struct task_struct *task, int cpu)
|
|
|
|
{
|
|
|
|
if (task != wakeup_task)
|
|
|
|
return;
|
|
|
|
|
|
|
|
wakeup_current_cpu = cpu;
|
|
|
|
}
|
|
|
|
|
2008-05-12 15:21:10 -04:00
|
|
|
static void notrace
|
2008-07-18 12:16:17 -04:00
|
|
|
probe_wakeup_sched_switch(struct rq *rq, struct task_struct *prev,
|
2008-05-12 15:21:10 -04:00
|
|
|
struct task_struct *next)
|
2008-05-12 15:20:42 -04:00
|
|
|
{
|
|
|
|
struct trace_array_cpu *data;
|
|
|
|
cycle_t T0, T1, delta;
|
|
|
|
unsigned long flags;
|
|
|
|
long disabled;
|
|
|
|
int cpu;
|
2008-10-01 13:14:09 -04:00
|
|
|
int pc;
|
2008-05-12 15:20:42 -04:00
|
|
|
|
2008-07-18 12:16:17 -04:00
|
|
|
tracing_record_cmdline(prev);
|
|
|
|
|
2008-05-12 15:20:42 -04:00
|
|
|
if (unlikely(!tracer_enabled))
|
|
|
|
return;
|
|
|
|
|
|
|
|
/*
|
|
|
|
* When we start a new trace, we set wakeup_task to NULL
|
|
|
|
* and then set tracer_enabled = 1. We want to make sure
|
|
|
|
* that another CPU does not see the tracer_enabled = 1
|
|
|
|
* and the wakeup_task with an older task, that might
|
|
|
|
* actually be the same as next.
|
|
|
|
*/
|
|
|
|
smp_rmb();
|
|
|
|
|
|
|
|
if (next != wakeup_task)
|
|
|
|
return;
|
|
|
|
|
2008-10-01 13:14:09 -04:00
|
|
|
pc = preempt_count();
|
|
|
|
|
2008-05-12 15:20:42 -04:00
|
|
|
/* disable local data, not wakeup_cpu data */
|
|
|
|
cpu = raw_smp_processor_id();
|
2008-07-18 12:16:17 -04:00
|
|
|
disabled = atomic_inc_return(&wakeup_trace->data[cpu]->disabled);
|
2008-05-12 15:20:42 -04:00
|
|
|
if (likely(disabled != 1))
|
|
|
|
goto out;
|
|
|
|
|
2008-07-16 00:13:45 -04:00
|
|
|
local_irq_save(flags);
|
2009-12-02 14:01:25 -05:00
|
|
|
arch_spin_lock(&wakeup_lock);
|
2008-05-12 15:20:42 -04:00
|
|
|
|
|
|
|
/* We could race with grabbing wakeup_lock */
|
|
|
|
if (unlikely(!tracer_enabled || next != wakeup_task))
|
|
|
|
goto out_unlock;
|
|
|
|
|
2009-03-26 10:25:24 -04:00
|
|
|
/* The task we are waiting for is waking up */
|
|
|
|
data = wakeup_trace->data[wakeup_cpu];
|
|
|
|
|
tracing: remove CALLER_ADDR2 from wakeup tracer
Maneesh Soni was getting a crash when running the wakeup tracer.
We debugged it down to the recording of the function with the
CALLER_ADDR2 macro. This is used to get the location of the caller
to schedule.
But the problem comes when schedule is called by assmebly. In the case
that Maneesh had, retint_careful would call schedule. But retint_careful
does not set up a proper frame pointer. CALLER_ADDR2 is defined as
__builtin_return_address(2). This produces the following assembly in
the wakeup tracer code.
mov 0x0(%rbp),%rcx <--- get the frame pointer of the caller
mov %r14d,%r8d
mov 0xf2de8e(%rip),%rdi
mov 0x8(%rcx),%rsi <-- this is __builtin_return_address(1)
mov 0x28(%rdi,%rax,8),%rbx
mov (%rcx),%rax <-- get the frame pointer of the caller's caller
mov %r12,%rcx
mov 0x8(%rax),%rdx <-- this is __builtin_return_address(2)
At the reading of 0x8(%rax) Maneesh's machine would take a fault.
The reason is that retint_careful did not set up the return address
and the content of %rax here was zero.
To verify this, I sent Maneesh a patch to create a frame pointer
in retint_careful. He ran the test again but this time he would take
the same type of fault from sysret_careful. The retint_careful was no
longer an issue, but there are other callers that still have issues.
Instead of adding frame pointers for all callers to schedule (in possibly
all archs), it is much safer to simply not use CALLER_ADDR2. This
loses out on knowing what called schedule, but the function tracer
will help there if needed.
Reported-by: Maneesh Soni <maneesh@in.ibm.com>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
Signed-off-by: Ingo Molnar <mingo@elte.hu>
2009-04-03 11:12:23 -04:00
|
|
|
trace_function(wakeup_trace, CALLER_ADDR0, CALLER_ADDR1, flags, pc);
|
2009-02-05 01:13:37 -05:00
|
|
|
tracing_sched_switch_trace(wakeup_trace, prev, next, flags, pc);
|
2008-05-12 15:20:42 -04:00
|
|
|
|
|
|
|
T0 = data->preempt_timestamp;
|
2008-05-12 15:20:46 -04:00
|
|
|
T1 = ftrace_now(cpu);
|
2008-05-12 15:20:42 -04:00
|
|
|
delta = T1-T0;
|
|
|
|
|
|
|
|
if (!report_latency(delta))
|
|
|
|
goto out_unlock;
|
|
|
|
|
2009-09-12 19:43:07 -04:00
|
|
|
if (likely(!is_tracing_stopped())) {
|
|
|
|
tracing_max_latency = delta;
|
|
|
|
update_max_tr(wakeup_trace, wakeup_task, wakeup_cpu);
|
|
|
|
}
|
2008-05-12 15:20:42 -04:00
|
|
|
|
|
|
|
out_unlock:
|
2008-07-18 12:16:17 -04:00
|
|
|
__wakeup_reset(wakeup_trace);
|
2009-12-02 14:01:25 -05:00
|
|
|
arch_spin_unlock(&wakeup_lock);
|
2008-07-16 00:13:45 -04:00
|
|
|
local_irq_restore(flags);
|
2008-05-12 15:20:42 -04:00
|
|
|
out:
|
2008-07-18 12:16:17 -04:00
|
|
|
atomic_dec(&wakeup_trace->data[cpu]->disabled);
|
2008-05-12 15:21:10 -04:00
|
|
|
}
|
|
|
|
|
2008-05-12 15:20:51 -04:00
|
|
|
static void __wakeup_reset(struct trace_array *tr)
|
2008-05-12 15:20:42 -04:00
|
|
|
{
|
|
|
|
wakeup_cpu = -1;
|
|
|
|
wakeup_prio = -1;
|
|
|
|
|
|
|
|
if (wakeup_task)
|
|
|
|
put_task_struct(wakeup_task);
|
|
|
|
|
|
|
|
wakeup_task = NULL;
|
|
|
|
}
|
|
|
|
|
2008-05-12 15:20:51 -04:00
|
|
|
static void wakeup_reset(struct trace_array *tr)
|
2008-05-12 15:20:42 -04:00
|
|
|
{
|
|
|
|
unsigned long flags;
|
|
|
|
|
2009-09-01 11:06:29 -04:00
|
|
|
tracing_reset_online_cpus(tr);
|
|
|
|
|
2008-07-16 00:13:45 -04:00
|
|
|
local_irq_save(flags);
|
2009-12-02 14:01:25 -05:00
|
|
|
arch_spin_lock(&wakeup_lock);
|
2008-05-12 15:20:42 -04:00
|
|
|
__wakeup_reset(tr);
|
2009-12-02 14:01:25 -05:00
|
|
|
arch_spin_unlock(&wakeup_lock);
|
2008-07-16 00:13:45 -04:00
|
|
|
local_irq_restore(flags);
|
2008-05-12 15:20:42 -04:00
|
|
|
}
|
|
|
|
|
2008-05-12 15:20:51 -04:00
|
|
|
static void
|
2008-12-16 02:07:03 -05:00
|
|
|
probe_wakeup(struct rq *rq, struct task_struct *p, int success)
|
2008-05-12 15:20:42 -04:00
|
|
|
{
|
2009-01-21 17:17:04 -05:00
|
|
|
struct trace_array_cpu *data;
|
2008-05-12 15:20:42 -04:00
|
|
|
int cpu = smp_processor_id();
|
|
|
|
unsigned long flags;
|
|
|
|
long disabled;
|
2008-10-01 13:14:09 -04:00
|
|
|
int pc;
|
2008-05-12 15:20:42 -04:00
|
|
|
|
2008-07-18 12:16:17 -04:00
|
|
|
if (likely(!tracer_enabled))
|
|
|
|
return;
|
|
|
|
|
|
|
|
tracing_record_cmdline(p);
|
|
|
|
tracing_record_cmdline(current);
|
|
|
|
|
2009-01-21 16:24:46 -05:00
|
|
|
if ((wakeup_rt && !rt_task(p)) ||
|
2008-05-12 15:20:42 -04:00
|
|
|
p->prio >= wakeup_prio ||
|
2008-07-18 12:16:17 -04:00
|
|
|
p->prio >= current->prio)
|
2008-05-12 15:20:42 -04:00
|
|
|
return;
|
|
|
|
|
2008-10-01 13:14:09 -04:00
|
|
|
pc = preempt_count();
|
2008-07-18 12:16:17 -04:00
|
|
|
disabled = atomic_inc_return(&wakeup_trace->data[cpu]->disabled);
|
2008-05-12 15:20:42 -04:00
|
|
|
if (unlikely(disabled != 1))
|
|
|
|
goto out;
|
|
|
|
|
|
|
|
/* interrupts should be off from try_to_wake_up */
|
2009-12-02 14:01:25 -05:00
|
|
|
arch_spin_lock(&wakeup_lock);
|
2008-05-12 15:20:42 -04:00
|
|
|
|
|
|
|
/* check for races. */
|
|
|
|
if (!tracer_enabled || p->prio >= wakeup_prio)
|
|
|
|
goto out_locked;
|
|
|
|
|
|
|
|
/* reset the trace */
|
2008-07-18 12:16:17 -04:00
|
|
|
__wakeup_reset(wakeup_trace);
|
2008-05-12 15:20:42 -04:00
|
|
|
|
|
|
|
wakeup_cpu = task_cpu(p);
|
tracing: do not grab lock in wakeup latency function tracing
The wakeup tracer, when enabled, has its own function tracer.
It only traces the functions on the CPU where the task it is following
is on. If a task is woken on one CPU but then migrates to another CPU
before it wakes up, the latency tracer will then start tracing functions
on the other CPU.
To find which CPU the task is on, the wakeup function tracer performs
a task_cpu(wakeup_task). But to make sure the task does not disappear
it grabs the wakeup_lock, which is also taken when the task wakes up.
By taking this lock, the function tracer does not need to worry about
the task being freed as it checks its cpu.
Jan Blunck found a problem with this approach on his 32 CPU box. When
a task is being traced by the wakeup tracer, all functions take this
lock. That means that on all 32 CPUs, each function call is taking
this one lock to see if the task is on that CPU. This lock has just
serialized all functions on all 32 CPUs. Needless to say, this caused
major issues on that box. It would even lockup.
This patch changes the wakeup latency to insert a probe on the migrate task
tracepoint. When a task changes its CPU that it will run on, the
probe will take note. Now the wakeup function tracer no longer needs
to take the lock. It only compares the current CPU with a variable that
holds the current CPU the task is on. We don't worry about races since
it is OK to add or miss a function trace.
Reported-by: Jan Blunck <jblunck@suse.de>
Tested-by: Jan Blunck <jblunck@suse.de>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
2009-09-09 10:36:01 -04:00
|
|
|
wakeup_current_cpu = wakeup_cpu;
|
2008-05-12 15:20:42 -04:00
|
|
|
wakeup_prio = p->prio;
|
|
|
|
|
|
|
|
wakeup_task = p;
|
|
|
|
get_task_struct(wakeup_task);
|
|
|
|
|
|
|
|
local_save_flags(flags);
|
|
|
|
|
2009-01-21 17:17:04 -05:00
|
|
|
data = wakeup_trace->data[wakeup_cpu];
|
|
|
|
data->preempt_timestamp = ftrace_now(cpu);
|
2009-02-05 01:13:37 -05:00
|
|
|
tracing_sched_wakeup_trace(wakeup_trace, p, current, flags, pc);
|
tracing: remove CALLER_ADDR2 from wakeup tracer
Maneesh Soni was getting a crash when running the wakeup tracer.
We debugged it down to the recording of the function with the
CALLER_ADDR2 macro. This is used to get the location of the caller
to schedule.
But the problem comes when schedule is called by assmebly. In the case
that Maneesh had, retint_careful would call schedule. But retint_careful
does not set up a proper frame pointer. CALLER_ADDR2 is defined as
__builtin_return_address(2). This produces the following assembly in
the wakeup tracer code.
mov 0x0(%rbp),%rcx <--- get the frame pointer of the caller
mov %r14d,%r8d
mov 0xf2de8e(%rip),%rdi
mov 0x8(%rcx),%rsi <-- this is __builtin_return_address(1)
mov 0x28(%rdi,%rax,8),%rbx
mov (%rcx),%rax <-- get the frame pointer of the caller's caller
mov %r12,%rcx
mov 0x8(%rax),%rdx <-- this is __builtin_return_address(2)
At the reading of 0x8(%rax) Maneesh's machine would take a fault.
The reason is that retint_careful did not set up the return address
and the content of %rax here was zero.
To verify this, I sent Maneesh a patch to create a frame pointer
in retint_careful. He ran the test again but this time he would take
the same type of fault from sysret_careful. The retint_careful was no
longer an issue, but there are other callers that still have issues.
Instead of adding frame pointers for all callers to schedule (in possibly
all archs), it is much safer to simply not use CALLER_ADDR2. This
loses out on knowing what called schedule, but the function tracer
will help there if needed.
Reported-by: Maneesh Soni <maneesh@in.ibm.com>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
Signed-off-by: Ingo Molnar <mingo@elte.hu>
2009-04-03 11:12:23 -04:00
|
|
|
|
|
|
|
/*
|
|
|
|
* We must be careful in using CALLER_ADDR2. But since wake_up
|
|
|
|
* is not called by an assembly function (where as schedule is)
|
|
|
|
* it should be safe to use it here.
|
|
|
|
*/
|
2009-02-05 01:13:37 -05:00
|
|
|
trace_function(wakeup_trace, CALLER_ADDR1, CALLER_ADDR2, flags, pc);
|
2008-05-12 15:20:42 -04:00
|
|
|
|
|
|
|
out_locked:
|
2009-12-02 14:01:25 -05:00
|
|
|
arch_spin_unlock(&wakeup_lock);
|
2008-05-12 15:20:42 -04:00
|
|
|
out:
|
2008-07-18 12:16:17 -04:00
|
|
|
atomic_dec(&wakeup_trace->data[cpu]->disabled);
|
2008-05-12 15:20:42 -04:00
|
|
|
}
|
|
|
|
|
2008-05-12 15:20:51 -04:00
|
|
|
static void start_wakeup_tracer(struct trace_array *tr)
|
2008-05-12 15:20:42 -04:00
|
|
|
{
|
2008-05-12 15:21:10 -04:00
|
|
|
int ret;
|
|
|
|
|
2008-07-18 12:16:17 -04:00
|
|
|
ret = register_trace_sched_wakeup(probe_wakeup);
|
2008-05-12 15:21:10 -04:00
|
|
|
if (ret) {
|
2008-07-18 12:16:17 -04:00
|
|
|
pr_info("wakeup trace: Couldn't activate tracepoint"
|
2008-05-12 15:21:10 -04:00
|
|
|
" probe to kernel_sched_wakeup\n");
|
|
|
|
return;
|
|
|
|
}
|
|
|
|
|
2008-07-18 12:16:17 -04:00
|
|
|
ret = register_trace_sched_wakeup_new(probe_wakeup);
|
2008-05-12 15:21:10 -04:00
|
|
|
if (ret) {
|
2008-07-18 12:16:17 -04:00
|
|
|
pr_info("wakeup trace: Couldn't activate tracepoint"
|
2008-05-12 15:21:10 -04:00
|
|
|
" probe to kernel_sched_wakeup_new\n");
|
|
|
|
goto fail_deprobe;
|
|
|
|
}
|
|
|
|
|
2008-07-18 12:16:17 -04:00
|
|
|
ret = register_trace_sched_switch(probe_wakeup_sched_switch);
|
2008-05-12 15:21:10 -04:00
|
|
|
if (ret) {
|
2008-07-18 12:16:17 -04:00
|
|
|
pr_info("sched trace: Couldn't activate tracepoint"
|
2009-02-17 01:10:02 -05:00
|
|
|
" probe to kernel_sched_switch\n");
|
2008-05-12 15:21:10 -04:00
|
|
|
goto fail_deprobe_wake_new;
|
|
|
|
}
|
|
|
|
|
tracing: do not grab lock in wakeup latency function tracing
The wakeup tracer, when enabled, has its own function tracer.
It only traces the functions on the CPU where the task it is following
is on. If a task is woken on one CPU but then migrates to another CPU
before it wakes up, the latency tracer will then start tracing functions
on the other CPU.
To find which CPU the task is on, the wakeup function tracer performs
a task_cpu(wakeup_task). But to make sure the task does not disappear
it grabs the wakeup_lock, which is also taken when the task wakes up.
By taking this lock, the function tracer does not need to worry about
the task being freed as it checks its cpu.
Jan Blunck found a problem with this approach on his 32 CPU box. When
a task is being traced by the wakeup tracer, all functions take this
lock. That means that on all 32 CPUs, each function call is taking
this one lock to see if the task is on that CPU. This lock has just
serialized all functions on all 32 CPUs. Needless to say, this caused
major issues on that box. It would even lockup.
This patch changes the wakeup latency to insert a probe on the migrate task
tracepoint. When a task changes its CPU that it will run on, the
probe will take note. Now the wakeup function tracer no longer needs
to take the lock. It only compares the current CPU with a variable that
holds the current CPU the task is on. We don't worry about races since
it is OK to add or miss a function trace.
Reported-by: Jan Blunck <jblunck@suse.de>
Tested-by: Jan Blunck <jblunck@suse.de>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
2009-09-09 10:36:01 -04:00
|
|
|
ret = register_trace_sched_migrate_task(probe_wakeup_migrate_task);
|
|
|
|
if (ret) {
|
|
|
|
pr_info("wakeup trace: Couldn't activate tracepoint"
|
|
|
|
" probe to kernel_sched_migrate_task\n");
|
|
|
|
return;
|
|
|
|
}
|
|
|
|
|
2008-05-12 15:20:42 -04:00
|
|
|
wakeup_reset(tr);
|
|
|
|
|
|
|
|
/*
|
|
|
|
* Don't let the tracer_enabled = 1 show up before
|
|
|
|
* the wakeup_task is reset. This may be overkill since
|
|
|
|
* wakeup_reset does a spin_unlock after setting the
|
|
|
|
* wakeup_task to NULL, but I want to be safe.
|
|
|
|
* This is a slow path anyway.
|
|
|
|
*/
|
|
|
|
smp_wmb();
|
|
|
|
|
2008-05-22 00:22:19 -04:00
|
|
|
register_ftrace_function(&trace_ops);
|
2008-05-12 15:20:42 -04:00
|
|
|
|
2009-01-21 14:36:52 -05:00
|
|
|
if (tracing_is_enabled())
|
ftrace: restructure tracing start/stop infrastructure
Impact: change where tracing is started up and stopped
Currently, when a new tracer is selected via echo'ing a tracer name into
the current_tracer file, the startup is only done if tracing_enabled is
set to one. If tracing_enabled is changed to zero (by echo'ing 0 into
the tracing_enabled file) a full shutdown is performed.
The full startup and shutdown of a tracer can be expensive and the
user can lose out traces when echo'ing in 0 to the tracing_enabled file,
because the process takes too long. There can also be places that
the user would like to start and stop the tracer several times and
doing the full startup and shutdown of a tracer might be too expensive.
This patch performs the full startup and shutdown when a tracer is
selected. It also adds a way to do a quick start or stop of a tracer.
The quick version is just a flag that prevents the tracing from
taking place, but the overhead of the code is still there.
For example, the startup of a tracer may enable tracepoints, or enable
the function tracer. The stop and start will just set a flag to
have the tracer ignore the calls when the tracepoint or function trace
is called. The overhead of the tracer may still be present when
the tracer is stopped, but no tracing will occur. Setting the tracer
to the 'nop' tracer (or any other tracer) will perform the shutdown
of the tracer which will disable the tracepoint or disable the
function tracer.
The tracing_enabled file will simply start or stop tracing.
This change is all internal. The end result for the user should be the same
as before. If tracing_enabled is not set, no trace will happen.
If tracing_enabled is set, then the trace will happen. The tracing_enabled
variable is static between tracers. Enabling tracing_enabled and
going to another tracer will keep tracing_enabled enabled. Same
is true with disabling tracing_enabled.
This patch will now provide a fast start/stop method to the users
for enabling or disabling tracing.
Note: There were two methods to the struct tracer that were never
used: The methods start and stop. These were to be used as a hook
to the reading of the trace output, but ended up not being
necessary. These two methods are now used to enable the start
and stop of each tracer, in case the tracer needs to do more than
just not write into the buffer. For example, the irqsoff tracer
must stop recording max latencies when tracing is stopped.
Signed-off-by: Steven Rostedt <srostedt@redhat.com>
Signed-off-by: Ingo Molnar <mingo@elte.hu>
2008-11-05 16:05:44 -05:00
|
|
|
tracer_enabled = 1;
|
2009-01-21 14:36:52 -05:00
|
|
|
else
|
ftrace: restructure tracing start/stop infrastructure
Impact: change where tracing is started up and stopped
Currently, when a new tracer is selected via echo'ing a tracer name into
the current_tracer file, the startup is only done if tracing_enabled is
set to one. If tracing_enabled is changed to zero (by echo'ing 0 into
the tracing_enabled file) a full shutdown is performed.
The full startup and shutdown of a tracer can be expensive and the
user can lose out traces when echo'ing in 0 to the tracing_enabled file,
because the process takes too long. There can also be places that
the user would like to start and stop the tracer several times and
doing the full startup and shutdown of a tracer might be too expensive.
This patch performs the full startup and shutdown when a tracer is
selected. It also adds a way to do a quick start or stop of a tracer.
The quick version is just a flag that prevents the tracing from
taking place, but the overhead of the code is still there.
For example, the startup of a tracer may enable tracepoints, or enable
the function tracer. The stop and start will just set a flag to
have the tracer ignore the calls when the tracepoint or function trace
is called. The overhead of the tracer may still be present when
the tracer is stopped, but no tracing will occur. Setting the tracer
to the 'nop' tracer (or any other tracer) will perform the shutdown
of the tracer which will disable the tracepoint or disable the
function tracer.
The tracing_enabled file will simply start or stop tracing.
This change is all internal. The end result for the user should be the same
as before. If tracing_enabled is not set, no trace will happen.
If tracing_enabled is set, then the trace will happen. The tracing_enabled
variable is static between tracers. Enabling tracing_enabled and
going to another tracer will keep tracing_enabled enabled. Same
is true with disabling tracing_enabled.
This patch will now provide a fast start/stop method to the users
for enabling or disabling tracing.
Note: There were two methods to the struct tracer that were never
used: The methods start and stop. These were to be used as a hook
to the reading of the trace output, but ended up not being
necessary. These two methods are now used to enable the start
and stop of each tracer, in case the tracer needs to do more than
just not write into the buffer. For example, the irqsoff tracer
must stop recording max latencies when tracing is stopped.
Signed-off-by: Steven Rostedt <srostedt@redhat.com>
Signed-off-by: Ingo Molnar <mingo@elte.hu>
2008-11-05 16:05:44 -05:00
|
|
|
tracer_enabled = 0;
|
2008-07-10 20:58:13 -04:00
|
|
|
|
2008-05-12 15:20:42 -04:00
|
|
|
return;
|
2008-05-12 15:21:10 -04:00
|
|
|
fail_deprobe_wake_new:
|
2008-07-18 12:16:17 -04:00
|
|
|
unregister_trace_sched_wakeup_new(probe_wakeup);
|
2008-05-12 15:21:10 -04:00
|
|
|
fail_deprobe:
|
2008-07-18 12:16:17 -04:00
|
|
|
unregister_trace_sched_wakeup(probe_wakeup);
|
2008-05-12 15:20:42 -04:00
|
|
|
}
|
|
|
|
|
2008-05-12 15:20:51 -04:00
|
|
|
static void stop_wakeup_tracer(struct trace_array *tr)
|
2008-05-12 15:20:42 -04:00
|
|
|
{
|
|
|
|
tracer_enabled = 0;
|
2008-05-22 00:22:19 -04:00
|
|
|
unregister_ftrace_function(&trace_ops);
|
2008-07-18 12:16:17 -04:00
|
|
|
unregister_trace_sched_switch(probe_wakeup_sched_switch);
|
|
|
|
unregister_trace_sched_wakeup_new(probe_wakeup);
|
|
|
|
unregister_trace_sched_wakeup(probe_wakeup);
|
tracing: do not grab lock in wakeup latency function tracing
The wakeup tracer, when enabled, has its own function tracer.
It only traces the functions on the CPU where the task it is following
is on. If a task is woken on one CPU but then migrates to another CPU
before it wakes up, the latency tracer will then start tracing functions
on the other CPU.
To find which CPU the task is on, the wakeup function tracer performs
a task_cpu(wakeup_task). But to make sure the task does not disappear
it grabs the wakeup_lock, which is also taken when the task wakes up.
By taking this lock, the function tracer does not need to worry about
the task being freed as it checks its cpu.
Jan Blunck found a problem with this approach on his 32 CPU box. When
a task is being traced by the wakeup tracer, all functions take this
lock. That means that on all 32 CPUs, each function call is taking
this one lock to see if the task is on that CPU. This lock has just
serialized all functions on all 32 CPUs. Needless to say, this caused
major issues on that box. It would even lockup.
This patch changes the wakeup latency to insert a probe on the migrate task
tracepoint. When a task changes its CPU that it will run on, the
probe will take note. Now the wakeup function tracer no longer needs
to take the lock. It only compares the current CPU with a variable that
holds the current CPU the task is on. We don't worry about races since
it is OK to add or miss a function trace.
Reported-by: Jan Blunck <jblunck@suse.de>
Tested-by: Jan Blunck <jblunck@suse.de>
Signed-off-by: Steven Rostedt <rostedt@goodmis.org>
2009-09-09 10:36:01 -04:00
|
|
|
unregister_trace_sched_migrate_task(probe_wakeup_migrate_task);
|
2008-05-12 15:20:42 -04:00
|
|
|
}
|
|
|
|
|
2009-01-21 16:24:46 -05:00
|
|
|
static int __wakeup_tracer_init(struct trace_array *tr)
|
2008-05-12 15:20:42 -04:00
|
|
|
{
|
2009-03-04 22:15:30 -05:00
|
|
|
save_lat_flag = trace_flags & TRACE_ITER_LATENCY_FMT;
|
|
|
|
trace_flags |= TRACE_ITER_LATENCY_FMT;
|
|
|
|
|
2009-01-15 23:40:11 -05:00
|
|
|
tracing_max_latency = 0;
|
2008-05-12 15:20:42 -04:00
|
|
|
wakeup_trace = tr;
|
2008-11-07 22:36:02 -05:00
|
|
|
start_wakeup_tracer(tr);
|
2008-11-15 23:57:26 -05:00
|
|
|
return 0;
|
2008-05-12 15:20:42 -04:00
|
|
|
}
|
|
|
|
|
2009-01-21 16:24:46 -05:00
|
|
|
static int wakeup_tracer_init(struct trace_array *tr)
|
|
|
|
{
|
|
|
|
wakeup_rt = 0;
|
|
|
|
return __wakeup_tracer_init(tr);
|
|
|
|
}
|
|
|
|
|
|
|
|
static int wakeup_rt_tracer_init(struct trace_array *tr)
|
|
|
|
{
|
|
|
|
wakeup_rt = 1;
|
|
|
|
return __wakeup_tracer_init(tr);
|
|
|
|
}
|
|
|
|
|
2008-05-12 15:20:51 -04:00
|
|
|
static void wakeup_tracer_reset(struct trace_array *tr)
|
2008-05-12 15:20:42 -04:00
|
|
|
{
|
2008-11-07 22:36:02 -05:00
|
|
|
stop_wakeup_tracer(tr);
|
|
|
|
/* make sure we put back any tasks we are tracing */
|
|
|
|
wakeup_reset(tr);
|
2009-03-04 22:15:30 -05:00
|
|
|
|
|
|
|
if (!save_lat_flag)
|
|
|
|
trace_flags &= ~TRACE_ITER_LATENCY_FMT;
|
2008-05-12 15:20:42 -04:00
|
|
|
}
|
|
|
|
|
ftrace: restructure tracing start/stop infrastructure
Impact: change where tracing is started up and stopped
Currently, when a new tracer is selected via echo'ing a tracer name into
the current_tracer file, the startup is only done if tracing_enabled is
set to one. If tracing_enabled is changed to zero (by echo'ing 0 into
the tracing_enabled file) a full shutdown is performed.
The full startup and shutdown of a tracer can be expensive and the
user can lose out traces when echo'ing in 0 to the tracing_enabled file,
because the process takes too long. There can also be places that
the user would like to start and stop the tracer several times and
doing the full startup and shutdown of a tracer might be too expensive.
This patch performs the full startup and shutdown when a tracer is
selected. It also adds a way to do a quick start or stop of a tracer.
The quick version is just a flag that prevents the tracing from
taking place, but the overhead of the code is still there.
For example, the startup of a tracer may enable tracepoints, or enable
the function tracer. The stop and start will just set a flag to
have the tracer ignore the calls when the tracepoint or function trace
is called. The overhead of the tracer may still be present when
the tracer is stopped, but no tracing will occur. Setting the tracer
to the 'nop' tracer (or any other tracer) will perform the shutdown
of the tracer which will disable the tracepoint or disable the
function tracer.
The tracing_enabled file will simply start or stop tracing.
This change is all internal. The end result for the user should be the same
as before. If tracing_enabled is not set, no trace will happen.
If tracing_enabled is set, then the trace will happen. The tracing_enabled
variable is static between tracers. Enabling tracing_enabled and
going to another tracer will keep tracing_enabled enabled. Same
is true with disabling tracing_enabled.
This patch will now provide a fast start/stop method to the users
for enabling or disabling tracing.
Note: There were two methods to the struct tracer that were never
used: The methods start and stop. These were to be used as a hook
to the reading of the trace output, but ended up not being
necessary. These two methods are now used to enable the start
and stop of each tracer, in case the tracer needs to do more than
just not write into the buffer. For example, the irqsoff tracer
must stop recording max latencies when tracing is stopped.
Signed-off-by: Steven Rostedt <srostedt@redhat.com>
Signed-off-by: Ingo Molnar <mingo@elte.hu>
2008-11-05 16:05:44 -05:00
|
|
|
static void wakeup_tracer_start(struct trace_array *tr)
|
|
|
|
{
|
|
|
|
wakeup_reset(tr);
|
|
|
|
tracer_enabled = 1;
|
|
|
|
}
|
|
|
|
|
|
|
|
static void wakeup_tracer_stop(struct trace_array *tr)
|
|
|
|
{
|
|
|
|
tracer_enabled = 0;
|
2008-05-12 15:20:42 -04:00
|
|
|
}
|
|
|
|
|
|
|
|
static struct tracer wakeup_tracer __read_mostly =
|
|
|
|
{
|
|
|
|
.name = "wakeup",
|
|
|
|
.init = wakeup_tracer_init,
|
|
|
|
.reset = wakeup_tracer_reset,
|
ftrace: restructure tracing start/stop infrastructure
Impact: change where tracing is started up and stopped
Currently, when a new tracer is selected via echo'ing a tracer name into
the current_tracer file, the startup is only done if tracing_enabled is
set to one. If tracing_enabled is changed to zero (by echo'ing 0 into
the tracing_enabled file) a full shutdown is performed.
The full startup and shutdown of a tracer can be expensive and the
user can lose out traces when echo'ing in 0 to the tracing_enabled file,
because the process takes too long. There can also be places that
the user would like to start and stop the tracer several times and
doing the full startup and shutdown of a tracer might be too expensive.
This patch performs the full startup and shutdown when a tracer is
selected. It also adds a way to do a quick start or stop of a tracer.
The quick version is just a flag that prevents the tracing from
taking place, but the overhead of the code is still there.
For example, the startup of a tracer may enable tracepoints, or enable
the function tracer. The stop and start will just set a flag to
have the tracer ignore the calls when the tracepoint or function trace
is called. The overhead of the tracer may still be present when
the tracer is stopped, but no tracing will occur. Setting the tracer
to the 'nop' tracer (or any other tracer) will perform the shutdown
of the tracer which will disable the tracepoint or disable the
function tracer.
The tracing_enabled file will simply start or stop tracing.
This change is all internal. The end result for the user should be the same
as before. If tracing_enabled is not set, no trace will happen.
If tracing_enabled is set, then the trace will happen. The tracing_enabled
variable is static between tracers. Enabling tracing_enabled and
going to another tracer will keep tracing_enabled enabled. Same
is true with disabling tracing_enabled.
This patch will now provide a fast start/stop method to the users
for enabling or disabling tracing.
Note: There were two methods to the struct tracer that were never
used: The methods start and stop. These were to be used as a hook
to the reading of the trace output, but ended up not being
necessary. These two methods are now used to enable the start
and stop of each tracer, in case the tracer needs to do more than
just not write into the buffer. For example, the irqsoff tracer
must stop recording max latencies when tracing is stopped.
Signed-off-by: Steven Rostedt <srostedt@redhat.com>
Signed-off-by: Ingo Molnar <mingo@elte.hu>
2008-11-05 16:05:44 -05:00
|
|
|
.start = wakeup_tracer_start,
|
|
|
|
.stop = wakeup_tracer_stop,
|
2008-05-12 15:20:42 -04:00
|
|
|
.print_max = 1,
|
2008-05-12 15:20:44 -04:00
|
|
|
#ifdef CONFIG_FTRACE_SELFTEST
|
|
|
|
.selftest = trace_selftest_startup_wakeup,
|
|
|
|
#endif
|
2008-05-12 15:20:42 -04:00
|
|
|
};
|
|
|
|
|
2009-01-21 16:24:46 -05:00
|
|
|
static struct tracer wakeup_rt_tracer __read_mostly =
|
|
|
|
{
|
|
|
|
.name = "wakeup_rt",
|
|
|
|
.init = wakeup_rt_tracer_init,
|
|
|
|
.reset = wakeup_tracer_reset,
|
|
|
|
.start = wakeup_tracer_start,
|
|
|
|
.stop = wakeup_tracer_stop,
|
2009-02-10 20:25:00 -05:00
|
|
|
.wait_pipe = poll_wait_pipe,
|
2009-01-21 16:24:46 -05:00
|
|
|
.print_max = 1,
|
|
|
|
#ifdef CONFIG_FTRACE_SELFTEST
|
|
|
|
.selftest = trace_selftest_startup_wakeup,
|
|
|
|
#endif
|
|
|
|
};
|
|
|
|
|
2008-05-12 15:20:42 -04:00
|
|
|
__init static int init_wakeup_tracer(void)
|
|
|
|
{
|
|
|
|
int ret;
|
|
|
|
|
|
|
|
ret = register_tracer(&wakeup_tracer);
|
|
|
|
if (ret)
|
|
|
|
return ret;
|
|
|
|
|
2009-01-21 16:24:46 -05:00
|
|
|
ret = register_tracer(&wakeup_rt_tracer);
|
|
|
|
if (ret)
|
|
|
|
return ret;
|
|
|
|
|
2008-05-12 15:20:42 -04:00
|
|
|
return 0;
|
|
|
|
}
|
|
|
|
device_initcall(init_wakeup_tracer);
|