trace_functions_graph.c 39.2 KB
Newer Older
1 2 3
/*
 *
 * Function graph tracer.
4
 * Copyright (c) 2008-2009 Frederic Weisbecker <fweisbec@gmail.com>
5 6 7 8 9 10 11
 * Mostly borrowed from function tracer which
 * is Copyright (c) Steven Rostedt <srostedt@redhat.com>
 *
 */
#include <linux/debugfs.h>
#include <linux/uaccess.h>
#include <linux/ftrace.h>
12
#include <linux/slab.h>
13 14 15
#include <linux/fs.h>

#include "trace.h"
16
#include "trace_output.h"
17

18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44
static bool kill_ftrace_graph;

/**
 * ftrace_graph_is_dead - returns true if ftrace_graph_stop() was called
 *
 * ftrace_graph_stop() is called when a severe error is detected in
 * the function graph tracing. This function is called by the critical
 * paths of function graph to keep those paths from doing any more harm.
 */
bool ftrace_graph_is_dead(void)
{
	return kill_ftrace_graph;
}

/**
 * ftrace_graph_stop - set to permanently disable function graph tracincg
 *
 * In case of an error int function graph tracing, this is called
 * to try to keep function graph tracing from causing any more harm.
 * Usually this is pretty severe and this is called to try to at least
 * get a warning out to the user.
 */
void ftrace_graph_stop(void)
{
	kill_ftrace_graph = true;
}

45 46 47
/* When set, irq functions will be ignored */
static int ftrace_graph_skip_irqs;

48
struct fgraph_cpu_data {
49 50
	pid_t		last_pid;
	int		depth;
51
	int		depth_irq;
52
	int		ignore;
53
	unsigned long	enter_funcs[FTRACE_RETFUNC_DEPTH];
54 55 56
};

struct fgraph_data {
57
	struct fgraph_cpu_data __percpu *cpu_data;
58 59 60 61 62 63

	/* Place to preserve last processed entry. */
	struct ftrace_graph_ent_entry	ent;
	struct ftrace_graph_ret_entry	ret;
	int				failed;
	int				cpu;
64 65
};

66
#define TRACE_GRAPH_INDENT	2
67

68 69
static unsigned int max_depth;

70
static struct tracer_opt trace_opts[] = {
71
	/* Display overruns? (for self-debug purpose) */
72 73 74 75 76
	{ TRACER_OPT(funcgraph-overrun, TRACE_GRAPH_PRINT_OVERRUN) },
	/* Display CPU ? */
	{ TRACER_OPT(funcgraph-cpu, TRACE_GRAPH_PRINT_CPU) },
	/* Display Overhead ? */
	{ TRACER_OPT(funcgraph-overhead, TRACE_GRAPH_PRINT_OVERHEAD) },
77 78
	/* Display proc name/pid */
	{ TRACER_OPT(funcgraph-proc, TRACE_GRAPH_PRINT_PROC) },
79 80 81 82
	/* Display duration of execution */
	{ TRACER_OPT(funcgraph-duration, TRACE_GRAPH_PRINT_DURATION) },
	/* Display absolute time of an entry */
	{ TRACER_OPT(funcgraph-abstime, TRACE_GRAPH_PRINT_ABS_TIME) },
83 84
	/* Display interrupts */
	{ TRACER_OPT(funcgraph-irqs, TRACE_GRAPH_PRINT_IRQS) },
85 86
	/* Display function name after trailing } */
	{ TRACER_OPT(funcgraph-tail, TRACE_GRAPH_PRINT_TAIL) },
87 88 89 90
	{ } /* Empty entry */
};

static struct tracer_flags tracer_flags = {
91
	/* Don't display overruns, proc, or tail by default */
92
	.val = TRACE_GRAPH_PRINT_CPU | TRACE_GRAPH_PRINT_OVERHEAD |
93
	       TRACE_GRAPH_PRINT_DURATION | TRACE_GRAPH_PRINT_IRQS,
94 95 96
	.opts = trace_opts
};

97
static struct trace_array *graph_array;
98

99 100 101 102 103 104
/*
 * DURATION column is being also used to display IRQ signs,
 * following values are used by print_graph_irq and others
 * to fill in space into DURATION column.
 */
enum {
105 106 107
	FLAGS_FILL_FULL  = 1 << TRACE_GRAPH_PRINT_FILL_SHIFT,
	FLAGS_FILL_START = 2 << TRACE_GRAPH_PRINT_FILL_SHIFT,
	FLAGS_FILL_END   = 3 << TRACE_GRAPH_PRINT_FILL_SHIFT,
108 109 110 111 112
};

static enum print_line_t
print_graph_duration(unsigned long long duration, struct trace_seq *s,
		     u32 flags);
113

114 115
/* Add a function return address to the trace stack on thread info.*/
int
116 117
ftrace_push_return_trace(unsigned long ret, unsigned long func, int *depth,
			 unsigned long frame_pointer)
118
{
119
	unsigned long long calltime;
120 121
	int index;

122 123 124
	if (unlikely(ftrace_graph_is_dead()))
		return -EBUSY;

125 126 127
	if (!current->ret_stack)
		return -EBUSY;

128 129 130 131 132 133
	/*
	 * We must make sure the ret_stack is tested before we read
	 * anything else.
	 */
	smp_rmb();

134 135 136 137 138 139
	/* The return trace stack is full */
	if (current->curr_ret_stack == FTRACE_RETFUNC_DEPTH - 1) {
		atomic_inc(&current->trace_overrun);
		return -EBUSY;
	}

140 141 142 143 144 145 146 147 148 149 150 151 152 153 154 155 156 157 158
	/*
	 * The curr_ret_stack is an index to ftrace return stack of
	 * current task.  Its value should be in [0, FTRACE_RETFUNC_
	 * DEPTH) when the function graph tracer is used.  To support
	 * filtering out specific functions, it makes the index
	 * negative by subtracting huge value (FTRACE_NOTRACE_DEPTH)
	 * so when it sees a negative index the ftrace will ignore
	 * the record.  And the index gets recovered when returning
	 * from the filtered function by adding the FTRACE_NOTRACE_
	 * DEPTH and then it'll continue to record functions normally.
	 *
	 * The curr_ret_stack is initialized to -1 and get increased
	 * in this function.  So it can be less than -1 only if it was
	 * filtered out via ftrace_graph_notrace_addr() which can be
	 * set from set_graph_notrace file in debugfs by user.
	 */
	if (current->curr_ret_stack < -1)
		return -EBUSY;

159 160
	calltime = trace_clock_local();

161
	index = ++current->curr_ret_stack;
162 163
	if (ftrace_graph_notrace_addr(func))
		current->curr_ret_stack -= FTRACE_NOTRACE_DEPTH;
164 165 166
	barrier();
	current->ret_stack[index].ret = ret;
	current->ret_stack[index].func = func;
167
	current->ret_stack[index].calltime = calltime;
168
	current->ret_stack[index].subtime = 0;
169
	current->ret_stack[index].fp = frame_pointer;
170
	*depth = current->curr_ret_stack;
171 172 173 174 175

	return 0;
}

/* Retrieve a function return address to the trace stack on thread info.*/
176
static void
177 178
ftrace_pop_return_trace(struct ftrace_graph_ret *trace, unsigned long *ret,
			unsigned long frame_pointer)
