Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 1 | /* |
| 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 Weisbecker | 5601020 | 2008-10-02 13:26:05 +0200 | [diff] [blame] | 11 | #include <linux/kallsyms.h> |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 12 | |
| 13 | #include "trace.h" |
Steven Rostedt | f0868d1 | 2008-12-23 23:24:12 -0500 | [diff] [blame] | 14 | #include "trace_output.h" |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 15 | |
| 16 | static struct trace_array *boot_trace; |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 17 | static bool pre_initcalls_finished; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 18 | |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 19 | /* Tells the boot tracer that the pre_smp_initcalls are finished. |
| 20 | * So we are ready . |
| 21 | * It doesn't enable sched events tracing however. |
| 22 | * You have to call enable_boot_trace to do so. |
| 23 | */ |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 24 | void start_boot_trace(void) |
| 25 | { |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 26 | pre_initcalls_finished = true; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 27 | } |
| 28 | |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 29 | void enable_boot_trace(void) |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 30 | { |
Steven Rostedt | 79fb076 | 2009-02-02 21:38:33 -0500 | [diff] [blame^] | 31 | if (boot_trace && pre_initcalls_finished) |
Steven Rostedt | e168e05 | 2008-11-07 22:36:02 -0500 | [diff] [blame] | 32 | tracing_start_sched_switch_record(); |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 33 | } |
| 34 | |
| 35 | void disable_boot_trace(void) |
| 36 | { |
Steven Rostedt | 79fb076 | 2009-02-02 21:38:33 -0500 | [diff] [blame^] | 37 | if (boot_trace && pre_initcalls_finished) |
Steven Rostedt | e168e05 | 2008-11-07 22:36:02 -0500 | [diff] [blame] | 38 | tracing_stop_sched_switch_record(); |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 39 | } |
| 40 | |
Frederic Weisbecker | 1c80025 | 2008-11-16 05:57:26 +0100 | [diff] [blame] | 41 | static int boot_trace_init(struct trace_array *tr) |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 42 | { |
| 43 | int cpu; |
| 44 | boot_trace = tr; |
| 45 | |
Steven Rostedt | 79fb076 | 2009-02-02 21:38:33 -0500 | [diff] [blame^] | 46 | if (!tr) |
| 47 | return 0; |
| 48 | |
Rusty Russell | 4462344 | 2009-01-01 10:12:23 +1030 | [diff] [blame] | 49 | for_each_cpu(cpu, cpu_possible_mask) |
Steven Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 50 | tracing_reset(tr, cpu); |
Frederic Weisbecker | d7ad44b | 2008-10-31 13:20:08 +0100 | [diff] [blame] | 51 | |
Steven Rostedt | e168e05 | 2008-11-07 22:36:02 -0500 | [diff] [blame] | 52 | tracing_sched_switch_assign_trace(tr); |
Frederic Weisbecker | 1c80025 | 2008-11-16 05:57:26 +0100 | [diff] [blame] | 53 | return 0; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 54 | } |
| 55 | |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 56 | static enum print_line_t |
| 57 | initcall_call_print_line(struct trace_iterator *iter) |
| 58 | { |
| 59 | struct trace_entry *entry = iter->ent; |
| 60 | struct trace_seq *s = &iter->seq; |
| 61 | struct trace_boot_call *field; |
| 62 | struct boot_trace_call *call; |
| 63 | u64 ts; |
| 64 | unsigned long nsec_rem; |
| 65 | int ret; |
| 66 | |
| 67 | trace_assign_type(field, entry); |
| 68 | call = &field->boot_call; |
| 69 | ts = iter->ts; |
| 70 | nsec_rem = do_div(ts, 1000000000); |
| 71 | |
| 72 | ret = trace_seq_printf(s, "[%5ld.%09ld] calling %s @ %i\n", |
| 73 | (unsigned long)ts, nsec_rem, call->func, call->caller); |
| 74 | |
| 75 | if (!ret) |
| 76 | return TRACE_TYPE_PARTIAL_LINE; |
| 77 | else |
| 78 | return TRACE_TYPE_HANDLED; |
| 79 | } |
| 80 | |
| 81 | static enum print_line_t |
| 82 | initcall_ret_print_line(struct trace_iterator *iter) |
| 83 | { |
| 84 | struct trace_entry *entry = iter->ent; |
| 85 | struct trace_seq *s = &iter->seq; |
| 86 | struct trace_boot_ret *field; |
| 87 | struct boot_trace_ret *init_ret; |
| 88 | u64 ts; |
| 89 | unsigned long nsec_rem; |
| 90 | int ret; |
| 91 | |
| 92 | trace_assign_type(field, entry); |
| 93 | init_ret = &field->boot_ret; |
| 94 | ts = iter->ts; |
| 95 | nsec_rem = do_div(ts, 1000000000); |
| 96 | |
| 97 | ret = trace_seq_printf(s, "[%5ld.%09ld] initcall %s " |
| 98 | "returned %d after %llu msecs\n", |
| 99 | (unsigned long) ts, |
| 100 | nsec_rem, |
| 101 | init_ret->func, init_ret->result, init_ret->duration); |
| 102 | |
| 103 | if (!ret) |
| 104 | return TRACE_TYPE_PARTIAL_LINE; |
| 105 | else |
| 106 | return TRACE_TYPE_HANDLED; |
| 107 | } |
| 108 | |
Frederic Weisbecker | 9e9efff | 2008-09-29 20:31:58 +0200 | [diff] [blame] | 109 | static enum print_line_t initcall_print_line(struct trace_iterator *iter) |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 110 | { |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 111 | struct trace_entry *entry = iter->ent; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 112 | |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 113 | switch (entry->type) { |
| 114 | case TRACE_BOOT_CALL: |
| 115 | return initcall_call_print_line(iter); |
| 116 | case TRACE_BOOT_RET: |
| 117 | return initcall_ret_print_line(iter); |
| 118 | default: |
| 119 | return TRACE_TYPE_UNHANDLED; |
Frederic Weisbecker | 9e9efff | 2008-09-29 20:31:58 +0200 | [diff] [blame] | 120 | } |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 121 | } |
| 122 | |
| 123 | struct tracer boot_tracer __read_mostly = |
| 124 | { |
| 125 | .name = "initcall", |
| 126 | .init = boot_trace_init, |
Pekka J Enberg | 213cc06 | 2008-12-19 12:08:39 +0200 | [diff] [blame] | 127 | .reset = tracing_reset_online_cpus, |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 128 | .print_line = initcall_print_line, |
| 129 | }; |
| 130 | |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 131 | void trace_boot_call(struct boot_trace_call *bt, initcall_t fn) |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 132 | { |
Steven Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 133 | struct ring_buffer_event *event; |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 134 | struct trace_boot_call *entry; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 135 | unsigned long irq_flags; |
| 136 | struct trace_array *tr = boot_trace; |
| 137 | |
Steven Rostedt | 79fb076 | 2009-02-02 21:38:33 -0500 | [diff] [blame^] | 138 | if (!tr || !pre_initcalls_finished) |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 139 | return; |
| 140 | |
Frederic Weisbecker | 5601020 | 2008-10-02 13:26:05 +0200 | [diff] [blame] | 141 | /* Get its name now since this function could |
| 142 | * disappear because it is in the .init section. |
| 143 | */ |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 144 | sprint_symbol(bt->func, (unsigned long)fn); |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 145 | preempt_disable(); |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 146 | |
Steven Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 147 | event = ring_buffer_lock_reserve(tr->buffer, sizeof(*entry), |
| 148 | &irq_flags); |
| 149 | if (!event) |
| 150 | goto out; |
| 151 | entry = ring_buffer_event_data(event); |
Steven Rostedt | 3869705 | 2008-10-01 13:14:09 -0400 | [diff] [blame] | 152 | tracing_generic_entry_update(&entry->ent, 0, 0); |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 153 | entry->ent.type = TRACE_BOOT_CALL; |
| 154 | entry->boot_call = *bt; |
| 155 | ring_buffer_unlock_commit(tr->buffer, event, irq_flags); |
| 156 | |
| 157 | trace_wake_up(); |
| 158 | |
| 159 | out: |
| 160 | preempt_enable(); |
| 161 | } |
| 162 | |
| 163 | void trace_boot_ret(struct boot_trace_ret *bt, initcall_t fn) |
| 164 | { |
| 165 | struct ring_buffer_event *event; |
| 166 | struct trace_boot_ret *entry; |
| 167 | unsigned long irq_flags; |
| 168 | struct trace_array *tr = boot_trace; |
| 169 | |
Steven Rostedt | 79fb076 | 2009-02-02 21:38:33 -0500 | [diff] [blame^] | 170 | if (!tr || !pre_initcalls_finished) |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 171 | return; |
| 172 | |
| 173 | sprint_symbol(bt->func, (unsigned long)fn); |
| 174 | preempt_disable(); |
| 175 | |
| 176 | event = ring_buffer_lock_reserve(tr->buffer, sizeof(*entry), |
| 177 | &irq_flags); |
| 178 | if (!event) |
| 179 | goto out; |
| 180 | entry = ring_buffer_event_data(event); |
| 181 | tracing_generic_entry_update(&entry->ent, 0, 0); |
| 182 | entry->ent.type = TRACE_BOOT_RET; |
| 183 | entry->boot_ret = *bt; |
Steven Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 184 | ring_buffer_unlock_commit(tr->buffer, event, irq_flags); |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 185 | |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 186 | trace_wake_up(); |
| 187 | |
Steven Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 188 | out: |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 189 | preempt_enable(); |
| 190 | } |