Loading...
1#undef TRACE_SYSTEM
2#define TRACE_SYSTEM timer
3
4#if !defined(_TRACE_TIMER_H) || defined(TRACE_HEADER_MULTI_READ)
5#define _TRACE_TIMER_H
6
7#include <linux/tracepoint.h>
8#include <linux/hrtimer.h>
9#include <linux/timer.h>
10
11DECLARE_EVENT_CLASS(timer_class,
12
13 TP_PROTO(struct timer_list *timer),
14
15 TP_ARGS(timer),
16
17 TP_STRUCT__entry(
18 __field( void *, timer )
19 ),
20
21 TP_fast_assign(
22 __entry->timer = timer;
23 ),
24
25 TP_printk("timer=%p", __entry->timer)
26);
27
28/**
29 * timer_init - called when the timer is initialized
30 * @timer: pointer to struct timer_list
31 */
32DEFINE_EVENT(timer_class, timer_init,
33
34 TP_PROTO(struct timer_list *timer),
35
36 TP_ARGS(timer)
37);
38
39/**
40 * timer_start - called when the timer is started
41 * @timer: pointer to struct timer_list
42 * @expires: the timers expiry time
43 */
44TRACE_EVENT(timer_start,
45
46 TP_PROTO(struct timer_list *timer, unsigned long expires),
47
48 TP_ARGS(timer, expires),
49
50 TP_STRUCT__entry(
51 __field( void *, timer )
52 __field( void *, function )
53 __field( unsigned long, expires )
54 __field( unsigned long, now )
55 ),
56
57 TP_fast_assign(
58 __entry->timer = timer;
59 __entry->function = timer->function;
60 __entry->expires = expires;
61 __entry->now = jiffies;
62 ),
63
64 TP_printk("timer=%p function=%pf expires=%lu [timeout=%ld]",
65 __entry->timer, __entry->function, __entry->expires,
66 (long)__entry->expires - __entry->now)
67);
68
69/**
70 * timer_expire_entry - called immediately before the timer callback
71 * @timer: pointer to struct timer_list
72 *
73 * Allows to determine the timer latency.
74 */
75TRACE_EVENT(timer_expire_entry,
76
77 TP_PROTO(struct timer_list *timer),
78
79 TP_ARGS(timer),
80
81 TP_STRUCT__entry(
82 __field( void *, timer )
83 __field( unsigned long, now )
84 __field( void *, function)
85 ),
86
87 TP_fast_assign(
88 __entry->timer = timer;
89 __entry->now = jiffies;
90 __entry->function = timer->function;
91 ),
92
93 TP_printk("timer=%p function=%pf now=%lu", __entry->timer, __entry->function,__entry->now)
94);
95
96/**
97 * timer_expire_exit - called immediately after the timer callback returns
98 * @timer: pointer to struct timer_list
99 *
100 * When used in combination with the timer_expire_entry tracepoint we can
101 * determine the runtime of the timer callback function.
102 *
103 * NOTE: Do NOT derefernce timer in TP_fast_assign. The pointer might
104 * be invalid. We solely track the pointer.
105 */
106DEFINE_EVENT(timer_class, timer_expire_exit,
107
108 TP_PROTO(struct timer_list *timer),
109
110 TP_ARGS(timer)
111);
112
113/**
114 * timer_cancel - called when the timer is canceled
115 * @timer: pointer to struct timer_list
116 */
117DEFINE_EVENT(timer_class, timer_cancel,
118
119 TP_PROTO(struct timer_list *timer),
120
121 TP_ARGS(timer)
122);
123
124/**
125 * hrtimer_init - called when the hrtimer is initialized
126 * @timer: pointer to struct hrtimer
127 * @clockid: the hrtimers clock
128 * @mode: the hrtimers mode
129 */
130TRACE_EVENT(hrtimer_init,
131
132 TP_PROTO(struct hrtimer *hrtimer, clockid_t clockid,
133 enum hrtimer_mode mode),
134
135 TP_ARGS(hrtimer, clockid, mode),
136
137 TP_STRUCT__entry(
138 __field( void *, hrtimer )
139 __field( clockid_t, clockid )
140 __field( enum hrtimer_mode, mode )
141 ),
142
143 TP_fast_assign(
144 __entry->hrtimer = hrtimer;
145 __entry->clockid = clockid;
146 __entry->mode = mode;
147 ),
148
149 TP_printk("hrtimer=%p clockid=%s mode=%s", __entry->hrtimer,
150 __entry->clockid == CLOCK_REALTIME ?
151 "CLOCK_REALTIME" : "CLOCK_MONOTONIC",
152 __entry->mode == HRTIMER_MODE_ABS ?
153 "HRTIMER_MODE_ABS" : "HRTIMER_MODE_REL")
154);
155
156/**
157 * hrtimer_start - called when the hrtimer is started
158 * @timer: pointer to struct hrtimer
159 */
160TRACE_EVENT(hrtimer_start,
161
162 TP_PROTO(struct hrtimer *hrtimer),
163
164 TP_ARGS(hrtimer),
165
166 TP_STRUCT__entry(
167 __field( void *, hrtimer )
168 __field( void *, function )
169 __field( s64, expires )
170 __field( s64, softexpires )
171 ),
172
173 TP_fast_assign(
174 __entry->hrtimer = hrtimer;
175 __entry->function = hrtimer->function;
176 __entry->expires = hrtimer_get_expires(hrtimer).tv64;
177 __entry->softexpires = hrtimer_get_softexpires(hrtimer).tv64;
178 ),
179
180 TP_printk("hrtimer=%p function=%pf expires=%llu softexpires=%llu",
181 __entry->hrtimer, __entry->function,
182 (unsigned long long)ktime_to_ns((ktime_t) {
183 .tv64 = __entry->expires }),
184 (unsigned long long)ktime_to_ns((ktime_t) {
185 .tv64 = __entry->softexpires }))
186);
187
188/**
189 * htimmer_expire_entry - called immediately before the hrtimer callback
190 * @timer: pointer to struct hrtimer
191 * @now: pointer to variable which contains current time of the
192 * timers base.
193 *
194 * Allows to determine the timer latency.
195 */
196TRACE_EVENT(hrtimer_expire_entry,
197
198 TP_PROTO(struct hrtimer *hrtimer, ktime_t *now),
199
200 TP_ARGS(hrtimer, now),
201
202 TP_STRUCT__entry(
203 __field( void *, hrtimer )
204 __field( s64, now )
205 __field( void *, function)
206 ),
207
208 TP_fast_assign(
209 __entry->hrtimer = hrtimer;
210 __entry->now = now->tv64;
211 __entry->function = hrtimer->function;
212 ),
213
214 TP_printk("hrtimer=%p function=%pf now=%llu", __entry->hrtimer, __entry->function,
215 (unsigned long long)ktime_to_ns((ktime_t) { .tv64 = __entry->now }))
216 );
217
218DECLARE_EVENT_CLASS(hrtimer_class,
219
220 TP_PROTO(struct hrtimer *hrtimer),
221
222 TP_ARGS(hrtimer),
223
224 TP_STRUCT__entry(
225 __field( void *, hrtimer )
226 ),
227
228 TP_fast_assign(
229 __entry->hrtimer = hrtimer;
230 ),
231
232 TP_printk("hrtimer=%p", __entry->hrtimer)
233);
234
235/**
236 * hrtimer_expire_exit - called immediately after the hrtimer callback returns
237 * @timer: pointer to struct hrtimer
238 *
239 * When used in combination with the hrtimer_expire_entry tracepoint we can
240 * determine the runtime of the callback function.
241 */
242DEFINE_EVENT(hrtimer_class, hrtimer_expire_exit,
243
244 TP_PROTO(struct hrtimer *hrtimer),
245
246 TP_ARGS(hrtimer)
247);
248
249/**
250 * hrtimer_cancel - called when the hrtimer is canceled
251 * @hrtimer: pointer to struct hrtimer
252 */
253DEFINE_EVENT(hrtimer_class, hrtimer_cancel,
254
255 TP_PROTO(struct hrtimer *hrtimer),
256
257 TP_ARGS(hrtimer)
258);
259
260/**
261 * itimer_state - called when itimer is started or canceled
262 * @which: name of the interval timer
263 * @value: the itimers value, itimer is canceled if value->it_value is
264 * zero, otherwise it is started
265 * @expires: the itimers expiry time
266 */
267TRACE_EVENT(itimer_state,
268
269 TP_PROTO(int which, const struct itimerval *const value,
270 cputime_t expires),
271
272 TP_ARGS(which, value, expires),
273
274 TP_STRUCT__entry(
275 __field( int, which )
276 __field( cputime_t, expires )
277 __field( long, value_sec )
278 __field( long, value_usec )
279 __field( long, interval_sec )
280 __field( long, interval_usec )
281 ),
282
283 TP_fast_assign(
284 __entry->which = which;
285 __entry->expires = expires;
286 __entry->value_sec = value->it_value.tv_sec;
287 __entry->value_usec = value->it_value.tv_usec;
288 __entry->interval_sec = value->it_interval.tv_sec;
289 __entry->interval_usec = value->it_interval.tv_usec;
290 ),
291
292 TP_printk("which=%d expires=%llu it_value=%ld.%ld it_interval=%ld.%ld",
293 __entry->which, (unsigned long long)__entry->expires,
294 __entry->value_sec, __entry->value_usec,
295 __entry->interval_sec, __entry->interval_usec)
296);
297
298/**
299 * itimer_expire - called when itimer expires
300 * @which: type of the interval timer
301 * @pid: pid of the process which owns the timer
302 * @now: current time, used to calculate the latency of itimer
303 */
304TRACE_EVENT(itimer_expire,
305
306 TP_PROTO(int which, struct pid *pid, cputime_t now),
307
308 TP_ARGS(which, pid, now),
309
310 TP_STRUCT__entry(
311 __field( int , which )
312 __field( pid_t, pid )
313 __field( cputime_t, now )
314 ),
315
316 TP_fast_assign(
317 __entry->which = which;
318 __entry->now = now;
319 __entry->pid = pid_nr(pid);
320 ),
321
322 TP_printk("which=%d pid=%d now=%llu", __entry->which,
323 (int) __entry->pid, (unsigned long long)__entry->now)
324);
325
326#endif /* _TRACE_TIMER_H */
327
328/* This part must be outside protection */
329#include <trace/define_trace.h>
1/* SPDX-License-Identifier: GPL-2.0 */
2#undef TRACE_SYSTEM
3#define TRACE_SYSTEM timer
4
5#if !defined(_TRACE_TIMER_H) || defined(TRACE_HEADER_MULTI_READ)
6#define _TRACE_TIMER_H
7
8#include <linux/tracepoint.h>
9#include <linux/hrtimer.h>
10#include <linux/timer.h>
11
12DECLARE_EVENT_CLASS(timer_class,
13
14 TP_PROTO(struct timer_list *timer),
15
16 TP_ARGS(timer),
17
18 TP_STRUCT__entry(
19 __field( void *, timer )
20 ),
21
22 TP_fast_assign(
23 __entry->timer = timer;
24 ),
25
26 TP_printk("timer=%p", __entry->timer)
27);
28
29/**
30 * timer_init - called when the timer is initialized
31 * @timer: pointer to struct timer_list
32 */
33DEFINE_EVENT(timer_class, timer_init,
34
35 TP_PROTO(struct timer_list *timer),
36
37 TP_ARGS(timer)
38);
39
40#define decode_timer_flags(flags) \
41 __print_flags(flags, "|", \
42 { TIMER_MIGRATING, "M" }, \
43 { TIMER_DEFERRABLE, "D" }, \
44 { TIMER_PINNED, "P" }, \
45 { TIMER_IRQSAFE, "I" })
46
47/**
48 * timer_start - called when the timer is started
49 * @timer: pointer to struct timer_list
50 * @expires: the timers expiry time
51 * @flags: the timers flags
52 */
53TRACE_EVENT(timer_start,
54
55 TP_PROTO(struct timer_list *timer,
56 unsigned long expires,
57 unsigned int flags),
58
59 TP_ARGS(timer, expires, flags),
60
61 TP_STRUCT__entry(
62 __field( void *, timer )
63 __field( void *, function )
64 __field( unsigned long, expires )
65 __field( unsigned long, now )
66 __field( unsigned int, flags )
67 ),
68
69 TP_fast_assign(
70 __entry->timer = timer;
71 __entry->function = timer->function;
72 __entry->expires = expires;
73 __entry->now = jiffies;
74 __entry->flags = flags;
75 ),
76
77 TP_printk("timer=%p function=%ps expires=%lu [timeout=%ld] cpu=%u idx=%u flags=%s",
78 __entry->timer, __entry->function, __entry->expires,
79 (long)__entry->expires - __entry->now,
80 __entry->flags & TIMER_CPUMASK,
81 __entry->flags >> TIMER_ARRAYSHIFT,
82 decode_timer_flags(__entry->flags & TIMER_TRACE_FLAGMASK))
83);
84
85/**
86 * timer_expire_entry - called immediately before the timer callback
87 * @timer: pointer to struct timer_list
88 * @baseclk: value of timer_base::clk when timer expires
89 *
90 * Allows to determine the timer latency.
91 */
92TRACE_EVENT(timer_expire_entry,
93
94 TP_PROTO(struct timer_list *timer, unsigned long baseclk),
95
96 TP_ARGS(timer, baseclk),
97
98 TP_STRUCT__entry(
99 __field( void *, timer )
100 __field( unsigned long, now )
101 __field( void *, function)
102 __field( unsigned long, baseclk )
103 ),
104
105 TP_fast_assign(
106 __entry->timer = timer;
107 __entry->now = jiffies;
108 __entry->function = timer->function;
109 __entry->baseclk = baseclk;
110 ),
111
112 TP_printk("timer=%p function=%ps now=%lu baseclk=%lu",
113 __entry->timer, __entry->function, __entry->now,
114 __entry->baseclk)
115);
116
117/**
118 * timer_expire_exit - called immediately after the timer callback returns
119 * @timer: pointer to struct timer_list
120 *
121 * When used in combination with the timer_expire_entry tracepoint we can
122 * determine the runtime of the timer callback function.
123 *
124 * NOTE: Do NOT dereference timer in TP_fast_assign. The pointer might
125 * be invalid. We solely track the pointer.
126 */
127DEFINE_EVENT(timer_class, timer_expire_exit,
128
129 TP_PROTO(struct timer_list *timer),
130
131 TP_ARGS(timer)
132);
133
134/**
135 * timer_cancel - called when the timer is canceled
136 * @timer: pointer to struct timer_list
137 */
138DEFINE_EVENT(timer_class, timer_cancel,
139
140 TP_PROTO(struct timer_list *timer),
141
142 TP_ARGS(timer)
143);
144
145#define decode_clockid(type) \
146 __print_symbolic(type, \
147 { CLOCK_REALTIME, "CLOCK_REALTIME" }, \
148 { CLOCK_MONOTONIC, "CLOCK_MONOTONIC" }, \
149 { CLOCK_BOOTTIME, "CLOCK_BOOTTIME" }, \
150 { CLOCK_TAI, "CLOCK_TAI" })
151
152#define decode_hrtimer_mode(mode) \
153 __print_symbolic(mode, \
154 { HRTIMER_MODE_ABS, "ABS" }, \
155 { HRTIMER_MODE_REL, "REL" }, \
156 { HRTIMER_MODE_ABS_PINNED, "ABS|PINNED" }, \
157 { HRTIMER_MODE_REL_PINNED, "REL|PINNED" }, \
158 { HRTIMER_MODE_ABS_SOFT, "ABS|SOFT" }, \
159 { HRTIMER_MODE_REL_SOFT, "REL|SOFT" }, \
160 { HRTIMER_MODE_ABS_PINNED_SOFT, "ABS|PINNED|SOFT" }, \
161 { HRTIMER_MODE_REL_PINNED_SOFT, "REL|PINNED|SOFT" })
162
163/**
164 * hrtimer_init - called when the hrtimer is initialized
165 * @hrtimer: pointer to struct hrtimer
166 * @clockid: the hrtimers clock
167 * @mode: the hrtimers mode
168 */
169TRACE_EVENT(hrtimer_init,
170
171 TP_PROTO(struct hrtimer *hrtimer, clockid_t clockid,
172 enum hrtimer_mode mode),
173
174 TP_ARGS(hrtimer, clockid, mode),
175
176 TP_STRUCT__entry(
177 __field( void *, hrtimer )
178 __field( clockid_t, clockid )
179 __field( enum hrtimer_mode, mode )
180 ),
181
182 TP_fast_assign(
183 __entry->hrtimer = hrtimer;
184 __entry->clockid = clockid;
185 __entry->mode = mode;
186 ),
187
188 TP_printk("hrtimer=%p clockid=%s mode=%s", __entry->hrtimer,
189 decode_clockid(__entry->clockid),
190 decode_hrtimer_mode(__entry->mode))
191);
192
193/**
194 * hrtimer_start - called when the hrtimer is started
195 * @hrtimer: pointer to struct hrtimer
196 * @mode: the hrtimers mode
197 */
198TRACE_EVENT(hrtimer_start,
199
200 TP_PROTO(struct hrtimer *hrtimer, enum hrtimer_mode mode),
201
202 TP_ARGS(hrtimer, mode),
203
204 TP_STRUCT__entry(
205 __field( void *, hrtimer )
206 __field( void *, function )
207 __field( s64, expires )
208 __field( s64, softexpires )
209 __field( enum hrtimer_mode, mode )
210 ),
211
212 TP_fast_assign(
213 __entry->hrtimer = hrtimer;
214 __entry->function = hrtimer->function;
215 __entry->expires = hrtimer_get_expires(hrtimer);
216 __entry->softexpires = hrtimer_get_softexpires(hrtimer);
217 __entry->mode = mode;
218 ),
219
220 TP_printk("hrtimer=%p function=%ps expires=%llu softexpires=%llu "
221 "mode=%s", __entry->hrtimer, __entry->function,
222 (unsigned long long) __entry->expires,
223 (unsigned long long) __entry->softexpires,
224 decode_hrtimer_mode(__entry->mode))
225);
226
227/**
228 * hrtimer_expire_entry - called immediately before the hrtimer callback
229 * @hrtimer: pointer to struct hrtimer
230 * @now: pointer to variable which contains current time of the
231 * timers base.
232 *
233 * Allows to determine the timer latency.
234 */
235TRACE_EVENT(hrtimer_expire_entry,
236
237 TP_PROTO(struct hrtimer *hrtimer, ktime_t *now),
238
239 TP_ARGS(hrtimer, now),
240
241 TP_STRUCT__entry(
242 __field( void *, hrtimer )
243 __field( s64, now )
244 __field( void *, function)
245 ),
246
247 TP_fast_assign(
248 __entry->hrtimer = hrtimer;
249 __entry->now = *now;
250 __entry->function = hrtimer->function;
251 ),
252
253 TP_printk("hrtimer=%p function=%ps now=%llu",
254 __entry->hrtimer, __entry->function,
255 (unsigned long long) __entry->now)
256);
257
258DECLARE_EVENT_CLASS(hrtimer_class,
259
260 TP_PROTO(struct hrtimer *hrtimer),
261
262 TP_ARGS(hrtimer),
263
264 TP_STRUCT__entry(
265 __field( void *, hrtimer )
266 ),
267
268 TP_fast_assign(
269 __entry->hrtimer = hrtimer;
270 ),
271
272 TP_printk("hrtimer=%p", __entry->hrtimer)
273);
274
275/**
276 * hrtimer_expire_exit - called immediately after the hrtimer callback returns
277 * @hrtimer: pointer to struct hrtimer
278 *
279 * When used in combination with the hrtimer_expire_entry tracepoint we can
280 * determine the runtime of the callback function.
281 */
282DEFINE_EVENT(hrtimer_class, hrtimer_expire_exit,
283
284 TP_PROTO(struct hrtimer *hrtimer),
285
286 TP_ARGS(hrtimer)
287);
288
289/**
290 * hrtimer_cancel - called when the hrtimer is canceled
291 * @hrtimer: pointer to struct hrtimer
292 */
293DEFINE_EVENT(hrtimer_class, hrtimer_cancel,
294
295 TP_PROTO(struct hrtimer *hrtimer),
296
297 TP_ARGS(hrtimer)
298);
299
300/**
301 * itimer_state - called when itimer is started or canceled
302 * @which: name of the interval timer
303 * @value: the itimers value, itimer is canceled if value->it_value is
304 * zero, otherwise it is started
305 * @expires: the itimers expiry time
306 */
307TRACE_EVENT(itimer_state,
308
309 TP_PROTO(int which, const struct itimerspec64 *const value,
310 unsigned long long expires),
311
312 TP_ARGS(which, value, expires),
313
314 TP_STRUCT__entry(
315 __field( int, which )
316 __field( unsigned long long, expires )
317 __field( long, value_sec )
318 __field( long, value_nsec )
319 __field( long, interval_sec )
320 __field( long, interval_nsec )
321 ),
322
323 TP_fast_assign(
324 __entry->which = which;
325 __entry->expires = expires;
326 __entry->value_sec = value->it_value.tv_sec;
327 __entry->value_nsec = value->it_value.tv_nsec;
328 __entry->interval_sec = value->it_interval.tv_sec;
329 __entry->interval_nsec = value->it_interval.tv_nsec;
330 ),
331
332 TP_printk("which=%d expires=%llu it_value=%ld.%06ld it_interval=%ld.%06ld",
333 __entry->which, __entry->expires,
334 __entry->value_sec, __entry->value_nsec / NSEC_PER_USEC,
335 __entry->interval_sec, __entry->interval_nsec / NSEC_PER_USEC)
336);
337
338/**
339 * itimer_expire - called when itimer expires
340 * @which: type of the interval timer
341 * @pid: pid of the process which owns the timer
342 * @now: current time, used to calculate the latency of itimer
343 */
344TRACE_EVENT(itimer_expire,
345
346 TP_PROTO(int which, struct pid *pid, unsigned long long now),
347
348 TP_ARGS(which, pid, now),
349
350 TP_STRUCT__entry(
351 __field( int , which )
352 __field( pid_t, pid )
353 __field( unsigned long long, now )
354 ),
355
356 TP_fast_assign(
357 __entry->which = which;
358 __entry->now = now;
359 __entry->pid = pid_nr(pid);
360 ),
361
362 TP_printk("which=%d pid=%d now=%llu", __entry->which,
363 (int) __entry->pid, __entry->now)
364);
365
366#ifdef CONFIG_NO_HZ_COMMON
367
368#define TICK_DEP_NAMES \
369 tick_dep_mask_name(NONE) \
370 tick_dep_name(POSIX_TIMER) \
371 tick_dep_name(PERF_EVENTS) \
372 tick_dep_name(SCHED) \
373 tick_dep_name(CLOCK_UNSTABLE) \
374 tick_dep_name_end(RCU)
375
376#undef tick_dep_name
377#undef tick_dep_mask_name
378#undef tick_dep_name_end
379
380/* The MASK will convert to their bits and they need to be processed too */
381#define tick_dep_name(sdep) TRACE_DEFINE_ENUM(TICK_DEP_BIT_##sdep); \
382 TRACE_DEFINE_ENUM(TICK_DEP_MASK_##sdep);
383#define tick_dep_name_end(sdep) TRACE_DEFINE_ENUM(TICK_DEP_BIT_##sdep); \
384 TRACE_DEFINE_ENUM(TICK_DEP_MASK_##sdep);
385/* NONE only has a mask defined for it */
386#define tick_dep_mask_name(sdep) TRACE_DEFINE_ENUM(TICK_DEP_MASK_##sdep);
387
388TICK_DEP_NAMES
389
390#undef tick_dep_name
391#undef tick_dep_mask_name
392#undef tick_dep_name_end
393
394#define tick_dep_name(sdep) { TICK_DEP_MASK_##sdep, #sdep },
395#define tick_dep_mask_name(sdep) { TICK_DEP_MASK_##sdep, #sdep },
396#define tick_dep_name_end(sdep) { TICK_DEP_MASK_##sdep, #sdep }
397
398#define show_tick_dep_name(val) \
399 __print_symbolic(val, TICK_DEP_NAMES)
400
401TRACE_EVENT(tick_stop,
402
403 TP_PROTO(int success, int dependency),
404
405 TP_ARGS(success, dependency),
406
407 TP_STRUCT__entry(
408 __field( int , success )
409 __field( int , dependency )
410 ),
411
412 TP_fast_assign(
413 __entry->success = success;
414 __entry->dependency = dependency;
415 ),
416
417 TP_printk("success=%d dependency=%s", __entry->success, \
418 show_tick_dep_name(__entry->dependency))
419);
420#endif
421
422#endif /* _TRACE_TIMER_H */
423
424/* This part must be outside protection */
425#include <trace/define_trace.h>