blob: 6c7738e4f98b66437ba97a2321a947d79a96917d [file] [log] [blame]
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001/*
2 *
3 * Function graph tracer.
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08004 * Copyright (c) 2008-2009 Frederic Weisbecker <fweisbec@gmail.com>
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01005 * Mostly borrowed from function tracer which
6 * is Copyright (c) Steven Rostedt <srostedt@redhat.com>
7 *
8 */
9#include <linux/debugfs.h>
10#include <linux/uaccess.h>
11#include <linux/ftrace.h>
12#include <linux/fs.h>
13
14#include "trace.h"
Steven Rostedtf0868d12008-12-23 23:24:12 -050015#include "trace_output.h"
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010016
Frederic Weisbecker287b6e62008-11-26 00:57:25 +010017#define TRACE_GRAPH_INDENT 2
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010018
Frederic Weisbecker1a056152008-11-28 00:42:46 +010019/* Flag options */
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010020#define TRACE_GRAPH_PRINT_OVERRUN 0x1
Frederic Weisbecker1a056152008-11-28 00:42:46 +010021#define TRACE_GRAPH_PRINT_CPU 0x2
22#define TRACE_GRAPH_PRINT_OVERHEAD 0x4
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +010023#define TRACE_GRAPH_PRINT_PROC 0x8
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -080024#define TRACE_GRAPH_PRINT_DURATION 0x10
25#define TRACE_GRAPH_PRINT_ABS_TIME 0X20
Frederic Weisbecker1a056152008-11-28 00:42:46 +010026
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010027static struct tracer_opt trace_opts[] = {
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -080028 /* Display overruns? (for self-debug purpose) */
Frederic Weisbecker1a056152008-11-28 00:42:46 +010029 { TRACER_OPT(funcgraph-overrun, TRACE_GRAPH_PRINT_OVERRUN) },
30 /* Display CPU ? */
31 { TRACER_OPT(funcgraph-cpu, TRACE_GRAPH_PRINT_CPU) },
32 /* Display Overhead ? */
33 { TRACER_OPT(funcgraph-overhead, TRACE_GRAPH_PRINT_OVERHEAD) },
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +010034 /* Display proc name/pid */
35 { TRACER_OPT(funcgraph-proc, TRACE_GRAPH_PRINT_PROC) },
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -080036 /* Display duration of execution */
37 { TRACER_OPT(funcgraph-duration, TRACE_GRAPH_PRINT_DURATION) },
38 /* Display absolute time of an entry */
39 { TRACER_OPT(funcgraph-abstime, TRACE_GRAPH_PRINT_ABS_TIME) },
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010040 { } /* Empty entry */
41};
42
43static struct tracer_flags tracer_flags = {
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +010044 /* Don't display overruns and proc by default */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -080045 .val = TRACE_GRAPH_PRINT_CPU | TRACE_GRAPH_PRINT_OVERHEAD |
46 TRACE_GRAPH_PRINT_DURATION,
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010047 .opts = trace_opts
48};
49
Frederic Weisbecker287b6e62008-11-26 00:57:25 +010050/* pid on the last trace processed */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -080051
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010052
Steven Rostedt712406a2009-02-09 10:54:03 -080053/* Add a function return address to the trace stack on thread info.*/
54int
55ftrace_push_return_trace(unsigned long ret, unsigned long long time,
56 unsigned long func, int *depth)
57{
58 int index;
59
60 if (!current->ret_stack)
61 return -EBUSY;
62
63 /* The return trace stack is full */
64 if (current->curr_ret_stack == FTRACE_RETFUNC_DEPTH - 1) {
65 atomic_inc(&current->trace_overrun);
66 return -EBUSY;
67 }
68
69 index = ++current->curr_ret_stack;
70 barrier();
71 current->ret_stack[index].ret = ret;
72 current->ret_stack[index].func = func;
73 current->ret_stack[index].calltime = time;
74 *depth = index;
75
76 return 0;
77}
78
79/* Retrieve a function return address to the trace stack on thread info.*/
80void
81ftrace_pop_return_trace(struct ftrace_graph_ret *trace, unsigned long *ret)
82{
83 int index;
84
85 index = current->curr_ret_stack;
86
87 if (unlikely(index < 0)) {
88 ftrace_graph_stop();
89 WARN_ON(1);
90 /* Might as well panic, otherwise we have no where to go */
91 *ret = (unsigned long)panic;
92 return;
93 }
94
95 *ret = current->ret_stack[index].ret;
96 trace->func = current->ret_stack[index].func;
97 trace->calltime = current->ret_stack[index].calltime;
98 trace->overrun = atomic_read(&current->trace_overrun);
99 trace->depth = index;
100 barrier();
101 current->curr_ret_stack--;
102
103}
104
105/*
106 * Send the trace to the ring-buffer.
107 * @return the original return address.
108 */
109unsigned long ftrace_return_to_handler(void)
110{
111 struct ftrace_graph_ret trace;
112 unsigned long ret;
113
114 ftrace_pop_return_trace(&trace, &ret);
115 trace.rettime = cpu_clock(raw_smp_processor_id());
116 ftrace_graph_return(&trace);
117
118 if (unlikely(!ret)) {
119 ftrace_graph_stop();
120 WARN_ON(1);
121 /* Might as well panic. What else to do? */
122 ret = (unsigned long)panic;
123 }
124
125 return ret;
126}
127
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100128static int graph_trace_init(struct trace_array *tr)
129{
Arnaldo Carvalho de Melof04109b2009-01-28 13:02:12 -0200130 int ret = register_ftrace_graph(&trace_graph_return,
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100131 &trace_graph_entry);
Steven Rostedt660c7f92008-11-26 00:16:26 -0500132 if (ret)
133 return ret;
134 tracing_start_cmdline_record();
135
136 return 0;
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100137}
138
139static void graph_trace_reset(struct trace_array *tr)
140{
Steven Rostedt660c7f92008-11-26 00:16:26 -0500141 tracing_stop_cmdline_record();
142 unregister_ftrace_graph();
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100143}
144
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100145static inline int log10_cpu(int nb)
146{
147 if (nb / 100)
148 return 3;
149 if (nb / 10)
150 return 2;
151 return 1;
152}
153
154static enum print_line_t
155print_graph_cpu(struct trace_seq *s, int cpu)
156{
157 int i;
158 int ret;
159 int log10_this = log10_cpu(cpu);
Rusty Russell44623442009-01-01 10:12:23 +1030160 int log10_all = log10_cpu(cpumask_weight(cpu_online_mask));
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100161
162
Ingo Molnard51090b2008-11-28 09:55:16 +0100163 /*
164 * Start with a space character - to make it stand out
165 * to the right a bit when trace output is pasted into
166 * email:
167 */
168 ret = trace_seq_printf(s, " ");
169
170 /*
171 * Tricky - we space the CPU field according to the max
172 * number of online CPUs. On a 2-cpu system it would take
173 * a maximum of 1 digit - on a 128 cpu system it would
174 * take up to 3 digits:
175 */
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100176 for (i = 0; i < log10_all - log10_this; i++) {
177 ret = trace_seq_printf(s, " ");
178 if (!ret)
179 return TRACE_TYPE_PARTIAL_LINE;
180 }
181 ret = trace_seq_printf(s, "%d) ", cpu);
182 if (!ret)
Ingo Molnard51090b2008-11-28 09:55:16 +0100183 return TRACE_TYPE_PARTIAL_LINE;
184
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100185 return TRACE_TYPE_HANDLED;
186}
187
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100188#define TRACE_GRAPH_PROCINFO_LENGTH 14
189
190static enum print_line_t
191print_graph_proc(struct trace_seq *s, pid_t pid)
192{
193 int i;
194 int ret;
195 int len;
196 char comm[8];
197 int spaces = 0;
198 /* sign + log10(MAX_INT) + '\0' */
199 char pid_str[11];
200
201 strncpy(comm, trace_find_cmdline(pid), 7);
202 comm[7] = '\0';
203 sprintf(pid_str, "%d", pid);
204
205 /* 1 stands for the "-" character */
206 len = strlen(comm) + strlen(pid_str) + 1;
207
208 if (len < TRACE_GRAPH_PROCINFO_LENGTH)
209 spaces = TRACE_GRAPH_PROCINFO_LENGTH - len;
210
211 /* First spaces to align center */
212 for (i = 0; i < spaces / 2; i++) {
213 ret = trace_seq_printf(s, " ");
214 if (!ret)
215 return TRACE_TYPE_PARTIAL_LINE;
216 }
217
218 ret = trace_seq_printf(s, "%s-%s", comm, pid_str);
219 if (!ret)
220 return TRACE_TYPE_PARTIAL_LINE;
221
222 /* Last spaces to align center */
223 for (i = 0; i < spaces - (spaces / 2); i++) {
224 ret = trace_seq_printf(s, " ");
225 if (!ret)
226 return TRACE_TYPE_PARTIAL_LINE;
227 }
228 return TRACE_TYPE_HANDLED;
229}
230
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100231
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100232/* If the pid changed since the last trace, output this event */
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100233static enum print_line_t
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800234verif_pid(struct trace_seq *s, pid_t pid, int cpu, pid_t *last_pids_cpu)
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100235{
Ingo Molnard51090b2008-11-28 09:55:16 +0100236 pid_t prev_pid;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800237 pid_t *last_pid;
Ingo Molnard51090b2008-11-28 09:55:16 +0100238 int ret;
Steven Rostedt660c7f92008-11-26 00:16:26 -0500239
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800240 if (!last_pids_cpu)
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100241 return TRACE_TYPE_HANDLED;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100242
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800243 last_pid = per_cpu_ptr(last_pids_cpu, cpu);
Steven Rostedt660c7f92008-11-26 00:16:26 -0500244
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800245 if (*last_pid == pid)
246 return TRACE_TYPE_HANDLED;
247
248 prev_pid = *last_pid;
249 *last_pid = pid;
250
251 if (prev_pid == -1)
252 return TRACE_TYPE_HANDLED;
Ingo Molnard51090b2008-11-28 09:55:16 +0100253/*
254 * Context-switch trace line:
255
256 ------------------------------------------
257 | 1) migration/0--1 => sshd-1755
258 ------------------------------------------
259
260 */
261 ret = trace_seq_printf(s,
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100262 " ------------------------------------------\n");
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100263 if (!ret)
Wenji Huang810dc732009-02-10 01:03:05 -0500264 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100265
266 ret = print_graph_cpu(s, cpu);
267 if (ret == TRACE_TYPE_PARTIAL_LINE)
Wenji Huang810dc732009-02-10 01:03:05 -0500268 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100269
270 ret = print_graph_proc(s, prev_pid);
271 if (ret == TRACE_TYPE_PARTIAL_LINE)
Wenji Huang810dc732009-02-10 01:03:05 -0500272 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100273
274 ret = trace_seq_printf(s, " => ");
275 if (!ret)
Wenji Huang810dc732009-02-10 01:03:05 -0500276 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100277
278 ret = print_graph_proc(s, pid);
279 if (ret == TRACE_TYPE_PARTIAL_LINE)
Wenji Huang810dc732009-02-10 01:03:05 -0500280 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100281
282 ret = trace_seq_printf(s,
283 "\n ------------------------------------------\n\n");
284 if (!ret)
Wenji Huang810dc732009-02-10 01:03:05 -0500285 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100286
Wenji Huang810dc732009-02-10 01:03:05 -0500287 return TRACE_TYPE_HANDLED;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100288}
289
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100290static struct ftrace_graph_ret_entry *
291get_return_for_leaf(struct trace_iterator *iter,
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100292 struct ftrace_graph_ent_entry *curr)
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100293{
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100294 struct ring_buffer_iter *ring_iter;
295 struct ring_buffer_event *event;
296 struct ftrace_graph_ret_entry *next;
297
298 ring_iter = iter->buffer_iter[iter->cpu];
299
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100300 /* First peek to compare current entry and the next one */
301 if (ring_iter)
302 event = ring_buffer_iter_peek(ring_iter, NULL);
303 else {
304 /* We need to consume the current entry to see the next one */
305 ring_buffer_consume(iter->tr->buffer, iter->cpu, NULL);
306 event = ring_buffer_peek(iter->tr->buffer, iter->cpu,
307 NULL);
308 }
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100309
310 if (!event)
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100311 return NULL;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100312
313 next = ring_buffer_event_data(event);
314
315 if (next->ent.type != TRACE_GRAPH_RET)
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100316 return NULL;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100317
318 if (curr->ent.pid != next->ent.pid ||
319 curr->graph_ent.func != next->ret.func)
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100320 return NULL;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100321
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100322 /* this is a leaf, now advance the iterator */
323 if (ring_iter)
324 ring_buffer_read(ring_iter, NULL);
325
326 return next;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100327}
328
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800329/* Signal a overhead of time execution to the output */
330static int
331print_graph_overhead(unsigned long long duration, struct trace_seq *s)
332{
333 /* If duration disappear, we don't need anything */
334 if (!(tracer_flags.val & TRACE_GRAPH_PRINT_DURATION))
335 return 1;
336
337 /* Non nested entry or return */
338 if (duration == -1)
339 return trace_seq_printf(s, " ");
340
341 if (tracer_flags.val & TRACE_GRAPH_PRINT_OVERHEAD) {
342 /* Duration exceeded 100 msecs */
343 if (duration > 100000ULL)
344 return trace_seq_printf(s, "! ");
345
346 /* Duration exceeded 10 msecs */
347 if (duration > 10000ULL)
348 return trace_seq_printf(s, "+ ");
349 }
350
351 return trace_seq_printf(s, " ");
352}
353
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100354static enum print_line_t
355print_graph_irq(struct trace_seq *s, unsigned long addr,
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800356 enum trace_type type, int cpu, pid_t pid)
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100357{
358 int ret;
359
360 if (addr < (unsigned long)__irqentry_text_start ||
361 addr >= (unsigned long)__irqentry_text_end)
362 return TRACE_TYPE_UNHANDLED;
363
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800364 /* Cpu */
365 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU) {
366 ret = print_graph_cpu(s, cpu);
367 if (ret == TRACE_TYPE_PARTIAL_LINE)
368 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100369 }
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800370 /* Proc */
371 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC) {
372 ret = print_graph_proc(s, pid);
373 if (ret == TRACE_TYPE_PARTIAL_LINE)
374 return TRACE_TYPE_PARTIAL_LINE;
375 ret = trace_seq_printf(s, " | ");
376 if (!ret)
377 return TRACE_TYPE_PARTIAL_LINE;
378 }
379
380 /* No overhead */
381 ret = print_graph_overhead(-1, s);
382 if (!ret)
383 return TRACE_TYPE_PARTIAL_LINE;
384
385 if (type == TRACE_GRAPH_ENT)
386 ret = trace_seq_printf(s, "==========>");
387 else
388 ret = trace_seq_printf(s, "<==========");
389
390 if (!ret)
391 return TRACE_TYPE_PARTIAL_LINE;
392
393 /* Don't close the duration column if haven't one */
394 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION)
395 trace_seq_printf(s, " |");
396 ret = trace_seq_printf(s, "\n");
397
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100398 if (!ret)
399 return TRACE_TYPE_PARTIAL_LINE;
400 return TRACE_TYPE_HANDLED;
401}
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100402
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100403static enum print_line_t
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100404print_graph_duration(unsigned long long duration, struct trace_seq *s)
405{
406 unsigned long nsecs_rem = do_div(duration, 1000);
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100407 /* log10(ULONG_MAX) + '\0' */
408 char msecs_str[21];
409 char nsecs_str[5];
410 int ret, len;
411 int i;
412
413 sprintf(msecs_str, "%lu", (unsigned long) duration);
414
415 /* Print msecs */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800416 ret = trace_seq_printf(s, "%s", msecs_str);
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100417 if (!ret)
418 return TRACE_TYPE_PARTIAL_LINE;
419
420 len = strlen(msecs_str);
421
422 /* Print nsecs (we don't want to exceed 7 numbers) */
423 if (len < 7) {
424 snprintf(nsecs_str, 8 - len, "%03lu", nsecs_rem);
425 ret = trace_seq_printf(s, ".%s", nsecs_str);
426 if (!ret)
427 return TRACE_TYPE_PARTIAL_LINE;
428 len += strlen(nsecs_str);
429 }
430
431 ret = trace_seq_printf(s, " us ");
432 if (!ret)
433 return TRACE_TYPE_PARTIAL_LINE;
434
435 /* Print remaining spaces to fit the row's width */
436 for (i = len; i < 7; i++) {
437 ret = trace_seq_printf(s, " ");
438 if (!ret)
439 return TRACE_TYPE_PARTIAL_LINE;
440 }
441
442 ret = trace_seq_printf(s, "| ");
443 if (!ret)
444 return TRACE_TYPE_PARTIAL_LINE;
445 return TRACE_TYPE_HANDLED;
446
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100447}
448
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800449static int print_graph_abs_time(u64 t, struct trace_seq *s)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100450{
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800451 unsigned long usecs_rem;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100452
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800453 usecs_rem = do_div(t, 1000000000);
454 usecs_rem /= 1000;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100455
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800456 return trace_seq_printf(s, "%5lu.%06lu | ",
457 (unsigned long)t, usecs_rem);
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100458}
459
460/* Case of a leaf function on its call entry */
461static enum print_line_t
462print_graph_entry_leaf(struct trace_iterator *iter,
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100463 struct ftrace_graph_ent_entry *entry,
464 struct ftrace_graph_ret_entry *ret_entry, struct trace_seq *s)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100465{
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100466 struct ftrace_graph_ret *graph_ret;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100467 struct ftrace_graph_ent *call;
468 unsigned long long duration;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100469 int ret;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100470 int i;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100471
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100472 graph_ret = &ret_entry->ret;
473 call = &entry->graph_ent;
474 duration = graph_ret->rettime - graph_ret->calltime;
Steven Rostedt437f24fb2008-11-26 00:16:27 -0500475
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100476 /* Overhead */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800477 ret = print_graph_overhead(duration, s);
478 if (!ret)
479 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100480
481 /* Duration */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800482 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION) {
483 ret = print_graph_duration(duration, s);
484 if (ret == TRACE_TYPE_PARTIAL_LINE)
485 return TRACE_TYPE_PARTIAL_LINE;
486 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100487
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100488 /* Function */
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100489 for (i = 0; i < call->depth * TRACE_GRAPH_INDENT; i++) {
490 ret = trace_seq_printf(s, " ");
491 if (!ret)
492 return TRACE_TYPE_PARTIAL_LINE;
493 }
494
495 ret = seq_print_ip_sym(s, call->func, 0);
496 if (!ret)
497 return TRACE_TYPE_PARTIAL_LINE;
498
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100499 ret = trace_seq_printf(s, "();\n");
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100500 if (!ret)
501 return TRACE_TYPE_PARTIAL_LINE;
502
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100503 return TRACE_TYPE_HANDLED;
504}
505
506static enum print_line_t
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100507print_graph_entry_nested(struct ftrace_graph_ent_entry *entry,
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100508 struct trace_seq *s, pid_t pid, int cpu)
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100509{
510 int i;
511 int ret;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100512 struct ftrace_graph_ent *call = &entry->graph_ent;
513
514 /* No overhead */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800515 ret = print_graph_overhead(-1, s);
516 if (!ret)
517 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100518
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800519 /* No time */
520 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION) {
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100521 ret = trace_seq_printf(s, " | ");
522 if (!ret)
523 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100524 }
525
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100526 /* Function */
527 for (i = 0; i < call->depth * TRACE_GRAPH_INDENT; i++) {
528 ret = trace_seq_printf(s, " ");
529 if (!ret)
530 return TRACE_TYPE_PARTIAL_LINE;
531 }
532
533 ret = seq_print_ip_sym(s, call->func, 0);
534 if (!ret)
535 return TRACE_TYPE_PARTIAL_LINE;
536
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100537 ret = trace_seq_printf(s, "() {\n");
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100538 if (!ret)
539 return TRACE_TYPE_PARTIAL_LINE;
540
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100541 /*
542 * we already consumed the current entry to check the next one
543 * and see if this is a leaf.
544 */
545 return TRACE_TYPE_NO_CONSUME;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100546}
547
548static enum print_line_t
549print_graph_entry(struct ftrace_graph_ent_entry *field, struct trace_seq *s,
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800550 struct trace_iterator *iter)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100551{
552 int ret;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800553 int cpu = iter->cpu;
554 pid_t *last_entry = iter->private;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100555 struct trace_entry *ent = iter->ent;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800556 struct ftrace_graph_ent *call = &field->graph_ent;
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100557 struct ftrace_graph_ret_entry *leaf_ret;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100558
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100559 /* Pid */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800560 if (verif_pid(s, ent->pid, cpu, last_entry) == TRACE_TYPE_PARTIAL_LINE)
Steven Rostedt437f24fb2008-11-26 00:16:27 -0500561 return TRACE_TYPE_PARTIAL_LINE;
562
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800563 /* Interrupt */
564 ret = print_graph_irq(s, call->func, TRACE_GRAPH_ENT, cpu, ent->pid);
565 if (ret == TRACE_TYPE_PARTIAL_LINE)
566 return TRACE_TYPE_PARTIAL_LINE;
567
568 /* Absolute time */
569 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME) {
570 ret = print_graph_abs_time(iter->ts, s);
571 if (!ret)
572 return TRACE_TYPE_PARTIAL_LINE;
573 }
574
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100575 /* Cpu */
576 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU) {
577 ret = print_graph_cpu(s, cpu);
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100578 if (ret == TRACE_TYPE_PARTIAL_LINE)
579 return TRACE_TYPE_PARTIAL_LINE;
580 }
581
582 /* Proc */
583 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC) {
584 ret = print_graph_proc(s, ent->pid);
585 if (ret == TRACE_TYPE_PARTIAL_LINE)
586 return TRACE_TYPE_PARTIAL_LINE;
587
588 ret = trace_seq_printf(s, " | ");
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100589 if (!ret)
590 return TRACE_TYPE_PARTIAL_LINE;
591 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100592
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100593 leaf_ret = get_return_for_leaf(iter, field);
594 if (leaf_ret)
595 return print_graph_entry_leaf(iter, field, leaf_ret, s);
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100596 else
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100597 return print_graph_entry_nested(field, s, iter->ent->pid, cpu);
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100598
599}
600
601static enum print_line_t
602print_graph_return(struct ftrace_graph_ret *trace, struct trace_seq *s,
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800603 struct trace_entry *ent, struct trace_iterator *iter)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100604{
605 int i;
606 int ret;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800607 int cpu = iter->cpu;
608 pid_t *last_pid = iter->private;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100609 unsigned long long duration = trace->rettime - trace->calltime;
610
611 /* Pid */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800612 if (verif_pid(s, ent->pid, cpu, last_pid) == TRACE_TYPE_PARTIAL_LINE)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100613 return TRACE_TYPE_PARTIAL_LINE;
614
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800615 /* Absolute time */
616 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME) {
617 ret = print_graph_abs_time(iter->ts, s);
618 if (!ret)
619 return TRACE_TYPE_PARTIAL_LINE;
620 }
621
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100622 /* Cpu */
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100623 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU) {
624 ret = print_graph_cpu(s, cpu);
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100625 if (ret == TRACE_TYPE_PARTIAL_LINE)
626 return TRACE_TYPE_PARTIAL_LINE;
627 }
628
629 /* Proc */
630 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC) {
631 ret = print_graph_proc(s, ent->pid);
632 if (ret == TRACE_TYPE_PARTIAL_LINE)
633 return TRACE_TYPE_PARTIAL_LINE;
634
635 ret = trace_seq_printf(s, " | ");
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100636 if (!ret)
637 return TRACE_TYPE_PARTIAL_LINE;
638 }
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100639
640 /* Overhead */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800641 ret = print_graph_overhead(duration, s);
642 if (!ret)
643 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100644
645 /* Duration */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800646 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION) {
647 ret = print_graph_duration(duration, s);
648 if (ret == TRACE_TYPE_PARTIAL_LINE)
649 return TRACE_TYPE_PARTIAL_LINE;
650 }
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100651
652 /* Closing brace */
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100653 for (i = 0; i < trace->depth * TRACE_GRAPH_INDENT; i++) {
654 ret = trace_seq_printf(s, " ");
655 if (!ret)
656 return TRACE_TYPE_PARTIAL_LINE;
657 }
658
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100659 ret = trace_seq_printf(s, "}\n");
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100660 if (!ret)
661 return TRACE_TYPE_PARTIAL_LINE;
662
663 /* Overrun */
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100664 if (tracer_flags.val & TRACE_GRAPH_PRINT_OVERRUN) {
665 ret = trace_seq_printf(s, " (Overruns: %lu)\n",
666 trace->overrun);
667 if (!ret)
668 return TRACE_TYPE_PARTIAL_LINE;
669 }
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100670
671 ret = print_graph_irq(s, trace->func, TRACE_GRAPH_RET, cpu, ent->pid);
672 if (ret == TRACE_TYPE_PARTIAL_LINE)
673 return TRACE_TYPE_PARTIAL_LINE;
674
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100675 return TRACE_TYPE_HANDLED;
676}
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100677
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100678static enum print_line_t
679print_graph_comment(struct print_entry *trace, struct trace_seq *s,
680 struct trace_entry *ent, struct trace_iterator *iter)
681{
682 int i;
683 int ret;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800684 int cpu = iter->cpu;
685 pid_t *last_pid = iter->private;
686
687 /* Absolute time */
688 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME) {
689 ret = print_graph_abs_time(iter->ts, s);
690 if (!ret)
691 return TRACE_TYPE_PARTIAL_LINE;
692 }
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100693
694 /* Pid */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800695 if (verif_pid(s, ent->pid, cpu, last_pid) == TRACE_TYPE_PARTIAL_LINE)
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100696 return TRACE_TYPE_PARTIAL_LINE;
697
698 /* Cpu */
699 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU) {
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800700 ret = print_graph_cpu(s, cpu);
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100701 if (ret == TRACE_TYPE_PARTIAL_LINE)
702 return TRACE_TYPE_PARTIAL_LINE;
703 }
704
705 /* Proc */
706 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC) {
707 ret = print_graph_proc(s, ent->pid);
708 if (ret == TRACE_TYPE_PARTIAL_LINE)
709 return TRACE_TYPE_PARTIAL_LINE;
710
711 ret = trace_seq_printf(s, " | ");
712 if (!ret)
713 return TRACE_TYPE_PARTIAL_LINE;
714 }
715
716 /* No overhead */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800717 ret = print_graph_overhead(-1, s);
718 if (!ret)
719 return TRACE_TYPE_PARTIAL_LINE;
720
721 /* No time */
722 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION) {
723 ret = trace_seq_printf(s, " | ");
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100724 if (!ret)
725 return TRACE_TYPE_PARTIAL_LINE;
726 }
727
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100728 /* Indentation */
729 if (trace->depth > 0)
730 for (i = 0; i < (trace->depth + 1) * TRACE_GRAPH_INDENT; i++) {
731 ret = trace_seq_printf(s, " ");
732 if (!ret)
733 return TRACE_TYPE_PARTIAL_LINE;
734 }
735
736 /* The comment */
737 ret = trace_seq_printf(s, "/* %s", trace->buf);
738 if (!ret)
739 return TRACE_TYPE_PARTIAL_LINE;
740
Frederic Weisbecker412d0bb2008-12-24 01:43:25 +0100741 /* Strip ending newline */
742 if (s->buffer[s->len - 1] == '\n') {
743 s->buffer[s->len - 1] = '\0';
744 s->len--;
745 }
746
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100747 ret = trace_seq_printf(s, " */\n");
748 if (!ret)
749 return TRACE_TYPE_PARTIAL_LINE;
750
751 return TRACE_TYPE_HANDLED;
752}
753
754
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100755enum print_line_t
756print_graph_function(struct trace_iterator *iter)
757{
758 struct trace_seq *s = &iter->seq;
759 struct trace_entry *entry = iter->ent;
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100760
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100761 switch (entry->type) {
762 case TRACE_GRAPH_ENT: {
763 struct ftrace_graph_ent_entry *field;
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100764 trace_assign_type(field, entry);
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800765 return print_graph_entry(field, s, iter);
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100766 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100767 case TRACE_GRAPH_RET: {
768 struct ftrace_graph_ret_entry *field;
769 trace_assign_type(field, entry);
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800770 return print_graph_return(&field->ret, s, entry, iter);
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100771 }
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100772 case TRACE_PRINT: {
773 struct print_entry *field;
774 trace_assign_type(field, entry);
775 return print_graph_comment(field, s, entry, iter);
776 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100777 default:
778 return TRACE_TYPE_UNHANDLED;
779 }
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100780}
781
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100782static void print_graph_headers(struct seq_file *s)
783{
784 /* 1st line */
785 seq_printf(s, "# ");
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800786 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME)
787 seq_printf(s, " TIME ");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100788 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800789 seq_printf(s, "CPU");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100790 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800791 seq_printf(s, " TASK/PID ");
792 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION)
793 seq_printf(s, " DURATION ");
794 seq_printf(s, " FUNCTION CALLS\n");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100795
796 /* 2nd line */
797 seq_printf(s, "# ");
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800798 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME)
799 seq_printf(s, " | ");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100800 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800801 seq_printf(s, "| ");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100802 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800803 seq_printf(s, " | | ");
804 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION)
805 seq_printf(s, " | | ");
806 seq_printf(s, " | | | |\n");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100807}
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800808
809static void graph_trace_open(struct trace_iterator *iter)
810{
811 /* pid on the last trace processed */
812 pid_t *last_pid = alloc_percpu(pid_t);
813 int cpu;
814
815 if (!last_pid)
816 pr_warning("function graph tracer: not enough memory\n");
817 else
818 for_each_possible_cpu(cpu) {
819 pid_t *pid = per_cpu_ptr(last_pid, cpu);
820 *pid = -1;
821 }
822
823 iter->private = last_pid;
824}
825
826static void graph_trace_close(struct trace_iterator *iter)
827{
828 percpu_free(iter->private);
829}
830
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100831static struct tracer graph_trace __read_mostly = {
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100832 .name = "function_graph",
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800833 .open = graph_trace_open,
834 .close = graph_trace_close,
Frederic Weisbecker6eaaa5d2009-02-11 02:25:00 +0100835 .wait_pipe = poll_wait_pipe,
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100836 .init = graph_trace_init,
837 .reset = graph_trace_reset,
838 .print_line = print_graph_function,
839 .print_header = print_graph_headers,
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100840 .flags = &tracer_flags,
Frederic Weisbecker7447dce2009-02-07 21:33:57 +0100841#ifdef CONFIG_FTRACE_SELFTEST
842 .selftest = trace_selftest_startup_function_graph,
843#endif
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100844};
845
846static __init int init_graph_trace(void)
847{
848 return register_tracer(&graph_trace);
849}
850
851device_initcall(init_graph_trace);