179 180 181 182 183
{
	int index;

	index = current->curr_ret_stack;

184 185 186 187 188 189 190 191 192 193 194
	/*
	 * A negative index here means that it's just returned from a
	 * notrace'd function.  Recover index to get an original
	 * return address.  See ftrace_push_return_trace().
	 *
	 * TODO: Need to check whether the stack gets corrupted.
	 */
	if (index < 0)
		index += FTRACE_NOTRACE_DEPTH;

	if (unlikely(index < 0 || index >= FTRACE_RETFUNC_DEPTH)) {
195 196 197 198 199 200 201
		ftrace_graph_stop();
		WARN_ON(1);
		/* Might as well panic, otherwise we have no where to go */
		*ret = (unsigned long)panic;
		return;
	}

202
#if defined(CONFIG_HAVE_FUNCTION_GRAPH_FP_TEST) && !defined(CC_USING_FENTRY)
203 204 205 206 207 208 209 210 211 212
	/*
	 * The arch may choose to record the frame pointer used
	 * and check it here to make sure that it is what we expect it
	 * to be. If gcc does not set the place holder of the return
	 * address in the frame pointer, and does a copy instead, then
	 * the function graph trace will fail. This test detects this
	 * case.
	 *
	 * Currently, x86_32 with optimize for size (-Os) makes the latest
	 * gcc do the above.
213 214 215
	 *
	 * Note, -mfentry does not use frame pointers, and this test
	 *  is not needed if CC_USING_FENTRY is set.
216 217 218 219
	 */
	if (unlikely(current->ret_stack[index].fp != frame_pointer)) {
		ftrace_graph_stop();
		WARN(1, "Bad frame pointer: expected %lx, received %lx\n"
220
		     "  from func %ps return to %lx\n",
221 222 223 224 225 226 227 228 229
		     current->ret_stack[index].fp,
		     frame_pointer,
		     (void *)current->ret_stack[index].func,
		     current->ret_stack[index].ret);
		*ret = (unsigned long)panic;
		return;
	}
#endif

230 231 232 233 234 235 236 237 238 239 240
	*ret = current->ret_stack[index].ret;
	trace->func = current->ret_stack[index].func;
	trace->calltime = current->ret_stack[index].calltime;
	trace->overrun = atomic_read(&current->trace_overrun);
	trace->depth = index;
}

/*
 * Send the trace to the ring-buffer.
 * @return the original return address.
 */
241
unsigned long ftrace_return_to_handler(unsigned long frame_pointer)
242 243 244 245
{
	struct ftrace_graph_ret trace;
	unsigned long ret;

246
	ftrace_pop_return_trace(&trace, &ret, frame_pointer);
247
	trace.rettime = trace_clock_local();
248 249
	barrier();
	current->curr_ret_stack--;
250 251 252 253 254 255 256 257 258
	/*
	 * The curr_ret_stack can be less than -1 only if it was
	 * filtered out and it's about to return from the function.
	 * Recover the index and continue to trace normal functions.
	 */
	if (current->curr_ret_stack < -1) {
		current->curr_ret_stack += FTRACE_NOTRACE_DEPTH;
		return ret;
	}
259

260 261 262 263 264 265 266
	/*
	 * The trace should run after decrementing the ret counter
	 * in case an interrupt were to come in. We don't want to
	 * lose the interrupt if max_depth is set.
	 */
	ftrace_graph_return(&trace);

267 268 269 270 271 272 273 274 275 276
	if (unlikely(!ret)) {
		ftrace_graph_stop();
		WARN_ON(1);
		/* Might as well panic. What else to do? */
		ret = (unsigned long)panic;
	}

	return ret;
}

277
int __trace_graph_entry(struct trace_array *tr,
278 279 280 281 282 283
				struct ftrace_graph_ent *trace,
				unsigned long flags,
				int pc)
{
	struct ftrace_event_call *call = &event_funcgraph_entry;
	struct ring_buffer_event *event;
284
	struct ring_buffer *buffer = tr->trace_buffer.buffer;
285 286
	struct ftrace_graph_ent_entry *entry;

R
Rusty Russell 已提交
287
	if (unlikely(__this_cpu_read(ftrace_cpu_disabled)))
288 289
		return 0;

290
	event = trace_buffer_lock_reserve(buffer, TRACE_GRAPH_ENT,
291 292 293 294 295
					  sizeof(*entry), flags, pc);
	if (!event)
		return 0;
	entry	= ring_buffer_event_data(event);
	entry->graph_ent			= *trace;
296
	if (!call_filter_check_discard(call, entry, buffer, event))
297
		__buffer_unlock_commit(buffer, event);
298 299 300 301

	return 1;
}

302 303
static inline int ftrace_graph_ignore_irqs(void)
{
304
	if (!ftrace_graph_skip_irqs || trace_recursion_test(TRACE_IRQ_BIT))
305 306 307 308 309
		return 0;

	return in_irq();
}

310 311 312 313 314 315 316 317 318 319 320 321 322
int trace_graph_entry(struct ftrace_graph_ent *trace)
{
	struct trace_array *tr = graph_array;
	struct trace_array_cpu *data;
	unsigned long flags;
	long disabled;
	int ret;
	int cpu;
	int pc;

	if (!ftrace_trace_task(current))
		return 0;

323
	/* trace it when it is-nested-in or is a function enabled. */
324
	if ((!(trace->depth || ftrace_graph_addr(trace->func)) ||
325
	     ftrace_graph_ignore_irqs()) || (trace->depth < 0) ||
326
	    (max_depth && trace->depth >= max_depth))
327 328
		return 0;

329 330 331 332 333 334 335 336 337 338
	/*
	 * Do not trace a function if it's filtered by set_graph_notrace.
	 * Make the index of ret stack negative to indicate that it should
	 * ignore further functions.  But it needs its own ret stack entry
	 * to recover the original index in order to continue tracing after
	 * returning from the function.
	 */
	if (ftrace_graph_notrace_addr(trace->func))
		return 1;

339 340
	local_irq_save(flags);
	cpu = raw_smp_processor_id();
341
	data = per_cpu_ptr(tr->trace_buffer.data, cpu);
342 343 344 345 346 347 348 349 350 351 352 353 354 355
	disabled = atomic_inc_return(&data->disabled);
	if (likely(disabled == 1)) {
		pc = preempt_count();
		ret = __trace_graph_entry(tr, trace, flags, pc);
	} else {
		ret = 0;
	}

	atomic_dec(&data->disabled);
	local_irq_restore(flags);

	return ret;
}

356
static int trace_graph_thresh_entry(struct ftrace_graph_ent *trace)
357 358 359 360 361 362 363
{
	if (tracing_thresh)
		return 1;
	else
		return trace_graph_entry(trace);
}

364 365 366 367 368 369 370 371 372 373 374 375 376 377 378 379 380 381 382 383 384 385 386 387 388 389 390 391
static void
__trace_graph_function(struct trace_array *tr,
		unsigned long ip, unsigned long flags, int pc)
{
	u64 time = trace_clock_local();
	struct ftrace_graph_ent ent = {
		.func  = ip,
		.depth = 0,
	};
	struct ftrace_graph_ret ret = {
		.func     = ip,
		.depth    = 0,
		.calltime = time,
		.rettime  = time,
	};

	__trace_graph_entry(tr, &ent, flags, pc);
	__trace_graph_return(tr, &ret, flags, pc);
}

void
trace_graph_function(struct trace_array *tr,
		unsigned long ip, unsigned long parent_ip,
		unsigned long flags, int pc)
{
	__trace_graph_function(tr, ip, flags, pc);
}

