aboutsummaryrefslogtreecommitdiffstats
diff options
context:
space:
mode:
authorBjoern Brandenburg <bbb@mpi-sws.org>2015-08-09 07:18:48 -0400
committerBjoern Brandenburg <bbb@mpi-sws.org>2017-05-26 17:12:26 -0400
commit5b9cd45eb4fd99afd6b66111f9106d1e65c76bb7 (patch)
tree7e10fa74c243b495879af40d8dbba13564d8de2f
parentb556571e81005147c0b72112b386e6820dcf30be (diff)
Add tracepoint support
This patch integrates LITMUS^RT's sched_trace_XXX() macros with Linux's notion of tracepoints. This is useful to visualize schedules in kernel shark and similar tools. Historically, LITMUS^RT's sched_trace predates Linux's tracepoint infrastructure.
-rw-r--r--include/litmus/sched_trace.h8
-rw-r--r--include/trace/events/litmus.h231
-rw-r--r--litmus/Kconfig18
-rw-r--r--litmus/sched_task_trace.c4
4 files changed, 261 insertions, 0 deletions
diff --git a/include/litmus/sched_trace.h b/include/litmus/sched_trace.h
index 589c63a142b5..0532424fbee6 100644
--- a/include/litmus/sched_trace.h
+++ b/include/litmus/sched_trace.h
@@ -196,24 +196,28 @@ feather_callback void do_sched_trace_sys_release(unsigned long id,
196 do { \ 196 do { \
197 SCHED_TRACE(SCHED_TRACE_BASE_ID + 2, \ 197 SCHED_TRACE(SCHED_TRACE_BASE_ID + 2, \
198 do_sched_trace_task_param, t); \ 198 do_sched_trace_task_param, t); \
199 trace_litmus_task_param(t); \
199 } while (0) 200 } while (0)
200 201
201#define sched_trace_task_release(t) \ 202#define sched_trace_task_release(t) \
202 do { \ 203 do { \
203 SCHED_TRACE(SCHED_TRACE_BASE_ID + 3, \ 204 SCHED_TRACE(SCHED_TRACE_BASE_ID + 3, \
204 do_sched_trace_task_release, t); \ 205 do_sched_trace_task_release, t); \
206 trace_litmus_task_release(t); \
205 } while (0) 207 } while (0)
206 208
207#define sched_trace_task_switch_to(t) \ 209#define sched_trace_task_switch_to(t) \
208 do { \ 210 do { \
209 SCHED_TRACE(SCHED_TRACE_BASE_ID + 4, \ 211 SCHED_TRACE(SCHED_TRACE_BASE_ID + 4, \
210 do_sched_trace_task_switch_to, t); \ 212 do_sched_trace_task_switch_to, t); \
213 trace_litmus_switch_to(t); \
211 } while (0) 214 } while (0)
212 215
213#define sched_trace_task_switch_away(t) \ 216#define sched_trace_task_switch_away(t) \
214 do { \ 217 do { \
215 SCHED_TRACE(SCHED_TRACE_BASE_ID + 5, \ 218 SCHED_TRACE(SCHED_TRACE_BASE_ID + 5, \
216 do_sched_trace_task_switch_away, t); \ 219 do_sched_trace_task_switch_away, t); \
220 trace_litmus_switch_away(t); \
217 } while (0) 221 } while (0)
218 222
219#define sched_trace_task_completion(t, forced) \ 223#define sched_trace_task_completion(t, forced) \
@@ -221,18 +225,21 @@ feather_callback void do_sched_trace_sys_release(unsigned long id,
221 SCHED_TRACE2(SCHED_TRACE_BASE_ID + 6, \ 225 SCHED_TRACE2(SCHED_TRACE_BASE_ID + 6, \
222 do_sched_trace_task_completion, t, \ 226 do_sched_trace_task_completion, t, \
223 (unsigned long) forced); \ 227 (unsigned long) forced); \
228 trace_litmus_task_completion(t, forced); \
224 } while (0) 229 } while (0)
225 230
226#define sched_trace_task_block(t) \ 231#define sched_trace_task_block(t) \
227 do { \ 232 do { \
228 SCHED_TRACE(SCHED_TRACE_BASE_ID + 7, \ 233 SCHED_TRACE(SCHED_TRACE_BASE_ID + 7, \
229 do_sched_trace_task_block, t); \ 234 do_sched_trace_task_block, t); \
235 trace_litmus_task_block(t); \
230 } while (0) 236 } while (0)
231 237
232#define sched_trace_task_resume(t) \ 238#define sched_trace_task_resume(t) \
233 do { \ 239 do { \
234 SCHED_TRACE(SCHED_TRACE_BASE_ID + 8, \ 240 SCHED_TRACE(SCHED_TRACE_BASE_ID + 8, \
235 do_sched_trace_task_resume, t); \ 241 do_sched_trace_task_resume, t); \
242 trace_litmus_task_resume(t); \
236 } while (0) 243 } while (0)
237 244
238#define sched_trace_action(t, action) \ 245#define sched_trace_action(t, action) \
@@ -244,6 +251,7 @@ feather_callback void do_sched_trace_sys_release(unsigned long id,
244 do { \ 251 do { \
245 SCHED_TRACE(SCHED_TRACE_BASE_ID + 10, \ 252 SCHED_TRACE(SCHED_TRACE_BASE_ID + 10, \
246 do_sched_trace_sys_release, when); \ 253 do_sched_trace_sys_release, when); \
254 trace_litmus_sys_release(when); \
247 } while (0) 255 } while (0)
248 256
249#define sched_trace_last_suspension_as_completion(t) \ 257#define sched_trace_last_suspension_as_completion(t) \
diff --git a/include/trace/events/litmus.h b/include/trace/events/litmus.h
new file mode 100644
index 000000000000..0fffcee02be0
--- /dev/null
+++ b/include/trace/events/litmus.h
@@ -0,0 +1,231 @@
1/*
2 * LITMUS^RT kernel style scheduling tracepoints
3 */
4#undef TRACE_SYSTEM
5#define TRACE_SYSTEM litmus
6
7#if !defined(_SCHED_TASK_TRACEPOINT_H) || defined(TRACE_HEADER_MULTI_READ)
8#define _SCHED_TASK_TRACEPOINT_H
9
10#include <linux/tracepoint.h>
11
12#include <litmus/litmus.h>
13#include <litmus/rt_param.h>
14
15/*
16 * Tracing task admission
17 */
18TRACE_EVENT(litmus_task_param,
19
20 TP_PROTO(struct task_struct *t),
21
22 TP_ARGS(t),
23
24 TP_STRUCT__entry(
25 __field( pid_t, pid )
26 __field( unsigned int, job )
27 __field( lt_t, wcet )
28 __field( lt_t, period )
29 __field( lt_t, phase )
30 __field( int, partition )
31 ),
32
33 TP_fast_assign(
34 __entry->pid = t ? t->pid : 0;
35 __entry->job = t ? t->rt_param.job_params.job_no : 0;
36 __entry->wcet = get_exec_cost(t);
37 __entry->period = get_rt_period(t);
38 __entry->phase = get_rt_phase(t);
39 __entry->partition = get_partition(t);
40 ),
41
42 TP_printk("period(%d, %Lu).\nwcet(%d, %Lu).\n",
43 __entry->pid, __entry->period,
44 __entry->pid, __entry->wcet)
45);
46
47/*
48 * Tracing jobs release
49 */
50TRACE_EVENT(litmus_task_release,
51
52 TP_PROTO(struct task_struct *t),
53
54 TP_ARGS(t),
55
56 TP_STRUCT__entry(
57 __field( pid_t, pid )
58 __field( unsigned int, job )
59 __field( lt_t, release )
60 __field( lt_t, deadline )
61 ),
62
63 TP_fast_assign(
64 __entry->pid = t ? t->pid : 0;
65 __entry->job = t ? t->rt_param.job_params.job_no : 0;
66 __entry->release = get_release(t);
67 __entry->deadline = get_deadline(t);
68 ),
69
70 TP_printk("release(job(%u, %u)): %Lu\ndeadline(job(%u, %u)): %Lu\n",
71 __entry->pid, __entry->job, __entry->release,
72 __entry->pid, __entry->job, __entry->deadline)
73);
74
75/*
76 * Tracepoint for switching to new task
77 */
78TRACE_EVENT(litmus_switch_to,
79
80 TP_PROTO(struct task_struct *t),
81
82 TP_ARGS(t),
83
84 TP_STRUCT__entry(
85 __field( pid_t, pid )
86 __field( unsigned int, job )
87 __field( lt_t, when )
88 __field( lt_t, exec_time )
89 ),
90
91 TP_fast_assign(
92 __entry->pid = is_realtime(t) ? t->pid : 0;
93 __entry->job = is_realtime(t) ? t->rt_param.job_params.job_no : 0;
94 __entry->when = litmus_clock();
95 __entry->exec_time = get_exec_time(t);
96 ),
97
98 TP_printk("switch_to(job(%u, %u)): %Lu (exec: %Lu)\n",
99 __entry->pid, __entry->job,
100 __entry->when, __entry->exec_time)
101);
102
103/*
104 * Tracepoint for switching away previous task
105 */
106TRACE_EVENT(litmus_switch_away,
107
108 TP_PROTO(struct task_struct *t),
109
110 TP_ARGS(t),
111
112 TP_STRUCT__entry(
113 __field( pid_t, pid )
114 __field( unsigned int, job )
115 __field( lt_t, when )
116 __field( lt_t, exec_time )
117 ),
118
119 TP_fast_assign(
120 __entry->pid = is_realtime(t) ? t->pid : 0;
121 __entry->job = is_realtime(t) ? t->rt_param.job_params.job_no : 0;
122 __entry->when = litmus_clock();
123 __entry->exec_time = get_exec_time(t);
124 ),
125
126 TP_printk("switch_away(job(%u, %u)): %Lu (exec: %Lu)\n",
127 __entry->pid, __entry->job,
128 __entry->when, __entry->exec_time)
129);
130
131/*
132 * Tracing jobs completion
133 */
134TRACE_EVENT(litmus_task_completion,
135
136 TP_PROTO(struct task_struct *t, unsigned long forced),
137
138 TP_ARGS(t, forced),
139
140 TP_STRUCT__entry(
141 __field( pid_t, pid )
142 __field( unsigned int, job )
143 __field( lt_t, when )
144 __field( unsigned long, forced )
145 ),
146
147 TP_fast_assign(
148 __entry->pid = t ? t->pid : 0;
149 __entry->job = t ? t->rt_param.job_params.job_no : 0;
150 __entry->when = litmus_clock();
151 __entry->forced = forced;
152 ),
153
154 TP_printk("completed(job(%u, %u)): %Lu (forced: %lu)\n",
155 __entry->pid, __entry->job,
156 __entry->when, __entry->forced)
157);
158
159/*
160 * Trace blocking tasks.
161 */
162TRACE_EVENT(litmus_task_block,
163
164 TP_PROTO(struct task_struct *t),
165
166 TP_ARGS(t),
167
168 TP_STRUCT__entry(
169 __field( pid_t, pid )
170 __field( lt_t, when )
171 ),
172
173 TP_fast_assign(
174 __entry->pid = t ? t->pid : 0;
175 __entry->when = litmus_clock();
176 ),
177
178 TP_printk("(%u) blocks: %Lu\n", __entry->pid, __entry->when)
179);
180
181/*
182 * Tracing jobs resume
183 */
184TRACE_EVENT(litmus_task_resume,
185
186 TP_PROTO(struct task_struct *t),
187
188 TP_ARGS(t),
189
190 TP_STRUCT__entry(
191 __field( pid_t, pid )
192 __field( unsigned int, job )
193 __field( lt_t, when )
194 ),
195
196 TP_fast_assign(
197 __entry->pid = t ? t->pid : 0;
198 __entry->job = t ? t->rt_param.job_params.job_no : 0;
199 __entry->when = litmus_clock();
200 ),
201
202 TP_printk("resume(job(%u, %u)): %Lu\n",
203 __entry->pid, __entry->job, __entry->when)
204);
205
206/*
207 * Trace synchronous release
208 */
209TRACE_EVENT(litmus_sys_release,
210
211 TP_PROTO(lt_t *start),
212
213 TP_ARGS(start),
214
215 TP_STRUCT__entry(
216 __field( lt_t, rel )
217 __field( lt_t, when )
218 ),
219
220 TP_fast_assign(
221 __entry->rel = *start;
222 __entry->when = litmus_clock();
223 ),
224
225 TP_printk("SynRelease(%Lu) at %Lu\n", __entry->rel, __entry->when)
226);
227
228#endif /* _SCHED_TASK_TRACEPOINT_H */
229
230/* Must stay outside the protection */
231#include <trace/define_trace.h>
diff --git a/litmus/Kconfig b/litmus/Kconfig
index 19211ac1320a..5408ef6b159b 100644
--- a/litmus/Kconfig
+++ b/litmus/Kconfig
@@ -58,6 +58,24 @@ config SCHED_TASK_TRACE_SHIFT
58 10 => 1k events 58 10 => 1k events
59 8 => 512 events 59 8 => 512 events
60 60
61config SCHED_LITMUS_TRACEPOINT
62 bool "Enable Event/Tracepoint Tracing for real-time task tracing"
63 depends on TRACEPOINTS
64 default n
65 help
66 Enable kernel-style events (tracepoint) for Litmus. Litmus events
67 trace the same functions as the above sched_trace_XXX(), but can
68 be enabled independently.
69 Litmus tracepoints can be recorded and analyzed together (single
70 time reference) with all other kernel tracing events (e.g.,
71 sched:sched_switch, etc.).
72
73 This also enables a quick way to visualize schedule traces using
74 trace-cmd utility and kernelshark visualizer.
75
76 Say Yes for debugging and visualization purposes.
77 Say No for overhead tracing.
78
61config SCHED_OVERHEAD_TRACE 79config SCHED_OVERHEAD_TRACE
62 bool "Record timestamps for overhead measurements" 80 bool "Record timestamps for overhead measurements"
63 depends on FEATHER_TRACE 81 depends on FEATHER_TRACE
diff --git a/litmus/sched_task_trace.c b/litmus/sched_task_trace.c
index cfbc1c77383a..f89d08405944 100644
--- a/litmus/sched_task_trace.c
+++ b/litmus/sched_task_trace.c
@@ -15,6 +15,10 @@
15#include <litmus/feather_trace.h> 15#include <litmus/feather_trace.h>
16#include <litmus/ftdev.h> 16#include <litmus/ftdev.h>
17 17
18#ifdef CONFIG_SCHED_LITMUS_TRACEPOINT
19#define CREATE_TRACE_POINTS
20#include <trace/events/litmus.h>
21#endif
18 22
19#define NO_EVENTS (1 << CONFIG_SCHED_TASK_TRACE_SHIFT) 23#define NO_EVENTS (1 << CONFIG_SCHED_TASK_TRACE_SHIFT)
20 24