trace_stack.c 10.2 KB
Newer Older
Steven Rostedt's avatar
Steven Rostedt committed
1 2 3 4 5 6 7 8 9 10 11
/*
 * Copyright (C) 2008 Steven Rostedt <srostedt@redhat.com>
 *
 */
#include <linux/stacktrace.h>
#include <linux/kallsyms.h>
#include <linux/seq_file.h>
#include <linux/spinlock.h>
#include <linux/uaccess.h>
#include <linux/ftrace.h>
#include <linux/module.h>
12
#include <linux/sysctl.h>
Steven Rostedt's avatar
Steven Rostedt committed
13
#include <linux/init.h>
14 15 16

#include <asm/setup.h>

Steven Rostedt's avatar
Steven Rostedt committed
17 18 19 20
#include "trace.h"

#define STACK_TRACE_ENTRIES 500

21 22 23 24
static unsigned long stack_dump_trace[STACK_TRACE_ENTRIES+1] =
	 { [0 ... (STACK_TRACE_ENTRIES)] = ULONG_MAX };
static unsigned stack_dump_index[STACK_TRACE_ENTRIES];

25 26 27 28 29
/*
 * Reserve one entry for the passed in ip. This will allow
 * us to remove most or all of the stack size overhead
 * added by the stack tracer itself.
 */
Steven Rostedt's avatar
Steven Rostedt committed
30
static struct stack_trace max_stack_trace = {
31
	.max_entries		= STACK_TRACE_ENTRIES - 1,
32
	.entries		= &stack_dump_trace[0],
Steven Rostedt's avatar
Steven Rostedt committed
33 34 35
};

static unsigned long max_stack_size;
36
static arch_spinlock_t max_stack_lock =
37
	(arch_spinlock_t)__ARCH_SPIN_LOCK_UNLOCKED;
Steven Rostedt's avatar
Steven Rostedt committed
38 39

static DEFINE_PER_CPU(int, trace_active);
40 41 42 43
static DEFINE_MUTEX(stack_sysctl_mutex);

int stack_tracer_enabled;
static int last_stack_tracer_enabled;
Steven Rostedt's avatar
Steven Rostedt committed
44

45 46 47 48 49 50 51
static inline void print_max_stack(void)
{
	long i;
	int size;

	pr_emerg("        Depth    Size   Location    (%d entries)\n"
			   "        -----    ----   --------\n",
52
			   max_stack_trace.nr_entries);
53 54 55 56 57 58 59 60 61 62 63 64 65 66 67

	for (i = 0; i < max_stack_trace.nr_entries; i++) {
		if (stack_dump_trace[i] == ULONG_MAX)
			break;
		if (i+1 == max_stack_trace.nr_entries ||
				stack_dump_trace[i+1] == ULONG_MAX)
			size = stack_dump_index[i];
		else
			size = stack_dump_index[i] - stack_dump_index[i+1];

		pr_emerg("%3ld) %8d   %5d   %pS\n", i, stack_dump_index[i],
				size, (void *)stack_dump_trace[i]);
	}
}