392
void __trace_graph_return(struct trace_array *tr,
393 394 395 396 397 398
				struct ftrace_graph_ret *trace,
				unsigned long flags,
				int pc)
{
	struct ftrace_event_call *call = &event_funcgraph_exit;
	struct ring_buffer_event *event;
399
	struct ring_buffer *buffer = tr->trace_buffer.buffer;
400 401
	struct ftrace_graph_ret_entry *entry;

R
Rusty Russell 已提交
402
	if (unlikely(__this_cpu_read(ftrace_cpu_disabled)))
403 404
		return;

405
	event = trace_buffer_lock_reserve(buffer, TRACE_GRAPH_RET,
406 407 408 409 410
					  sizeof(*entry), flags, pc);
	if (!event)
		return;
	entry	= ring_buffer_event_data(event);
	entry->ret				= *trace;
411
	if (!call_filter_check_discard(call, entry, buffer, event))
412
		__buffer_unlock_commit(buffer, event);
413 414 415 416 417 418 419 420 421 422 423 424 425
}

void trace_graph_return(struct ftrace_graph_ret *trace)
{
	struct trace_array *tr = graph_array;
	struct trace_array_cpu *data;
	unsigned long flags;
	long disabled;
	int cpu;
	int pc;

	local_irq_save(flags);
	cpu = raw_smp_processor_id();
426
	data = per_cpu_ptr(tr->trace_buffer.data, cpu);
427 428 429 430 431 432 433 434 435
	disabled = atomic_inc_return(&data->disabled);
	if (likely(disabled == 1)) {
		pc = preempt_count();
		__trace_graph_return(tr, trace, flags, pc);
	}
	atomic_dec(&data->disabled);
	local_irq_restore(flags);
}

436 437 438 439 440 441 442 443 444
void set_graph_array(struct trace_array *tr)
{
	graph_array = tr;

	/* Make graph_array visible before we start tracing */

	smp_mb();
}

445
static void trace_graph_thresh_return(struct ftrace_graph_ret *trace)
446 447 448 449 450 451 452 453
{
	if (tracing_thresh &&
	    (trace->rettime - trace->calltime < tracing_thresh))
		return;
	else
		trace_graph_return(trace);
}

454 455
static int graph_trace_init(struct trace_array *tr)
{
456 457
	int ret;

458
	set_graph_array(tr);
459 460 461 462 463 464
	if (tracing_thresh)
		ret = register_ftrace_graph(&trace_graph_thresh_return,
					    &trace_graph_thresh_entry);
	else
		ret = register_ftrace_graph(&trace_graph_return,
					    &trace_graph_entry);
465 466 467 468 469
	if (ret)
		return ret;
	tracing_start_cmdline_record();

	return 0;
470 471 472 473
}

static void graph_trace_reset(struct trace_array *tr)
{
474 475
	tracing_stop_cmdline_record();
	unregister_ftrace_graph();
476 477
}

478
static int graph_trace_update_thresh(struct trace_array *tr)
479 480 481 482 483
{
	graph_trace_reset(tr);
	return graph_trace_init(tr);
}

484
static int max_bytes_for_cpu;
485 486 487 488 489 490

static enum print_line_t
print_graph_cpu(struct trace_seq *s, int cpu)
{
	int ret;

491 492 493 494 495
	/*
	 * Start with a space character - to make it stand out
	 * to the right a bit when trace output is pasted into
	 * email:
	 */
496
	ret = trace_seq_printf(s, " %*d) ", max_bytes_for_cpu, cpu);
497
	if (!ret)
498 499
		return TRACE_TYPE_PARTIAL_LINE;

500 501 502
	return TRACE_TYPE_HANDLED;
}

503 504 505 506 507
#define TRACE_GRAPH_PROCINFO_LENGTH	14

static enum print_line_t
print_graph_proc(struct trace_seq *s, pid_t pid)
{
508
	char comm[TASK_COMM_LEN];
509 510
	/* sign + log10(MAX_INT) + '\0' */
	char pid_str[11];
511 512 513 514
	int spaces = 0;
	int ret;
	int len;
	int i;
515

516
	trace_find_cmdline(pid, comm);
517 518 519 520 521 522 523 524 525 526 527
	comm[7] = '\0';
	sprintf(pid_str, "%d", pid);

	/* 1 stands for the "-" character */
	len = strlen(comm) + strlen(pid_str) + 1;

	if (len < TRACE_GRAPH_PROCINFO_LENGTH)
		spaces = TRACE_GRAPH_PROCINFO_LENGTH - len;

	/* First spaces to align center */
	for (i = 0; i < spaces / 2; i++) {
528
		ret = trace_seq_putc(s, ' ');
529 530 531 532 533 534 535 536 537 538
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
	}

	ret = trace_seq_printf(s, "%s-%s", comm, pid_str);
	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;

	/* Last spaces to align center */
	for (i = 0; i < spaces - (spaces / 2); i++) {
539
		ret = trace_seq_putc(s, ' ');
540 541 542 543 544 545
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
	}
	return TRACE_TYPE_HANDLED;
}

546

547 548 549
static enum print_line_t
print_graph_lat_fmt(struct trace_seq *s, struct trace_entry *entry)
{
550
	if (!trace_seq_putc(s, ' '))
551 552
		return 0;

553
	return trace_print_lat_fmt(s, entry);
554 555
}

556
/* If the pid changed since the last trace, output this event */
557
static enum print_line_t
558
verif_pid(struct trace_seq *s, pid_t pid, int cpu, struct fgraph_data *data)
559
{
560
	pid_t prev_pid;
561
	pid_t *last_pid;
562
	int ret;
563

564
	if (!data)
565 566
		return TRACE_TYPE_HANDLED;

567
	last_pid = &(per_cpu_ptr(data->cpu_data, cpu)->last_pid);
568 569

	if (*last_pid == pid)
570
		return TRACE_TYPE_HANDLED;
571

572 573
	prev_pid = *last_pid;
	*last_pid = pid;
574

575 576
	if (prev_pid == -1)
		return TRACE_TYPE_HANDLED;
577 578 579 580 581 582 583 584
/*
 * Context-switch trace line:

 ------------------------------------------
 | 1)  migration/0--1  =>  sshd-1755
 ------------------------------------------

 */
585
	ret = trace_seq_puts(s,
586
		" ------------------------------------------\n");
587
	if (!ret)
588
		return TRACE_TYPE_PARTIAL_LINE;
589 590 591

	ret = print_graph_cpu(s, cpu);
	if (ret == TRACE_TYPE_PARTIAL_LINE)
592
		return TRACE_TYPE_PARTIAL_LINE;
593 594 595

	ret = print_graph_proc(s, prev_pid);
	if (ret == TRACE_TYPE_PARTIAL_LINE)
596
		return TRACE_TYPE_PARTIAL_LINE;
597

598
	ret = trace_seq_puts(s, " => ");
599
	if (!ret)
600
		return TRACE_TYPE_PARTIAL_LINE;
601 602 603

	ret = print_graph_proc(s, pid);
	if (ret == TRACE_TYPE_PARTIAL_LINE)
604
		return TRACE_TYPE_PARTIAL_LINE;
605

606
	ret = trace_seq_puts(s,
607 608
		"\n ------------------------------------------\n\n");
	if (!ret)
609
		return TRACE_TYPE_PARTIAL_LINE;
610

611
	return TRACE_TYPE_HANDLED;
612 613
}

