blob: a4fa2c57e34e376e0f58920e816db6c159530bcb [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 Weisbecker79a9d462008-10-31 13:34:45 +010040static void reset_boot_trace(struct trace_array *tr)
Frederic Weisbecker097d0362008-10-03 15:39:21 +020041{
Steven Rostedt75f5c472008-11-07 22:36:02 -050042 int cpu;
43
44 tr->time_start = ftrace_now(tr->cpu);
45
46 for_each_online_cpu(cpu)
47 tracing_reset(tr, cpu);
Frederic Weisbecker097d0362008-10-03 15:39:21 +020048}
49
Frederic Weisbecker1c800252008-11-16 05:57:26 +010050static int boot_trace_init(struct trace_array *tr)
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010051{
52 int cpu;
53 boot_trace = tr;
54
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010055 for_each_cpu_mask(cpu, cpu_possible_map)
Steven Rostedt3928a8a2008-09-29 23:02:41 -040056 tracing_reset(tr, cpu);
Frederic Weisbeckerd7ad44b2008-10-31 13:20:08 +010057
Steven Rostedte168e052008-11-07 22:36:02 -050058 tracing_sched_switch_assign_trace(tr);
Frederic Weisbecker1c800252008-11-16 05:57:26 +010059 return 0;
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +010060}
61
Frederic Weisbecker74239072008-11-11 23:24:42 +010062static enum print_line_t
63initcall_call_print_line(struct trace_iterator *iter)
64{
65 struct trace_entry *entry = iter->ent;
66 struct trace_seq *s = &iter->seq;
67 struct trace_boot_call *field;
68 struct boot_trace_call *call;
69 u64 ts;
70 unsigned long nsec_rem;
71 int ret;
72
73 trace_assign_type(field, entry);
74 call = &field->boot_call;
75 ts = iter->ts;
76 nsec_rem = do_div(ts, 1000000000);
77
78 ret = trace_seq_printf(s, "[%5ld.%09ld] calling %s @ %i\n",
79 (unsigned long)ts, nsec_rem, call->func, call->caller);
80
81 if (!ret)
82 return TRACE_TYPE_PARTIAL_LINE;
83 else
84 return TRACE_TYPE_HANDLED;
85}
86
87static enum print_line_t
88initcall_ret_print_line(struct trace_iterator *iter)
89{
90 struct trace_entry *entry = iter->ent;
91 struct trace_seq *s = &iter->seq;
92 struct trace_boot_ret *field;
93 struct boot_trace_ret *init_ret;
94 u64 ts;
95 unsigned long nsec_rem;
96 int ret;
97
98 trace_assign_type(field, entry);
99 init_ret = &field->boot_ret;
100 ts = iter->ts;
101 nsec_rem = do_div(ts, 1000000000);
102
103 ret = trace_seq_printf(s, "[%5ld.%09ld] initcall %s "
104 "returned %d after %llu msecs\n",
105 (unsigned long) ts,
106 nsec_rem,
107 init_ret->func, init_ret->result, init_ret->duration);
108
109 if (!ret)
110 return TRACE_TYPE_PARTIAL_LINE;
111 else
112 return TRACE_TYPE_HANDLED;
113}
114
Frederic Weisbecker9e9efff2008-09-29 20:31:58 +0200115static enum print_line_t initcall_print_line(struct trace_iterator *iter)
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100116{
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100117 struct trace_entry *entry = iter->ent;
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100118
Frederic Weisbecker74239072008-11-11 23:24:42 +0100119 switch (entry->type) {
120 case TRACE_BOOT_CALL:
121 return initcall_call_print_line(iter);
122 case TRACE_BOOT_RET:
123 return initcall_ret_print_line(iter);
124 default:
125 return TRACE_TYPE_UNHANDLED;
Frederic Weisbecker9e9efff2008-09-29 20:31:58 +0200126 }
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100127}
128
129struct tracer boot_tracer __read_mostly =
130{
131 .name = "initcall",
132 .init = boot_trace_init,
Frederic Weisbecker097d0362008-10-03 15:39:21 +0200133 .reset = reset_boot_trace,
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100134 .print_line = initcall_print_line,
135};
136
Frederic Weisbecker74239072008-11-11 23:24:42 +0100137void trace_boot_call(struct boot_trace_call *bt, initcall_t fn)
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100138{
Steven Rostedt3928a8a2008-09-29 23:02:41 -0400139 struct ring_buffer_event *event;
Frederic Weisbecker74239072008-11-11 23:24:42 +0100140 struct trace_boot_call *entry;
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100141 unsigned long irq_flags;
142 struct trace_array *tr = boot_trace;
143
Frederic Weisbecker71566a02008-10-31 12:57:20 +0100144 if (!pre_initcalls_finished)
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100145 return;
146
Frederic Weisbecker56010202008-10-02 13:26:05 +0200147 /* Get its name now since this function could
148 * disappear because it is in the .init section.
149 */
Frederic Weisbecker74239072008-11-11 23:24:42 +0100150 sprint_symbol(bt->func, (unsigned long)fn);
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100151 preempt_disable();
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100152
Steven Rostedt3928a8a2008-09-29 23:02:41 -0400153 event = ring_buffer_lock_reserve(tr->buffer, sizeof(*entry),
154 &irq_flags);
155 if (!event)
156 goto out;
157 entry = ring_buffer_event_data(event);
Steven Rostedt38697052008-10-01 13:14:09 -0400158 tracing_generic_entry_update(&entry->ent, 0, 0);
Frederic Weisbecker74239072008-11-11 23:24:42 +0100159 entry->ent.type = TRACE_BOOT_CALL;
160 entry->boot_call = *bt;
161 ring_buffer_unlock_commit(tr->buffer, event, irq_flags);
162
163 trace_wake_up();
164
165 out:
166 preempt_enable();
167}
168
169void trace_boot_ret(struct boot_trace_ret *bt, initcall_t fn)
170{
171 struct ring_buffer_event *event;
172 struct trace_boot_ret *entry;
173 unsigned long irq_flags;
174 struct trace_array *tr = boot_trace;
175
176 if (!pre_initcalls_finished)
177 return;
178
179 sprint_symbol(bt->func, (unsigned long)fn);
180 preempt_disable();
181
182 event = ring_buffer_lock_reserve(tr->buffer, sizeof(*entry),
183 &irq_flags);
184 if (!event)
185 goto out;
186 entry = ring_buffer_event_data(event);
187 tracing_generic_entry_update(&entry->ent, 0, 0);
188 entry->ent.type = TRACE_BOOT_RET;
189 entry->boot_ret = *bt;
Steven Rostedt3928a8a2008-09-29 23:02:41 -0400190 ring_buffer_unlock_commit(tr->buffer, event, irq_flags);
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100191
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100192 trace_wake_up();
193
Steven Rostedt3928a8a2008-09-29 23:02:41 -0400194 out:
Frédéric Weisbeckerd13744c2008-09-23 11:32:08 +0100195 preempt_enable();
196}