2008-09-23 12:32:08 +02:00
|
|
|
/*
|
|
|
|
* ring buffer based initcalls tracer
|
|
|
|
*
|
|
|
|
* Copyright (C) 2008 Frederic Weisbecker <fweisbec@gmail.com>
|
|
|
|
*
|
|
|
|
*/
|
|
|
|
|
|
|
|
#include <linux/init.h>
|
|
|
|
#include <linux/debugfs.h>
|
|
|
|
#include <linux/ftrace.h>
|
2008-10-02 13:26:05 +02:00
|
|
|
#include <linux/kallsyms.h>
|
2009-03-27 07:55:44 +01:00
|
|
|
#include <linux/time.h>
|
2008-09-23 12:32:08 +02:00
|
|
|
|
|
|
|
#include "trace.h"
|
2008-12-24 05:24:12 +01:00
|
|
|
#include "trace_output.h"
|
2008-09-23 12:32:08 +02:00
|
|
|
|
|
|
|
static struct trace_array *boot_trace;
|
2008-10-31 12:57:20 +01:00
|
|
|
static bool pre_initcalls_finished;
|
2008-09-23 12:32:08 +02:00
|
|
|
|
2008-10-31 12:57:20 +01:00
|
|
|
/* Tells the boot tracer that the pre_smp_initcalls are finished.
|
|
|
|
* So we are ready .
|
|
|
|
* It doesn't enable sched events tracing however.
|
|
|
|
* You have to call enable_boot_trace to do so.
|
|
|
|
*/
|
2008-09-23 12:32:08 +02:00
|
|
|
void start_boot_trace(void)
|
|
|
|
{
|
2008-10-31 12:57:20 +01:00
|
|
|
pre_initcalls_finished = true;
|
|
|
|
}
|
|
|
|
|
|
|
|
void enable_boot_trace(void)
|
|
|
|
{
|
2009-02-03 03:38:33 +01:00
|
|
|
if (boot_trace && pre_initcalls_finished)
|
2008-11-08 04:36:02 +01:00
|
|
|
tracing_start_sched_switch_record();
|
2008-09-23 12:32:08 +02:00
|
|
|
}
|
|
|
|
|
2008-10-31 12:57:20 +01:00
|
|
|
void disable_boot_trace(void)
|
2008-09-23 12:32:08 +02:00
|
|
|
{
|
2009-02-03 03:38:33 +01:00
|
|
|
if (boot_trace && pre_initcalls_finished)
|
2008-11-08 04:36:02 +01:00
|
|
|
tracing_stop_sched_switch_record();
|
2008-09-23 12:32:08 +02:00
|
|
|
}
|
|
|
|
|
2008-11-16 05:57:26 +01:00
|
|
|
static int boot_trace_init(struct trace_array *tr)
|
2008-09-23 12:32:08 +02:00
|
|
|
{
|
|
|
|
boot_trace = tr;
|
|
|
|
|
2009-02-03 03:38:33 +01:00
|
|
|
if (!tr)
|
|
|
|
return 0;
|
|
|
|
|
2009-09-04 18:12:39 +02:00
|
|
|
tracing_reset_online_cpus(tr);
|
2008-10-31 13:20:08 +01:00
|
|
|
|
2008-11-08 04:36:02 +01:00
|
|
|
tracing_sched_switch_assign_trace(tr);
|
2008-11-16 05:57:26 +01:00
|
|
|
return 0;
|
2008-09-23 12:32:08 +02:00
|
|
|
}
|
|
|
|
|
2008-11-11 23:24:42 +01:00
|
|
|
static enum print_line_t
|
|
|
|
initcall_call_print_line(struct trace_iterator *iter)
|
2008-09-23 12:32:08 +02:00
|
|
|
{
|
2008-11-11 23:24:42 +01:00
|
|
|
struct trace_entry *entry = iter->ent;
|
|
|
|
struct trace_seq *s = &iter->seq;
|
|
|
|
struct trace_boot_call *field;
|
|
|
|
struct boot_trace_call *call;
|
|
|
|
u64 ts;
|
|
|
|
unsigned long nsec_rem;
|
2008-09-29 20:31:58 +02:00
|
|
|
int ret;
|
2008-11-11 23:24:42 +01:00
|
|
|
|
|
|
|
trace_assign_type(field, entry);
|
|
|
|
call = &field->boot_call;
|
|
|
|
ts = iter->ts;
|
2009-03-27 07:55:44 +01:00
|
|
|
nsec_rem = do_div(ts, NSEC_PER_SEC);
|
2008-11-11 23:24:42 +01:00
|
|
|
|
|
|
|
ret = trace_seq_printf(s, "[%5ld.%09ld] calling %s @ %i\n",
|
|
|
|
(unsigned long)ts, nsec_rem, call->func, call->caller);
|
|
|
|
|
|
|
|
if (!ret)
|
|
|
|
return TRACE_TYPE_PARTIAL_LINE;
|
|
|
|
else
|
|
|
|
return TRACE_TYPE_HANDLED;
|
|
|
|
}
|
|
|
|
|
|
|
|
static enum print_line_t
|
|
|
|
initcall_ret_print_line(struct trace_iterator *iter)
|
|
|
|
{
|
2008-09-23 12:32:08 +02:00
|
|
|
struct trace_entry *entry = iter->ent;
|
|
|
|
struct trace_seq *s = &iter->seq;
|
2008-11-11 23:24:42 +01:00
|
|
|
struct trace_boot_ret *field;
|
|
|
|
struct boot_trace_ret *init_ret;
|
|
|
|
u64 ts;
|
|
|
|
unsigned long nsec_rem;
|
|
|
|
int ret;
|
|
|
|
|
|
|
|
trace_assign_type(field, entry);
|
|
|
|
init_ret = &field->boot_ret;
|
|
|
|
ts = iter->ts;
|
2009-03-27 07:55:44 +01:00
|
|
|
nsec_rem = do_div(ts, NSEC_PER_SEC);
|
2008-11-11 23:24:42 +01:00
|
|
|
|
|
|
|
ret = trace_seq_printf(s, "[%5ld.%09ld] initcall %s "
|
|
|
|
"returned %d after %llu msecs\n",
|
|
|
|
(unsigned long) ts,
|
|
|
|
nsec_rem,
|
|
|
|
init_ret->func, init_ret->result, init_ret->duration);
|
|
|
|
|
|
|
|
if (!ret)
|
|
|
|
return TRACE_TYPE_PARTIAL_LINE;
|
|
|
|
else
|
2008-10-02 12:59:20 +02:00
|
|
|
return TRACE_TYPE_HANDLED;
|
2008-11-11 23:24:42 +01:00
|
|
|
}
|
|
|
|
|
|
|
|
static enum print_line_t initcall_print_line(struct trace_iterator *iter)
|
|
|
|
{
|
|
|
|
struct trace_entry *entry = iter->ent;
|
|
|
|
|
|
|
|
switch (entry->type) {
|
|
|
|
case TRACE_BOOT_CALL:
|
|
|
|
return initcall_call_print_line(iter);
|
|
|
|
case TRACE_BOOT_RET:
|
|
|
|
return initcall_ret_print_line(iter);
|
|
|
|
default:
|
|
|
|
return TRACE_TYPE_UNHANDLED;
|
2008-09-29 20:31:58 +02:00
|
|
|
}
|
2008-09-23 12:32:08 +02:00
|
|
|
}
|
|
|
|
|
|
|
|
struct tracer boot_tracer __read_mostly =
|
|
|
|
{
|
|
|
|
.name = "initcall",
|
|
|
|
.init = boot_trace_init,
|
2008-12-19 11:08:39 +01:00
|
|
|
.reset = tracing_reset_online_cpus,
|
2008-09-23 12:32:08 +02:00
|
|
|
.print_line = initcall_print_line,
|
|
|
|
};
|
|
|
|
|
2008-11-11 23:24:42 +01:00
|
|
|
void trace_boot_call(struct boot_trace_call *bt, initcall_t fn)
|
2008-09-23 12:32:08 +02:00
|
|
|
{
|
2009-09-13 05:34:04 +02:00
|
|
|
struct ftrace_event_call *call = &event_boot_call;
|
2008-09-30 05:02:41 +02:00
|
|
|
struct ring_buffer_event *event;
|
2009-09-02 20:17:06 +02:00
|
|
|
struct ring_buffer *buffer;
|
2008-11-11 23:24:42 +01:00
|
|
|
struct trace_boot_call *entry;
|
2008-09-23 12:32:08 +02:00
|
|
|
struct trace_array *tr = boot_trace;
|
|
|
|
|
2009-02-03 03:38:33 +01:00
|
|
|
if (!tr || !pre_initcalls_finished)
|
2008-09-23 12:32:08 +02:00
|
|
|
return;
|
|
|
|
|
2008-10-02 13:26:05 +02:00
|
|
|
/* Get its name now since this function could
|
|
|
|
* disappear because it is in the .init section.
|
|
|
|
*/
|
2008-11-11 23:24:42 +01:00
|
|
|
sprint_symbol(bt->func, (unsigned long)fn);
|
|
|
|
preempt_disable();
|
|
|
|
|
2009-09-02 20:17:06 +02:00
|
|
|
buffer = tr->buffer;
|
|
|
|
event = trace_buffer_lock_reserve(buffer, TRACE_BOOT_CALL,
|
tracing: Introduce trace_buffer_{lock_reserve,unlock_commit}
Impact: new API
These new functions do what previously was being open coded, reducing
the number of details ftrace plugin writers have to worry about.
It also standardizes the handling of stacktrace, userstacktrace and
other trace options we may introduce in the future.
With this patch, for instance, the blk tracer (and some others already
in the tree) can use the "userstacktrace" /d/tracing/trace_options
facility.
$ codiff /tmp/vmlinux.before /tmp/vmlinux.after
linux-2.6-tip/kernel/trace/trace.c:
trace_vprintk | -5
trace_graph_return | -22
trace_graph_entry | -26
trace_function | -45
__ftrace_trace_stack | -27
ftrace_trace_userstack | -29
tracing_sched_switch_trace | -66
tracing_stop | +1
trace_seq_to_user | -1
ftrace_trace_special | -63
ftrace_special | +1
tracing_sched_wakeup_trace | -70
tracing_reset_online_cpus | -1
13 functions changed, 2 bytes added, 355 bytes removed, diff: -353
linux-2.6-tip/block/blktrace.c:
__blk_add_trace | -58
1 function changed, 58 bytes removed, diff: -58
linux-2.6-tip/kernel/trace/trace.c:
trace_buffer_lock_reserve | +88
trace_buffer_unlock_commit | +86
2 functions changed, 174 bytes added, diff: +174
/tmp/vmlinux.after:
16 functions changed, 176 bytes added, 413 bytes removed, diff: -237
Signed-off-by: Arnaldo Carvalho de Melo <acme@redhat.com>
Acked-by: Frédéric Weisbecker <fweisbec@gmail.com>
Signed-off-by: Ingo Molnar <mingo@elte.hu>
2009-02-05 19:14:13 +01:00
|
|
|
sizeof(*entry), 0, 0);
|
2008-11-11 23:24:42 +01:00
|
|
|
if (!event)
|
|
|
|
goto out;
|
|
|
|
entry = ring_buffer_event_data(event);
|
|
|
|
entry->boot_call = *bt;
|
2009-09-13 05:34:04 +02:00
|
|
|
if (!filter_check_discard(call, entry, buffer, event))
|
|
|
|
trace_buffer_unlock_commit(buffer, event, 0, 0);
|
2008-11-11 23:24:42 +01:00
|
|
|
out:
|
|
|
|
preempt_enable();
|
|
|
|
}
|
|
|
|
|
|
|
|
void trace_boot_ret(struct boot_trace_ret *bt, initcall_t fn)
|
|
|
|
{
|
2009-09-13 05:34:04 +02:00
|
|
|
struct ftrace_event_call *call = &event_boot_ret;
|
2008-11-11 23:24:42 +01:00
|
|
|
struct ring_buffer_event *event;
|
2009-09-02 20:17:06 +02:00
|
|
|
struct ring_buffer *buffer;
|
2008-11-11 23:24:42 +01:00
|
|
|
struct trace_boot_ret *entry;
|
|
|
|
struct trace_array *tr = boot_trace;
|
|
|
|
|
2009-02-03 03:38:33 +01:00
|
|
|
if (!tr || !pre_initcalls_finished)
|
2008-11-11 23:24:42 +01:00
|
|
|
return;
|
|
|
|
|
|
|
|
sprint_symbol(bt->func, (unsigned long)fn);
|
2008-09-23 12:32:08 +02:00
|
|
|
preempt_disable();
|
|
|
|
|
2009-09-02 20:17:06 +02:00
|
|
|
buffer = tr->buffer;
|
|
|
|
event = trace_buffer_lock_reserve(buffer, TRACE_BOOT_RET,
|
tracing: Introduce trace_buffer_{lock_reserve,unlock_commit}
Impact: new API
These new functions do what previously was being open coded, reducing
the number of details ftrace plugin writers have to worry about.
It also standardizes the handling of stacktrace, userstacktrace and
other trace options we may introduce in the future.
With this patch, for instance, the blk tracer (and some others already
in the tree) can use the "userstacktrace" /d/tracing/trace_options
facility.
$ codiff /tmp/vmlinux.before /tmp/vmlinux.after
linux-2.6-tip/kernel/trace/trace.c:
trace_vprintk | -5
trace_graph_return | -22
trace_graph_entry | -26
trace_function | -45
__ftrace_trace_stack | -27
ftrace_trace_userstack | -29
tracing_sched_switch_trace | -66
tracing_stop | +1
trace_seq_to_user | -1
ftrace_trace_special | -63
ftrace_special | +1
tracing_sched_wakeup_trace | -70
tracing_reset_online_cpus | -1
13 functions changed, 2 bytes added, 355 bytes removed, diff: -353
linux-2.6-tip/block/blktrace.c:
__blk_add_trace | -58
1 function changed, 58 bytes removed, diff: -58
linux-2.6-tip/kernel/trace/trace.c:
trace_buffer_lock_reserve | +88
trace_buffer_unlock_commit | +86
2 functions changed, 174 bytes added, diff: +174
/tmp/vmlinux.after:
16 functions changed, 176 bytes added, 413 bytes removed, diff: -237
Signed-off-by: Arnaldo Carvalho de Melo <acme@redhat.com>
Acked-by: Frédéric Weisbecker <fweisbec@gmail.com>
Signed-off-by: Ingo Molnar <mingo@elte.hu>
2009-02-05 19:14:13 +01:00
|
|
|
sizeof(*entry), 0, 0);
|
2008-09-30 05:02:41 +02:00
|
|
|
if (!event)
|
|
|
|
goto out;
|
|
|
|
entry = ring_buffer_event_data(event);
|
2008-11-11 23:24:42 +01:00
|
|
|
entry->boot_ret = *bt;
|
2009-09-13 05:34:04 +02:00
|
|
|
if (!filter_check_discard(call, entry, buffer, event))
|
|
|
|
trace_buffer_unlock_commit(buffer, event, 0, 0);
|
2008-09-30 05:02:41 +02:00
|
|
|
out:
|
2008-09-23 12:32:08 +02:00
|
|
|
preempt_enable();
|
|
|
|
}
|