614 615
static struct ftrace_graph_ret_entry *
get_return_for_leaf(struct trace_iterator *iter,
616 617
		struct ftrace_graph_ent_entry *curr)
{
618 619
	struct fgraph_data *data = iter->private;
	struct ring_buffer_iter *ring_iter = NULL;
620 621 622
	struct ring_buffer_event *event;
	struct ftrace_graph_ret_entry *next;

623 624 625 626 627 628 629 630
	/*
	 * If the previous output failed to write to the seq buffer,
	 * then we just reuse the data from before.
	 */
	if (data && data->failed) {
		curr = &data->ent;
		next = &data->ret;
	} else {
631

632
		ring_iter = trace_buffer_iter(iter, iter->cpu);
633 634 635 636 637 638 639 640 641

		/* First peek to compare current entry and the next one */
		if (ring_iter)
			event = ring_buffer_iter_peek(ring_iter, NULL);
		else {
			/*
			 * We need to consume the current entry to see
			 * the next one.
			 */
642
			ring_buffer_consume(iter->trace_buffer->buffer, iter->cpu,
643
					    NULL, NULL);
644
			event = ring_buffer_peek(iter->trace_buffer->buffer, iter->cpu,
645
						 NULL, NULL);
646
		}
647

648 649 650 651
		if (!event)
			return NULL;

		next = ring_buffer_event_data(event);
652

653 654 655 656 657 658
		if (data) {
			/*
			 * Save current and next entries for later reference
			 * if the output fails.
			 */
			data->ent = *curr;
659 660 661 662 663 664 665 666 667
			/*
			 * If the next event is not a return type, then
			 * we only care about what type it is. Otherwise we can
			 * safely copy the entire event.
			 */
			if (next->ent.type == TRACE_GRAPH_RET)
				data->ret = *next;
			else
				data->ret.ent.type = next->ent.type;
668 669
		}
	}
670 671

	if (next->ent.type != TRACE_GRAPH_RET)
672
		return NULL;
673 674 675

	if (curr->ent.pid != next->ent.pid ||
			curr->graph_ent.func != next->ret.func)
676
		return NULL;
677

678 679 680 681 682
	/* this is a leaf, now advance the iterator */
	if (ring_iter)
		ring_buffer_read(ring_iter, NULL);

	return next;
683 684
}

685 686 687 688 689 690 691 692 693 694 695
static int print_graph_abs_time(u64 t, struct trace_seq *s)
{
	unsigned long usecs_rem;

	usecs_rem = do_div(t, NSEC_PER_SEC);
	usecs_rem /= 1000;

	return trace_seq_printf(s, "%5lu.%06lu |  ",
			(unsigned long)t, usecs_rem);
}

696
static enum print_line_t
697
print_graph_irq(struct trace_iterator *iter, unsigned long addr,
698
		enum trace_type type, int cpu, pid_t pid, u32 flags)
699 700
{
	int ret;
701
	struct trace_seq *s = &iter->seq;
702 703 704 705 706

	if (addr < (unsigned long)__irqentry_text_start ||
		addr >= (unsigned long)__irqentry_text_end)
		return TRACE_TYPE_UNHANDLED;

707 708 709 710 711 712 713
	if (trace_flags & TRACE_ITER_CONTEXT_INFO) {
		/* Absolute time */
		if (flags & TRACE_GRAPH_PRINT_ABS_TIME) {
			ret = print_graph_abs_time(iter->ts, s);
			if (!ret)
				return TRACE_TYPE_PARTIAL_LINE;
		}
714

715 716 717 718 719 720
		/* Cpu */
		if (flags & TRACE_GRAPH_PRINT_CPU) {
			ret = print_graph_cpu(s, cpu);
			if (ret == TRACE_TYPE_PARTIAL_LINE)
				return TRACE_TYPE_PARTIAL_LINE;
		}
721

722 723 724 725 726
		/* Proc */
		if (flags & TRACE_GRAPH_PRINT_PROC) {
			ret = print_graph_proc(s, pid);
			if (ret == TRACE_TYPE_PARTIAL_LINE)
				return TRACE_TYPE_PARTIAL_LINE;
727
			ret = trace_seq_puts(s, " | ");
728 729 730
			if (!ret)
				return TRACE_TYPE_PARTIAL_LINE;
		}
731
	}
732

733
	/* No overhead */
734
	ret = print_graph_duration(0, s, flags | FLAGS_FILL_START);
735 736
	if (ret != TRACE_TYPE_HANDLED)
		return ret;
737

738
	if (type == TRACE_GRAPH_ENT)
739
		ret = trace_seq_puts(s, "==========>");
740
	else
741
		ret = trace_seq_puts(s, "<==========");
742 743 744 745

	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;

746
	ret = print_graph_duration(0, s, flags | FLAGS_FILL_END);
747 748 749
	if (ret != TRACE_TYPE_HANDLED)
		return ret;

750
	ret = trace_seq_putc(s, '\n');
751 752 753 754 755

	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;
	return TRACE_TYPE_HANDLED;
}
756

757 758
enum print_line_t
trace_print_graph_duration(unsigned long long duration, struct trace_seq *s)
759 760
{
	unsigned long nsecs_rem = do_div(duration, 1000);
761 762 763 764 765 766 767 768 769
	/* log10(ULONG_MAX) + '\0' */
	char msecs_str[21];
	char nsecs_str[5];
	int ret, len;
	int i;

	sprintf(msecs_str, "%lu", (unsigned long) duration);

	/* Print msecs */
770
	ret = trace_seq_printf(s, "%s", msecs_str);
771 772 773 774 775 776 777
	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;

	len = strlen(msecs_str);

	/* Print nsecs (we don't want to exceed 7 numbers) */
	if (len < 7) {
778 779 780
		size_t slen = min_t(size_t, sizeof(nsecs_str), 8UL - len);

		snprintf(nsecs_str, slen, "%03lu", nsecs_rem);
781 782 783 784 785 786
		ret = trace_seq_printf(s, ".%s", nsecs_str);
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
		len += strlen(nsecs_str);
	}

787
	ret = trace_seq_puts(s, " us ");
788 789 790 791 792
	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;

	/* Print remaining spaces to fit the row's width */
	for (i = len; i < 7; i++) {
793
		ret = trace_seq_putc(s, ' ');
794 795 796
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
	}
797 798 799 800
	return TRACE_TYPE_HANDLED;
}

static enum print_line_t
801 802
print_graph_duration(unsigned long long duration, struct trace_seq *s,
		     u32 flags)