68
static inline void
69
check_stack(unsigned long ip, unsigned long *stack)
Steven Rostedt's avatar
Steven Rostedt committed
70
{
71
	unsigned long this_size, flags; unsigned long *p, *top, *start;
72 73
	static int tracer_frame;
	int frame_size = ACCESS_ONCE(tracer_frame);
74
	int i, x;
Steven Rostedt's avatar
Steven Rostedt committed
75

76
	this_size = ((unsigned long)stack) & (THREAD_SIZE-1);
Steven Rostedt's avatar
Steven Rostedt committed
77
	this_size = THREAD_SIZE - this_size;
78 79
	/* Remove the frame of the tracer */
	this_size -= frame_size;
Steven Rostedt's avatar
Steven Rostedt committed
80 81 82 83

	if (this_size <= max_stack_size)
		return;

84
	/* we do not handle interrupt stacks yet */
85
	if (!object_is_on_stack(stack))
86 87
		return;

88
	local_irq_save(flags);
89
	arch_spin_lock(&max_stack_lock);
Steven Rostedt's avatar
Steven Rostedt committed
90

91 92 93 94 95 96
	/*
	 * RCU may not be watching, make it see us.
	 * The stack trace code uses rcu_sched.
	 */
	rcu_irq_enter();

97 98 99 100
	/* In case another CPU set the tracer_frame on us */
	if (unlikely(!frame_size))
		this_size -= tracer_frame;

Steven Rostedt's avatar
Steven Rostedt committed
101 102 103 104 105 106
	/* a race could have already updated it */
	if (this_size <= max_stack_size)
		goto out;

	max_stack_size = this_size;

107
	max_stack_trace.nr_entries = 0;
108
	max_stack_trace.skip = 3;
Steven Rostedt's avatar
Steven Rostedt committed
109 110 111

	save_stack_trace(&max_stack_trace);

112 113 114 115 116
	/* Skip over the overhead of the stack tracer itself */
	for (i = 0; i < max_stack_trace.nr_entries; i++) {
		if (stack_dump_trace[i] == ip)
			break;
	}
117

118 119 120
	/*
	 * Now find where in the stack these are.
	 */
121
	x = 0;
122
	start = stack;
123 124 125 126 127 128 129 130 131 132 133
	top = (unsigned long *)
		(((unsigned long)start & ~(THREAD_SIZE-1)) + THREAD_SIZE);

	/*
	 * Loop through all the entries. One of the entries may
	 * for some reason be missed on the stack, so we may
	 * have to account for them. If they are all there, this
	 * loop will only happen once. This code only takes place
	 * on a new max, so it is far from a fast path.
	 */
	while (i < max_stack_trace.nr_entries) {
134
		int found = 0;
135

136
		stack_dump_index[x] = this_size;
137 138 139
		p = start;

		for (; p < top && i < max_stack_trace.nr_entries; p++) {
140 141
			if (stack_dump_trace[i] == ULONG_MAX)
				break;
142
			if (*p == stack_dump_trace[i]) {
143 144
				stack_dump_trace[x] = stack_dump_trace[i++];
				this_size = stack_dump_index[x++] =
145
					(top - p) * sizeof(unsigned long);
146
				found = 1;
147 148
				/* Start the search from here */
				start = p + 1;
149 150 151 152 153 154 155
				/*
				 * We do not want to show the overhead
				 * of the stack tracer stack in the
				 * max stack. If we haven't figured
				 * out what that is, then figure it out
				 * now.
				 */
156
				if (unlikely(!tracer_frame)) {
157 158 159 160
					tracer_frame = (p - stack) *
						sizeof(unsigned long);
					max_stack_size -= tracer_frame;
				}
161 162 163
			}
		}

164 165
		if (!found)
			i++;
166 167
	}

168 169 170 171
	max_stack_trace.nr_entries = x;
	for (; x < i; x++)
		stack_dump_trace[x] = ULONG_MAX;

172
	if (task_stack_end_corrupted(current)) {
173 174 175 176
		print_max_stack();
		BUG();
	}

Steven Rostedt's avatar
Steven Rostedt committed
177
 out:
178
	rcu_irq_exit();
179
	arch_spin_unlock(&max_stack_lock);
180
	local_irq_restore(flags);
Steven Rostedt's avatar
Steven Rostedt committed
181 182 183
}

static void
184 185
stack_trace_call(unsigned long ip, unsigned long parent_ip,
		 struct ftrace_ops *op, struct pt_regs *pt_regs)
Steven Rostedt's avatar
Steven Rostedt committed
186
{
187
	unsigned long stack;
188
	int cpu;
Steven Rostedt's avatar
Steven Rostedt committed
189

190
	preempt_disable_notrace();
Steven Rostedt's avatar
Steven Rostedt committed
191 192 193 194 195 196

	cpu = raw_smp_processor_id();
	/* no atomic needed, we only modify this variable by this cpu */
	if (per_cpu(trace_active, cpu)++ != 0)
		goto out;

197
	ip += MCOUNT_INSN_SIZE;
198 199

	check_stack(ip, &stack);
Steven Rostedt's avatar
Steven Rostedt committed
200 201 202 203

 out:
	per_cpu(trace_active, cpu)--;
	/* prevent recursion in schedule */
204
	preempt_enable_notrace();
Steven Rostedt's avatar
Steven Rostedt committed
205 206 207 208 209
}

static struct ftrace_ops trace_ops __read_mostly =
{
	.func = stack_trace_call,
210
	.flags = FTRACE_OPS_FL_RECURSION_SAFE,
Steven Rostedt's avatar
Steven Rostedt committed
211 212 213 214 215 216 217 218 219 220 221 222 223 224 225 226 227 228 229 230 231 232 233
};

static ssize_t
stack_max_size_read(struct file *filp, char __user *ubuf,
		    size_t count, loff_t *ppos)
{
	unsigned long *ptr = filp->private_data;
	char buf[64];
	int r;

	r = snprintf(buf, sizeof(buf), "%ld\n", *ptr);
	if (r > sizeof(buf))
		r = sizeof(buf);
	return simple_read_from_buffer(ubuf, count, ppos, buf, r);
}

