blob: c009553a8e815829cc47658744868d86d66e2d32 [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 Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100354static int print_graph_abs_time(u64 t, struct trace_seq *s)
355{
356 unsigned long usecs_rem;
357
358 usecs_rem = do_div(t, NSEC_PER_SEC);
359 usecs_rem /= 1000;
360
361 return trace_seq_printf(s, "%5lu.%06lu | ",
362 (unsigned long)t, usecs_rem);
363}
364
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100365static enum print_line_t
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100366print_graph_irq(struct trace_iterator *iter, unsigned long addr,
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800367 enum trace_type type, int cpu, pid_t pid)
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100368{
369 int ret;
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100370 struct trace_seq *s = &iter->seq;
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100371
372 if (addr < (unsigned long)__irqentry_text_start ||
373 addr >= (unsigned long)__irqentry_text_end)
374 return TRACE_TYPE_UNHANDLED;
375
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100376 /* Absolute time */
377 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME) {
378 ret = print_graph_abs_time(iter->ts, s);
379 if (!ret)
380 return TRACE_TYPE_PARTIAL_LINE;
381 }
382
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800383 /* Cpu */
384 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU) {
385 ret = print_graph_cpu(s, cpu);
386 if (ret == TRACE_TYPE_PARTIAL_LINE)
387 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100388 }
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800389 /* Proc */
390 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC) {
391 ret = print_graph_proc(s, pid);
392 if (ret == TRACE_TYPE_PARTIAL_LINE)
393 return TRACE_TYPE_PARTIAL_LINE;
394 ret = trace_seq_printf(s, " | ");
395 if (!ret)
396 return TRACE_TYPE_PARTIAL_LINE;
397 }
398
399 /* No overhead */
400 ret = print_graph_overhead(-1, s);
401 if (!ret)
402 return TRACE_TYPE_PARTIAL_LINE;
403
404 if (type == TRACE_GRAPH_ENT)
405 ret = trace_seq_printf(s, "==========>");
406 else
407 ret = trace_seq_printf(s, "<==========");
408
409 if (!ret)
410 return TRACE_TYPE_PARTIAL_LINE;
411
412 /* Don't close the duration column if haven't one */
413 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION)
414 trace_seq_printf(s, " |");
415 ret = trace_seq_printf(s, "\n");
416
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100417 if (!ret)
418 return TRACE_TYPE_PARTIAL_LINE;
419 return TRACE_TYPE_HANDLED;
420}
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100421
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100422static enum print_line_t
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100423print_graph_duration(unsigned long long duration, struct trace_seq *s)
424{
425 unsigned long nsecs_rem = do_div(duration, 1000);
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100426 /* log10(ULONG_MAX) + '\0' */
427 char msecs_str[21];
428 char nsecs_str[5];
429 int ret, len;
430 int i;
431
432 sprintf(msecs_str, "%lu", (unsigned long) duration);
433
434 /* Print msecs */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800435 ret = trace_seq_printf(s, "%s", msecs_str);
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100436 if (!ret)
437 return TRACE_TYPE_PARTIAL_LINE;
438
439 len = strlen(msecs_str);
440
441 /* Print nsecs (we don't want to exceed 7 numbers) */
442 if (len < 7) {
443 snprintf(nsecs_str, 8 - len, "%03lu", nsecs_rem);
444 ret = trace_seq_printf(s, ".%s", nsecs_str);
445 if (!ret)
446 return TRACE_TYPE_PARTIAL_LINE;
447 len += strlen(nsecs_str);
448 }
449
450 ret = trace_seq_printf(s, " us ");
451 if (!ret)
452 return TRACE_TYPE_PARTIAL_LINE;
453
454 /* Print remaining spaces to fit the row's width */
455 for (i = len; i < 7; i++) {
456 ret = trace_seq_printf(s, " ");
457 if (!ret)
458 return TRACE_TYPE_PARTIAL_LINE;
459 }
460
461 ret = trace_seq_printf(s, "| ");
462 if (!ret)
463 return TRACE_TYPE_PARTIAL_LINE;
464 return TRACE_TYPE_HANDLED;
465
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100466}
467
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100468/* Case of a leaf function on its call entry */
469static enum print_line_t
470print_graph_entry_leaf(struct trace_iterator *iter,
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100471 struct ftrace_graph_ent_entry *entry,
472 struct ftrace_graph_ret_entry *ret_entry, struct trace_seq *s)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100473{
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100474 struct ftrace_graph_ret *graph_ret;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100475 struct ftrace_graph_ent *call;
476 unsigned long long duration;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100477 int ret;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100478 int i;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100479
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100480 graph_ret = &ret_entry->ret;
481 call = &entry->graph_ent;
482 duration = graph_ret->rettime - graph_ret->calltime;
Steven Rostedt437f24f2008-11-26 00:16:27 -0500483
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100484 /* Overhead */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800485 ret = print_graph_overhead(duration, s);
486 if (!ret)
487 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100488
489 /* Duration */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800490 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION) {
491 ret = print_graph_duration(duration, s);
492 if (ret == TRACE_TYPE_PARTIAL_LINE)
493 return TRACE_TYPE_PARTIAL_LINE;
494 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100495
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100496 /* Function */
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100497 for (i = 0; i < call->depth * TRACE_GRAPH_INDENT; i++) {
498 ret = trace_seq_printf(s, " ");
499 if (!ret)
500 return TRACE_TYPE_PARTIAL_LINE;
501 }
502
503 ret = seq_print_ip_sym(s, call->func, 0);
504 if (!ret)
505 return TRACE_TYPE_PARTIAL_LINE;
506
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100507 ret = trace_seq_printf(s, "();\n");
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100508 if (!ret)
509 return TRACE_TYPE_PARTIAL_LINE;
510
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100511 return TRACE_TYPE_HANDLED;
512}
513
514static enum print_line_t
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100515print_graph_entry_nested(struct ftrace_graph_ent_entry *entry,
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100516 struct trace_seq *s, pid_t pid, int cpu)
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100517{
518 int i;
519 int ret;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100520 struct ftrace_graph_ent *call = &entry->graph_ent;
521
522 /* No overhead */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800523 ret = print_graph_overhead(-1, s);
524 if (!ret)
525 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100526
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800527 /* No time */
528 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION) {
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100529 ret = trace_seq_printf(s, " | ");
530 if (!ret)
531 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100532 }
533
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100534 /* Function */
535 for (i = 0; i < call->depth * TRACE_GRAPH_INDENT; i++) {
536 ret = trace_seq_printf(s, " ");
537 if (!ret)
538 return TRACE_TYPE_PARTIAL_LINE;
539 }
540
541 ret = seq_print_ip_sym(s, call->func, 0);
542 if (!ret)
543 return TRACE_TYPE_PARTIAL_LINE;
544
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100545 ret = trace_seq_printf(s, "() {\n");
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100546 if (!ret)
547 return TRACE_TYPE_PARTIAL_LINE;
548
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100549 /*
550 * we already consumed the current entry to check the next one
551 * and see if this is a leaf.
552 */
553 return TRACE_TYPE_NO_CONSUME;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100554}
555
556static enum print_line_t
557print_graph_entry(struct ftrace_graph_ent_entry *field, struct trace_seq *s,
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800558 struct trace_iterator *iter)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100559{
560 int ret;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800561 int cpu = iter->cpu;
562 pid_t *last_entry = iter->private;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100563 struct trace_entry *ent = iter->ent;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800564 struct ftrace_graph_ent *call = &field->graph_ent;
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100565 struct ftrace_graph_ret_entry *leaf_ret;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100566
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100567 /* Pid */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800568 if (verif_pid(s, ent->pid, cpu, last_entry) == TRACE_TYPE_PARTIAL_LINE)
Steven Rostedt437f24f2008-11-26 00:16:27 -0500569 return TRACE_TYPE_PARTIAL_LINE;
570
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800571 /* Interrupt */
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100572 ret = print_graph_irq(iter, call->func, TRACE_GRAPH_ENT, cpu, ent->pid);
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800573 if (ret == TRACE_TYPE_PARTIAL_LINE)
574 return TRACE_TYPE_PARTIAL_LINE;
575
576 /* Absolute time */
577 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME) {
578 ret = print_graph_abs_time(iter->ts, s);
579 if (!ret)
580 return TRACE_TYPE_PARTIAL_LINE;
581 }
582
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100583 /* Cpu */
584 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU) {
585 ret = print_graph_cpu(s, cpu);
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100586 if (ret == TRACE_TYPE_PARTIAL_LINE)
587 return TRACE_TYPE_PARTIAL_LINE;
588 }
589
590 /* Proc */
591 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC) {
Ingo Molnar00a8bf82009-02-19 13:01:37 +0100592 ret = print_graph_proc(s, ent->pid);
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100593 if (ret == TRACE_TYPE_PARTIAL_LINE)
594 return TRACE_TYPE_PARTIAL_LINE;
595
596 ret = trace_seq_printf(s, " | ");
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100597 if (!ret)
598 return TRACE_TYPE_PARTIAL_LINE;
599 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100600
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100601 leaf_ret = get_return_for_leaf(iter, field);
602 if (leaf_ret)
603 return print_graph_entry_leaf(iter, field, leaf_ret, s);
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100604 else
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100605 return print_graph_entry_nested(field, s, iter->ent->pid, cpu);
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100606
607}
608
609static enum print_line_t
610print_graph_return(struct ftrace_graph_ret *trace, struct trace_seq *s,
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800611 struct trace_entry *ent, struct trace_iterator *iter)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100612{
613 int i;
614 int ret;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800615 int cpu = iter->cpu;
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100616 pid_t *last_pid = iter->private, pid = ent->pid;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100617 unsigned long long duration = trace->rettime - trace->calltime;
618
619 /* Pid */
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100620 if (verif_pid(s, pid, cpu, last_pid) == TRACE_TYPE_PARTIAL_LINE)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100621 return TRACE_TYPE_PARTIAL_LINE;
622
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800623 /* Absolute time */
624 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME) {
625 ret = print_graph_abs_time(iter->ts, s);
626 if (!ret)
627 return TRACE_TYPE_PARTIAL_LINE;
628 }
629
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100630 /* Cpu */
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100631 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU) {
632 ret = print_graph_cpu(s, cpu);
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100633 if (ret == TRACE_TYPE_PARTIAL_LINE)
634 return TRACE_TYPE_PARTIAL_LINE;
635 }
636
637 /* Proc */
638 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC) {
639 ret = print_graph_proc(s, ent->pid);
640 if (ret == TRACE_TYPE_PARTIAL_LINE)
641 return TRACE_TYPE_PARTIAL_LINE;
642
643 ret = trace_seq_printf(s, " | ");
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100644 if (!ret)
645 return TRACE_TYPE_PARTIAL_LINE;
646 }
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100647
648 /* Overhead */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800649 ret = print_graph_overhead(duration, s);
650 if (!ret)
651 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100652
653 /* Duration */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800654 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION) {
655 ret = print_graph_duration(duration, s);
656 if (ret == TRACE_TYPE_PARTIAL_LINE)
657 return TRACE_TYPE_PARTIAL_LINE;
658 }
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100659
660 /* Closing brace */
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100661 for (i = 0; i < trace->depth * TRACE_GRAPH_INDENT; i++) {
662 ret = trace_seq_printf(s, " ");
663 if (!ret)
664 return TRACE_TYPE_PARTIAL_LINE;
665 }
666
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100667 ret = trace_seq_printf(s, "}\n");
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100668 if (!ret)
669 return TRACE_TYPE_PARTIAL_LINE;
670
671 /* Overrun */
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100672 if (tracer_flags.val & TRACE_GRAPH_PRINT_OVERRUN) {
673 ret = trace_seq_printf(s, " (Overruns: %lu)\n",
674 trace->overrun);
675 if (!ret)
676 return TRACE_TYPE_PARTIAL_LINE;
677 }
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100678
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100679 ret = print_graph_irq(iter, trace->func, TRACE_GRAPH_RET, cpu, pid);
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100680 if (ret == TRACE_TYPE_PARTIAL_LINE)
681 return TRACE_TYPE_PARTIAL_LINE;
682
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100683 return TRACE_TYPE_HANDLED;
684}
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100685
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100686static enum print_line_t
687print_graph_comment(struct print_entry *trace, struct trace_seq *s,
688 struct trace_entry *ent, struct trace_iterator *iter)
689{
690 int i;
691 int ret;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800692 int cpu = iter->cpu;
693 pid_t *last_pid = iter->private;
694
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100695 /* Pid */
696 if (verif_pid(s, ent->pid, cpu, last_pid) == TRACE_TYPE_PARTIAL_LINE)
697 return TRACE_TYPE_PARTIAL_LINE;
698
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800699 /* Absolute time */
700 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME) {
701 ret = print_graph_abs_time(iter->ts, s);
702 if (!ret)
703 return TRACE_TYPE_PARTIAL_LINE;
704 }
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100705
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100706 /* Cpu */
707 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU) {
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800708 ret = print_graph_cpu(s, cpu);
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100709 if (ret == TRACE_TYPE_PARTIAL_LINE)
710 return TRACE_TYPE_PARTIAL_LINE;
711 }
712
713 /* Proc */
714 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC) {
715 ret = print_graph_proc(s, ent->pid);
716 if (ret == TRACE_TYPE_PARTIAL_LINE)
717 return TRACE_TYPE_PARTIAL_LINE;
718
719 ret = trace_seq_printf(s, " | ");
720 if (!ret)
721 return TRACE_TYPE_PARTIAL_LINE;
722 }
723
724 /* No overhead */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800725 ret = print_graph_overhead(-1, s);
726 if (!ret)
727 return TRACE_TYPE_PARTIAL_LINE;
728
729 /* No time */
730 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION) {
731 ret = trace_seq_printf(s, " | ");
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100732 if (!ret)
733 return TRACE_TYPE_PARTIAL_LINE;
734 }
735
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100736 /* Indentation */
737 if (trace->depth > 0)
738 for (i = 0; i < (trace->depth + 1) * TRACE_GRAPH_INDENT; i++) {
739 ret = trace_seq_printf(s, " ");
740 if (!ret)
741 return TRACE_TYPE_PARTIAL_LINE;
742 }
743
744 /* The comment */
745 ret = trace_seq_printf(s, "/* %s", trace->buf);
746 if (!ret)
747 return TRACE_TYPE_PARTIAL_LINE;
748
Frederic Weisbecker412d0bb2008-12-24 01:43:25 +0100749 /* Strip ending newline */
750 if (s->buffer[s->len - 1] == '\n') {
751 s->buffer[s->len - 1] = '\0';
752 s->len--;
753 }
754
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100755 ret = trace_seq_printf(s, " */\n");
756 if (!ret)
757 return TRACE_TYPE_PARTIAL_LINE;
758
759 return TRACE_TYPE_HANDLED;
760}
761
762
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100763enum print_line_t
764print_graph_function(struct trace_iterator *iter)
765{
766 struct trace_seq *s = &iter->seq;
767 struct trace_entry *entry = iter->ent;
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100768
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100769 switch (entry->type) {
770 case TRACE_GRAPH_ENT: {
771 struct ftrace_graph_ent_entry *field;
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100772 trace_assign_type(field, entry);
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800773 return print_graph_entry(field, s, iter);
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100774 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100775 case TRACE_GRAPH_RET: {
776 struct ftrace_graph_ret_entry *field;
777 trace_assign_type(field, entry);
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800778 return print_graph_return(&field->ret, s, entry, iter);
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100779 }
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100780 case TRACE_PRINT: {
781 struct print_entry *field;
782 trace_assign_type(field, entry);
783 return print_graph_comment(field, s, entry, iter);
784 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100785 default:
786 return TRACE_TYPE_UNHANDLED;
787 }
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100788}
789
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100790static void print_graph_headers(struct seq_file *s)
791{
792 /* 1st line */
793 seq_printf(s, "# ");
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800794 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME)
795 seq_printf(s, " TIME ");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100796 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800797 seq_printf(s, "CPU");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100798 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800799 seq_printf(s, " TASK/PID ");
800 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION)
801 seq_printf(s, " DURATION ");
802 seq_printf(s, " FUNCTION CALLS\n");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100803
804 /* 2nd line */
805 seq_printf(s, "# ");
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800806 if (tracer_flags.val & TRACE_GRAPH_PRINT_ABS_TIME)
807 seq_printf(s, " | ");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100808 if (tracer_flags.val & TRACE_GRAPH_PRINT_CPU)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800809 seq_printf(s, "| ");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100810 if (tracer_flags.val & TRACE_GRAPH_PRINT_PROC)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800811 seq_printf(s, " | | ");
812 if (tracer_flags.val & TRACE_GRAPH_PRINT_DURATION)
813 seq_printf(s, " | | ");
814 seq_printf(s, " | | | |\n");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100815}
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800816
817static void graph_trace_open(struct trace_iterator *iter)
818{
819 /* pid on the last trace processed */
820 pid_t *last_pid = alloc_percpu(pid_t);
821 int cpu;
822
823 if (!last_pid)
824 pr_warning("function graph tracer: not enough memory\n");
825 else
826 for_each_possible_cpu(cpu) {
827 pid_t *pid = per_cpu_ptr(last_pid, cpu);
828 *pid = -1;
829 }
830
831 iter->private = last_pid;
832}
833
834static void graph_trace_close(struct trace_iterator *iter)
835{
836 percpu_free(iter->private);
837}
838
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100839static struct tracer graph_trace __read_mostly = {
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100840 .name = "function_graph",
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800841 .open = graph_trace_open,
842 .close = graph_trace_close,
Frederic Weisbecker6eaaa5d2009-02-11 02:25:00 +0100843 .wait_pipe = poll_wait_pipe,
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +0100844 .init = graph_trace_init,
845 .reset = graph_trace_reset,
846 .print_line = print_graph_function,
847 .print_header = print_graph_headers,
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100848 .flags = &tracer_flags,
Frederic Weisbecker7447dce2009-02-07 21:33:57 +0100849#ifdef CONFIG_FTRACE_SELFTEST
850 .selftest = trace_selftest_startup_function_graph,
851#endif
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100852};
853
854static __init int init_graph_trace(void)
855{
856 return register_tracer(&graph_trace);
857}
858
859device_initcall(init_graph_trace);