803
{
804 805
	int ret = -1;

806 807 808
	if (!(flags & TRACE_GRAPH_PRINT_DURATION) ||
	    !(trace_flags & TRACE_ITER_CONTEXT_INFO))
			return TRACE_TYPE_HANDLED;
809 810

	/* No real adata, just filling the column with spaces */
811 812
	switch (flags & TRACE_GRAPH_PRINT_FILL_MASK) {
	case FLAGS_FILL_FULL:
813
		ret = trace_seq_puts(s, "              |  ");
814
		return ret ? TRACE_TYPE_HANDLED : TRACE_TYPE_PARTIAL_LINE;
815
	case FLAGS_FILL_START:
816
		ret = trace_seq_puts(s, "  ");
817
		return ret ? TRACE_TYPE_HANDLED : TRACE_TYPE_PARTIAL_LINE;
818
	case FLAGS_FILL_END:
819
		ret = trace_seq_puts(s, " |");
820 821 822 823 824 825 826
		return ret ? TRACE_TYPE_HANDLED : TRACE_TYPE_PARTIAL_LINE;
	}

	/* Signal a overhead of time execution to the output */
	if (flags & TRACE_GRAPH_PRINT_OVERHEAD) {
		/* Duration exceeded 100 msecs */
		if (duration > 100000ULL)
827
			ret = trace_seq_puts(s, "! ");
828 829
		/* Duration exceeded 10 msecs */
		else if (duration > 10000ULL)
830
			ret = trace_seq_puts(s, "+ ");
831 832 833 834 835 836 837 838
	}

	/*
	 * The -1 means we either did not exceed the duration tresholds
	 * or we dont want to print out the overhead. Either way we need
	 * to fill out the space.
	 */
	if (ret == -1)
839
		ret = trace_seq_puts(s, "  ");
840 841 842 843

	/* Catching here any failure happenned above */
	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;
844 845 846 847

	ret = trace_print_graph_duration(duration, s);
	if (ret != TRACE_TYPE_HANDLED)
		return ret;
848

849
	ret = trace_seq_puts(s, "|  ");
850 851 852
	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;

853
	return TRACE_TYPE_HANDLED;
854 855 856
}

/* Case of a leaf function on its call entry */
857
static enum print_line_t
858
print_graph_entry_leaf(struct trace_iterator *iter,
859
		struct ftrace_graph_ent_entry *entry,
860 861
		struct ftrace_graph_ret_entry *ret_entry,
		struct trace_seq *s, u32 flags)
862
{
863
	struct fgraph_data *data = iter->private;
864 865 866
	struct ftrace_graph_ret *graph_ret;
	struct ftrace_graph_ent *call;
	unsigned long long duration;
867
	int ret;
868
	int i;
869

870 871 872 873
	graph_ret = &ret_entry->ret;
	call = &entry->graph_ent;
	duration = graph_ret->rettime - graph_ret->calltime;

874
	if (data) {
875
		struct fgraph_cpu_data *cpu_data;
876
		int cpu = iter->cpu;
877 878

		cpu_data = per_cpu_ptr(data->cpu_data, cpu);
879 880 881 882 883 884

		/*
		 * Comments display at + 1 to depth. Since
		 * this is a leaf function, keep the comments
		 * equal to this depth.
		 */
885 886 887 888 889
		cpu_data->depth = call->depth - 1;

		/* No need to keep this function around for this depth */
		if (call->depth < FTRACE_RETFUNC_DEPTH)
			cpu_data->enter_funcs[call->depth] = 0;
890 891
	}

892 893 894
	/* Overhead and duration */
	ret = print_graph_duration(duration, s, flags);
	if (ret == TRACE_TYPE_PARTIAL_LINE)
895
		return TRACE_TYPE_PARTIAL_LINE;
896

897 898
	/* Function */
	for (i = 0; i < call->depth * TRACE_GRAPH_INDENT; i++) {
899
		ret = trace_seq_putc(s, ' ');
900 901 902 903
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
	}

904
	ret = trace_seq_printf(s, "%ps();\n", (void *)call->func);
905 906 907 908 909 910 911
	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;

	return TRACE_TYPE_HANDLED;
}

static enum print_line_t
912 913
print_graph_entry_nested(struct trace_iterator *iter,
			 struct ftrace_graph_ent_entry *entry,
914
			 struct trace_seq *s, int cpu, u32 flags)
915 916
{
	struct ftrace_graph_ent *call = &entry->graph_ent;
917 918 919 920 921
	struct fgraph_data *data = iter->private;
	int ret;
	int i;

	if (data) {
922
		struct fgraph_cpu_data *cpu_data;
923 924
		int cpu = iter->cpu;

925 926 927 928 929 930
		cpu_data = per_cpu_ptr(data->cpu_data, cpu);
		cpu_data->depth = call->depth;

		/* Save this function pointer to see if the exit matches */
		if (call->depth < FTRACE_RETFUNC_DEPTH)
			cpu_data->enter_funcs[call->depth] = call->func;
931
	}
932

933
	/* No time */
934
	ret = print_graph_duration(0, s, flags | FLAGS_FILL_FULL);
935 936
	if (ret != TRACE_TYPE_HANDLED)
		return ret;
937

938
	/* Function */
939
	for (i = 0; i < call->depth * TRACE_GRAPH_INDENT; i++) {
940
		ret = trace_seq_putc(s, ' ');
941 942
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
943 944
	}

945
	ret = trace_seq_printf(s, "%ps() {\n", (void *)call->func);
946 947 948
	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;

949 950 951 952 953
	/*
	 * we already consumed the current entry to check the next one
	 * and see if this is a leaf.
	 */
	return TRACE_TYPE_NO_CONSUME;
954 955
}

956
static enum print_line_t
957
print_graph_prologue(struct trace_iterator *iter, struct trace_seq *s,
958
		     int type, unsigned long addr, u32 flags)
959
{
960
	struct fgraph_data *data = iter->private;
961
	struct trace_entry *ent = iter->ent;
962 963
	int cpu = iter->cpu;
	int ret;
964

965
	/* Pid */
966
	if (verif_pid(s, ent->pid, cpu, data) == TRACE_TYPE_PARTIAL_LINE)
967 968
		return TRACE_TYPE_PARTIAL_LINE;

969 970
	if (type) {
		/* Interrupt */
971
		ret = print_graph_irq(iter, addr, type, cpu, ent->pid, flags);
972 973 974
		if (ret == TRACE_TYPE_PARTIAL_LINE)
			return TRACE_TYPE_PARTIAL_LINE;
	}
975

976 977 978
	if (!(trace_flags & TRACE_ITER_CONTEXT_INFO))
		return 0;

979
	/* Absolute time */
980
	if (flags & TRACE_GRAPH_PRINT_ABS_TIME) {
981 982 983 984 985
		ret = print_graph_abs_time(iter->ts, s);
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
	}

986
	/* Cpu */
987
	if (flags & TRACE_GRAPH_PRINT_CPU) {
988
		ret = print_graph_cpu(s, cpu);
989 990 991 992 993
		if (ret == TRACE_TYPE_PARTIAL_LINE)
			return TRACE_TYPE_PARTIAL_LINE;
	}

	/* Proc */
994
	if (flags & TRACE_GRAPH_PRINT_PROC) {
995
		ret = print_graph_proc(s, ent->pid);
996 997 998
		if (ret == TRACE_TYPE_PARTIAL_LINE)
			return TRACE_TYPE_PARTIAL_LINE;

999
		ret = trace_seq_puts(s, " | ");
1000 1001 1002
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
	}
1003

1004 1005 1006 1007 1008 1009 1010
	/* Latency format */
	if (trace_flags & TRACE_ITER_LATENCY_FMT) {
		ret = print_graph_lat_fmt(s, ent);
		if (ret == TRACE_TYPE_PARTIAL_LINE)
			return TRACE_TYPE_PARTIAL_LINE;
	}

1011 1012 1013
	return 0;
}

1014 1015 1016 1017 1018
/*
 * Entry check for irq code
 *
 * returns 1 if
 *  - we are inside irq code
L
Lucas De Marchi 已提交
1019
 *  - we just entered irq code
1020 1021 1022 1023 1024 1025 1026 1027 1028 1029
 *
 * retunns 0 if
 *  - funcgraph-interrupts option is set
 *  - we are not inside irq code
 */
