779 lines · 18.5 KB · C
RawDownload
// SPDX-License-Identifier: GPL-2.0/* * trace irqs off critical timings * * Copyright (C) 2007-2008 Steven Rostedt <srostedt@redhat.com> * Copyright (C) 2008 Ingo Molnar <mingo@redhat.com> * * From code in the latency_tracer, that is: * *  Copyright (C) 2004-2006 Ingo Molnar *  Copyright (C) 2004 Nadia Yvette Chambers */#include <linux/kallsyms.h>#include <linux/uaccess.h>#include <linux/module.h>#include <linux/ftrace.h>#include <linux/kprobes.h> #include "trace.h" #include <trace/events/preemptirq.h> #if defined(CONFIG_IRQSOFF_TRACER) || defined(CONFIG_PREEMPT_TRACER)static struct trace_array		*irqsoff_trace __read_mostly;static int				tracer_enabled __read_mostly; static DEFINE_PER_CPU(int, tracing_cpu); static DEFINE_RAW_SPINLOCK(max_trace_lock); enum {	TRACER_IRQS_OFF		= (1 << 1),	TRACER_PREEMPT_OFF	= (1 << 2),}; static int trace_type __read_mostly; static int save_flags; static void stop_irqsoff_tracer(struct trace_array *tr, int graph);static int start_irqsoff_tracer(struct trace_array *tr, int graph); #ifdef CONFIG_PREEMPT_TRACERstatic inline intpreempt_trace(int pc){	return ((trace_type & TRACER_PREEMPT_OFF) && pc);}#else# define preempt_trace(pc) (0)#endif #ifdef CONFIG_IRQSOFF_TRACERstatic inline intirq_trace(void){	return ((trace_type & TRACER_IRQS_OFF) &&		irqs_disabled());}#else# define irq_trace() (0)#endif #ifdef CONFIG_FUNCTION_GRAPH_TRACERstatic int irqsoff_display_graph(struct trace_array *tr, int set);# define is_graph(tr) ((tr)->trace_flags & TRACE_ITER(DISPLAY_GRAPH))#elsestatic inline int irqsoff_display_graph(struct trace_array *tr, int set){	return -EINVAL;}# define is_graph(tr) false#endif /* * Sequence count - we record it when starting a measurement and * skip the latency if the sequence has changed - some other section * did a maximum and could disturb our measurement with serial console * printouts, etc. Truly coinciding maximum latencies should be rare * and what happens together happens separately as well, so this doesn't * decrease the validity of the maximum found: */static __cacheline_aligned_in_smp	unsigned long max_sequence; #ifdef CONFIG_FUNCTION_TRACER/* * Prologue for the preempt and irqs off function tracers. * * Returns 1 if it is OK to continue, and data->disabled is *            incremented. *         0 if the trace is to be ignored, and data->disabled *            is kept the same. * * Note, this function is also used outside this ifdef but *  inside the #ifdef of the function graph tracer below. *  This is OK, since the function graph tracer is *  dependent on the function tracer. */static int func_prolog_dec(struct trace_array *tr,			   struct trace_array_cpu **data,			   unsigned long *flags){	long disabled;	int cpu; 	/*	 * Does not matter if we preempt. We test the flags	 * afterward, to see if irqs are disabled or not.	 * If we preempt and get a false positive, the flags	 * test will fail.	 */	cpu = raw_smp_processor_id();	if (likely(!per_cpu(tracing_cpu, cpu)))		return 0; 	local_save_flags(*flags);	/*	 * Slight chance to get a false positive on tracing_cpu,	 * although I'm starting to think there isn't a chance.	 * Leave this for now just to be paranoid.	 */	if (!irqs_disabled_flags(*flags) && !preempt_count())		return 0; 	*data = per_cpu_ptr(tr->array_buffer.data, cpu);	disabled = local_inc_return(&(*data)->disabled); 	if (likely(disabled == 1))		return 1; 	local_dec(&(*data)->disabled); 	return 0;} /* * irqsoff uses its own tracer function to keep the overhead down: */static voidirqsoff_tracer_call(unsigned long ip, unsigned long parent_ip,		    struct ftrace_ops *op, struct ftrace_regs *fregs){	struct trace_array *tr = irqsoff_trace;	struct trace_array_cpu *data;	unsigned long flags;	unsigned int trace_ctx; 	if (!func_prolog_dec(tr, &data, &flags))		return; 	trace_ctx = tracing_gen_ctx_flags(flags); 	trace_function(tr, ip, parent_ip, trace_ctx, fregs); 	local_dec(&data->disabled);}#endif /* CONFIG_FUNCTION_TRACER */ #ifdef CONFIG_FUNCTION_GRAPH_TRACERstatic int irqsoff_display_graph(struct trace_array *tr, int set){	int cpu; 	if (!(is_graph(tr) ^ set))		return 0; 	stop_irqsoff_tracer(irqsoff_trace, !set); 	for_each_possible_cpu(cpu)		per_cpu(tracing_cpu, cpu) = 0; 	tr->max_latency = 0;	tracing_reset_online_cpus(&irqsoff_trace->array_buffer); 	return start_irqsoff_tracer(irqsoff_trace, set);} static int irqsoff_graph_entry(struct ftrace_graph_ent *trace,			       struct fgraph_ops *gops,			       struct ftrace_regs *fregs){	struct trace_array *tr = irqsoff_trace;	struct trace_array_cpu *data;	unsigned long flags;	unsigned int trace_ctx;	u64 *calltime;	int ret = 0; 	if (ftrace_graph_ignore_func(gops, trace))		return 0;	/*	 * 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; 	if (!func_prolog_dec(tr, &data, &flags))		return 0; 	calltime = fgraph_reserve_data(gops->idx, sizeof(*calltime));	if (calltime) {		*calltime = trace_clock_local();		trace_ctx = tracing_gen_ctx_flags(flags);		ret = __trace_graph_entry(tr, trace, trace_ctx);	}	local_dec(&data->disabled); 	return ret;} static void irqsoff_graph_return(struct ftrace_graph_ret *trace,				 struct fgraph_ops *gops,				 struct ftrace_regs *fregs){	struct trace_array *tr = irqsoff_trace;	struct trace_array_cpu *data;	unsigned long flags;	unsigned int trace_ctx;	u64 *calltime;	u64 rettime;	int size; 	ftrace_graph_addr_finish(gops, trace); 	if (!func_prolog_dec(tr, &data, &flags))		return; 	rettime = trace_clock_local();	calltime = fgraph_retrieve_data(gops->idx, &size);	if (calltime) {		trace_ctx = tracing_gen_ctx_flags(flags);		__trace_graph_return(tr, trace, trace_ctx, *calltime, rettime);	}	local_dec(&data->disabled);} static struct fgraph_ops fgraph_ops = {	.entryfunc		= &irqsoff_graph_entry,	.retfunc		= &irqsoff_graph_return,}; static void irqsoff_trace_open(struct trace_iterator *iter){	if (is_graph(iter->tr))		graph_trace_open(iter);} static void irqsoff_trace_close(struct trace_iterator *iter){	if (iter->private)		graph_trace_close(iter);} #define GRAPH_TRACER_FLAGS (TRACE_GRAPH_PRINT_CPU | \			    TRACE_GRAPH_PRINT_PROC | \			    TRACE_GRAPH_PRINT_REL_TIME | \			    TRACE_GRAPH_PRINT_DURATION) static enum print_line_t irqsoff_print_line(struct trace_iterator *iter){	/*	 * In graph mode call the graph tracer output function,	 * otherwise go with the TRACE_FN event handler	 */	if (is_graph(iter->tr))		return print_graph_function_flags(iter, GRAPH_TRACER_FLAGS); 	return TRACE_TYPE_UNHANDLED;} static void irqsoff_print_header(struct seq_file *s){	struct trace_array *tr = irqsoff_trace; 	if (is_graph(tr))		print_graph_headers_flags(s, GRAPH_TRACER_FLAGS);	else		trace_default_header(s);} static void__trace_function(struct trace_array *tr,		 unsigned long ip, unsigned long parent_ip,		 unsigned int trace_ctx){	if (is_graph(tr))		trace_graph_function(tr, ip, parent_ip, trace_ctx);	else		trace_function(tr, ip, parent_ip, trace_ctx, NULL);} #elsestatic inline void__trace_function(struct trace_array *tr,		 unsigned long ip, unsigned long parent_ip,		 unsigned int trace_ctx){	return trace_function(tr, ip, parent_ip, trace_ctx, NULL);} static enum print_line_t irqsoff_print_line(struct trace_iterator *iter){	return TRACE_TYPE_UNHANDLED;} static void irqsoff_trace_open(struct trace_iterator *iter) { }static void irqsoff_trace_close(struct trace_iterator *iter) { } #ifdef CONFIG_FUNCTION_TRACERstatic void irqsoff_print_header(struct seq_file *s){	trace_default_header(s);}#elsestatic void irqsoff_print_header(struct seq_file *s){	trace_latency_header(s);}#endif /* CONFIG_FUNCTION_TRACER */#endif /* CONFIG_FUNCTION_GRAPH_TRACER */ /* * Should this new latency be reported/recorded? */static bool report_latency(struct trace_array *tr, u64 delta){	if (tracing_thresh) {		if (delta < tracing_thresh)			return false;	} else {		if (delta <= tr->max_latency)			return false;	}	return true;} static voidcheck_critical_timing(struct trace_array *tr,		      struct trace_array_cpu *data,		      unsigned long parent_ip,		      int cpu){	u64 T0, T1, delta;	unsigned long flags;	unsigned int trace_ctx; 	T0 = data->preempt_timestamp;	T1 = ftrace_now(cpu);	delta = T1-T0; 	trace_ctx = tracing_gen_ctx(); 	if (!report_latency(tr, delta))		goto out; 	raw_spin_lock_irqsave(&max_trace_lock, flags); 	/* check if we are still the max latency */	if (!report_latency(tr, delta))		goto out_unlock; 	__trace_function(tr, CALLER_ADDR0, parent_ip, trace_ctx);	/* Skip 5 functions to get to the irq/preempt enable function */	__trace_stack(tr, trace_ctx, 5); 	if (data->critical_sequence != max_sequence)		goto out_unlock; 	data->critical_end = parent_ip; 	if (likely(!is_tracing_stopped())) {		tr->max_latency = delta;		update_max_tr_single(tr, current, cpu);	} 	max_sequence++; out_unlock:	raw_spin_unlock_irqrestore(&max_trace_lock, flags); out:	data->critical_sequence = max_sequence;	data->preempt_timestamp = ftrace_now(cpu);	__trace_function(tr, CALLER_ADDR0, parent_ip, trace_ctx);} static nokprobe_inline voidstart_critical_timing(unsigned long ip, unsigned long parent_ip){	int cpu;	struct trace_array *tr = irqsoff_trace;	struct trace_array_cpu *data;	long disabled; 	if (!tracer_enabled || !tracing_is_enabled())		return; 	cpu = raw_smp_processor_id(); 	if (per_cpu(tracing_cpu, cpu))		return; 	data = per_cpu_ptr(tr->array_buffer.data, cpu); 	if (unlikely(!data) || local_read(&data->disabled))		return; 	disabled = local_inc_return(&data->disabled); 	if (disabled == 1) {		data->critical_sequence = max_sequence;		data->preempt_timestamp = ftrace_now(cpu);		data->critical_start = parent_ip ? : ip; 		__trace_function(tr, ip, parent_ip, tracing_gen_ctx()); 		per_cpu(tracing_cpu, cpu) = 1;	} 	local_dec(&data->disabled);} static nokprobe_inline voidstop_critical_timing(unsigned long ip, unsigned long parent_ip){	int cpu;	struct trace_array *tr = irqsoff_trace;	struct trace_array_cpu *data;	unsigned int trace_ctx;	long disabled; 	cpu = raw_smp_processor_id();	/* Always clear the tracing cpu on stopping the trace */	if (unlikely(per_cpu(tracing_cpu, cpu)))		per_cpu(tracing_cpu, cpu) = 0;	else		return; 	if (!tracer_enabled || !tracing_is_enabled())		return; 	data = per_cpu_ptr(tr->array_buffer.data, cpu); 	if (unlikely(!data) ||	    !data->critical_start || local_read(&data->disabled))		return; 	disabled = local_inc_return(&data->disabled); 	if (disabled == 1) {		trace_ctx = tracing_gen_ctx();		__trace_function(tr, ip, parent_ip, trace_ctx);		check_critical_timing(tr, data, parent_ip ? : ip, cpu);		data->critical_start = 0;	} 	local_dec(&data->disabled);} /* start and stop critical timings used to for stoppage (in idle) */void start_critical_timings(void){	if (preempt_trace(preempt_count()) || irq_trace())		start_critical_timing(CALLER_ADDR0, CALLER_ADDR1);}EXPORT_SYMBOL_GPL(start_critical_timings);NOKPROBE_SYMBOL(start_critical_timings); void stop_critical_timings(void){	if (preempt_trace(preempt_count()) || irq_trace())		stop_critical_timing(CALLER_ADDR0, CALLER_ADDR1);}EXPORT_SYMBOL_GPL(stop_critical_timings);NOKPROBE_SYMBOL(stop_critical_timings); #ifdef CONFIG_FUNCTION_TRACERstatic bool function_enabled; static int register_irqsoff_function(struct trace_array *tr, int graph, int set){	int ret; 	/* 'set' is set if TRACE_ITER(FUNCTION) is about to be set */	if (function_enabled || (!set && !(tr->trace_flags & TRACE_ITER(FUNCTION))))		return 0; 	if (graph)		ret = register_ftrace_graph(&fgraph_ops);	else		ret = register_ftrace_function(tr->ops); 	if (!ret)		function_enabled = true; 	return ret;

Lines 1–500 of 779.