blob: 366c8c333e136e77d79a4579b4e70f24242b1404 [file] [log] [blame]
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +01001/*
2 * ring buffer based initcalls tracer
3 *
4 * Copyright (C) 2008 Frederic Weisbecker <fweisbec@gmail.com>
5 *
6 */
7
8#include <linux/init.h>
9#include <linux/debugfs.h>
10#include <linux/ftrace.h>
Frederic Weisbecker56010202008-10-02 13:26:05 +020011#include <linux/kallsyms.h>
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010012
13#include "trace.h"
14
15static struct trace_array *boot_trace;
Frederic Weisbecker71566a02008-10-31 12:57:20 +010016static bool pre_initcalls_finished;
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010017
Frederic Weisbecker71566a02008-10-31 12:57:20 +010018/* Tells the boot tracer that the pre_smp_initcalls are finished.
19 * So we are ready .
20 * It doesn't enable sched events tracing however.
21 * You have to call enable_boot_trace to do so.
22 */
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010023void start_boot_trace(void)
24{
Frederic Weisbecker71566a02008-10-31 12:57:20 +010025 pre_initcalls_finished = true;
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010026}
27
Frederic Weisbecker71566a02008-10-31 12:57:20 +010028void enable_boot_trace(void)
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010029{
Frederic Weisbeckerd7ad44b2008-10-31 13:20:08 +010030 if (pre_initcalls_finished)
Steven Rostedte168e052008-11-07 22:36:02 -050031 tracing_start_sched_switch_record();
Frederic Weisbecker71566a02008-10-31 12:57:20 +010032}
33
34void disable_boot_trace(void)
35{
Frederic Weisbeckerd7ad44b2008-10-31 13:20:08 +010036 if (pre_initcalls_finished)
Steven Rostedte168e052008-11-07 22:36:02 -050037 tracing_stop_sched_switch_record();
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010038}
39
Frederic Weisbecker1c800252008-11-16 05:57:26 +010040static int boot_trace_init(struct trace_array *tr)
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010041{
42 int cpu;
43 boot_trace = tr;
44
Rusty Russell44623442009-01-01 10:12:23 +103045 for_each_cpu(cpu, cpu_possible_mask)
Steven Rostedt3928a8a2008-09-29 23:02:41 -040046 tracing_reset(tr, cpu);
Frederic Weisbeckerd7ad44b2008-10-31 13:20:08 +010047
Steven Rostedte168e052008-11-07 22:36:02 -050048 tracing_sched_switch_assign_trace(tr);
Frederic Weisbecker1c800252008-11-16 05:57:26 +010049 return 0;
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010050}
51
Frederic Weisbecker74239072008-11-11 23:24:42 +010052static enum print_line_t
53initcall_call_print_line(struct trace_iterator *iter)
54{
55 struct trace_entry *entry = iter->ent;
56 struct trace_seq *s = &iter->seq;
57 struct trace_boot_call *field;
58 struct boot_trace_call *call;
59 u64 ts;
60 unsigned long nsec_rem;
61 int ret;
62
63 trace_assign_type(field, entry);
64 call = &field->boot_call;
65 ts = iter->ts;
66 nsec_rem = do_div(ts, 1000000000);
67
68 ret = trace_seq_printf(s, "[%5ld.%09ld] calling %s @ %i\n",
69 (unsigned long)ts, nsec_rem, call->func, call->caller);
70
71 if (!ret)
72 return TRACE_TYPE_PARTIAL_LINE;
73 else
74 return TRACE_TYPE_HANDLED;
75}
76
77static enum print_line_t
78initcall_ret_print_line(struct trace_iterator *iter)
79{
80 struct trace_entry *entry = iter->ent;
81 struct trace_seq *s = &iter->seq;
82 struct trace_boot_ret *field;
83 struct boot_trace_ret *init_ret;
84 u64 ts;
85 unsigned long nsec_rem;
86 int ret;
87
88 trace_assign_type(field, entry);
89 init_ret = &field->boot_ret;
90 ts = iter->ts;
91 nsec_rem = do_div(ts, 1000000000);
92
93 ret = trace_seq_printf(s, "[%5ld.%09ld] initcall %s "
94 "returned %d after %llu msecs\n",
95 (unsigned long) ts,
96 nsec_rem,
97 init_ret->func, init_ret->result, init_ret->duration);
98
99 if (!ret)
100 return TRACE_TYPE_PARTIAL_LINE;
101 else
102 return TRACE_TYPE_HANDLED;
103}
104
Frederic Weisbecker9e9efff2008-09-29 20:31:58 +0200105static enum print_line_t initcall_print_line(struct trace_iterator *iter)
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100106{
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100107 struct trace_entry *entry = iter->ent;
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100108
Frederic Weisbecker74239072008-11-11 23:24:42 +0100109 switch (entry->type) {
110 case TRACE_BOOT_CALL:
111 return initcall_call_print_line(iter);
112 case TRACE_BOOT_RET:
113 return initcall_ret_print_line(iter);
114 default:
115 return TRACE_TYPE_UNHANDLED;
Frederic Weisbecker9e9efff2008-09-29 20:31:58 +0200116 }
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100117}
118
119struct tracer boot_tracer __read_mostly =
120{
121 .name = "initcall",
122 .init = boot_trace_init,
Pekka J Enberg213cc062008-12-19 12:08:39 +0200123 .reset = tracing_reset_online_cpus,
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100124 .print_line = initcall_print_line,
125};
126
Frederic Weisbecker74239072008-11-11 23:24:42 +0100127void trace_boot_call(struct boot_trace_call *bt, initcall_t fn)
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100128{
Steven Rostedt3928a8a2008-09-29 23:02:41 -0400129 struct ring_buffer_event *event;
Frederic Weisbecker74239072008-11-11 23:24:42 +0100130 struct trace_boot_call *entry;
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100131 unsigned long irq_flags;
132 struct trace_array *tr = boot_trace;
133
Frederic Weisbecker71566a02008-10-31 12:57:20 +0100134 if (!pre_initcalls_finished)
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100135 return;
136
Frederic Weisbecker56010202008-10-02 13:26:05 +0200137 /* Get its name now since this function could
138 * disappear because it is in the .init section.
139 */
Frederic Weisbecker74239072008-11-11 23:24:42 +0100140 sprint_symbol(bt->func, (unsigned long)fn);
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100141 preempt_disable();
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100142
Steven Rostedt3928a8a2008-09-29 23:02:41 -0400143 event = ring_buffer_lock_reserve(tr->buffer, sizeof(*entry),
144 &irq_flags);
145 if (!event)
146 goto out;
147 entry = ring_buffer_event_data(event);
Steven Rostedt38697052008-10-01 13:14:09 -0400148 tracing_generic_entry_update(&entry->ent, 0, 0);
Frederic Weisbecker74239072008-11-11 23:24:42 +0100149 entry->ent.type = TRACE_BOOT_CALL;
150 entry->boot_call = *bt;
151 ring_buffer_unlock_commit(tr->buffer, event, irq_flags);
152
153 trace_wake_up();
154
155 out:
156 preempt_enable();
157}
158
159void trace_boot_ret(struct boot_trace_ret *bt, initcall_t fn)
160{
161 struct ring_buffer_event *event;
162 struct trace_boot_ret *entry;
163 unsigned long irq_flags;
164 struct trace_array *tr = boot_trace;
165
166 if (!pre_initcalls_finished)
167 return;
168
169 sprint_symbol(bt->func, (unsigned long)fn);
170 preempt_disable();
171
172 event = ring_buffer_lock_reserve(tr->buffer, sizeof(*entry),
173 &irq_flags);
174 if (!event)
175 goto out;
176 entry = ring_buffer_event_data(event);
177 tracing_generic_entry_update(&entry->ent, 0, 0);
178 entry->ent.type = TRACE_BOOT_RET;
179 entry->boot_ret = *bt;
Steven Rostedt3928a8a2008-09-29 23:02:41 -0400180 ring_buffer_unlock_commit(tr->buffer, event, irq_flags);
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100181
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100182 trace_wake_up();
183
Steven Rostedt3928a8a2008-09-29 23:02:41 -0400184 out:
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100185 preempt_enable();
186}