static int
check_irq_entry(struct trace_iterator *iter, u32 flags,
		unsigned long addr, int depth)
{
	int cpu = iter->cpu;
1030
	int *depth_irq;
1031 1032
	struct fgraph_data *data = iter->private;

1033 1034 1035 1036 1037 1038 1039
	/*
	 * If we are either displaying irqs, or we got called as
	 * a graph event and private data does not exist,
	 * then we bypass the irq check.
	 */
	if ((flags & TRACE_GRAPH_PRINT_IRQS) ||
	    (!data))
1040 1041
		return 0;

1042 1043
	depth_irq = &(per_cpu_ptr(data->cpu_data, cpu)->depth_irq);

1044 1045 1046 1047 1048 1049 1050 1051 1052 1053 1054 1055 1056 1057 1058 1059 1060 1061 1062 1063 1064 1065 1066 1067 1068 1069 1070 1071 1072 1073 1074 1075
	/*
	 * We are inside the irq code
	 */
	if (*depth_irq >= 0)
		return 1;

	if ((addr < (unsigned long)__irqentry_text_start) ||
	    (addr >= (unsigned long)__irqentry_text_end))
		return 0;

	/*
	 * We are entering irq code.
	 */
	*depth_irq = depth;
	return 1;
}

/*
 * Return check for irq code
 *
 * returns 1 if
 *  - we are inside irq code
 *  - we just left irq code
 *
 * returns 0 if
 *  - funcgraph-interrupts option is set
 *  - we are not inside irq code
 */
static int
check_irq_return(struct trace_iterator *iter, u32 flags, int depth)
{
	int cpu = iter->cpu;
1076
	int *depth_irq;
1077 1078
	struct fgraph_data *data = iter->private;

1079 1080 1081 1082 1083 1084 1085
	/*
	 * If we are either displaying irqs, or we got called as
	 * a graph event and private data does not exist,
	 * then we bypass the irq check.
	 */
	if ((flags & TRACE_GRAPH_PRINT_IRQS) ||
	    (!data))
1086 1087
		return 0;

1088 1089
	depth_irq = &(per_cpu_ptr(data->cpu_data, cpu)->depth_irq);

1090 1091 1092 1093 1094 1095 1096 1097 1098 1099 1100 1101 1102 1103 1104 1105 1106 1107 1108 1109 1110 1111 1112 1113 1114 1115
	/*
	 * We are not inside the irq code.
	 */
	if (*depth_irq == -1)
		return 0;

	/*
	 * We are inside the irq code, and this is returning entry.
	 * Let's not trace it and clear the entry depth, since
	 * we are out of irq code.
	 *
	 * This condition ensures that we 'leave the irq code' once
	 * we are out of the entry depth. Thus protecting us from
	 * the RETURN entry loss.
	 */
	if (*depth_irq >= depth) {
		*depth_irq = -1;
		return 1;
	}

	/*
	 * We are inside the irq code, and this is not the entry.
	 */
	return 1;
}

1116 1117
static enum print_line_t
print_graph_entry(struct ftrace_graph_ent_entry *field, struct trace_seq *s,
1118
			struct trace_iterator *iter, u32 flags)
1119
{
1120
	struct fgraph_data *data = iter->private;
1121 1122
	struct ftrace_graph_ent *call = &field->graph_ent;
	struct ftrace_graph_ret_entry *leaf_ret;
1123 1124
	static enum print_line_t ret;
	int cpu = iter->cpu;
1125

1126 1127 1128
	if (check_irq_entry(iter, flags, call->func, call->depth))
		return TRACE_TYPE_HANDLED;

1129
	if (print_graph_prologue(iter, s, TRACE_GRAPH_ENT, call->func, flags))
1130 1131
		return TRACE_TYPE_PARTIAL_LINE;

1132 1133
	leaf_ret = get_return_for_leaf(iter, field);
	if (leaf_ret)
1134
		ret = print_graph_entry_leaf(iter, field, leaf_ret, s, flags);
1135
	else
1136
		ret = print_graph_entry_nested(iter, field, s, cpu, flags);
1137

1138 1139 1140 1141 1142 1143 1144 1145 1146 1147 1148 1149 1150
	if (data) {
		/*
		 * If we failed to write our output, then we need to make
		 * note of it. Because we already consumed our entry.
		 */
		if (s->full) {
			data->failed = 1;
			data->cpu = cpu;
		} else
			data->failed = 0;
	}

	return ret;
1151 1152
}

1153 1154
static enum print_line_t
print_graph_return(struct ftrace_graph_ret *trace, struct trace_seq *s,
1155 1156
		   struct trace_entry *ent, struct trace_iterator *iter,
		   u32 flags)
1157
{
1158
	unsigned long long duration = trace->rettime - trace->calltime;
1159 1160 1161
	struct fgraph_data *data = iter->private;
	pid_t pid = ent->pid;
	int cpu = iter->cpu;
1162
	int func_match = 1;
1163 1164 1165
	int ret;
	int i;

1166 1167 1168
	if (check_irq_return(iter, flags, trace->depth))
		return TRACE_TYPE_HANDLED;

1169
	if (data) {
1170 1171 1172 1173
		struct fgraph_cpu_data *cpu_data;
		int cpu = iter->cpu;

		cpu_data = per_cpu_ptr(data->cpu_data, cpu);
1174 1175 1176 1177 1178 1179

		/*
		 * Comments display at + 1 to depth. This is the
		 * return from a function, we now want the comments
		 * to display at the same level of the bracket.
		 */
1180 1181 1182 1183 1184 1185 1186
		cpu_data->depth = trace->depth - 1;

		if (trace->depth < FTRACE_RETFUNC_DEPTH) {
			if (cpu_data->enter_funcs[trace->depth] != trace->func)
				func_match = 0;
			cpu_data->enter_funcs[trace->depth] = 0;
		}
1187
	}
1188

1189
	if (print_graph_prologue(iter, s, 0, 0, flags))
1190 1191
		return TRACE_TYPE_PARTIAL_LINE;

1192 1193 1194
	/* Overhead and duration */
	ret = print_graph_duration(duration, s, flags);
	if (ret == TRACE_TYPE_PARTIAL_LINE)
1195
		return TRACE_TYPE_PARTIAL_LINE;
1196

1197
	/* Closing brace */
1198
	for (i = 0; i < trace->depth * TRACE_GRAPH_INDENT; i++) {
1199
		ret = trace_seq_putc(s, ' ');
1200 1201
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
1202 1203
	}

1204 1205 1206 1207
	/*
	 * If the return function does not have a matching entry,
	 * then the entry was lost. Instead of just printing
	 * the '}' and letting the user guess what function this
1208 1209
	 * belongs to, write out the function name. Always do
	 * that if the funcgraph-tail option is enabled.
1210
	 */
1211
	if (func_match && !(flags & TRACE_GRAPH_PRINT_TAIL)) {
1212
		ret = trace_seq_puts(s, "}\n");
1213 1214 1215
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
	} else {
1216
		ret = trace_seq_printf(s, "} /* %ps */\n", (void *)trace->func);
1217 1218 1219
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
	}
1220

1221
	/* Overrun */
1222
	if (flags & TRACE_GRAPH_PRINT_OVERRUN) {
1223 1224
		ret = trace_seq_printf(s, " (Overruns: %lu)\n",
					trace->overrun);
1225 1226
		if (!ret)
			return TRACE_TYPE_PARTIAL_LINE;
1227
	}
1228

1229 1230
	ret = print_graph_irq(iter, trace->func, TRACE_GRAPH_RET,
			      cpu, pid, flags);
1231 1232 1233
	if (ret == TRACE_TYPE_PARTIAL_LINE)
		return TRACE_TYPE_PARTIAL_LINE;

1234 1235 1236
	return TRACE_TYPE_HANDLED;
}

