Loading...
1#undef TRACE_SYSTEM
2#define TRACE_SYSTEM gfs2
3
4#if !defined(_TRACE_GFS2_H) || defined(TRACE_HEADER_MULTI_READ)
5#define _TRACE_GFS2_H
6
7#include <linux/tracepoint.h>
8
9#include <linux/fs.h>
10#include <linux/buffer_head.h>
11#include <linux/dlmconstants.h>
12#include <linux/gfs2_ondisk.h>
13#include <linux/writeback.h>
14#include <linux/ktime.h>
15#include "incore.h"
16#include "glock.h"
17
18#define dlm_state_name(nn) { DLM_LOCK_##nn, #nn }
19#define glock_trace_name(x) __print_symbolic(x, \
20 dlm_state_name(IV), \
21 dlm_state_name(NL), \
22 dlm_state_name(CR), \
23 dlm_state_name(CW), \
24 dlm_state_name(PR), \
25 dlm_state_name(PW), \
26 dlm_state_name(EX))
27
28#define block_state_name(x) __print_symbolic(x, \
29 { GFS2_BLKST_FREE, "free" }, \
30 { GFS2_BLKST_USED, "used" }, \
31 { GFS2_BLKST_DINODE, "dinode" }, \
32 { GFS2_BLKST_UNLINKED, "unlinked" })
33
34#define show_glock_flags(flags) __print_flags(flags, "", \
35 {(1UL << GLF_LOCK), "l" }, \
36 {(1UL << GLF_DEMOTE), "D" }, \
37 {(1UL << GLF_PENDING_DEMOTE), "d" }, \
38 {(1UL << GLF_DEMOTE_IN_PROGRESS), "p" }, \
39 {(1UL << GLF_DIRTY), "y" }, \
40 {(1UL << GLF_LFLUSH), "f" }, \
41 {(1UL << GLF_INVALIDATE_IN_PROGRESS), "i" }, \
42 {(1UL << GLF_REPLY_PENDING), "r" }, \
43 {(1UL << GLF_INITIAL), "I" }, \
44 {(1UL << GLF_FROZEN), "F" }, \
45 {(1UL << GLF_QUEUED), "q" }, \
46 {(1UL << GLF_LRU), "L" }, \
47 {(1UL << GLF_OBJECT), "o" }, \
48 {(1UL << GLF_BLOCKING), "b" })
49
50#ifndef NUMPTY
51#define NUMPTY
52static inline u8 glock_trace_state(unsigned int state)
53{
54 switch(state) {
55 case LM_ST_SHARED:
56 return DLM_LOCK_PR;
57 case LM_ST_DEFERRED:
58 return DLM_LOCK_CW;
59 case LM_ST_EXCLUSIVE:
60 return DLM_LOCK_EX;
61 }
62 return DLM_LOCK_NL;
63}
64#endif
65
66/* Section 1 - Locking
67 *
68 * Objectives:
69 * Latency: Remote demote request to state change
70 * Latency: Local lock request to state change
71 * Latency: State change to lock grant
72 * Correctness: Ordering of local lock state vs. I/O requests
73 * Correctness: Responses to remote demote requests
74 */
75
76/* General glock state change (DLM lock request completes) */
77TRACE_EVENT(gfs2_glock_state_change,
78
79 TP_PROTO(const struct gfs2_glock *gl, unsigned int new_state),
80
81 TP_ARGS(gl, new_state),
82
83 TP_STRUCT__entry(
84 __field( dev_t, dev )
85 __field( u64, glnum )
86 __field( u32, gltype )
87 __field( u8, cur_state )
88 __field( u8, new_state )
89 __field( u8, dmt_state )
90 __field( u8, tgt_state )
91 __field( unsigned long, flags )
92 ),
93
94 TP_fast_assign(
95 __entry->dev = gl->gl_sbd->sd_vfs->s_dev;
96 __entry->glnum = gl->gl_name.ln_number;
97 __entry->gltype = gl->gl_name.ln_type;
98 __entry->cur_state = glock_trace_state(gl->gl_state);
99 __entry->new_state = glock_trace_state(new_state);
100 __entry->tgt_state = glock_trace_state(gl->gl_target);
101 __entry->dmt_state = glock_trace_state(gl->gl_demote_state);
102 __entry->flags = gl->gl_flags | (gl->gl_object ? (1UL<<GLF_OBJECT) : 0);
103 ),
104
105 TP_printk("%u,%u glock %d:%lld state %s to %s tgt:%s dmt:%s flags:%s",
106 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
107 (unsigned long long)__entry->glnum,
108 glock_trace_name(__entry->cur_state),
109 glock_trace_name(__entry->new_state),
110 glock_trace_name(__entry->tgt_state),
111 glock_trace_name(__entry->dmt_state),
112 show_glock_flags(__entry->flags))
113);
114
115/* State change -> unlocked, glock is being deallocated */
116TRACE_EVENT(gfs2_glock_put,
117
118 TP_PROTO(const struct gfs2_glock *gl),
119
120 TP_ARGS(gl),
121
122 TP_STRUCT__entry(
123 __field( dev_t, dev )
124 __field( u64, glnum )
125 __field( u32, gltype )
126 __field( u8, cur_state )
127 __field( unsigned long, flags )
128 ),
129
130 TP_fast_assign(
131 __entry->dev = gl->gl_sbd->sd_vfs->s_dev;
132 __entry->gltype = gl->gl_name.ln_type;
133 __entry->glnum = gl->gl_name.ln_number;
134 __entry->cur_state = glock_trace_state(gl->gl_state);
135 __entry->flags = gl->gl_flags | (gl->gl_object ? (1UL<<GLF_OBJECT) : 0);
136 ),
137
138 TP_printk("%u,%u glock %d:%lld state %s => %s flags:%s",
139 MAJOR(__entry->dev), MINOR(__entry->dev),
140 __entry->gltype, (unsigned long long)__entry->glnum,
141 glock_trace_name(__entry->cur_state),
142 glock_trace_name(DLM_LOCK_IV),
143 show_glock_flags(__entry->flags))
144
145);
146
147/* Callback (local or remote) requesting lock demotion */
148TRACE_EVENT(gfs2_demote_rq,
149
150 TP_PROTO(const struct gfs2_glock *gl),
151
152 TP_ARGS(gl),
153
154 TP_STRUCT__entry(
155 __field( dev_t, dev )
156 __field( u64, glnum )
157 __field( u32, gltype )
158 __field( u8, cur_state )
159 __field( u8, dmt_state )
160 __field( unsigned long, flags )
161 ),
162
163 TP_fast_assign(
164 __entry->dev = gl->gl_sbd->sd_vfs->s_dev;
165 __entry->gltype = gl->gl_name.ln_type;
166 __entry->glnum = gl->gl_name.ln_number;
167 __entry->cur_state = glock_trace_state(gl->gl_state);
168 __entry->dmt_state = glock_trace_state(gl->gl_demote_state);
169 __entry->flags = gl->gl_flags | (gl->gl_object ? (1UL<<GLF_OBJECT) : 0);
170 ),
171
172 TP_printk("%u,%u glock %d:%lld demote %s to %s flags:%s",
173 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
174 (unsigned long long)__entry->glnum,
175 glock_trace_name(__entry->cur_state),
176 glock_trace_name(__entry->dmt_state),
177 show_glock_flags(__entry->flags))
178
179);
180
181/* Promotion/grant of a glock */
182TRACE_EVENT(gfs2_promote,
183
184 TP_PROTO(const struct gfs2_holder *gh, int first),
185
186 TP_ARGS(gh, first),
187
188 TP_STRUCT__entry(
189 __field( dev_t, dev )
190 __field( u64, glnum )
191 __field( u32, gltype )
192 __field( int, first )
193 __field( u8, state )
194 ),
195
196 TP_fast_assign(
197 __entry->dev = gh->gh_gl->gl_sbd->sd_vfs->s_dev;
198 __entry->glnum = gh->gh_gl->gl_name.ln_number;
199 __entry->gltype = gh->gh_gl->gl_name.ln_type;
200 __entry->first = first;
201 __entry->state = glock_trace_state(gh->gh_state);
202 ),
203
204 TP_printk("%u,%u glock %u:%llu promote %s %s",
205 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
206 (unsigned long long)__entry->glnum,
207 __entry->first ? "first": "other",
208 glock_trace_name(__entry->state))
209);
210
211/* Queue/dequeue a lock request */
212TRACE_EVENT(gfs2_glock_queue,
213
214 TP_PROTO(const struct gfs2_holder *gh, int queue),
215
216 TP_ARGS(gh, queue),
217
218 TP_STRUCT__entry(
219 __field( dev_t, dev )
220 __field( u64, glnum )
221 __field( u32, gltype )
222 __field( int, queue )
223 __field( u8, state )
224 ),
225
226 TP_fast_assign(
227 __entry->dev = gh->gh_gl->gl_sbd->sd_vfs->s_dev;
228 __entry->glnum = gh->gh_gl->gl_name.ln_number;
229 __entry->gltype = gh->gh_gl->gl_name.ln_type;
230 __entry->queue = queue;
231 __entry->state = glock_trace_state(gh->gh_state);
232 ),
233
234 TP_printk("%u,%u glock %u:%llu %squeue %s",
235 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
236 (unsigned long long)__entry->glnum,
237 __entry->queue ? "" : "de",
238 glock_trace_name(__entry->state))
239);
240
241/* DLM sends a reply to GFS2 */
242TRACE_EVENT(gfs2_glock_lock_time,
243
244 TP_PROTO(const struct gfs2_glock *gl, s64 tdiff),
245
246 TP_ARGS(gl, tdiff),
247
248 TP_STRUCT__entry(
249 __field( dev_t, dev )
250 __field( u64, glnum )
251 __field( u32, gltype )
252 __field( int, status )
253 __field( char, flags )
254 __field( s64, tdiff )
255 __field( s64, srtt )
256 __field( s64, srttvar )
257 __field( s64, srttb )
258 __field( s64, srttvarb )
259 __field( s64, sirt )
260 __field( s64, sirtvar )
261 __field( s64, dcount )
262 __field( s64, qcount )
263 ),
264
265 TP_fast_assign(
266 __entry->dev = gl->gl_sbd->sd_vfs->s_dev;
267 __entry->glnum = gl->gl_name.ln_number;
268 __entry->gltype = gl->gl_name.ln_type;
269 __entry->status = gl->gl_lksb.sb_status;
270 __entry->flags = gl->gl_lksb.sb_flags;
271 __entry->tdiff = tdiff;
272 __entry->srtt = gl->gl_stats.stats[GFS2_LKS_SRTT];
273 __entry->srttvar = gl->gl_stats.stats[GFS2_LKS_SRTTVAR];
274 __entry->srttb = gl->gl_stats.stats[GFS2_LKS_SRTTB];
275 __entry->srttvarb = gl->gl_stats.stats[GFS2_LKS_SRTTVARB];
276 __entry->sirt = gl->gl_stats.stats[GFS2_LKS_SIRT];
277 __entry->sirtvar = gl->gl_stats.stats[GFS2_LKS_SIRTVAR];
278 __entry->dcount = gl->gl_stats.stats[GFS2_LKS_DCOUNT];
279 __entry->qcount = gl->gl_stats.stats[GFS2_LKS_QCOUNT];
280 ),
281
282 TP_printk("%u,%u glock %d:%lld status:%d flags:%02x tdiff:%lld srtt:%lld/%lld srttb:%lld/%lld sirt:%lld/%lld dcnt:%lld qcnt:%lld",
283 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
284 (unsigned long long)__entry->glnum,
285 __entry->status, __entry->flags,
286 (long long)__entry->tdiff,
287 (long long)__entry->srtt,
288 (long long)__entry->srttvar,
289 (long long)__entry->srttb,
290 (long long)__entry->srttvarb,
291 (long long)__entry->sirt,
292 (long long)__entry->sirtvar,
293 (long long)__entry->dcount,
294 (long long)__entry->qcount)
295);
296
297/* Section 2 - Log/journal
298 *
299 * Objectives:
300 * Latency: Log flush time
301 * Correctness: pin/unpin vs. disk I/O ordering
302 * Performance: Log usage stats
303 */
304
305/* Pin/unpin a block in the log */
306TRACE_EVENT(gfs2_pin,
307
308 TP_PROTO(const struct gfs2_bufdata *bd, int pin),
309
310 TP_ARGS(bd, pin),
311
312 TP_STRUCT__entry(
313 __field( dev_t, dev )
314 __field( int, pin )
315 __field( u32, len )
316 __field( sector_t, block )
317 __field( u64, ino )
318 ),
319
320 TP_fast_assign(
321 __entry->dev = bd->bd_gl->gl_sbd->sd_vfs->s_dev;
322 __entry->pin = pin;
323 __entry->len = bd->bd_bh->b_size;
324 __entry->block = bd->bd_bh->b_blocknr;
325 __entry->ino = bd->bd_gl->gl_name.ln_number;
326 ),
327
328 TP_printk("%u,%u log %s %llu/%lu inode %llu",
329 MAJOR(__entry->dev), MINOR(__entry->dev),
330 __entry->pin ? "pin" : "unpin",
331 (unsigned long long)__entry->block,
332 (unsigned long)__entry->len,
333 (unsigned long long)__entry->ino)
334);
335
336/* Flushing the log */
337TRACE_EVENT(gfs2_log_flush,
338
339 TP_PROTO(const struct gfs2_sbd *sdp, int start),
340
341 TP_ARGS(sdp, start),
342
343 TP_STRUCT__entry(
344 __field( dev_t, dev )
345 __field( int, start )
346 __field( u64, log_seq )
347 ),
348
349 TP_fast_assign(
350 __entry->dev = sdp->sd_vfs->s_dev;
351 __entry->start = start;
352 __entry->log_seq = sdp->sd_log_sequence;
353 ),
354
355 TP_printk("%u,%u log flush %s %llu",
356 MAJOR(__entry->dev), MINOR(__entry->dev),
357 __entry->start ? "start" : "end",
358 (unsigned long long)__entry->log_seq)
359);
360
361/* Reserving/releasing blocks in the log */
362TRACE_EVENT(gfs2_log_blocks,
363
364 TP_PROTO(const struct gfs2_sbd *sdp, int blocks),
365
366 TP_ARGS(sdp, blocks),
367
368 TP_STRUCT__entry(
369 __field( dev_t, dev )
370 __field( int, blocks )
371 ),
372
373 TP_fast_assign(
374 __entry->dev = sdp->sd_vfs->s_dev;
375 __entry->blocks = blocks;
376 ),
377
378 TP_printk("%u,%u log reserve %d", MAJOR(__entry->dev),
379 MINOR(__entry->dev), __entry->blocks)
380);
381
382/* Writing back the AIL */
383TRACE_EVENT(gfs2_ail_flush,
384
385 TP_PROTO(const struct gfs2_sbd *sdp, const struct writeback_control *wbc, int start),
386
387 TP_ARGS(sdp, wbc, start),
388
389 TP_STRUCT__entry(
390 __field( dev_t, dev )
391 __field( int, start )
392 __field( int, sync_mode )
393 __field( long, nr_to_write )
394 ),
395
396 TP_fast_assign(
397 __entry->dev = sdp->sd_vfs->s_dev;
398 __entry->start = start;
399 __entry->sync_mode = wbc->sync_mode;
400 __entry->nr_to_write = wbc->nr_to_write;
401 ),
402
403 TP_printk("%u,%u ail flush %s %s %ld", MAJOR(__entry->dev),
404 MINOR(__entry->dev), __entry->start ? "start" : "end",
405 __entry->sync_mode == WB_SYNC_ALL ? "all" : "none",
406 __entry->nr_to_write)
407);
408
409/* Section 3 - bmap
410 *
411 * Objectives:
412 * Latency: Bmap request time
413 * Performance: Block allocator tracing
414 * Correctness: Test of disard generation vs. blocks allocated
415 */
416
417/* Map an extent of blocks, possibly a new allocation */
418TRACE_EVENT(gfs2_bmap,
419
420 TP_PROTO(const struct gfs2_inode *ip, const struct buffer_head *bh,
421 sector_t lblock, int create, int errno),
422
423 TP_ARGS(ip, bh, lblock, create, errno),
424
425 TP_STRUCT__entry(
426 __field( dev_t, dev )
427 __field( sector_t, lblock )
428 __field( sector_t, pblock )
429 __field( u64, inum )
430 __field( unsigned long, state )
431 __field( u32, len )
432 __field( int, create )
433 __field( int, errno )
434 ),
435
436 TP_fast_assign(
437 __entry->dev = ip->i_gl->gl_sbd->sd_vfs->s_dev;
438 __entry->lblock = lblock;
439 __entry->pblock = buffer_mapped(bh) ? bh->b_blocknr : 0;
440 __entry->inum = ip->i_no_addr;
441 __entry->state = bh->b_state;
442 __entry->len = bh->b_size;
443 __entry->create = create;
444 __entry->errno = errno;
445 ),
446
447 TP_printk("%u,%u bmap %llu map %llu/%lu to %llu flags:%08lx %s %d",
448 MAJOR(__entry->dev), MINOR(__entry->dev),
449 (unsigned long long)__entry->inum,
450 (unsigned long long)__entry->lblock,
451 (unsigned long)__entry->len,
452 (unsigned long long)__entry->pblock,
453 __entry->state, __entry->create ? "create " : "nocreate",
454 __entry->errno)
455);
456
457/* Keep track of blocks as they are allocated/freed */
458TRACE_EVENT(gfs2_block_alloc,
459
460 TP_PROTO(const struct gfs2_inode *ip, struct gfs2_rgrpd *rgd,
461 u64 block, unsigned len, u8 block_state),
462
463 TP_ARGS(ip, rgd, block, len, block_state),
464
465 TP_STRUCT__entry(
466 __field( dev_t, dev )
467 __field( u64, start )
468 __field( u64, inum )
469 __field( u32, len )
470 __field( u8, block_state )
471 __field( u64, rd_addr )
472 __field( u32, rd_free_clone )
473 ),
474
475 TP_fast_assign(
476 __entry->dev = ip->i_gl->gl_sbd->sd_vfs->s_dev;
477 __entry->start = block;
478 __entry->inum = ip->i_no_addr;
479 __entry->len = len;
480 __entry->block_state = block_state;
481 __entry->rd_addr = rgd->rd_addr;
482 __entry->rd_free_clone = rgd->rd_free_clone;
483 ),
484
485 TP_printk("%u,%u bmap %llu alloc %llu/%lu %s rg:%llu rf:%u",
486 MAJOR(__entry->dev), MINOR(__entry->dev),
487 (unsigned long long)__entry->inum,
488 (unsigned long long)__entry->start,
489 (unsigned long)__entry->len,
490 block_state_name(__entry->block_state),
491 (unsigned long long)__entry->rd_addr,
492 __entry->rd_free_clone)
493);
494
495#endif /* _TRACE_GFS2_H */
496
497/* This part must be outside protection */
498#undef TRACE_INCLUDE_PATH
499#define TRACE_INCLUDE_PATH .
500#define TRACE_INCLUDE_FILE trace_gfs2
501#include <trace/define_trace.h>
502
1/* SPDX-License-Identifier: GPL-2.0 */
2#undef TRACE_SYSTEM
3#define TRACE_SYSTEM gfs2
4
5#if !defined(_TRACE_GFS2_H) || defined(TRACE_HEADER_MULTI_READ)
6#define _TRACE_GFS2_H
7
8#include <linux/tracepoint.h>
9
10#include <linux/fs.h>
11#include <linux/buffer_head.h>
12#include <linux/dlmconstants.h>
13#include <linux/gfs2_ondisk.h>
14#include <linux/writeback.h>
15#include <linux/ktime.h>
16#include <linux/iomap.h>
17#include "incore.h"
18#include "glock.h"
19#include "rgrp.h"
20
21#define dlm_state_name(nn) { DLM_LOCK_##nn, #nn }
22#define glock_trace_name(x) __print_symbolic(x, \
23 dlm_state_name(IV), \
24 dlm_state_name(NL), \
25 dlm_state_name(CR), \
26 dlm_state_name(CW), \
27 dlm_state_name(PR), \
28 dlm_state_name(PW), \
29 dlm_state_name(EX))
30
31#define block_state_name(x) __print_symbolic(x, \
32 { GFS2_BLKST_FREE, "free" }, \
33 { GFS2_BLKST_USED, "used" }, \
34 { GFS2_BLKST_DINODE, "dinode" }, \
35 { GFS2_BLKST_UNLINKED, "unlinked" })
36
37#define TRACE_RS_DELETE 0
38#define TRACE_RS_TREEDEL 1
39#define TRACE_RS_INSERT 2
40#define TRACE_RS_CLAIM 3
41
42#define rs_func_name(x) __print_symbolic(x, \
43 { 0, "del " }, \
44 { 1, "tdel" }, \
45 { 2, "ins " }, \
46 { 3, "clm " })
47
48#define show_glock_flags(flags) __print_flags(flags, "", \
49 {(1UL << GLF_LOCK), "l" }, \
50 {(1UL << GLF_DEMOTE), "D" }, \
51 {(1UL << GLF_PENDING_DEMOTE), "d" }, \
52 {(1UL << GLF_DEMOTE_IN_PROGRESS), "p" }, \
53 {(1UL << GLF_DIRTY), "y" }, \
54 {(1UL << GLF_LFLUSH), "f" }, \
55 {(1UL << GLF_INVALIDATE_IN_PROGRESS), "i" }, \
56 {(1UL << GLF_REPLY_PENDING), "r" }, \
57 {(1UL << GLF_INITIAL), "I" }, \
58 {(1UL << GLF_FROZEN), "F" }, \
59 {(1UL << GLF_LRU), "L" }, \
60 {(1UL << GLF_OBJECT), "o" }, \
61 {(1UL << GLF_BLOCKING), "b" })
62
63#ifndef NUMPTY
64#define NUMPTY
65static inline u8 glock_trace_state(unsigned int state)
66{
67 switch(state) {
68 case LM_ST_SHARED:
69 return DLM_LOCK_PR;
70 case LM_ST_DEFERRED:
71 return DLM_LOCK_CW;
72 case LM_ST_EXCLUSIVE:
73 return DLM_LOCK_EX;
74 }
75 return DLM_LOCK_NL;
76}
77#endif
78
79/* Section 1 - Locking
80 *
81 * Objectives:
82 * Latency: Remote demote request to state change
83 * Latency: Local lock request to state change
84 * Latency: State change to lock grant
85 * Correctness: Ordering of local lock state vs. I/O requests
86 * Correctness: Responses to remote demote requests
87 */
88
89/* General glock state change (DLM lock request completes) */
90TRACE_EVENT(gfs2_glock_state_change,
91
92 TP_PROTO(const struct gfs2_glock *gl, unsigned int new_state),
93
94 TP_ARGS(gl, new_state),
95
96 TP_STRUCT__entry(
97 __field( dev_t, dev )
98 __field( u64, glnum )
99 __field( u32, gltype )
100 __field( u8, cur_state )
101 __field( u8, new_state )
102 __field( u8, dmt_state )
103 __field( u8, tgt_state )
104 __field( unsigned long, flags )
105 ),
106
107 TP_fast_assign(
108 __entry->dev = gl->gl_name.ln_sbd->sd_vfs->s_dev;
109 __entry->glnum = gl->gl_name.ln_number;
110 __entry->gltype = gl->gl_name.ln_type;
111 __entry->cur_state = glock_trace_state(gl->gl_state);
112 __entry->new_state = glock_trace_state(new_state);
113 __entry->tgt_state = glock_trace_state(gl->gl_target);
114 __entry->dmt_state = glock_trace_state(gl->gl_demote_state);
115 __entry->flags = gl->gl_flags | (gl->gl_object ? (1UL<<GLF_OBJECT) : 0);
116 ),
117
118 TP_printk("%u,%u glock %d:%lld state %s to %s tgt:%s dmt:%s flags:%s",
119 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
120 (unsigned long long)__entry->glnum,
121 glock_trace_name(__entry->cur_state),
122 glock_trace_name(__entry->new_state),
123 glock_trace_name(__entry->tgt_state),
124 glock_trace_name(__entry->dmt_state),
125 show_glock_flags(__entry->flags))
126);
127
128/* State change -> unlocked, glock is being deallocated */
129TRACE_EVENT(gfs2_glock_put,
130
131 TP_PROTO(const struct gfs2_glock *gl),
132
133 TP_ARGS(gl),
134
135 TP_STRUCT__entry(
136 __field( dev_t, dev )
137 __field( u64, glnum )
138 __field( u32, gltype )
139 __field( u8, cur_state )
140 __field( unsigned long, flags )
141 ),
142
143 TP_fast_assign(
144 __entry->dev = gl->gl_name.ln_sbd->sd_vfs->s_dev;
145 __entry->gltype = gl->gl_name.ln_type;
146 __entry->glnum = gl->gl_name.ln_number;
147 __entry->cur_state = glock_trace_state(gl->gl_state);
148 __entry->flags = gl->gl_flags | (gl->gl_object ? (1UL<<GLF_OBJECT) : 0);
149 ),
150
151 TP_printk("%u,%u glock %d:%lld state %s => %s flags:%s",
152 MAJOR(__entry->dev), MINOR(__entry->dev),
153 __entry->gltype, (unsigned long long)__entry->glnum,
154 glock_trace_name(__entry->cur_state),
155 glock_trace_name(DLM_LOCK_IV),
156 show_glock_flags(__entry->flags))
157
158);
159
160/* Callback (local or remote) requesting lock demotion */
161TRACE_EVENT(gfs2_demote_rq,
162
163 TP_PROTO(const struct gfs2_glock *gl, bool remote),
164
165 TP_ARGS(gl, remote),
166
167 TP_STRUCT__entry(
168 __field( dev_t, dev )
169 __field( u64, glnum )
170 __field( u32, gltype )
171 __field( u8, cur_state )
172 __field( u8, dmt_state )
173 __field( unsigned long, flags )
174 __field( bool, remote )
175 ),
176
177 TP_fast_assign(
178 __entry->dev = gl->gl_name.ln_sbd->sd_vfs->s_dev;
179 __entry->gltype = gl->gl_name.ln_type;
180 __entry->glnum = gl->gl_name.ln_number;
181 __entry->cur_state = glock_trace_state(gl->gl_state);
182 __entry->dmt_state = glock_trace_state(gl->gl_demote_state);
183 __entry->flags = gl->gl_flags | (gl->gl_object ? (1UL<<GLF_OBJECT) : 0);
184 __entry->remote = remote;
185 ),
186
187 TP_printk("%u,%u glock %d:%lld demote %s to %s flags:%s %s",
188 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
189 (unsigned long long)__entry->glnum,
190 glock_trace_name(__entry->cur_state),
191 glock_trace_name(__entry->dmt_state),
192 show_glock_flags(__entry->flags),
193 __entry->remote ? "remote" : "local")
194
195);
196
197/* Promotion/grant of a glock */
198TRACE_EVENT(gfs2_promote,
199
200 TP_PROTO(const struct gfs2_holder *gh),
201
202 TP_ARGS(gh),
203
204 TP_STRUCT__entry(
205 __field( dev_t, dev )
206 __field( u64, glnum )
207 __field( u32, gltype )
208 __field( u8, state )
209 ),
210
211 TP_fast_assign(
212 __entry->dev = gh->gh_gl->gl_name.ln_sbd->sd_vfs->s_dev;
213 __entry->glnum = gh->gh_gl->gl_name.ln_number;
214 __entry->gltype = gh->gh_gl->gl_name.ln_type;
215 __entry->state = glock_trace_state(gh->gh_state);
216 ),
217
218 TP_printk("%u,%u glock %u:%llu promote %s",
219 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
220 (unsigned long long)__entry->glnum,
221 glock_trace_name(__entry->state))
222);
223
224/* Queue/dequeue a lock request */
225TRACE_EVENT(gfs2_glock_queue,
226
227 TP_PROTO(const struct gfs2_holder *gh, int queue),
228
229 TP_ARGS(gh, queue),
230
231 TP_STRUCT__entry(
232 __field( dev_t, dev )
233 __field( u64, glnum )
234 __field( u32, gltype )
235 __field( int, queue )
236 __field( u8, state )
237 ),
238
239 TP_fast_assign(
240 __entry->dev = gh->gh_gl->gl_name.ln_sbd->sd_vfs->s_dev;
241 __entry->glnum = gh->gh_gl->gl_name.ln_number;
242 __entry->gltype = gh->gh_gl->gl_name.ln_type;
243 __entry->queue = queue;
244 __entry->state = glock_trace_state(gh->gh_state);
245 ),
246
247 TP_printk("%u,%u glock %u:%llu %squeue %s",
248 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
249 (unsigned long long)__entry->glnum,
250 __entry->queue ? "" : "de",
251 glock_trace_name(__entry->state))
252);
253
254/* DLM sends a reply to GFS2 */
255TRACE_EVENT(gfs2_glock_lock_time,
256
257 TP_PROTO(const struct gfs2_glock *gl, s64 tdiff),
258
259 TP_ARGS(gl, tdiff),
260
261 TP_STRUCT__entry(
262 __field( dev_t, dev )
263 __field( u64, glnum )
264 __field( u32, gltype )
265 __field( int, status )
266 __field( char, flags )
267 __field( s64, tdiff )
268 __field( u64, srtt )
269 __field( u64, srttvar )
270 __field( u64, srttb )
271 __field( u64, srttvarb )
272 __field( u64, sirt )
273 __field( u64, sirtvar )
274 __field( u64, dcount )
275 __field( u64, qcount )
276 ),
277
278 TP_fast_assign(
279 __entry->dev = gl->gl_name.ln_sbd->sd_vfs->s_dev;
280 __entry->glnum = gl->gl_name.ln_number;
281 __entry->gltype = gl->gl_name.ln_type;
282 __entry->status = gl->gl_lksb.sb_status;
283 __entry->flags = gl->gl_lksb.sb_flags;
284 __entry->tdiff = tdiff;
285 __entry->srtt = gl->gl_stats.stats[GFS2_LKS_SRTT];
286 __entry->srttvar = gl->gl_stats.stats[GFS2_LKS_SRTTVAR];
287 __entry->srttb = gl->gl_stats.stats[GFS2_LKS_SRTTB];
288 __entry->srttvarb = gl->gl_stats.stats[GFS2_LKS_SRTTVARB];
289 __entry->sirt = gl->gl_stats.stats[GFS2_LKS_SIRT];
290 __entry->sirtvar = gl->gl_stats.stats[GFS2_LKS_SIRTVAR];
291 __entry->dcount = gl->gl_stats.stats[GFS2_LKS_DCOUNT];
292 __entry->qcount = gl->gl_stats.stats[GFS2_LKS_QCOUNT];
293 ),
294
295 TP_printk("%u,%u glock %d:%lld status:%d flags:%02x tdiff:%lld srtt:%lld/%lld srttb:%lld/%lld sirt:%lld/%lld dcnt:%lld qcnt:%lld",
296 MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
297 (unsigned long long)__entry->glnum,
298 __entry->status, __entry->flags,
299 (long long)__entry->tdiff,
300 (long long)__entry->srtt,
301 (long long)__entry->srttvar,
302 (long long)__entry->srttb,
303 (long long)__entry->srttvarb,
304 (long long)__entry->sirt,
305 (long long)__entry->sirtvar,
306 (long long)__entry->dcount,
307 (long long)__entry->qcount)
308);
309
310/* Section 2 - Log/journal
311 *
312 * Objectives:
313 * Latency: Log flush time
314 * Correctness: pin/unpin vs. disk I/O ordering
315 * Performance: Log usage stats
316 */
317
318/* Pin/unpin a block in the log */
319TRACE_EVENT(gfs2_pin,
320
321 TP_PROTO(const struct gfs2_bufdata *bd, int pin),
322
323 TP_ARGS(bd, pin),
324
325 TP_STRUCT__entry(
326 __field( dev_t, dev )
327 __field( int, pin )
328 __field( u32, len )
329 __field( sector_t, block )
330 __field( u64, ino )
331 ),
332
333 TP_fast_assign(
334 __entry->dev = bd->bd_gl->gl_name.ln_sbd->sd_vfs->s_dev;
335 __entry->pin = pin;
336 __entry->len = bd->bd_bh->b_size;
337 __entry->block = bd->bd_bh->b_blocknr;
338 __entry->ino = bd->bd_gl->gl_name.ln_number;
339 ),
340
341 TP_printk("%u,%u log %s %llu/%lu inode %llu",
342 MAJOR(__entry->dev), MINOR(__entry->dev),
343 __entry->pin ? "pin" : "unpin",
344 (unsigned long long)__entry->block,
345 (unsigned long)__entry->len,
346 (unsigned long long)__entry->ino)
347);
348
349/* Flushing the log */
350TRACE_EVENT(gfs2_log_flush,
351
352 TP_PROTO(const struct gfs2_sbd *sdp, int start, u32 flags),
353
354 TP_ARGS(sdp, start, flags),
355
356 TP_STRUCT__entry(
357 __field( dev_t, dev )
358 __field( int, start )
359 __field( u64, log_seq )
360 __field( u32, flags )
361 ),
362
363 TP_fast_assign(
364 __entry->dev = sdp->sd_vfs->s_dev;
365 __entry->start = start;
366 __entry->log_seq = sdp->sd_log_sequence;
367 __entry->flags = flags;
368 ),
369
370 TP_printk("%u,%u log flush %s %llu %llx",
371 MAJOR(__entry->dev), MINOR(__entry->dev),
372 __entry->start ? "start" : "end",
373 (unsigned long long)__entry->log_seq,
374 (unsigned long long)__entry->flags)
375);
376
377/* Reserving/releasing blocks in the log */
378TRACE_EVENT(gfs2_log_blocks,
379
380 TP_PROTO(const struct gfs2_sbd *sdp, int blocks),
381
382 TP_ARGS(sdp, blocks),
383
384 TP_STRUCT__entry(
385 __field( dev_t, dev )
386 __field( int, blocks )
387 __field( int, blks_free )
388 ),
389
390 TP_fast_assign(
391 __entry->dev = sdp->sd_vfs->s_dev;
392 __entry->blocks = blocks;
393 __entry->blks_free = atomic_read(&sdp->sd_log_blks_free);
394 ),
395
396 TP_printk("%u,%u log reserve %d %d", MAJOR(__entry->dev),
397 MINOR(__entry->dev), __entry->blocks, __entry->blks_free)
398);
399
400/* Writing back the AIL */
401TRACE_EVENT(gfs2_ail_flush,
402
403 TP_PROTO(const struct gfs2_sbd *sdp, const struct writeback_control *wbc, int start),
404
405 TP_ARGS(sdp, wbc, start),
406
407 TP_STRUCT__entry(
408 __field( dev_t, dev )
409 __field( int, start )
410 __field( int, sync_mode )
411 __field( long, nr_to_write )
412 ),
413
414 TP_fast_assign(
415 __entry->dev = sdp->sd_vfs->s_dev;
416 __entry->start = start;
417 __entry->sync_mode = wbc->sync_mode;
418 __entry->nr_to_write = wbc->nr_to_write;
419 ),
420
421 TP_printk("%u,%u ail flush %s %s %ld", MAJOR(__entry->dev),
422 MINOR(__entry->dev), __entry->start ? "start" : "end",
423 __entry->sync_mode == WB_SYNC_ALL ? "all" : "none",
424 __entry->nr_to_write)
425);
426
427/* Section 3 - bmap
428 *
429 * Objectives:
430 * Latency: Bmap request time
431 * Performance: Block allocator tracing
432 * Correctness: Test of disard generation vs. blocks allocated
433 */
434
435/* Map an extent of blocks, possibly a new allocation */
436TRACE_EVENT(gfs2_bmap,
437
438 TP_PROTO(const struct gfs2_inode *ip, const struct buffer_head *bh,
439 sector_t lblock, int create, int errno),
440
441 TP_ARGS(ip, bh, lblock, create, errno),
442
443 TP_STRUCT__entry(
444 __field( dev_t, dev )
445 __field( sector_t, lblock )
446 __field( sector_t, pblock )
447 __field( u64, inum )
448 __field( unsigned long, state )
449 __field( u32, len )
450 __field( int, create )
451 __field( int, errno )
452 ),
453
454 TP_fast_assign(
455 __entry->dev = ip->i_gl->gl_name.ln_sbd->sd_vfs->s_dev;
456 __entry->lblock = lblock;
457 __entry->pblock = buffer_mapped(bh) ? bh->b_blocknr : 0;
458 __entry->inum = ip->i_no_addr;
459 __entry->state = bh->b_state;
460 __entry->len = bh->b_size;
461 __entry->create = create;
462 __entry->errno = errno;
463 ),
464
465 TP_printk("%u,%u bmap %llu map %llu/%lu to %llu flags:%08lx %s %d",
466 MAJOR(__entry->dev), MINOR(__entry->dev),
467 (unsigned long long)__entry->inum,
468 (unsigned long long)__entry->lblock,
469 (unsigned long)__entry->len,
470 (unsigned long long)__entry->pblock,
471 __entry->state, __entry->create ? "create " : "nocreate",
472 __entry->errno)
473);
474
475TRACE_EVENT(gfs2_iomap_start,
476
477 TP_PROTO(const struct gfs2_inode *ip, loff_t pos, ssize_t length,
478 u16 flags),
479
480 TP_ARGS(ip, pos, length, flags),
481
482 TP_STRUCT__entry(
483 __field( dev_t, dev )
484 __field( u64, inum )
485 __field( loff_t, pos )
486 __field( ssize_t, length )
487 __field( u16, flags )
488 ),
489
490 TP_fast_assign(
491 __entry->dev = ip->i_gl->gl_name.ln_sbd->sd_vfs->s_dev;
492 __entry->inum = ip->i_no_addr;
493 __entry->pos = pos;
494 __entry->length = length;
495 __entry->flags = flags;
496 ),
497
498 TP_printk("%u,%u bmap %llu iomap start %llu/%lu flags:%08x",
499 MAJOR(__entry->dev), MINOR(__entry->dev),
500 (unsigned long long)__entry->inum,
501 (unsigned long long)__entry->pos,
502 (unsigned long)__entry->length, (u16)__entry->flags)
503);
504
505TRACE_EVENT(gfs2_iomap_end,
506
507 TP_PROTO(const struct gfs2_inode *ip, struct iomap *iomap, int ret),
508
509 TP_ARGS(ip, iomap, ret),
510
511 TP_STRUCT__entry(
512 __field( dev_t, dev )
513 __field( u64, inum )
514 __field( loff_t, offset )
515 __field( ssize_t, length )
516 __field( sector_t, pblock )
517 __field( u16, flags )
518 __field( u16, type )
519 __field( int, ret )
520 ),
521
522 TP_fast_assign(
523 __entry->dev = ip->i_gl->gl_name.ln_sbd->sd_vfs->s_dev;
524 __entry->inum = ip->i_no_addr;
525 __entry->offset = iomap->offset;
526 __entry->length = iomap->length;
527 __entry->pblock = iomap->addr == IOMAP_NULL_ADDR ? 0 :
528 (iomap->addr >> ip->i_inode.i_blkbits);
529 __entry->flags = iomap->flags;
530 __entry->type = iomap->type;
531 __entry->ret = ret;
532 ),
533
534 TP_printk("%u,%u bmap %llu iomap end %llu/%lu to %llu ty:%d flags:%08x rc:%d",
535 MAJOR(__entry->dev), MINOR(__entry->dev),
536 (unsigned long long)__entry->inum,
537 (unsigned long long)__entry->offset,
538 (unsigned long)__entry->length,
539 (long long)__entry->pblock,
540 (u16)__entry->type,
541 (u16)__entry->flags, __entry->ret)
542);
543
544/* Keep track of blocks as they are allocated/freed */
545TRACE_EVENT(gfs2_block_alloc,
546
547 TP_PROTO(const struct gfs2_inode *ip, struct gfs2_rgrpd *rgd,
548 u64 block, unsigned len, u8 block_state),
549
550 TP_ARGS(ip, rgd, block, len, block_state),
551
552 TP_STRUCT__entry(
553 __field( dev_t, dev )
554 __field( u64, start )
555 __field( u64, inum )
556 __field( u32, len )
557 __field( u8, block_state )
558 __field( u64, rd_addr )
559 __field( u32, rd_free_clone )
560 __field( u32, rd_requested )
561 __field( u32, rd_reserved )
562 ),
563
564 TP_fast_assign(
565 __entry->dev = rgd->rd_gl->gl_name.ln_sbd->sd_vfs->s_dev;
566 __entry->start = block;
567 __entry->inum = ip->i_no_addr;
568 __entry->len = len;
569 __entry->block_state = block_state;
570 __entry->rd_addr = rgd->rd_addr;
571 __entry->rd_free_clone = rgd->rd_free_clone;
572 __entry->rd_requested = rgd->rd_requested;
573 __entry->rd_reserved = rgd->rd_reserved;
574 ),
575
576 TP_printk("%u,%u bmap %llu alloc %llu/%lu %s rg:%llu rf:%u rq:%u rr:%u",
577 MAJOR(__entry->dev), MINOR(__entry->dev),
578 (unsigned long long)__entry->inum,
579 (unsigned long long)__entry->start,
580 (unsigned long)__entry->len,
581 block_state_name(__entry->block_state),
582 (unsigned long long)__entry->rd_addr,
583 __entry->rd_free_clone,
584 __entry->rd_requested,
585 __entry->rd_reserved)
586);
587
588/* Keep track of multi-block reservations as they are allocated/freed */
589TRACE_EVENT(gfs2_rs,
590
591 TP_PROTO(const struct gfs2_blkreserv *rs, u8 func),
592
593 TP_ARGS(rs, func),
594
595 TP_STRUCT__entry(
596 __field( dev_t, dev )
597 __field( u64, rd_addr )
598 __field( u32, rd_free_clone )
599 __field( u32, rd_requested )
600 __field( u32, rd_reserved )
601 __field( u64, inum )
602 __field( u64, start )
603 __field( u32, requested )
604 __field( u32, reserved )
605 __field( u8, func )
606 ),
607
608 TP_fast_assign(
609 __entry->dev = rs->rs_rgd->rd_sbd->sd_vfs->s_dev;
610 __entry->rd_addr = rs->rs_rgd->rd_addr;
611 __entry->rd_free_clone = rs->rs_rgd->rd_free_clone;
612 __entry->rd_requested = rs->rs_rgd->rd_requested;
613 __entry->rd_reserved = rs->rs_rgd->rd_reserved;
614 __entry->inum = container_of(rs, struct gfs2_inode,
615 i_res)->i_no_addr;
616 __entry->start = rs->rs_start;
617 __entry->requested = rs->rs_requested;
618 __entry->reserved = rs->rs_reserved;
619 __entry->func = func;
620 ),
621
622 TP_printk("%u,%u bmap %llu resrv %llu rg:%llu rf:%u rq:%u rr:%u %s q:%u r:%u",
623 MAJOR(__entry->dev), MINOR(__entry->dev),
624 (unsigned long long)__entry->inum,
625 (unsigned long long)__entry->start,
626 (unsigned long long)__entry->rd_addr,
627 __entry->rd_free_clone,
628 __entry->rd_requested,
629 __entry->rd_reserved,
630 rs_func_name(__entry->func),
631 __entry->requested,
632 __entry->reserved)
633);
634
635#endif /* _TRACE_GFS2_H */
636
637/* This part must be outside protection */
638#undef TRACE_INCLUDE_PATH
639#define TRACE_INCLUDE_PATH .
640#define TRACE_INCLUDE_FILE trace_gfs2
641#include <trace/define_trace.h>
642