blob: 3604690be70b124b0d4977b11a4a55b685cf8f39 [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>
Tejun Heo5a0e3ad2010-03-24 17:04:11 +090012#include <linux/slab.h>
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010013#include <linux/fs.h>
14
15#include "trace.h"
Steven Rostedtf0868d12008-12-23 23:24:12 -050016#include "trace_output.h"
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010017
Steven Rostedt (Red Hat)1b2f1212014-06-25 10:39:46 -040018static bool kill_ftrace_graph;
19
20/**
21 * ftrace_graph_is_dead - returns true if ftrace_graph_stop() was called
22 *
23 * ftrace_graph_stop() is called when a severe error is detected in
24 * the function graph tracing. This function is called by the critical
25 * paths of function graph to keep those paths from doing any more harm.
26 */
27bool ftrace_graph_is_dead(void)
28{
29 return kill_ftrace_graph;
30}
31
32/**
33 * ftrace_graph_stop - set to permanently disable function graph tracincg
34 *
35 * In case of an error int function graph tracing, this is called
36 * to try to keep function graph tracing from causing any more harm.
37 * Usually this is pretty severe and this is called to try to at least
38 * get a warning out to the user.
39 */
40void ftrace_graph_stop(void)
41{
42 kill_ftrace_graph = true;
43 /*
44 * ftrace_stop() will be removed when all archs are updated to
45 * use ftrace_graph_is_dead()
46 */
47 ftrace_stop();
48}
49
Steven Rostedtb304d042010-09-14 18:58:33 -040050/* When set, irq functions will be ignored */
51static int ftrace_graph_skip_irqs;
52
Jiri Olsabe1eca32009-11-24 13:57:38 +010053struct fgraph_cpu_data {
Steven Rostedt2fbcdb32009-03-19 13:24:42 -040054 pid_t last_pid;
55 int depth;
Jiri Olsa2bd162122010-09-07 16:53:44 +020056 int depth_irq;
Jiri Olsabe1eca32009-11-24 13:57:38 +010057 int ignore;
Steven Rostedtf1c7f512010-02-26 17:08:16 -050058 unsigned long enter_funcs[FTRACE_RETFUNC_DEPTH];
Jiri Olsabe1eca32009-11-24 13:57:38 +010059};
60
61struct fgraph_data {
Namhyung Kim6016ee12010-08-11 12:47:59 +090062 struct fgraph_cpu_data __percpu *cpu_data;
Jiri Olsabe1eca32009-11-24 13:57:38 +010063
64 /* Place to preserve last processed entry. */
65 struct ftrace_graph_ent_entry ent;
66 struct ftrace_graph_ret_entry ret;
67 int failed;
68 int cpu;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -040069};
70
Frederic Weisbecker287b6e62008-11-26 00:57:25 +010071#define TRACE_GRAPH_INDENT 2
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010072
Steven Rostedt8741db52013-01-16 10:49:37 -050073static unsigned int max_depth;
74
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010075static struct tracer_opt trace_opts[] = {
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -080076 /* Display overruns? (for self-debug purpose) */
Frederic Weisbecker1a056152008-11-28 00:42:46 +010077 { TRACER_OPT(funcgraph-overrun, TRACE_GRAPH_PRINT_OVERRUN) },
78 /* Display CPU ? */
79 { TRACER_OPT(funcgraph-cpu, TRACE_GRAPH_PRINT_CPU) },
80 /* Display Overhead ? */
81 { TRACER_OPT(funcgraph-overhead, TRACE_GRAPH_PRINT_OVERHEAD) },
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +010082 /* Display proc name/pid */
83 { TRACER_OPT(funcgraph-proc, TRACE_GRAPH_PRINT_PROC) },
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -080084 /* Display duration of execution */
85 { TRACER_OPT(funcgraph-duration, TRACE_GRAPH_PRINT_DURATION) },
86 /* Display absolute time of an entry */
87 { TRACER_OPT(funcgraph-abstime, TRACE_GRAPH_PRINT_ABS_TIME) },
Jiri Olsa2bd162122010-09-07 16:53:44 +020088 /* Display interrupts */
89 { TRACER_OPT(funcgraph-irqs, TRACE_GRAPH_PRINT_IRQS) },
Robert Elliott607e3a22014-05-20 17:10:51 -050090 /* Display function name after trailing } */
91 { TRACER_OPT(funcgraph-tail, TRACE_GRAPH_PRINT_TAIL) },
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010092 { } /* Empty entry */
93};
94
95static struct tracer_flags tracer_flags = {
Robert Elliott607e3a22014-05-20 17:10:51 -050096 /* Don't display overruns, proc, or tail by default */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -080097 .val = TRACE_GRAPH_PRINT_CPU | TRACE_GRAPH_PRINT_OVERHEAD |
Jiri Olsa2bd162122010-09-07 16:53:44 +020098 TRACE_GRAPH_PRINT_DURATION | TRACE_GRAPH_PRINT_IRQS,
Frederic Weisbeckerfb526072008-11-25 21:07:04 +010099 .opts = trace_opts
100};
101
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200102static struct trace_array *graph_array;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800103
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200104/*
105 * DURATION column is being also used to display IRQ signs,
106 * following values are used by print_graph_irq and others
107 * to fill in space into DURATION column.
108 */
109enum {
Steven Rostedt (Red Hat)6fc84ea2013-11-06 14:50:06 -0500110 FLAGS_FILL_FULL = 1 << TRACE_GRAPH_PRINT_FILL_SHIFT,
111 FLAGS_FILL_START = 2 << TRACE_GRAPH_PRINT_FILL_SHIFT,
112 FLAGS_FILL_END = 3 << TRACE_GRAPH_PRINT_FILL_SHIFT,
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200113};
114
115static enum print_line_t
116print_graph_duration(unsigned long long duration, struct trace_seq *s,
117 u32 flags);
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100118
Steven Rostedt712406a2009-02-09 10:54:03 -0800119/* Add a function return address to the trace stack on thread info.*/
120int
Steven Rostedt71e308a2009-06-18 12:45:08 -0400121ftrace_push_return_trace(unsigned long ret, unsigned long func, int *depth,
122 unsigned long frame_pointer)
Steven Rostedt712406a2009-02-09 10:54:03 -0800123{
Steven Rostedt5d1a03d2009-03-23 23:38:49 -0400124 unsigned long long calltime;
Steven Rostedt712406a2009-02-09 10:54:03 -0800125 int index;
126
Steven Rostedt (Red Hat)1b2f1212014-06-25 10:39:46 -0400127 if (unlikely(ftrace_graph_is_dead()))
128 return -EBUSY;
129
Steven Rostedt712406a2009-02-09 10:54:03 -0800130 if (!current->ret_stack)
131 return -EBUSY;
132
Steven Rostedt82310a32009-06-02 12:26:07 -0400133 /*
134 * We must make sure the ret_stack is tested before we read
135 * anything else.
136 */
137 smp_rmb();
138
Steven Rostedt712406a2009-02-09 10:54:03 -0800139 /* The return trace stack is full */
140 if (current->curr_ret_stack == FTRACE_RETFUNC_DEPTH - 1) {
141 atomic_inc(&current->trace_overrun);
142 return -EBUSY;
143 }
144
Namhyung Kim29ad23b2013-10-14 17:24:26 +0900145 /*
146 * The curr_ret_stack is an index to ftrace return stack of
147 * current task. Its value should be in [0, FTRACE_RETFUNC_
148 * DEPTH) when the function graph tracer is used. To support
149 * filtering out specific functions, it makes the index
150 * negative by subtracting huge value (FTRACE_NOTRACE_DEPTH)
151 * so when it sees a negative index the ftrace will ignore
152 * the record. And the index gets recovered when returning
153 * from the filtered function by adding the FTRACE_NOTRACE_
154 * DEPTH and then it'll continue to record functions normally.
155 *
156 * The curr_ret_stack is initialized to -1 and get increased
157 * in this function. So it can be less than -1 only if it was
158 * filtered out via ftrace_graph_notrace_addr() which can be
159 * set from set_graph_notrace file in debugfs by user.
160 */
161 if (current->curr_ret_stack < -1)
162 return -EBUSY;
163
Steven Rostedt5d1a03d2009-03-23 23:38:49 -0400164 calltime = trace_clock_local();
165
Steven Rostedt712406a2009-02-09 10:54:03 -0800166 index = ++current->curr_ret_stack;
Namhyung Kim29ad23b2013-10-14 17:24:26 +0900167 if (ftrace_graph_notrace_addr(func))
168 current->curr_ret_stack -= FTRACE_NOTRACE_DEPTH;
Steven Rostedt712406a2009-02-09 10:54:03 -0800169 barrier();
170 current->ret_stack[index].ret = ret;
171 current->ret_stack[index].func = func;
Steven Rostedt5d1a03d2009-03-23 23:38:49 -0400172 current->ret_stack[index].calltime = calltime;
Steven Rostedta2a16d62009-03-24 23:17:58 -0400173 current->ret_stack[index].subtime = 0;
Steven Rostedt71e308a2009-06-18 12:45:08 -0400174 current->ret_stack[index].fp = frame_pointer;
Namhyung Kim29ad23b2013-10-14 17:24:26 +0900175 *depth = current->curr_ret_stack;
Steven Rostedt712406a2009-02-09 10:54:03 -0800176
177 return 0;
178}
179
180/* Retrieve a function return address to the trace stack on thread info.*/
Steven Rostedta2a16d62009-03-24 23:17:58 -0400181static void
Steven Rostedt71e308a2009-06-18 12:45:08 -0400182ftrace_pop_return_trace(struct ftrace_graph_ret *trace, unsigned long *ret,
183 unsigned long frame_pointer)
Steven Rostedt712406a2009-02-09 10:54:03 -0800184{
185 int index;
186
187 index = current->curr_ret_stack;
188
Namhyung Kim29ad23b2013-10-14 17:24:26 +0900189 /*
190 * A negative index here means that it's just returned from a
191 * notrace'd function. Recover index to get an original
192 * return address. See ftrace_push_return_trace().
193 *
194 * TODO: Need to check whether the stack gets corrupted.
195 */
196 if (index < 0)
197 index += FTRACE_NOTRACE_DEPTH;
198
199 if (unlikely(index < 0 || index >= FTRACE_RETFUNC_DEPTH)) {
Steven Rostedt712406a2009-02-09 10:54:03 -0800200 ftrace_graph_stop();
201 WARN_ON(1);
202 /* Might as well panic, otherwise we have no where to go */
203 *ret = (unsigned long)panic;
204 return;
205 }
206
Steven Rostedt781d0622011-02-09 13:27:22 -0500207#if defined(CONFIG_HAVE_FUNCTION_GRAPH_FP_TEST) && !defined(CC_USING_FENTRY)
Steven Rostedt71e308a2009-06-18 12:45:08 -0400208 /*
209 * The arch may choose to record the frame pointer used
210 * and check it here to make sure that it is what we expect it
211 * to be. If gcc does not set the place holder of the return
212 * address in the frame pointer, and does a copy instead, then
213 * the function graph trace will fail. This test detects this
214 * case.
215 *
216 * Currently, x86_32 with optimize for size (-Os) makes the latest
217 * gcc do the above.
Steven Rostedt781d0622011-02-09 13:27:22 -0500218 *
219 * Note, -mfentry does not use frame pointers, and this test
220 * is not needed if CC_USING_FENTRY is set.
Steven Rostedt71e308a2009-06-18 12:45:08 -0400221 */
222 if (unlikely(current->ret_stack[index].fp != frame_pointer)) {
223 ftrace_graph_stop();
224 WARN(1, "Bad frame pointer: expected %lx, received %lx\n"
Steven Rostedtb375a112009-09-17 00:05:58 -0400225 " from func %ps return to %lx\n",
Steven Rostedt71e308a2009-06-18 12:45:08 -0400226 current->ret_stack[index].fp,
227 frame_pointer,
228 (void *)current->ret_stack[index].func,
229 current->ret_stack[index].ret);
230 *ret = (unsigned long)panic;
231 return;
232 }
233#endif
234
Steven Rostedt712406a2009-02-09 10:54:03 -0800235 *ret = current->ret_stack[index].ret;
236 trace->func = current->ret_stack[index].func;
237 trace->calltime = current->ret_stack[index].calltime;
238 trace->overrun = atomic_read(&current->trace_overrun);
239 trace->depth = index;
Steven Rostedt712406a2009-02-09 10:54:03 -0800240}
241
242/*
243 * Send the trace to the ring-buffer.
244 * @return the original return address.
245 */
Steven Rostedt71e308a2009-06-18 12:45:08 -0400246unsigned long ftrace_return_to_handler(unsigned long frame_pointer)
Steven Rostedt712406a2009-02-09 10:54:03 -0800247{
248 struct ftrace_graph_ret trace;
249 unsigned long ret;
250
Steven Rostedt71e308a2009-06-18 12:45:08 -0400251 ftrace_pop_return_trace(&trace, &ret, frame_pointer);
Frederic Weisbecker00126932009-03-05 01:49:22 +0100252 trace.rettime = trace_clock_local();
Steven Rostedta2a16d62009-03-24 23:17:58 -0400253 barrier();
254 current->curr_ret_stack--;
Namhyung Kim29ad23b2013-10-14 17:24:26 +0900255 /*
256 * The curr_ret_stack can be less than -1 only if it was
257 * filtered out and it's about to return from the function.
258 * Recover the index and continue to trace normal functions.
259 */
260 if (current->curr_ret_stack < -1) {
261 current->curr_ret_stack += FTRACE_NOTRACE_DEPTH;
262 return ret;
263 }
Steven Rostedt712406a2009-02-09 10:54:03 -0800264
Steven Rostedt (Red Hat)03274a32013-01-29 17:30:31 -0500265 /*
266 * The trace should run after decrementing the ret counter
267 * in case an interrupt were to come in. We don't want to
268 * lose the interrupt if max_depth is set.
269 */
270 ftrace_graph_return(&trace);
271
Steven Rostedt712406a2009-02-09 10:54:03 -0800272 if (unlikely(!ret)) {
273 ftrace_graph_stop();
274 WARN_ON(1);
275 /* Might as well panic. What else to do? */
276 ret = (unsigned long)panic;
277 }
278
279 return ret;
280}
281
Jiri Olsa62b915f2010-04-02 19:01:22 +0200282int __trace_graph_entry(struct trace_array *tr,
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200283 struct ftrace_graph_ent *trace,
284 unsigned long flags,
285 int pc)
286{
287 struct ftrace_event_call *call = &event_funcgraph_entry;
288 struct ring_buffer_event *event;
Steven Rostedt (Red Hat)12883ef2013-03-05 09:24:35 -0500289 struct ring_buffer *buffer = tr->trace_buffer.buffer;
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200290 struct ftrace_graph_ent_entry *entry;
291
Rusty Russelldd17c8f2009-10-29 22:34:15 +0900292 if (unlikely(__this_cpu_read(ftrace_cpu_disabled)))
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200293 return 0;
294
Steven Rostedte77405a2009-09-02 14:17:06 -0400295 event = trace_buffer_lock_reserve(buffer, TRACE_GRAPH_ENT,
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200296 sizeof(*entry), flags, pc);
297 if (!event)
298 return 0;
299 entry = ring_buffer_event_data(event);
300 entry->graph_ent = *trace;
Tom Zanussif306cc82013-10-24 08:34:17 -0500301 if (!call_filter_check_discard(call, entry, buffer, event))
Steven Rostedt7ffbd482012-10-11 12:14:25 -0400302 __buffer_unlock_commit(buffer, event);
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200303
304 return 1;
305}
306
Steven Rostedtb304d042010-09-14 18:58:33 -0400307static inline int ftrace_graph_ignore_irqs(void)
308{
Steven Rostedte4a3f542011-06-14 19:02:29 -0400309 if (!ftrace_graph_skip_irqs || trace_recursion_test(TRACE_IRQ_BIT))
Steven Rostedtb304d042010-09-14 18:58:33 -0400310 return 0;
311
312 return in_irq();
313}
314
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200315int trace_graph_entry(struct ftrace_graph_ent *trace)
316{
317 struct trace_array *tr = graph_array;
318 struct trace_array_cpu *data;
319 unsigned long flags;
320 long disabled;
321 int ret;
322 int cpu;
323 int pc;
324
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200325 if (!ftrace_trace_task(current))
326 return 0;
327
Lai Jiangshanea2c68a2010-01-13 19:38:30 +0800328 /* trace it when it is-nested-in or is a function enabled. */
Steven Rostedt8741db52013-01-16 10:49:37 -0500329 if ((!(trace->depth || ftrace_graph_addr(trace->func)) ||
Namhyung Kim29ad23b2013-10-14 17:24:26 +0900330 ftrace_graph_ignore_irqs()) || (trace->depth < 0) ||
Steven Rostedt8741db52013-01-16 10:49:37 -0500331 (max_depth && trace->depth >= max_depth))
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200332 return 0;
333
Namhyung Kim29ad23b2013-10-14 17:24:26 +0900334 /*
335 * Do not trace a function if it's filtered by set_graph_notrace.
336 * Make the index of ret stack negative to indicate that it should
337 * ignore further functions. But it needs its own ret stack entry
338 * to recover the original index in order to continue tracing after
339 * returning from the function.
340 */
341 if (ftrace_graph_notrace_addr(trace->func))
342 return 1;
343
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200344 local_irq_save(flags);
345 cpu = raw_smp_processor_id();
Steven Rostedt (Red Hat)12883ef2013-03-05 09:24:35 -0500346 data = per_cpu_ptr(tr->trace_buffer.data, cpu);
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200347 disabled = atomic_inc_return(&data->disabled);
348 if (likely(disabled == 1)) {
349 pc = preempt_count();
350 ret = __trace_graph_entry(tr, trace, flags, pc);
351 } else {
352 ret = 0;
353 }
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200354
355 atomic_dec(&data->disabled);
356 local_irq_restore(flags);
357
358 return ret;
359}
360
Tim Bird0e950172010-02-25 15:36:43 -0800361int trace_graph_thresh_entry(struct ftrace_graph_ent *trace)
362{
363 if (tracing_thresh)
364 return 1;
365 else
366 return trace_graph_entry(trace);
367}
368
Jiri Olsa0a772622010-09-23 14:00:52 +0200369static void
370__trace_graph_function(struct trace_array *tr,
371 unsigned long ip, unsigned long flags, int pc)
372{
373 u64 time = trace_clock_local();
374 struct ftrace_graph_ent ent = {
375 .func = ip,
376 .depth = 0,
377 };
378 struct ftrace_graph_ret ret = {
379 .func = ip,
380 .depth = 0,
381 .calltime = time,
382 .rettime = time,
383 };
384
385 __trace_graph_entry(tr, &ent, flags, pc);
386 __trace_graph_return(tr, &ret, flags, pc);
387}
388
389void
390trace_graph_function(struct trace_array *tr,
391 unsigned long ip, unsigned long parent_ip,
392 unsigned long flags, int pc)
393{
Jiri Olsa0a772622010-09-23 14:00:52 +0200394 __trace_graph_function(tr, ip, flags, pc);
395}
396
Jiri Olsa62b915f2010-04-02 19:01:22 +0200397void __trace_graph_return(struct trace_array *tr,
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200398 struct ftrace_graph_ret *trace,
399 unsigned long flags,
400 int pc)
401{
402 struct ftrace_event_call *call = &event_funcgraph_exit;
403 struct ring_buffer_event *event;
Steven Rostedt (Red Hat)12883ef2013-03-05 09:24:35 -0500404 struct ring_buffer *buffer = tr->trace_buffer.buffer;
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200405 struct ftrace_graph_ret_entry *entry;
406
Rusty Russelldd17c8f2009-10-29 22:34:15 +0900407 if (unlikely(__this_cpu_read(ftrace_cpu_disabled)))
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200408 return;
409
Steven Rostedte77405a2009-09-02 14:17:06 -0400410 event = trace_buffer_lock_reserve(buffer, TRACE_GRAPH_RET,
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200411 sizeof(*entry), flags, pc);
412 if (!event)
413 return;
414 entry = ring_buffer_event_data(event);
415 entry->ret = *trace;
Tom Zanussif306cc82013-10-24 08:34:17 -0500416 if (!call_filter_check_discard(call, entry, buffer, event))
Steven Rostedt7ffbd482012-10-11 12:14:25 -0400417 __buffer_unlock_commit(buffer, event);
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200418}
419
420void trace_graph_return(struct ftrace_graph_ret *trace)
421{
422 struct trace_array *tr = graph_array;
423 struct trace_array_cpu *data;
424 unsigned long flags;
425 long disabled;
426 int cpu;
427 int pc;
428
429 local_irq_save(flags);
430 cpu = raw_smp_processor_id();
Steven Rostedt (Red Hat)12883ef2013-03-05 09:24:35 -0500431 data = per_cpu_ptr(tr->trace_buffer.data, cpu);
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200432 disabled = atomic_inc_return(&data->disabled);
433 if (likely(disabled == 1)) {
434 pc = preempt_count();
435 __trace_graph_return(tr, trace, flags, pc);
436 }
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200437 atomic_dec(&data->disabled);
438 local_irq_restore(flags);
439}
440
Frederic Weisbecker24a53652010-01-14 08:23:15 +0100441void set_graph_array(struct trace_array *tr)
442{
443 graph_array = tr;
444
445 /* Make graph_array visible before we start tracing */
446
447 smp_mb();
448}
449
Tim Bird0e950172010-02-25 15:36:43 -0800450void trace_graph_thresh_return(struct ftrace_graph_ret *trace)
451{
452 if (tracing_thresh &&
453 (trace->rettime - trace->calltime < tracing_thresh))
454 return;
455 else
456 trace_graph_return(trace);
457}
458
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100459static int graph_trace_init(struct trace_array *tr)
460{
Frederic Weisbecker1a0799a2009-07-29 18:59:58 +0200461 int ret;
462
Frederic Weisbecker24a53652010-01-14 08:23:15 +0100463 set_graph_array(tr);
Tim Bird0e950172010-02-25 15:36:43 -0800464 if (tracing_thresh)
465 ret = register_ftrace_graph(&trace_graph_thresh_return,
466 &trace_graph_thresh_entry);
467 else
468 ret = register_ftrace_graph(&trace_graph_return,
469 &trace_graph_entry);
Steven Rostedt660c7f92008-11-26 00:16:26 -0500470 if (ret)
471 return ret;
472 tracing_start_cmdline_record();
473
474 return 0;
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100475}
476
477static void graph_trace_reset(struct trace_array *tr)
478{
Steven Rostedt660c7f92008-11-26 00:16:26 -0500479 tracing_stop_cmdline_record();
480 unregister_ftrace_graph();
Frederic Weisbeckerfb526072008-11-25 21:07:04 +0100481}
482
Lai Jiangshan0c9e6f62009-07-28 20:26:06 +0800483static int max_bytes_for_cpu;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100484
485static enum print_line_t
486print_graph_cpu(struct trace_seq *s, int cpu)
487{
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100488 int ret;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100489
Ingo Molnard51090b2008-11-28 09:55:16 +0100490 /*
491 * Start with a space character - to make it stand out
492 * to the right a bit when trace output is pasted into
493 * email:
494 */
Lai Jiangshan0c9e6f62009-07-28 20:26:06 +0800495 ret = trace_seq_printf(s, " %*d) ", max_bytes_for_cpu, cpu);
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100496 if (!ret)
Ingo Molnard51090b2008-11-28 09:55:16 +0100497 return TRACE_TYPE_PARTIAL_LINE;
498
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100499 return TRACE_TYPE_HANDLED;
500}
501
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100502#define TRACE_GRAPH_PROCINFO_LENGTH 14
503
504static enum print_line_t
505print_graph_proc(struct trace_seq *s, pid_t pid)
506{
Steven Rostedt4ca53082009-03-16 19:20:15 -0400507 char comm[TASK_COMM_LEN];
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100508 /* sign + log10(MAX_INT) + '\0' */
509 char pid_str[11];
Steven Rostedt4ca53082009-03-16 19:20:15 -0400510 int spaces = 0;
511 int ret;
512 int len;
513 int i;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100514
Steven Rostedt4ca53082009-03-16 19:20:15 -0400515 trace_find_cmdline(pid, comm);
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100516 comm[7] = '\0';
517 sprintf(pid_str, "%d", pid);
518
519 /* 1 stands for the "-" character */
520 len = strlen(comm) + strlen(pid_str) + 1;
521
522 if (len < TRACE_GRAPH_PROCINFO_LENGTH)
523 spaces = TRACE_GRAPH_PROCINFO_LENGTH - len;
524
525 /* First spaces to align center */
526 for (i = 0; i < spaces / 2; i++) {
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800527 ret = trace_seq_putc(s, ' ');
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100528 if (!ret)
529 return TRACE_TYPE_PARTIAL_LINE;
530 }
531
532 ret = trace_seq_printf(s, "%s-%s", comm, pid_str);
533 if (!ret)
534 return TRACE_TYPE_PARTIAL_LINE;
535
536 /* Last spaces to align center */
537 for (i = 0; i < spaces - (spaces / 2); i++) {
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800538 ret = trace_seq_putc(s, ' ');
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100539 if (!ret)
540 return TRACE_TYPE_PARTIAL_LINE;
541 }
542 return TRACE_TYPE_HANDLED;
543}
544
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100545
Steven Rostedt49ff5902009-09-11 00:30:26 -0400546static enum print_line_t
547print_graph_lat_fmt(struct trace_seq *s, struct trace_entry *entry)
548{
Steven Rostedtf81c9722009-09-11 14:24:13 -0400549 if (!trace_seq_putc(s, ' '))
Steven Rostedt49ff5902009-09-11 00:30:26 -0400550 return 0;
551
Steven Rostedtf81c9722009-09-11 14:24:13 -0400552 return trace_print_lat_fmt(s, entry);
Steven Rostedt49ff5902009-09-11 00:30:26 -0400553}
554
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100555/* If the pid changed since the last trace, output this event */
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100556static enum print_line_t
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400557verif_pid(struct trace_seq *s, pid_t pid, int cpu, struct fgraph_data *data)
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100558{
Ingo Molnard51090b2008-11-28 09:55:16 +0100559 pid_t prev_pid;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800560 pid_t *last_pid;
Ingo Molnard51090b2008-11-28 09:55:16 +0100561 int ret;
Steven Rostedt660c7f92008-11-26 00:16:26 -0500562
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400563 if (!data)
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100564 return TRACE_TYPE_HANDLED;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100565
Jiri Olsabe1eca32009-11-24 13:57:38 +0100566 last_pid = &(per_cpu_ptr(data->cpu_data, cpu)->last_pid);
Steven Rostedt660c7f92008-11-26 00:16:26 -0500567
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800568 if (*last_pid == pid)
569 return TRACE_TYPE_HANDLED;
570
571 prev_pid = *last_pid;
572 *last_pid = pid;
573
574 if (prev_pid == -1)
575 return TRACE_TYPE_HANDLED;
Ingo Molnard51090b2008-11-28 09:55:16 +0100576/*
577 * Context-switch trace line:
578
579 ------------------------------------------
580 | 1) migration/0--1 => sshd-1755
581 ------------------------------------------
582
583 */
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800584 ret = trace_seq_puts(s,
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +0100585 " ------------------------------------------\n");
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100586 if (!ret)
Wenji Huang810dc732009-02-10 01:03:05 -0500587 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100588
589 ret = print_graph_cpu(s, cpu);
590 if (ret == TRACE_TYPE_PARTIAL_LINE)
Wenji Huang810dc732009-02-10 01:03:05 -0500591 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100592
593 ret = print_graph_proc(s, prev_pid);
594 if (ret == TRACE_TYPE_PARTIAL_LINE)
Wenji Huang810dc732009-02-10 01:03:05 -0500595 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100596
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800597 ret = trace_seq_puts(s, " => ");
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100598 if (!ret)
Wenji Huang810dc732009-02-10 01:03:05 -0500599 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100600
601 ret = print_graph_proc(s, pid);
602 if (ret == TRACE_TYPE_PARTIAL_LINE)
Wenji Huang810dc732009-02-10 01:03:05 -0500603 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100604
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800605 ret = trace_seq_puts(s,
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100606 "\n ------------------------------------------\n\n");
607 if (!ret)
Wenji Huang810dc732009-02-10 01:03:05 -0500608 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100609
Wenji Huang810dc732009-02-10 01:03:05 -0500610 return TRACE_TYPE_HANDLED;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100611}
612
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100613static struct ftrace_graph_ret_entry *
614get_return_for_leaf(struct trace_iterator *iter,
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100615 struct ftrace_graph_ent_entry *curr)
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100616{
Jiri Olsabe1eca32009-11-24 13:57:38 +0100617 struct fgraph_data *data = iter->private;
618 struct ring_buffer_iter *ring_iter = NULL;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100619 struct ring_buffer_event *event;
620 struct ftrace_graph_ret_entry *next;
621
Jiri Olsabe1eca32009-11-24 13:57:38 +0100622 /*
623 * If the previous output failed to write to the seq buffer,
624 * then we just reuse the data from before.
625 */
626 if (data && data->failed) {
627 curr = &data->ent;
628 next = &data->ret;
629 } else {
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100630
Steven Rostedt6d158a82012-06-27 20:46:14 -0400631 ring_iter = trace_buffer_iter(iter, iter->cpu);
Jiri Olsabe1eca32009-11-24 13:57:38 +0100632
633 /* First peek to compare current entry and the next one */
634 if (ring_iter)
635 event = ring_buffer_iter_peek(ring_iter, NULL);
636 else {
637 /*
638 * We need to consume the current entry to see
639 * the next one.
640 */
Steven Rostedt (Red Hat)12883ef2013-03-05 09:24:35 -0500641 ring_buffer_consume(iter->trace_buffer->buffer, iter->cpu,
Steven Rostedt66a8cb92010-03-31 13:21:56 -0400642 NULL, NULL);
Steven Rostedt (Red Hat)12883ef2013-03-05 09:24:35 -0500643 event = ring_buffer_peek(iter->trace_buffer->buffer, iter->cpu,
Steven Rostedt66a8cb92010-03-31 13:21:56 -0400644 NULL, NULL);
Jiri Olsabe1eca32009-11-24 13:57:38 +0100645 }
646
647 if (!event)
648 return NULL;
649
650 next = ring_buffer_event_data(event);
651
652 if (data) {
653 /*
654 * Save current and next entries for later reference
655 * if the output fails.
656 */
657 data->ent = *curr;
Shaohua Li575570f2010-07-27 16:06:34 +0800658 /*
659 * If the next event is not a return type, then
660 * we only care about what type it is. Otherwise we can
661 * safely copy the entire event.
662 */
663 if (next->ent.type == TRACE_GRAPH_RET)
664 data->ret = *next;
665 else
666 data->ret.ent.type = next->ent.type;
Jiri Olsabe1eca32009-11-24 13:57:38 +0100667 }
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100668 }
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100669
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100670 if (next->ent.type != TRACE_GRAPH_RET)
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100671 return NULL;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100672
673 if (curr->ent.pid != next->ent.pid ||
674 curr->graph_ent.func != next->ret.func)
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100675 return NULL;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100676
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100677 /* this is a leaf, now advance the iterator */
678 if (ring_iter)
679 ring_buffer_read(ring_iter, NULL);
680
681 return next;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100682}
683
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100684static int print_graph_abs_time(u64 t, struct trace_seq *s)
685{
686 unsigned long usecs_rem;
687
688 usecs_rem = do_div(t, NSEC_PER_SEC);
689 usecs_rem /= 1000;
690
691 return trace_seq_printf(s, "%5lu.%06lu | ",
692 (unsigned long)t, usecs_rem);
693}
694
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100695static enum print_line_t
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100696print_graph_irq(struct trace_iterator *iter, unsigned long addr,
Jiri Olsad7a8d9e2010-04-02 19:01:21 +0200697 enum trace_type type, int cpu, pid_t pid, u32 flags)
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100698{
699 int ret;
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100700 struct trace_seq *s = &iter->seq;
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100701
702 if (addr < (unsigned long)__irqentry_text_start ||
703 addr >= (unsigned long)__irqentry_text_end)
704 return TRACE_TYPE_UNHANDLED;
705
Jiri Olsa749230b2011-06-03 16:58:51 +0200706 if (trace_flags & TRACE_ITER_CONTEXT_INFO) {
707 /* Absolute time */
708 if (flags & TRACE_GRAPH_PRINT_ABS_TIME) {
709 ret = print_graph_abs_time(iter->ts, s);
710 if (!ret)
711 return TRACE_TYPE_PARTIAL_LINE;
712 }
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +0100713
Jiri Olsa749230b2011-06-03 16:58:51 +0200714 /* Cpu */
715 if (flags & TRACE_GRAPH_PRINT_CPU) {
716 ret = print_graph_cpu(s, cpu);
717 if (ret == TRACE_TYPE_PARTIAL_LINE)
718 return TRACE_TYPE_PARTIAL_LINE;
719 }
Steven Rostedt49ff5902009-09-11 00:30:26 -0400720
Jiri Olsa749230b2011-06-03 16:58:51 +0200721 /* Proc */
722 if (flags & TRACE_GRAPH_PRINT_PROC) {
723 ret = print_graph_proc(s, pid);
724 if (ret == TRACE_TYPE_PARTIAL_LINE)
725 return TRACE_TYPE_PARTIAL_LINE;
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800726 ret = trace_seq_puts(s, " | ");
Jiri Olsa749230b2011-06-03 16:58:51 +0200727 if (!ret)
728 return TRACE_TYPE_PARTIAL_LINE;
729 }
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800730 }
731
732 /* No overhead */
Steven Rostedt (Red Hat)6fc84ea2013-11-06 14:50:06 -0500733 ret = print_graph_duration(0, s, flags | FLAGS_FILL_START);
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200734 if (ret != TRACE_TYPE_HANDLED)
735 return ret;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800736
737 if (type == TRACE_GRAPH_ENT)
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800738 ret = trace_seq_puts(s, "==========>");
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800739 else
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800740 ret = trace_seq_puts(s, "<==========");
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800741
742 if (!ret)
743 return TRACE_TYPE_PARTIAL_LINE;
744
Steven Rostedt (Red Hat)6fc84ea2013-11-06 14:50:06 -0500745 ret = print_graph_duration(0, s, flags | FLAGS_FILL_END);
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200746 if (ret != TRACE_TYPE_HANDLED)
747 return ret;
748
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800749 ret = trace_seq_putc(s, '\n');
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800750
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100751 if (!ret)
752 return TRACE_TYPE_PARTIAL_LINE;
753 return TRACE_TYPE_HANDLED;
754}
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100755
Steven Rostedt0706f1c2009-03-23 23:12:58 -0400756enum print_line_t
757trace_print_graph_duration(unsigned long long duration, struct trace_seq *s)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100758{
759 unsigned long nsecs_rem = do_div(duration, 1000);
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100760 /* log10(ULONG_MAX) + '\0' */
761 char msecs_str[21];
762 char nsecs_str[5];
763 int ret, len;
764 int i;
765
766 sprintf(msecs_str, "%lu", (unsigned long) duration);
767
768 /* Print msecs */
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800769 ret = trace_seq_printf(s, "%s", msecs_str);
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100770 if (!ret)
771 return TRACE_TYPE_PARTIAL_LINE;
772
773 len = strlen(msecs_str);
774
775 /* Print nsecs (we don't want to exceed 7 numbers) */
776 if (len < 7) {
Borislav Petkov14cae9b2010-09-29 10:08:23 +0200777 size_t slen = min_t(size_t, sizeof(nsecs_str), 8UL - len);
778
779 snprintf(nsecs_str, slen, "%03lu", nsecs_rem);
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100780 ret = trace_seq_printf(s, ".%s", nsecs_str);
781 if (!ret)
782 return TRACE_TYPE_PARTIAL_LINE;
783 len += strlen(nsecs_str);
784 }
785
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800786 ret = trace_seq_puts(s, " us ");
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100787 if (!ret)
788 return TRACE_TYPE_PARTIAL_LINE;
789
790 /* Print remaining spaces to fit the row's width */
791 for (i = len; i < 7; i++) {
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800792 ret = trace_seq_putc(s, ' ');
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100793 if (!ret)
794 return TRACE_TYPE_PARTIAL_LINE;
795 }
Steven Rostedt0706f1c2009-03-23 23:12:58 -0400796 return TRACE_TYPE_HANDLED;
797}
798
799static enum print_line_t
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200800print_graph_duration(unsigned long long duration, struct trace_seq *s,
801 u32 flags)
Steven Rostedt0706f1c2009-03-23 23:12:58 -0400802{
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200803 int ret = -1;
804
Jiri Olsa749230b2011-06-03 16:58:51 +0200805 if (!(flags & TRACE_GRAPH_PRINT_DURATION) ||
806 !(trace_flags & TRACE_ITER_CONTEXT_INFO))
807 return TRACE_TYPE_HANDLED;
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200808
809 /* No real adata, just filling the column with spaces */
Steven Rostedt (Red Hat)6fc84ea2013-11-06 14:50:06 -0500810 switch (flags & TRACE_GRAPH_PRINT_FILL_MASK) {
811 case FLAGS_FILL_FULL:
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800812 ret = trace_seq_puts(s, " | ");
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200813 return ret ? TRACE_TYPE_HANDLED : TRACE_TYPE_PARTIAL_LINE;
Steven Rostedt (Red Hat)6fc84ea2013-11-06 14:50:06 -0500814 case FLAGS_FILL_START:
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800815 ret = trace_seq_puts(s, " ");
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200816 return ret ? TRACE_TYPE_HANDLED : TRACE_TYPE_PARTIAL_LINE;
Steven Rostedt (Red Hat)6fc84ea2013-11-06 14:50:06 -0500817 case FLAGS_FILL_END:
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800818 ret = trace_seq_puts(s, " |");
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200819 return ret ? TRACE_TYPE_HANDLED : TRACE_TYPE_PARTIAL_LINE;
820 }
821
822 /* Signal a overhead of time execution to the output */
823 if (flags & TRACE_GRAPH_PRINT_OVERHEAD) {
824 /* Duration exceeded 100 msecs */
825 if (duration > 100000ULL)
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800826 ret = trace_seq_puts(s, "! ");
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200827 /* Duration exceeded 10 msecs */
828 else if (duration > 10000ULL)
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800829 ret = trace_seq_puts(s, "+ ");
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200830 }
831
832 /*
833 * The -1 means we either did not exceed the duration tresholds
834 * or we dont want to print out the overhead. Either way we need
835 * to fill out the space.
836 */
837 if (ret == -1)
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800838 ret = trace_seq_puts(s, " ");
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200839
840 /* Catching here any failure happenned above */
841 if (!ret)
842 return TRACE_TYPE_PARTIAL_LINE;
Steven Rostedt0706f1c2009-03-23 23:12:58 -0400843
844 ret = trace_print_graph_duration(duration, s);
845 if (ret != TRACE_TYPE_HANDLED)
846 return ret;
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100847
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800848 ret = trace_seq_puts(s, "| ");
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100849 if (!ret)
850 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker166d3c72008-12-03 02:32:12 +0100851
Steven Rostedt0706f1c2009-03-23 23:12:58 -0400852 return TRACE_TYPE_HANDLED;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100853}
854
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100855/* Case of a leaf function on its call entry */
856static enum print_line_t
857print_graph_entry_leaf(struct trace_iterator *iter,
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100858 struct ftrace_graph_ent_entry *entry,
Jiri Olsad7a8d9e2010-04-02 19:01:21 +0200859 struct ftrace_graph_ret_entry *ret_entry,
860 struct trace_seq *s, u32 flags)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100861{
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400862 struct fgraph_data *data = iter->private;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100863 struct ftrace_graph_ret *graph_ret;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100864 struct ftrace_graph_ent *call;
865 unsigned long long duration;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100866 int ret;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100867 int i;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100868
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100869 graph_ret = &ret_entry->ret;
870 call = &entry->graph_ent;
871 duration = graph_ret->rettime - graph_ret->calltime;
Steven Rostedt437f24fb2008-11-26 00:16:27 -0500872
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400873 if (data) {
Steven Rostedtf1c7f512010-02-26 17:08:16 -0500874 struct fgraph_cpu_data *cpu_data;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400875 int cpu = iter->cpu;
Steven Rostedtf1c7f512010-02-26 17:08:16 -0500876
877 cpu_data = per_cpu_ptr(data->cpu_data, cpu);
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400878
879 /*
880 * Comments display at + 1 to depth. Since
881 * this is a leaf function, keep the comments
882 * equal to this depth.
883 */
Steven Rostedtf1c7f512010-02-26 17:08:16 -0500884 cpu_data->depth = call->depth - 1;
885
886 /* No need to keep this function around for this depth */
887 if (call->depth < FTRACE_RETFUNC_DEPTH)
888 cpu_data->enter_funcs[call->depth] = 0;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400889 }
890
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200891 /* Overhead and duration */
892 ret = print_graph_duration(duration, s, flags);
893 if (ret == TRACE_TYPE_PARTIAL_LINE)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800894 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100895
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100896 /* Function */
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100897 for (i = 0; i < call->depth * TRACE_GRAPH_INDENT; i++) {
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800898 ret = trace_seq_putc(s, ' ');
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100899 if (!ret)
900 return TRACE_TYPE_PARTIAL_LINE;
901 }
902
Steven Rostedtb375a112009-09-17 00:05:58 -0400903 ret = trace_seq_printf(s, "%ps();\n", (void *)call->func);
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100904 if (!ret)
905 return TRACE_TYPE_PARTIAL_LINE;
906
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100907 return TRACE_TYPE_HANDLED;
908}
909
910static enum print_line_t
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400911print_graph_entry_nested(struct trace_iterator *iter,
912 struct ftrace_graph_ent_entry *entry,
Jiri Olsad7a8d9e2010-04-02 19:01:21 +0200913 struct trace_seq *s, int cpu, u32 flags)
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100914{
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100915 struct ftrace_graph_ent *call = &entry->graph_ent;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400916 struct fgraph_data *data = iter->private;
917 int ret;
918 int i;
919
920 if (data) {
Steven Rostedtf1c7f512010-02-26 17:08:16 -0500921 struct fgraph_cpu_data *cpu_data;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400922 int cpu = iter->cpu;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400923
Steven Rostedtf1c7f512010-02-26 17:08:16 -0500924 cpu_data = per_cpu_ptr(data->cpu_data, cpu);
925 cpu_data->depth = call->depth;
926
927 /* Save this function pointer to see if the exit matches */
928 if (call->depth < FTRACE_RETFUNC_DEPTH)
929 cpu_data->enter_funcs[call->depth] = call->func;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400930 }
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100931
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800932 /* No time */
Steven Rostedt (Red Hat)6fc84ea2013-11-06 14:50:06 -0500933 ret = print_graph_duration(0, s, flags | FLAGS_FILL_FULL);
Jiri Olsaffeb80f2011-06-03 16:58:48 +0200934 if (ret != TRACE_TYPE_HANDLED)
935 return ret;
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +0100936
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100937 /* Function */
938 for (i = 0; i < call->depth * TRACE_GRAPH_INDENT; i++) {
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800939 ret = trace_seq_putc(s, ' ');
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100940 if (!ret)
941 return TRACE_TYPE_PARTIAL_LINE;
942 }
943
Steven Rostedtb375a112009-09-17 00:05:58 -0400944 ret = trace_seq_printf(s, "%ps() {\n", (void *)call->func);
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100945 if (!ret)
946 return TRACE_TYPE_PARTIAL_LINE;
947
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +0100948 /*
949 * we already consumed the current entry to check the next one
950 * and see if this is a leaf.
951 */
952 return TRACE_TYPE_NO_CONSUME;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100953}
954
955static enum print_line_t
Steven Rostedtac5f6c92009-03-19 11:29:23 -0400956print_graph_prologue(struct trace_iterator *iter, struct trace_seq *s,
Jiri Olsad7a8d9e2010-04-02 19:01:21 +0200957 int type, unsigned long addr, u32 flags)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100958{
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400959 struct fgraph_data *data = iter->private;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +0100960 struct trace_entry *ent = iter->ent;
Steven Rostedtac5f6c92009-03-19 11:29:23 -0400961 int cpu = iter->cpu;
962 int ret;
Frederic Weisbecker287b6e62008-11-26 00:57:25 +0100963
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100964 /* Pid */
Steven Rostedt2fbcdb32009-03-19 13:24:42 -0400965 if (verif_pid(s, ent->pid, cpu, data) == TRACE_TYPE_PARTIAL_LINE)
Steven Rostedt437f24fb2008-11-26 00:16:27 -0500966 return TRACE_TYPE_PARTIAL_LINE;
967
Steven Rostedtac5f6c92009-03-19 11:29:23 -0400968 if (type) {
969 /* Interrupt */
Jiri Olsad7a8d9e2010-04-02 19:01:21 +0200970 ret = print_graph_irq(iter, addr, type, cpu, ent->pid, flags);
Steven Rostedtac5f6c92009-03-19 11:29:23 -0400971 if (ret == TRACE_TYPE_PARTIAL_LINE)
972 return TRACE_TYPE_PARTIAL_LINE;
973 }
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800974
Jiri Olsa749230b2011-06-03 16:58:51 +0200975 if (!(trace_flags & TRACE_ITER_CONTEXT_INFO))
976 return 0;
977
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800978 /* Absolute time */
Jiri Olsad7a8d9e2010-04-02 19:01:21 +0200979 if (flags & TRACE_GRAPH_PRINT_ABS_TIME) {
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -0800980 ret = print_graph_abs_time(iter->ts, s);
981 if (!ret)
982 return TRACE_TYPE_PARTIAL_LINE;
983 }
984
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100985 /* Cpu */
Jiri Olsad7a8d9e2010-04-02 19:01:21 +0200986 if (flags & TRACE_GRAPH_PRINT_CPU) {
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100987 ret = print_graph_cpu(s, cpu);
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100988 if (ret == TRACE_TYPE_PARTIAL_LINE)
989 return TRACE_TYPE_PARTIAL_LINE;
990 }
991
992 /* Proc */
Jiri Olsad7a8d9e2010-04-02 19:01:21 +0200993 if (flags & TRACE_GRAPH_PRINT_PROC) {
Ingo Molnar00a8bf82009-02-19 13:01:37 +0100994 ret = print_graph_proc(s, ent->pid);
Frederic Weisbecker11e84ac2008-12-03 02:30:37 +0100995 if (ret == TRACE_TYPE_PARTIAL_LINE)
996 return TRACE_TYPE_PARTIAL_LINE;
997
zhangwei(Jovi)146c3442013-07-15 16:32:44 +0800998 ret = trace_seq_puts(s, " | ");
Frederic Weisbecker1a056152008-11-28 00:42:46 +0100999 if (!ret)
1000 return TRACE_TYPE_PARTIAL_LINE;
1001 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001002
Steven Rostedt49ff5902009-09-11 00:30:26 -04001003 /* Latency format */
1004 if (trace_flags & TRACE_ITER_LATENCY_FMT) {
1005 ret = print_graph_lat_fmt(s, ent);
1006 if (ret == TRACE_TYPE_PARTIAL_LINE)
1007 return TRACE_TYPE_PARTIAL_LINE;
1008 }
1009
Steven Rostedtac5f6c92009-03-19 11:29:23 -04001010 return 0;
1011}
1012
Jiri Olsa2bd162122010-09-07 16:53:44 +02001013/*
1014 * Entry check for irq code
1015 *
1016 * returns 1 if
1017 * - we are inside irq code
Lucas De Marchi25985ed2011-03-30 22:57:33 -03001018 * - we just entered irq code
Jiri Olsa2bd162122010-09-07 16:53:44 +02001019 *
1020 * retunns 0 if
1021 * - funcgraph-interrupts option is set
1022 * - we are not inside irq code
1023 */
1024static int
1025check_irq_entry(struct trace_iterator *iter, u32 flags,
1026 unsigned long addr, int depth)
1027{
1028 int cpu = iter->cpu;
Jiri Olsaa9d61172010-09-24 17:41:02 +02001029 int *depth_irq;
Jiri Olsa2bd162122010-09-07 16:53:44 +02001030 struct fgraph_data *data = iter->private;
Jiri Olsa2bd162122010-09-07 16:53:44 +02001031
Jiri Olsaa9d61172010-09-24 17:41:02 +02001032 /*
1033 * If we are either displaying irqs, or we got called as
1034 * a graph event and private data does not exist,
1035 * then we bypass the irq check.
1036 */
1037 if ((flags & TRACE_GRAPH_PRINT_IRQS) ||
1038 (!data))
Jiri Olsa2bd162122010-09-07 16:53:44 +02001039 return 0;
1040
Jiri Olsaa9d61172010-09-24 17:41:02 +02001041 depth_irq = &(per_cpu_ptr(data->cpu_data, cpu)->depth_irq);
1042
Jiri Olsa2bd162122010-09-07 16:53:44 +02001043 /*
1044 * We are inside the irq code
1045 */
1046 if (*depth_irq >= 0)
1047 return 1;
1048
1049 if ((addr < (unsigned long)__irqentry_text_start) ||
1050 (addr >= (unsigned long)__irqentry_text_end))
1051 return 0;
1052
1053 /*
1054 * We are entering irq code.
1055 */
1056 *depth_irq = depth;
1057 return 1;
1058}
1059
1060/*
1061 * Return check for irq code
1062 *
1063 * returns 1 if
1064 * - we are inside irq code
1065 * - we just left irq code
1066 *
1067 * returns 0 if
1068 * - funcgraph-interrupts option is set
1069 * - we are not inside irq code
1070 */
1071static int
1072check_irq_return(struct trace_iterator *iter, u32 flags, int depth)
1073{
1074 int cpu = iter->cpu;
Jiri Olsaa9d61172010-09-24 17:41:02 +02001075 int *depth_irq;
Jiri Olsa2bd162122010-09-07 16:53:44 +02001076 struct fgraph_data *data = iter->private;
Jiri Olsa2bd162122010-09-07 16:53:44 +02001077
Jiri Olsaa9d61172010-09-24 17:41:02 +02001078 /*
1079 * If we are either displaying irqs, or we got called as
1080 * a graph event and private data does not exist,
1081 * then we bypass the irq check.
1082 */
1083 if ((flags & TRACE_GRAPH_PRINT_IRQS) ||
1084 (!data))
Jiri Olsa2bd162122010-09-07 16:53:44 +02001085 return 0;
1086
Jiri Olsaa9d61172010-09-24 17:41:02 +02001087 depth_irq = &(per_cpu_ptr(data->cpu_data, cpu)->depth_irq);
1088
Jiri Olsa2bd162122010-09-07 16:53:44 +02001089 /*
1090 * We are not inside the irq code.
1091 */
1092 if (*depth_irq == -1)
1093 return 0;
1094
1095 /*
1096 * We are inside the irq code, and this is returning entry.
1097 * Let's not trace it and clear the entry depth, since
1098 * we are out of irq code.
1099 *
1100 * This condition ensures that we 'leave the irq code' once
1101 * we are out of the entry depth. Thus protecting us from
1102 * the RETURN entry loss.
1103 */
1104 if (*depth_irq >= depth) {
1105 *depth_irq = -1;
1106 return 1;
1107 }
1108
1109 /*
1110 * We are inside the irq code, and this is not the entry.
1111 */
1112 return 1;
1113}
1114
Steven Rostedtac5f6c92009-03-19 11:29:23 -04001115static enum print_line_t
1116print_graph_entry(struct ftrace_graph_ent_entry *field, struct trace_seq *s,
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001117 struct trace_iterator *iter, u32 flags)
Steven Rostedtac5f6c92009-03-19 11:29:23 -04001118{
Jiri Olsabe1eca32009-11-24 13:57:38 +01001119 struct fgraph_data *data = iter->private;
Steven Rostedtac5f6c92009-03-19 11:29:23 -04001120 struct ftrace_graph_ent *call = &field->graph_ent;
1121 struct ftrace_graph_ret_entry *leaf_ret;
Jiri Olsabe1eca32009-11-24 13:57:38 +01001122 static enum print_line_t ret;
1123 int cpu = iter->cpu;
Steven Rostedtac5f6c92009-03-19 11:29:23 -04001124
Jiri Olsa2bd162122010-09-07 16:53:44 +02001125 if (check_irq_entry(iter, flags, call->func, call->depth))
1126 return TRACE_TYPE_HANDLED;
1127
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001128 if (print_graph_prologue(iter, s, TRACE_GRAPH_ENT, call->func, flags))
Steven Rostedtac5f6c92009-03-19 11:29:23 -04001129 return TRACE_TYPE_PARTIAL_LINE;
1130
Frederic Weisbeckerb91facc2009-02-06 18:30:44 +01001131 leaf_ret = get_return_for_leaf(iter, field);
1132 if (leaf_ret)
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001133 ret = print_graph_entry_leaf(iter, field, leaf_ret, s, flags);
Frederic Weisbecker83a8df62008-11-27 01:46:33 +01001134 else
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001135 ret = print_graph_entry_nested(iter, field, s, cpu, flags);
Frederic Weisbecker83a8df62008-11-27 01:46:33 +01001136
Jiri Olsabe1eca32009-11-24 13:57:38 +01001137 if (data) {
1138 /*
1139 * If we failed to write our output, then we need to make
1140 * note of it. Because we already consumed our entry.
1141 */
1142 if (s->full) {
1143 data->failed = 1;
1144 data->cpu = cpu;
1145 } else
1146 data->failed = 0;
1147 }
1148
1149 return ret;
Frederic Weisbecker83a8df62008-11-27 01:46:33 +01001150}
1151
1152static enum print_line_t
1153print_graph_return(struct ftrace_graph_ret *trace, struct trace_seq *s,
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001154 struct trace_entry *ent, struct trace_iterator *iter,
1155 u32 flags)
Frederic Weisbecker83a8df62008-11-27 01:46:33 +01001156{
Frederic Weisbecker83a8df62008-11-27 01:46:33 +01001157 unsigned long long duration = trace->rettime - trace->calltime;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001158 struct fgraph_data *data = iter->private;
1159 pid_t pid = ent->pid;
1160 int cpu = iter->cpu;
Steven Rostedtf1c7f512010-02-26 17:08:16 -05001161 int func_match = 1;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001162 int ret;
1163 int i;
1164
Jiri Olsa2bd162122010-09-07 16:53:44 +02001165 if (check_irq_return(iter, flags, trace->depth))
1166 return TRACE_TYPE_HANDLED;
1167
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001168 if (data) {
Steven Rostedtf1c7f512010-02-26 17:08:16 -05001169 struct fgraph_cpu_data *cpu_data;
1170 int cpu = iter->cpu;
1171
1172 cpu_data = per_cpu_ptr(data->cpu_data, cpu);
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001173
1174 /*
1175 * Comments display at + 1 to depth. This is the
1176 * return from a function, we now want the comments
1177 * to display at the same level of the bracket.
1178 */
Steven Rostedtf1c7f512010-02-26 17:08:16 -05001179 cpu_data->depth = trace->depth - 1;
1180
1181 if (trace->depth < FTRACE_RETFUNC_DEPTH) {
1182 if (cpu_data->enter_funcs[trace->depth] != trace->func)
1183 func_match = 0;
1184 cpu_data->enter_funcs[trace->depth] = 0;
1185 }
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001186 }
Frederic Weisbecker83a8df62008-11-27 01:46:33 +01001187
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001188 if (print_graph_prologue(iter, s, 0, 0, flags))
Frederic Weisbecker83a8df62008-11-27 01:46:33 +01001189 return TRACE_TYPE_PARTIAL_LINE;
1190
Jiri Olsaffeb80f2011-06-03 16:58:48 +02001191 /* Overhead and duration */
1192 ret = print_graph_duration(duration, s, flags);
1193 if (ret == TRACE_TYPE_PARTIAL_LINE)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001194 return TRACE_TYPE_PARTIAL_LINE;
Frederic Weisbecker1a056152008-11-28 00:42:46 +01001195
Frederic Weisbecker83a8df62008-11-27 01:46:33 +01001196 /* Closing brace */
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001197 for (i = 0; i < trace->depth * TRACE_GRAPH_INDENT; i++) {
zhangwei(Jovi)146c3442013-07-15 16:32:44 +08001198 ret = trace_seq_putc(s, ' ');
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001199 if (!ret)
1200 return TRACE_TYPE_PARTIAL_LINE;
1201 }
1202
Steven Rostedtf1c7f512010-02-26 17:08:16 -05001203 /*
1204 * If the return function does not have a matching entry,
1205 * then the entry was lost. Instead of just printing
1206 * the '}' and letting the user guess what function this
Robert Elliott607e3a22014-05-20 17:10:51 -05001207 * belongs to, write out the function name. Always do
1208 * that if the funcgraph-tail option is enabled.
Steven Rostedtf1c7f512010-02-26 17:08:16 -05001209 */
Robert Elliott607e3a22014-05-20 17:10:51 -05001210 if (func_match && !(flags & TRACE_GRAPH_PRINT_TAIL)) {
zhangwei(Jovi)146c3442013-07-15 16:32:44 +08001211 ret = trace_seq_puts(s, "}\n");
Steven Rostedtf1c7f512010-02-26 17:08:16 -05001212 if (!ret)
1213 return TRACE_TYPE_PARTIAL_LINE;
1214 } else {
Steven Rostedta094fe02010-03-05 20:08:58 -05001215 ret = trace_seq_printf(s, "} /* %ps */\n", (void *)trace->func);
Steven Rostedtf1c7f512010-02-26 17:08:16 -05001216 if (!ret)
1217 return TRACE_TYPE_PARTIAL_LINE;
1218 }
Frederic Weisbecker83a8df62008-11-27 01:46:33 +01001219
1220 /* Overrun */
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001221 if (flags & TRACE_GRAPH_PRINT_OVERRUN) {
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001222 ret = trace_seq_printf(s, " (Overruns: %lu)\n",
1223 trace->overrun);
1224 if (!ret)
1225 return TRACE_TYPE_PARTIAL_LINE;
1226 }
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +01001227
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001228 ret = print_graph_irq(iter, trace->func, TRACE_GRAPH_RET,
1229 cpu, pid, flags);
Frederic Weisbeckerf8b755a2008-12-09 23:55:25 +01001230 if (ret == TRACE_TYPE_PARTIAL_LINE)
1231 return TRACE_TYPE_PARTIAL_LINE;
1232
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001233 return TRACE_TYPE_HANDLED;
1234}
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001235
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +01001236static enum print_line_t
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001237print_graph_comment(struct trace_seq *s, struct trace_entry *ent,
1238 struct trace_iterator *iter, u32 flags)
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +01001239{
Steven Rostedt5087f8d2009-03-19 15:14:46 -04001240 unsigned long sym_flags = (trace_flags & TRACE_ITER_SYM_MASK);
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001241 struct fgraph_data *data = iter->private;
Steven Rostedt5087f8d2009-03-19 15:14:46 -04001242 struct trace_event *event;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001243 int depth = 0;
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +01001244 int ret;
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001245 int i;
1246
1247 if (data)
Jiri Olsabe1eca32009-11-24 13:57:38 +01001248 depth = per_cpu_ptr(data->cpu_data, iter->cpu)->depth;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001249
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001250 if (print_graph_prologue(iter, s, 0, 0, flags))
Frederic Weisbeckerd1f9cbd2009-02-18 04:25:25 +01001251 return TRACE_TYPE_PARTIAL_LINE;
1252
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001253 /* No time */
Steven Rostedt (Red Hat)6fc84ea2013-11-06 14:50:06 -05001254 ret = print_graph_duration(0, s, flags | FLAGS_FILL_FULL);
Jiri Olsaffeb80f2011-06-03 16:58:48 +02001255 if (ret != TRACE_TYPE_HANDLED)
1256 return ret;
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +01001257
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +01001258 /* Indentation */
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001259 if (depth > 0)
1260 for (i = 0; i < (depth + 1) * TRACE_GRAPH_INDENT; i++) {
zhangwei(Jovi)146c3442013-07-15 16:32:44 +08001261 ret = trace_seq_putc(s, ' ');
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +01001262 if (!ret)
1263 return TRACE_TYPE_PARTIAL_LINE;
1264 }
1265
1266 /* The comment */
zhangwei(Jovi)146c3442013-07-15 16:32:44 +08001267 ret = trace_seq_puts(s, "/* ");
Frederic Weisbecker769b0442009-03-06 17:21:49 +01001268 if (!ret)
1269 return TRACE_TYPE_PARTIAL_LINE;
1270
Steven Rostedt5087f8d2009-03-19 15:14:46 -04001271 switch (iter->ent->type) {
1272 case TRACE_BPRINT:
1273 ret = trace_print_bprintk_msg_only(iter);
1274 if (ret != TRACE_TYPE_HANDLED)
1275 return ret;
1276 break;
1277 case TRACE_PRINT:
1278 ret = trace_print_printk_msg_only(iter);
1279 if (ret != TRACE_TYPE_HANDLED)
1280 return ret;
1281 break;
1282 default:
1283 event = ftrace_find_event(ent->type);
1284 if (!event)
1285 return TRACE_TYPE_UNHANDLED;
1286
Steven Rostedta9a57762010-04-22 18:46:14 -04001287 ret = event->funcs->trace(iter, sym_flags, event);
Steven Rostedt5087f8d2009-03-19 15:14:46 -04001288 if (ret != TRACE_TYPE_HANDLED)
1289 return ret;
1290 }
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +01001291
Frederic Weisbecker412d0bb2008-12-24 01:43:25 +01001292 /* Strip ending newline */
1293 if (s->buffer[s->len - 1] == '\n') {
1294 s->buffer[s->len - 1] = '\0';
1295 s->len--;
1296 }
1297
zhangwei(Jovi)146c3442013-07-15 16:32:44 +08001298 ret = trace_seq_puts(s, " */\n");
Frederic Weisbecker1fd8f2a2008-12-03 23:45:11 +01001299 if (!ret)
1300 return TRACE_TYPE_PARTIAL_LINE;
1301
1302 return TRACE_TYPE_HANDLED;
1303}
1304
1305
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001306enum print_line_t
Jiri Olsa321e68b2011-06-03 16:58:47 +02001307print_graph_function_flags(struct trace_iterator *iter, u32 flags)
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001308{
Jiri Olsabe1eca32009-11-24 13:57:38 +01001309 struct ftrace_graph_ent_entry *field;
1310 struct fgraph_data *data = iter->private;
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001311 struct trace_entry *entry = iter->ent;
Steven Rostedt5087f8d2009-03-19 15:14:46 -04001312 struct trace_seq *s = &iter->seq;
Jiri Olsabe1eca32009-11-24 13:57:38 +01001313 int cpu = iter->cpu;
1314 int ret;
1315
1316 if (data && per_cpu_ptr(data->cpu_data, cpu)->ignore) {
1317 per_cpu_ptr(data->cpu_data, cpu)->ignore = 0;
1318 return TRACE_TYPE_HANDLED;
1319 }
1320
1321 /*
1322 * If the last output failed, there's a possibility we need
1323 * to print out the missing entry which would never go out.
1324 */
1325 if (data && data->failed) {
1326 field = &data->ent;
1327 iter->cpu = data->cpu;
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001328 ret = print_graph_entry(field, s, iter, flags);
Jiri Olsabe1eca32009-11-24 13:57:38 +01001329 if (ret == TRACE_TYPE_HANDLED && iter->cpu != cpu) {
1330 per_cpu_ptr(data->cpu_data, iter->cpu)->ignore = 1;
1331 ret = TRACE_TYPE_NO_CONSUME;
1332 }
1333 iter->cpu = cpu;
1334 return ret;
1335 }
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001336
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001337 switch (entry->type) {
1338 case TRACE_GRAPH_ENT: {
Lai Jiangshan38ceb592009-07-28 20:11:24 +08001339 /*
1340 * print_graph_entry() may consume the current event,
1341 * thus @field may become invalid, so we need to save it.
1342 * sizeof(struct ftrace_graph_ent_entry) is very small,
1343 * it can be safely saved at the stack.
1344 */
Jiri Olsabe1eca32009-11-24 13:57:38 +01001345 struct ftrace_graph_ent_entry saved;
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001346 trace_assign_type(field, entry);
Lai Jiangshan38ceb592009-07-28 20:11:24 +08001347 saved = *field;
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001348 return print_graph_entry(&saved, s, iter, flags);
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001349 }
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001350 case TRACE_GRAPH_RET: {
1351 struct ftrace_graph_ret_entry *field;
1352 trace_assign_type(field, entry);
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001353 return print_graph_return(&field->ret, s, entry, iter, flags);
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001354 }
Jiri Olsa62b915f2010-04-02 19:01:22 +02001355 case TRACE_STACK:
1356 case TRACE_FN:
1357 /* dont trace stack and functions as comments */
1358 return TRACE_TYPE_UNHANDLED;
1359
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001360 default:
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001361 return print_graph_comment(s, entry, iter, flags);
Frederic Weisbecker287b6e62008-11-26 00:57:25 +01001362 }
Steven Rostedt5087f8d2009-03-19 15:14:46 -04001363
1364 return TRACE_TYPE_HANDLED;
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001365}
1366
Jiri Olsa9106b692010-04-02 19:01:20 +02001367static enum print_line_t
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001368print_graph_function(struct trace_iterator *iter)
1369{
Jiri Olsa321e68b2011-06-03 16:58:47 +02001370 return print_graph_function_flags(iter, tracer_flags.val);
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001371}
1372
1373static enum print_line_t
Steven Rostedta9a57762010-04-22 18:46:14 -04001374print_graph_function_event(struct trace_iterator *iter, int flags,
1375 struct trace_event *event)
Jiri Olsa9106b692010-04-02 19:01:20 +02001376{
1377 return print_graph_function(iter);
1378}
1379
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001380static void print_lat_header(struct seq_file *s, u32 flags)
Steven Rostedt49ff5902009-09-11 00:30:26 -04001381{
1382 static const char spaces[] = " " /* 16 spaces */
1383 " " /* 4 spaces */
1384 " "; /* 17 spaces */
1385 int size = 0;
1386
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001387 if (flags & TRACE_GRAPH_PRINT_ABS_TIME)
Steven Rostedt49ff5902009-09-11 00:30:26 -04001388 size += 16;
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001389 if (flags & TRACE_GRAPH_PRINT_CPU)
Steven Rostedt49ff5902009-09-11 00:30:26 -04001390 size += 4;
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001391 if (flags & TRACE_GRAPH_PRINT_PROC)
Steven Rostedt49ff5902009-09-11 00:30:26 -04001392 size += 17;
1393
1394 seq_printf(s, "#%.*s _-----=> irqs-off \n", size, spaces);
1395 seq_printf(s, "#%.*s / _----=> need-resched \n", size, spaces);
1396 seq_printf(s, "#%.*s| / _---=> hardirq/softirq \n", size, spaces);
1397 seq_printf(s, "#%.*s|| / _--=> preempt-depth \n", size, spaces);
Jiri Olsa199abfa2011-06-03 16:58:50 +02001398 seq_printf(s, "#%.*s||| / \n", size, spaces);
Steven Rostedt49ff5902009-09-11 00:30:26 -04001399}
1400
Jiri Olsa0a772622010-09-23 14:00:52 +02001401static void __print_graph_headers_flags(struct seq_file *s, u32 flags)
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +01001402{
Steven Rostedt49ff5902009-09-11 00:30:26 -04001403 int lat = trace_flags & TRACE_ITER_LATENCY_FMT;
1404
1405 if (lat)
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001406 print_lat_header(s, flags);
Steven Rostedt49ff5902009-09-11 00:30:26 -04001407
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +01001408 /* 1st line */
Steven Rostedt49ff5902009-09-11 00:30:26 -04001409 seq_printf(s, "#");
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001410 if (flags & TRACE_GRAPH_PRINT_ABS_TIME)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001411 seq_printf(s, " TIME ");
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001412 if (flags & TRACE_GRAPH_PRINT_CPU)
Steven Rostedt49ff5902009-09-11 00:30:26 -04001413 seq_printf(s, " CPU");
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001414 if (flags & TRACE_GRAPH_PRINT_PROC)
Steven Rostedt49ff5902009-09-11 00:30:26 -04001415 seq_printf(s, " TASK/PID ");
1416 if (lat)
Jiri Olsa199abfa2011-06-03 16:58:50 +02001417 seq_printf(s, "||||");
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001418 if (flags & TRACE_GRAPH_PRINT_DURATION)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001419 seq_printf(s, " DURATION ");
1420 seq_printf(s, " FUNCTION CALLS\n");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +01001421
1422 /* 2nd line */
Steven Rostedt49ff5902009-09-11 00:30:26 -04001423 seq_printf(s, "#");
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001424 if (flags & TRACE_GRAPH_PRINT_ABS_TIME)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001425 seq_printf(s, " | ");
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001426 if (flags & TRACE_GRAPH_PRINT_CPU)
Steven Rostedt49ff5902009-09-11 00:30:26 -04001427 seq_printf(s, " | ");
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001428 if (flags & TRACE_GRAPH_PRINT_PROC)
Steven Rostedt49ff5902009-09-11 00:30:26 -04001429 seq_printf(s, " | | ");
1430 if (lat)
Jiri Olsa199abfa2011-06-03 16:58:50 +02001431 seq_printf(s, "||||");
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001432 if (flags & TRACE_GRAPH_PRINT_DURATION)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001433 seq_printf(s, " | | ");
1434 seq_printf(s, " | | | |\n");
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +01001435}
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001436
Jiri Olsa62b915f2010-04-02 19:01:22 +02001437void print_graph_headers(struct seq_file *s)
Jiri Olsad7a8d9e2010-04-02 19:01:21 +02001438{
1439 print_graph_headers_flags(s, tracer_flags.val);
1440}
1441
Jiri Olsa0a772622010-09-23 14:00:52 +02001442void print_graph_headers_flags(struct seq_file *s, u32 flags)
1443{
1444 struct trace_iterator *iter = s->private;
1445
Jiri Olsa749230b2011-06-03 16:58:51 +02001446 if (!(trace_flags & TRACE_ITER_CONTEXT_INFO))
1447 return;
1448
Jiri Olsa0a772622010-09-23 14:00:52 +02001449 if (trace_flags & TRACE_ITER_LATENCY_FMT) {
1450 /* print nothing if the buffers are empty */
1451 if (trace_empty(iter))
1452 return;
1453
1454 print_trace_header(s, iter);
Jiri Olsa321e68b2011-06-03 16:58:47 +02001455 }
Jiri Olsa0a772622010-09-23 14:00:52 +02001456
1457 __print_graph_headers_flags(s, flags);
1458}
1459
Jiri Olsa62b915f2010-04-02 19:01:22 +02001460void graph_trace_open(struct trace_iterator *iter)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001461{
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001462 /* pid and depth on the last trace processed */
Jiri Olsabe1eca32009-11-24 13:57:38 +01001463 struct fgraph_data *data;
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001464 int cpu;
1465
Jiri Olsabe1eca32009-11-24 13:57:38 +01001466 iter->private = NULL;
1467
1468 data = kzalloc(sizeof(*data), GFP_KERNEL);
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001469 if (!data)
Jiri Olsabe1eca32009-11-24 13:57:38 +01001470 goto out_err;
1471
1472 data->cpu_data = alloc_percpu(struct fgraph_cpu_data);
1473 if (!data->cpu_data)
1474 goto out_err_free;
1475
1476 for_each_possible_cpu(cpu) {
1477 pid_t *pid = &(per_cpu_ptr(data->cpu_data, cpu)->last_pid);
1478 int *depth = &(per_cpu_ptr(data->cpu_data, cpu)->depth);
1479 int *ignore = &(per_cpu_ptr(data->cpu_data, cpu)->ignore);
Jiri Olsa2bd162122010-09-07 16:53:44 +02001480 int *depth_irq = &(per_cpu_ptr(data->cpu_data, cpu)->depth_irq);
1481
Jiri Olsabe1eca32009-11-24 13:57:38 +01001482 *pid = -1;
1483 *depth = 0;
1484 *ignore = 0;
Jiri Olsa2bd162122010-09-07 16:53:44 +02001485 *depth_irq = -1;
Jiri Olsabe1eca32009-11-24 13:57:38 +01001486 }
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001487
Steven Rostedt2fbcdb32009-03-19 13:24:42 -04001488 iter->private = data;
Jiri Olsabe1eca32009-11-24 13:57:38 +01001489
1490 return;
1491
1492 out_err_free:
1493 kfree(data);
1494 out_err:
1495 pr_warning("function graph tracer: not enough memory\n");
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001496}
1497
Jiri Olsa62b915f2010-04-02 19:01:22 +02001498void graph_trace_close(struct trace_iterator *iter)
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001499{
Jiri Olsabe1eca32009-11-24 13:57:38 +01001500 struct fgraph_data *data = iter->private;
1501
1502 if (data) {
1503 free_percpu(data->cpu_data);
1504 kfree(data);
1505 }
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001506}
1507
Steven Rostedt (Red Hat)8c1a49a2014-01-10 11:13:54 -05001508static int
1509func_graph_set_flag(struct trace_array *tr, u32 old_flags, u32 bit, int set)
Steven Rostedtb304d042010-09-14 18:58:33 -04001510{
1511 if (bit == TRACE_GRAPH_PRINT_IRQS)
1512 ftrace_graph_skip_irqs = !set;
1513
1514 return 0;
1515}
1516
Steven Rostedta9a57762010-04-22 18:46:14 -04001517static struct trace_event_functions graph_functions = {
1518 .trace = print_graph_function_event,
1519};
1520
Jiri Olsa9106b692010-04-02 19:01:20 +02001521static struct trace_event graph_trace_entry_event = {
1522 .type = TRACE_GRAPH_ENT,
Steven Rostedta9a57762010-04-22 18:46:14 -04001523 .funcs = &graph_functions,
Jiri Olsa9106b692010-04-02 19:01:20 +02001524};
1525
1526static struct trace_event graph_trace_ret_event = {
1527 .type = TRACE_GRAPH_RET,
Steven Rostedta9a57762010-04-22 18:46:14 -04001528 .funcs = &graph_functions
Jiri Olsa9106b692010-04-02 19:01:20 +02001529};
1530
Steven Rostedt (Red Hat)8f768992013-07-18 14:41:51 -04001531static struct tracer graph_trace __tracer_data = {
Steven Rostedtef180122009-03-10 14:10:56 -04001532 .name = "function_graph",
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001533 .open = graph_trace_open,
Jiri Olsabe1eca32009-11-24 13:57:38 +01001534 .pipe_open = graph_trace_open,
Frederic Weisbecker9005f3e2009-01-22 17:04:53 -08001535 .close = graph_trace_close,
Jiri Olsabe1eca32009-11-24 13:57:38 +01001536 .pipe_close = graph_trace_close,
Steven Rostedtef180122009-03-10 14:10:56 -04001537 .init = graph_trace_init,
1538 .reset = graph_trace_reset,
Frederic Weisbeckerdecbec32008-12-08 01:56:06 +01001539 .print_line = print_graph_function,
1540 .print_header = print_graph_headers,
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001541 .flags = &tracer_flags,
Steven Rostedtb304d042010-09-14 18:58:33 -04001542 .set_flag = func_graph_set_flag,
Frederic Weisbecker7447dce2009-02-07 21:33:57 +01001543#ifdef CONFIG_FTRACE_SELFTEST
1544 .selftest = trace_selftest_startup_function_graph,
1545#endif
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001546};
1547
Steven Rostedt8741db52013-01-16 10:49:37 -05001548
1549static ssize_t
1550graph_depth_write(struct file *filp, const char __user *ubuf, size_t cnt,
1551 loff_t *ppos)
1552{
1553 unsigned long val;
1554 int ret;
1555
1556 ret = kstrtoul_from_user(ubuf, cnt, 10, &val);
1557 if (ret)
1558 return ret;
1559
1560 max_depth = val;
1561
1562 *ppos += cnt;
1563
1564 return cnt;
1565}
1566
1567static ssize_t
1568graph_depth_read(struct file *filp, char __user *ubuf, size_t cnt,
1569 loff_t *ppos)
1570{
1571 char buf[15]; /* More than enough to hold UINT_MAX + "\n"*/
1572 int n;
1573
1574 n = sprintf(buf, "%d\n", max_depth);
1575
1576 return simple_read_from_buffer(ubuf, cnt, ppos, buf, n);
1577}
1578
1579static const struct file_operations graph_depth_fops = {
1580 .open = tracing_open_generic,
1581 .write = graph_depth_write,
1582 .read = graph_depth_read,
1583 .llseek = generic_file_llseek,
1584};
1585
1586static __init int init_graph_debugfs(void)
1587{
1588 struct dentry *d_tracer;
1589
1590 d_tracer = tracing_init_dentry();
1591 if (!d_tracer)
1592 return 0;
1593
1594 trace_create_file("max_graph_depth", 0644, d_tracer,
1595 NULL, &graph_depth_fops);
1596
1597 return 0;
1598}
1599fs_initcall(init_graph_debugfs);
1600
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001601static __init int init_graph_trace(void)
1602{
Lai Jiangshan0c9e6f62009-07-28 20:26:06 +08001603 max_bytes_for_cpu = snprintf(NULL, 0, "%d", nr_cpu_ids - 1);
1604
Jiri Olsa9106b692010-04-02 19:01:20 +02001605 if (!register_ftrace_event(&graph_trace_entry_event)) {
1606 pr_warning("Warning: could not register graph trace events\n");
1607 return 1;
1608 }
1609
1610 if (!register_ftrace_event(&graph_trace_ret_event)) {
1611 pr_warning("Warning: could not register graph trace events\n");
1612 return 1;
1613 }
1614
Frederic Weisbeckerfb526072008-11-25 21:07:04 +01001615 return register_tracer(&graph_trace);
1616}
1617
Steven Rostedt6f415672012-10-05 12:13:07 -04001618core_initcall(init_graph_trace);