1237
static enum print_line_t
1238 1239
print_graph_comment(struct trace_seq *s, struct trace_entry *ent,
		    struct trace_iterator *iter, u32 flags)
1240
{
1241
	unsigned long sym_flags = (trace_flags & TRACE_ITER_SYM_MASK);
1242
	struct fgraph_data *data = iter->private;
1243
	struct trace_event *event;
1244
	int depth = 0;
1245
	int ret;
1246 1247 1248
	int i;

	if (data)
1249
		depth = per_cpu_ptr(data->cpu_data, iter->cpu)->depth;
1250

1251
	if (print_graph_prologue(iter, s, 0, 0, flags))
1252 1253
		return TRACE_TYPE_PARTIAL_LINE;

1254
	/* No time */
1255
	ret = print_graph_duration(0, s, flags | FLAGS_FILL_FULL);
1256 1257
	if (ret != TRACE_TYPE_HANDLED)
		return ret;
1258 1259

	/* Indentation */
1260 1261
	if (depth > 0)
		for (i = 0; i < (depth + 1) * TRACE_GRAPH_INDENT; i++) {
1262
			ret = trace_seq_putc(s, ' ');
1263 1264 1265 1266 1267
			if (!ret)
				return TRACE_TYPE_PARTIAL_LINE;
		}

	/* The comment */
1268
	ret = trace_seq_puts(s, "/* ");
1269 1270 1271
	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;

1272 1273 1274 1275 1276 1277 1278 1279 1280 1281 1282 1283 1284 1285 1286 1287
	switch (iter->ent->type) {
	case TRACE_BPRINT:
		ret = trace_print_bprintk_msg_only(iter);
		if (ret != TRACE_TYPE_HANDLED)
			return ret;
		break;
	case TRACE_PRINT:
		ret = trace_print_printk_msg_only(iter);
		if (ret != TRACE_TYPE_HANDLED)
			return ret;
		break;
	default:
		event = ftrace_find_event(ent->type);
		if (!event)
			return TRACE_TYPE_UNHANDLED;

1288
		ret = event->funcs->trace(iter, sym_flags, event);
1289 1290 1291
		if (ret != TRACE_TYPE_HANDLED)
			return ret;
	}
1292

1293 1294 1295 1296 1297 1298
	/* Strip ending newline */
	if (s->buffer[s->len - 1] == '\n') {
		s->buffer[s->len - 1] = '\0';
		s->len--;
	}

1299
	ret = trace_seq_puts(s, " */\n");
1300 1301 1302 1303 1304 1305 1306
	if (!ret)
		return TRACE_TYPE_PARTIAL_LINE;

	return TRACE_TYPE_HANDLED;
}


1307
enum print_line_t
1308
print_graph_function_flags(struct trace_iterator *iter, u32 flags)
1309
{
1310 1311
	struct ftrace_graph_ent_entry *field;
	struct fgraph_data *data = iter->private;
1312
	struct trace_entry *entry = iter->ent;
1313
	struct trace_seq *s = &iter->seq;
1314 1315 1316 1317 1318 1319 1320 1321 1322 1323 1324 1325 1326 1327 1328
	int cpu = iter->cpu;
	int ret;

	if (data && per_cpu_ptr(data->cpu_data, cpu)->ignore) {
		per_cpu_ptr(data->cpu_data, cpu)->ignore = 0;
		return TRACE_TYPE_HANDLED;
	}

	/*
	 * If the last output failed, there's a possibility we need
	 * to print out the missing entry which would never go out.
	 */
	if (data && data->failed) {
		field = &data->ent;
		iter->cpu = data->cpu;
1329
		ret = print_graph_entry(field, s, iter, flags);
1330 1331 1332 1333 1334 1335 1336
		if (ret == TRACE_TYPE_HANDLED && iter->cpu != cpu) {
			per_cpu_ptr(data->cpu_data, iter->cpu)->ignore = 1;
			ret = TRACE_TYPE_NO_CONSUME;
		}
		iter->cpu = cpu;
		return ret;
	}
1337

1338 1339
	switch (entry->type) {
	case TRACE_GRAPH_ENT: {
1340 1341 1342 1343 1344 1345
		/*
		 * print_graph_entry() may consume the current event,
		 * thus @field may become invalid, so we need to save it.
		 * sizeof(struct ftrace_graph_ent_entry) is very small,
		 * it can be safely saved at the stack.
		 */
1346
		struct ftrace_graph_ent_entry saved;
1347
		trace_assign_type(field, entry);
1348
		saved = *field;
1349
		return print_graph_entry(&saved, s, iter, flags);
1350 1351 1352 1353
	}
	case TRACE_GRAPH_RET: {
		struct ftrace_graph_ret_entry *field;
		trace_assign_type(field, entry);
1354
		return print_graph_return(&field->ret, s, entry, iter, flags);
1355
	}
1356 1357 1358 1359 1360
	case TRACE_STACK:
	case TRACE_FN:
		/* dont trace stack and functions as comments */
		return TRACE_TYPE_UNHANDLED;

1361
	default:
1362
		return print_graph_comment(s, entry, iter, flags);
1363
	}
1364 1365

	return TRACE_TYPE_HANDLED;
1366 1367
}

1368 1369 1370
static enum print_line_t
print_graph_function(struct trace_iterator *iter)
{
1371
	return print_graph_function_flags(iter, tracer_flags.val);
1372 1373
}

1374
static enum print_line_t
1375 1376
print_graph_function_event(struct trace_iterator *iter, int flags,
			   struct trace_event *event)
1377 1378 1379 1380
{
	return print_graph_function(iter);
}

1381
static void print_lat_header(struct seq_file *s, u32 flags)
1382 1383 1384 1385 1386 1387
{
	static const char spaces[] = "                "	/* 16 spaces */
		"    "					/* 4 spaces */
		"                 ";			/* 17 spaces */
	int size = 0;

1388
	if (flags & TRACE_GRAPH_PRINT_ABS_TIME)
1389
		size += 16;
1390
	if (flags & TRACE_GRAPH_PRINT_CPU)
1391
		size += 4;
1392
	if (flags & TRACE_GRAPH_PRINT_PROC)
1393 1394 1395 1396 1397 1398
		size += 17;

	seq_printf(s, "#%.*s  _-----=> irqs-off        \n", size, spaces);
	seq_printf(s, "#%.*s / _----=> need-resched    \n", size, spaces);
	seq_printf(s, "#%.*s| / _---=> hardirq/softirq \n", size, spaces);
	seq_printf(s, "#%.*s|| / _--=> preempt-depth   \n", size, spaces);
1399
	seq_printf(s, "#%.*s||| /                      \n", size, spaces);
1400 1401
}