static ssize_t
stack_max_size_write(struct file *filp, const char __user *ubuf,
		     size_t count, loff_t *ppos)
{
	long *ptr = filp->private_data;
	unsigned long val, flags;
	int ret;
234
	int cpu;
Steven Rostedt's avatar
Steven Rostedt committed
235

236 237
	ret = kstrtoul_from_user(ubuf, count, 10, &val);
	if (ret)
Steven Rostedt's avatar
Steven Rostedt committed
238 239
		return ret;

240
	local_irq_save(flags);
241 242 243 244 245 246 247 248 249

	/*
	 * In case we trace inside arch_spin_lock() or after (NMI),
	 * we will cause circular lock, so we also need to increase
	 * the percpu trace_active here.
	 */
	cpu = smp_processor_id();
	per_cpu(trace_active, cpu)++;

250
	arch_spin_lock(&max_stack_lock);
Steven Rostedt's avatar
Steven Rostedt committed
251
	*ptr = val;
252
	arch_spin_unlock(&max_stack_lock);
253 254

	per_cpu(trace_active, cpu)--;
255
	local_irq_restore(flags);
Steven Rostedt's avatar
Steven Rostedt committed
256 257 258 259

	return count;
}

260
static const struct file_operations stack_max_size_fops = {
Steven Rostedt's avatar
Steven Rostedt committed
261 262 263
	.open		= tracing_open_generic,
	.read		= stack_max_size_read,
	.write		= stack_max_size_write,
264
	.llseek		= default_llseek,
Steven Rostedt's avatar
Steven Rostedt committed
265 266 267
};

static void *
Li Zefan's avatar
Li Zefan committed
268
__next(struct seq_file *m, loff_t *pos)
Steven Rostedt's avatar
Steven Rostedt committed
269
{
Li Zefan's avatar
Li Zefan committed
270
	long n = *pos - 1;
Steven Rostedt's avatar
Steven Rostedt committed
271

272
	if (n > max_stack_trace.nr_entries || stack_dump_trace[n] == ULONG_MAX)
Steven Rostedt's avatar
Steven Rostedt committed
273 274
		return NULL;

Li Zefan's avatar
Li Zefan committed
275
	m->private = (void *)n;
276
	return &m->private;
Steven Rostedt's avatar
Steven Rostedt committed
277 278
}

Li Zefan's avatar
Li Zefan committed
279 280
static void *
t_next(struct seq_file *m, void *v, loff_t *pos)
Steven Rostedt's avatar
Steven Rostedt committed
281
{
Li Zefan's avatar
Li Zefan committed
282 283 284
	(*pos)++;
	return __next(m, pos);
}
Steven Rostedt's avatar
Steven Rostedt committed
285

Li Zefan's avatar
Li Zefan committed
286 287
static void *t_start(struct seq_file *m, loff_t *pos)
{
288 289
	int cpu;

Steven Rostedt's avatar
Steven Rostedt committed
290
	local_irq_disable();
291 292 293 294

	cpu = smp_processor_id();
	per_cpu(trace_active, cpu)++;

295
	arch_spin_lock(&max_stack_lock);
Steven Rostedt's avatar
Steven Rostedt committed
296

297 298 299
	if (*pos == 0)
		return SEQ_START_TOKEN;

Li Zefan's avatar
Li Zefan committed
300
	return __next(m, pos);
Steven Rostedt's avatar
Steven Rostedt committed
301 302 303 304
}

static void t_stop(struct seq_file *m, void *p)
{
305 306
	int cpu;

307
	arch_spin_unlock(&max_stack_lock);
308 309 310 311

	cpu = smp_processor_id();
	per_cpu(trace_active, cpu)--;

Steven Rostedt's avatar
Steven Rostedt committed
312 313 314
	local_irq_enable();
}

315
static void trace_lookup_stack(struct seq_file *m, long i)
Steven Rostedt's avatar
Steven Rostedt committed
316
{
317
	unsigned long addr = stack_dump_trace[i];
Steven Rostedt's avatar
Steven Rostedt committed
318

319
	seq_printf(m, "%pS\n", (void *)addr);
Steven Rostedt's avatar
Steven Rostedt committed
320 321
}

322 323 324 325 326 327 328 329 330 331 332
static void print_disabled(struct seq_file *m)
{
	seq_puts(m, "#\n"
		 "#  Stack tracer disabled\n"
		 "#\n"
		 "# To enable the stack tracer, either add 'stacktrace' to the\n"
		 "# kernel command line\n"
		 "# or 'echo 1 > /proc/sys/kernel/stack_tracer_enabled'\n"
		 "#\n");
}

