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" |
| 14 | |
| 15 | static struct trace_array *boot_trace; |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 16 | static bool pre_initcalls_finished; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 17 | |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 18 | /* 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 Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 23 | void start_boot_trace(void) |
| 24 | { |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 25 | pre_initcalls_finished = true; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 26 | } |
| 27 | |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 28 | void enable_boot_trace(void) |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 29 | { |
Frederic Weisbecker | d7ad44b | 2008-10-31 13:20:08 +0100 | [diff] [blame] | 30 | if (pre_initcalls_finished) |
Steven Rostedt | e168e05 | 2008-11-07 22:36:02 -0500 | [diff] [blame] | 31 | tracing_start_sched_switch_record(); |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 32 | } |
| 33 | |
| 34 | void disable_boot_trace(void) |
| 35 | { |
Frederic Weisbecker | d7ad44b | 2008-10-31 13:20:08 +0100 | [diff] [blame] | 36 | if (pre_initcalls_finished) |
Steven Rostedt | e168e05 | 2008-11-07 22:36:02 -0500 | [diff] [blame] | 37 | tracing_stop_sched_switch_record(); |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 38 | } |
| 39 | |
Frederic Weisbecker | 1c80025 | 2008-11-16 05:57:26 +0100 | [diff] [blame] | 40 | static int boot_trace_init(struct trace_array *tr) |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 41 | { |
| 42 | int cpu; |
| 43 | boot_trace = tr; |
| 44 | |
Rusty Russell | 4462344 | 2009-01-01 10:12:23 +1030 | [diff] [blame] | 45 | for_each_cpu(cpu, cpu_possible_mask) |
Steven Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 46 | tracing_reset(tr, cpu); |
Frederic Weisbecker | d7ad44b | 2008-10-31 13:20:08 +0100 | [diff] [blame] | 47 | |
Steven Rostedt | e168e05 | 2008-11-07 22:36:02 -0500 | [diff] [blame] | 48 | tracing_sched_switch_assign_trace(tr); |
Frederic Weisbecker | 1c80025 | 2008-11-16 05:57:26 +0100 | [diff] [blame] | 49 | return 0; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 50 | } |
| 51 | |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 52 | static enum print_line_t |
| 53 | initcall_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 | |
| 77 | static enum print_line_t |
| 78 | initcall_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 Weisbecker | 9e9efff | 2008-09-29 20:31:58 +0200 | [diff] [blame] | 105 | 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] | 106 | { |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 107 | struct trace_entry *entry = iter->ent; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 108 | |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 109 | 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 Weisbecker | 9e9efff | 2008-09-29 20:31:58 +0200 | [diff] [blame] | 116 | } |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 117 | } |
| 118 | |
| 119 | struct tracer boot_tracer __read_mostly = |
| 120 | { |
| 121 | .name = "initcall", |
| 122 | .init = boot_trace_init, |
Pekka J Enberg | 213cc06 | 2008-12-19 12:08:39 +0200 | [diff] [blame] | 123 | .reset = tracing_reset_online_cpus, |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 124 | .print_line = initcall_print_line, |
| 125 | }; |
| 126 | |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 127 | 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] | 128 | { |
Steven Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 129 | struct ring_buffer_event *event; |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 130 | struct trace_boot_call *entry; |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 131 | unsigned long irq_flags; |
| 132 | struct trace_array *tr = boot_trace; |
| 133 | |
Frederic Weisbecker | 71566a0 | 2008-10-31 12:57:20 +0100 | [diff] [blame] | 134 | if (!pre_initcalls_finished) |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 135 | return; |
| 136 | |
Frederic Weisbecker | 5601020 | 2008-10-02 13:26:05 +0200 | [diff] [blame] | 137 | /* Get its name now since this function could |
| 138 | * disappear because it is in the .init section. |
| 139 | */ |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 140 | sprint_symbol(bt->func, (unsigned long)fn); |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 141 | preempt_disable(); |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 142 | |
Steven Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 143 | 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 Rostedt | 3869705 | 2008-10-01 13:14:09 -0400 | [diff] [blame] | 148 | tracing_generic_entry_update(&entry->ent, 0, 0); |
Frederic Weisbecker | 7423907 | 2008-11-11 23:24:42 +0100 | [diff] [blame] | 149 | 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 | |
| 159 | void 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 Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 180 | ring_buffer_unlock_commit(tr->buffer, event, irq_flags); |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 181 | |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 182 | trace_wake_up(); |
| 183 | |
Steven Rostedt | 3928a8a | 2008-09-29 23:02:41 -0400 | [diff] [blame] | 184 | out: |
Frédéric Weisbecker | d13744c | 2008-09-23 11:32:08 +0100 | [diff] [blame] | 185 | preempt_enable(); |
| 186 | } |