1402
static void __print_graph_headers_flags(struct seq_file *s, u32 flags)
1403
{
1404 1405 1406
	int lat = trace_flags & TRACE_ITER_LATENCY_FMT;

	if (lat)
1407
		print_lat_header(s, flags);
1408

1409
	/* 1st line */
1410
	seq_putc(s, '#');
1411
	if (flags & TRACE_GRAPH_PRINT_ABS_TIME)
1412
		seq_puts(s, "     TIME       ");
1413
	if (flags & TRACE_GRAPH_PRINT_CPU)
1414
		seq_puts(s, " CPU");
1415
	if (flags & TRACE_GRAPH_PRINT_PROC)
1416
		seq_puts(s, "  TASK/PID       ");
1417
	if (lat)
1418
		seq_puts(s, "||||");
1419
	if (flags & TRACE_GRAPH_PRINT_DURATION)
1420 1421
		seq_puts(s, "  DURATION   ");
	seq_puts(s, "               FUNCTION CALLS\n");
1422 1423

	/* 2nd line */
1424
	seq_putc(s, '#');
1425
	if (flags & TRACE_GRAPH_PRINT_ABS_TIME)
1426
		seq_puts(s, "      |         ");
1427
	if (flags & TRACE_GRAPH_PRINT_CPU)
1428
		seq_puts(s, " |  ");
1429
	if (flags & TRACE_GRAPH_PRINT_PROC)
1430
		seq_puts(s, "   |    |        ");
1431
	if (lat)
1432
		seq_puts(s, "||||");
1433
	if (flags & TRACE_GRAPH_PRINT_DURATION)
1434 1435
		seq_puts(s, "   |   |      ");
	seq_puts(s, "               |   |   |   |\n");
1436
}
1437

1438
static void print_graph_headers(struct seq_file *s)
1439 1440 1441 1442
{
	print_graph_headers_flags(s, tracer_flags.val);
}

1443 1444 1445 1446
void print_graph_headers_flags(struct seq_file *s, u32 flags)
{
	struct trace_iterator *iter = s->private;

1447 1448 1449
	if (!(trace_flags & TRACE_ITER_CONTEXT_INFO))
		return;

1450 1451 1452 1453 1454 1455
	if (trace_flags & TRACE_ITER_LATENCY_FMT) {
		/* print nothing if the buffers are empty */
		if (trace_empty(iter))
			return;

		print_trace_header(s, iter);
1456
	}
1457 1458 1459 1460

	__print_graph_headers_flags(s, flags);
}

1461
void graph_trace_open(struct trace_iterator *iter)
1462
{
1463
	/* pid and depth on the last trace processed */
1464
	struct fgraph_data *data;
1465 1466
	int cpu;

1467 1468 1469
	iter->private = NULL;

	data = kzalloc(sizeof(*data), GFP_KERNEL);
1470
	if (!data)
1471 1472 1473 1474 1475 1476 1477 1478 1479 1480
		goto out_err;

	data->cpu_data = alloc_percpu(struct fgraph_cpu_data);
	if (!data->cpu_data)
		goto out_err_free;

	for_each_possible_cpu(cpu) {
		pid_t *pid = &(per_cpu_ptr(data->cpu_data, cpu)->last_pid);
		int *depth = &(per_cpu_ptr(data->cpu_data, cpu)->depth);
		int *ignore = &(per_cpu_ptr(data->cpu_data, cpu)->ignore);
1481 1482
		int *depth_irq = &(per_cpu_ptr(data->cpu_data, cpu)->depth_irq);

1483 1484 1485
		*pid = -1;
		*depth = 0;
		*ignore = 0;
1486
		*depth_irq = -1;
1487
	}
1488

1489
	iter->private = data;
1490 1491 1492 1493 1494 1495 1496

	return;

 out_err_free:
	kfree(data);
 out_err:
	pr_warning("function graph tracer: not enough memory\n");
1497 1498
}

1499
void graph_trace_close(struct trace_iterator *iter)
1500
{
1501 1502 1503 1504 1505 1506
	struct fgraph_data *data = iter->private;

	if (data) {
		free_percpu(data->cpu_data);
		kfree(data);
	}
1507 1508
}

1509 1510
static int
func_graph_set_flag(struct trace_array *tr, u32 old_flags, u32 bit, int set)
1511 1512 1513 1514 1515 1516 1517
{
	if (bit == TRACE_GRAPH_PRINT_IRQS)
		ftrace_graph_skip_irqs = !set;

	return 0;
}

1518 1519 1520 1521
static struct trace_event_functions graph_functions = {
	.trace		= print_graph_function_event,
};

1522 1523
static struct trace_event graph_trace_entry_event = {
	.type		= TRACE_GRAPH_ENT,
1524
	.funcs		= &graph_functions,
1525 1526 1527 1528
};

static struct trace_event graph_trace_ret_event = {
	.type		= TRACE_GRAPH_RET,
1529
	.funcs		= &graph_functions
1530 1531
};

1532
static struct tracer graph_trace __tracer_data = {
1533
	.name		= "function_graph",
1534
	.update_thresh	= graph_trace_update_thresh,
1535
	.open		= graph_trace_open,
1536
	.pipe_open	= graph_trace_open,
1537
	.close		= graph_trace_close,
1538
	.pipe_close	= graph_trace_close,
1539 1540
	.init		= graph_trace_init,
	.reset		= graph_trace_reset,
1541 1542
	.print_line	= print_graph_function,
	.print_header	= print_graph_headers,
1543
	.flags		= &tracer_flags,
1544
	.set_flag	= func_graph_set_flag,
1545 1546 1547
#ifdef CONFIG_FTRACE_SELFTEST
	.selftest	= trace_selftest_startup_function_graph,
#endif
1548 1549
};

1550 1551 1552 1553 1554 1555 1556 1557 1558 1559 1560 1561 1562 1563 1564 1565 1566 1567 1568 1569 1570 1571 1572 1573 1574 1575 1576 1577 1578 1579 1580 1581 1582 1583 1584 1585 1586 1587 1588 1589 1590 1591 1592 1593 1594 1595 1596 1597 1598 1599 1600 1601 1602

static ssize_t
graph_depth_write(struct file *filp, const char __user *ubuf, size_t cnt,
		  loff_t *ppos)
{
	unsigned long val;
	int ret;

	ret = kstrtoul_from_user(ubuf, cnt, 10, &val);
	if (ret)
		return ret;

	max_depth = val;

	*ppos += cnt;

	return cnt;
}

static ssize_t
graph_depth_read(struct file *filp, char __user *ubuf, size_t cnt,
		 loff_t *ppos)
{
	char buf[15]; /* More than enough to hold UINT_MAX + "\n"*/
	int n;

	n = sprintf(buf, "%d\n", max_depth);

	return simple_read_from_buffer(ubuf, cnt, ppos, buf, n);
}

static const struct file_operations graph_depth_fops = {
	.open		= tracing_open_generic,
	.write		= graph_depth_write,
	.read		= graph_depth_read,
	.llseek		= generic_file_llseek,
};

static __init int init_graph_debugfs(void)
{
	struct dentry *d_tracer;

	d_tracer = tracing_init_dentry();
	if (!d_tracer)
		return 0;

	trace_create_file("max_graph_depth", 0644, d_tracer,
			  NULL, &graph_depth_fops);

	return 0;
}
fs_initcall(init_graph_debugfs);

1603 1604
static __init int init_graph_trace(void)
{
1605 1606
	max_bytes_for_cpu = snprintf(NULL, 0, "%d", nr_cpu_ids - 1);

1607 1608 1609 1610 1611 1612 1613 1614 1615 1616
	if (!register_ftrace_event(&graph_trace_entry_event)) {
		pr_warning("Warning: could not register graph trace events\n");
		return 1;
	}

	if (!register_ftrace_event(&graph_trace_ret_event)) {
		pr_warning("Warning: could not register graph trace events\n");
		return 1;
	}

1617 1618 1619
	return register_tracer(&graph_trace);
}

1620
core_initcall(init_graph_trace);