Steven Rostedt's avatar
Steven Rostedt committed
333 334
static int t_show(struct seq_file *m, void *v)
{
335
	long i;
336 337
	int size;

338
	if (v == SEQ_START_TOKEN) {
339
		seq_printf(m, "        Depth    Size   Location"
340
			   "    (%d entries)\n"
341
			   "        -----    ----   --------\n",
342
			   max_stack_trace.nr_entries);
343 344 345 346

		if (!stack_tracer_enabled && !max_stack_size)
			print_disabled(m);

347 348
		return 0;
	}
Steven Rostedt's avatar
Steven Rostedt committed
349

350 351
	i = *(long *)v;

352 353
	if (i >= max_stack_trace.nr_entries ||
	    stack_dump_trace[i] == ULONG_MAX)
Steven Rostedt's avatar
Steven Rostedt committed
354 355
		return 0;

356 357 358 359 360 361 362 363 364
	if (i+1 == max_stack_trace.nr_entries ||
	    stack_dump_trace[i+1] == ULONG_MAX)
		size = stack_dump_index[i];
	else
		size = stack_dump_index[i] - stack_dump_index[i+1];

	seq_printf(m, "%3ld) %8d   %5d   ", i, stack_dump_index[i], size);

	trace_lookup_stack(m, i);
Steven Rostedt's avatar
Steven Rostedt committed
365 366 367 368

	return 0;
}

369
static const struct seq_operations stack_trace_seq_ops = {
Steven Rostedt's avatar
Steven Rostedt committed
370 371 372 373 374 375 376 377
	.start		= t_start,
	.next		= t_next,
	.stop		= t_stop,
	.show		= t_show,
};

static int stack_trace_open(struct inode *inode, struct file *file)
{
378
	return seq_open(file, &stack_trace_seq_ops);
Steven Rostedt's avatar
Steven Rostedt committed
379 380
}

381
static const struct file_operations stack_trace_fops = {
Steven Rostedt's avatar
Steven Rostedt committed
382 383 384
	.open		= stack_trace_open,
	.read		= seq_read,
	.llseek		= seq_lseek,
385
	.release	= seq_release,
Steven Rostedt's avatar
Steven Rostedt committed
386 387
};

388 389 390 391 392 393 394 395 396 397 398
static int
stack_trace_filter_open(struct inode *inode, struct file *file)
{
	return ftrace_regex_open(&trace_ops, FTRACE_ITER_FILTER,
				 inode, file);
}

static const struct file_operations stack_trace_filter_fops = {
	.open = stack_trace_filter_open,
	.read = seq_read,
	.write = ftrace_filter_write,
399
	.llseek = tracing_lseek,
400 401 402
	.release = ftrace_regex_release,
};

403 404
int
stack_trace_sysctl(struct ctl_table *table, int write,
405
		   void __user *buffer, size_t *lenp,
406 407 408 409 410 411
		   loff_t *ppos)
{
	int ret;

	mutex_lock(&stack_sysctl_mutex);

412
	ret = proc_dointvec(table, write, buffer, lenp, ppos);
413 414

	if (ret || !write ||
415
	    (last_stack_tracer_enabled == !!stack_tracer_enabled))
416 417
		goto out;

418
	last_stack_tracer_enabled = !!stack_tracer_enabled;
419 420 421 422 423 424 425 426 427 428 429

	if (stack_tracer_enabled)
		register_ftrace_function(&trace_ops);
	else
		unregister_ftrace_function(&trace_ops);

 out:
	mutex_unlock(&stack_sysctl_mutex);
	return ret;
}

430 431
static char stack_trace_filter_buf[COMMAND_LINE_SIZE+1] __initdata;

432 433
static __init int enable_stacktrace(char *str)
{
434 435 436
	if (strncmp(str, "_filter=", 8) == 0)
		strncpy(stack_trace_filter_buf, str+8, COMMAND_LINE_SIZE);

437 438
	stack_tracer_enabled = 1;
	last_stack_tracer_enabled = 1;
439 440 441 442
	return 1;
}
__setup("stacktrace", enable_stacktrace);

Steven Rostedt's avatar
Steven Rostedt committed
443 444 445 446 447
static __init int stack_trace_init(void)
{
	struct dentry *d_tracer;

	d_tracer = tracing_init_dentry();
448
	if (IS_ERR(d_tracer))
449
		return 0;
Steven Rostedt's avatar
Steven Rostedt committed
450

451 452
	trace_create_file("stack_max_size", 0644, d_tracer,
			&max_stack_size, &stack_max_size_fops);
Steven Rostedt's avatar
Steven Rostedt committed
453

454 455
	trace_create_file("stack_trace", 0444, d_tracer,
			NULL, &stack_trace_fops);
Steven Rostedt's avatar
Steven Rostedt committed
456

457 458 459
	trace_create_file("stack_trace_filter", 0444, d_tracer,
			NULL, &stack_trace_filter_fops);

460 461 462
	if (stack_trace_filter_buf[0])
		ftrace_set_early_filter(&trace_ops, stack_trace_filter_buf, 1);

463
	if (stack_tracer_enabled)
464
		register_ftrace_function(&trace_ops);
Steven Rostedt's avatar
Steven Rostedt committed
465 466 467 468 469

	return 0;
}

device_initcall(stack_trace_init);