[linux-2.6-block.git] / fs / gfs2 / trace_gfs2.h

#undef TRACE_SYSTEM
#define TRACE_SYSTEM gfs2

#if !defined(_TRACE_GFS2_H) || defined(TRACE_HEADER_MULTI_READ)
#define _TRACE_GFS2_H

#include <linux/tracepoint.h>

#include <linux/fs.h>
#include <linux/buffer_head.h>
#include <linux/dlmconstants.h>
#include <linux/gfs2_ondisk.h>
#include <linux/writeback.h>
#include <linux/ktime.h>
#include "incore.h"
#include "glock.h"
#include "rgrp.h"

#define dlm_state_name(nn) { DLM_LOCK_##nn, #nn }
#define glock_trace_name(x) __print_symbolic(x,		\
			    dlm_state_name(IV),		\
			    dlm_state_name(NL),		\
			    dlm_state_name(CR),		\
			    dlm_state_name(CW),		\
			    dlm_state_name(PR),		\
			    dlm_state_name(PW),		\
			    dlm_state_name(EX))

#define block_state_name(x) __print_symbolic(x,			\
			    { GFS2_BLKST_FREE, "free" },	\
			    { GFS2_BLKST_USED, "used" },	\
			    { GFS2_BLKST_DINODE, "dinode" },	\
			    { GFS2_BLKST_UNLINKED, "unlinked" })

#define TRACE_RS_DELETE  0
#define TRACE_RS_TREEDEL 1
#define TRACE_RS_INSERT  2
#define TRACE_RS_CLAIM   3

#define rs_func_name(x) __print_symbolic(x,	\
					 { 0, "del " },	\
					 { 1, "tdel" },	\
					 { 2, "ins " },	\
					 { 3, "clm " })

#define show_glock_flags(flags) __print_flags(flags, "",	\
	{(1UL << GLF_LOCK),			"l" },		\
	{(1UL << GLF_DEMOTE),			"D" },		\
	{(1UL << GLF_PENDING_DEMOTE),		"d" },		\
	{(1UL << GLF_DEMOTE_IN_PROGRESS),	"p" },		\
	{(1UL << GLF_DIRTY),			"y" },		\
	{(1UL << GLF_LFLUSH),			"f" },		\
	{(1UL << GLF_INVALIDATE_IN_PROGRESS),	"i" },		\
	{(1UL << GLF_REPLY_PENDING),		"r" },		\
	{(1UL << GLF_INITIAL),			"I" },		\
	{(1UL << GLF_FROZEN),			"F" },		\
	{(1UL << GLF_QUEUED),			"q" },		\
	{(1UL << GLF_LRU),			"L" },		\
	{(1UL << GLF_OBJECT),			"o" },		\
	{(1UL << GLF_BLOCKING),			"b" })

#ifndef NUMPTY
#define NUMPTY
static inline u8 glock_trace_state(unsigned int state)
{
	switch(state) {
	case LM_ST_SHARED:
		return DLM_LOCK_PR;
	case LM_ST_DEFERRED:
		return DLM_LOCK_CW;
	case LM_ST_EXCLUSIVE:
		return DLM_LOCK_EX;
	}
	return DLM_LOCK_NL;
}
#endif

/* Section 1 - Locking
 *
 * Objectives:
 * Latency: Remote demote request to state change
 * Latency: Local lock request to state change
 * Latency: State change to lock grant
 * Correctness: Ordering of local lock state vs. I/O requests
 * Correctness: Responses to remote demote requests
 */

/* General glock state change (DLM lock request completes) */
TRACE_EVENT(gfs2_glock_state_change,

	TP_PROTO(const struct gfs2_glock *gl, unsigned int new_state),

	TP_ARGS(gl, new_state),

	TP_STRUCT__entry(
		__field(	dev_t,	dev			)
		__field(	u64,	glnum			)
		__field(	u32,	gltype			)
		__field(	u8,	cur_state		)
		__field(	u8,	new_state		)
		__field(	u8,	dmt_state		)
		__field(	u8,	tgt_state		)
		__field(	unsigned long,	flags		)
	),

	TP_fast_assign(
		__entry->dev		= gl->gl_name.ln_sbd->sd_vfs->s_dev;
		__entry->glnum		= gl->gl_name.ln_number;
		__entry->gltype		= gl->gl_name.ln_type;
		__entry->cur_state	= glock_trace_state(gl->gl_state);
		__entry->new_state	= glock_trace_state(new_state);
		__entry->tgt_state	= glock_trace_state(gl->gl_target);
		__entry->dmt_state	= glock_trace_state(gl->gl_demote_state);
		__entry->flags		= gl->gl_flags | (gl->gl_object ? (1UL<<GLF_OBJECT) : 0);
	),

	TP_printk("%u,%u glock %d:%lld state %s to %s tgt:%s dmt:%s flags:%s",
		  MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
		 (unsigned long long)__entry->glnum,
		  glock_trace_name(__entry->cur_state),
		  glock_trace_name(__entry->new_state),
		  glock_trace_name(__entry->tgt_state),
		  glock_trace_name(__entry->dmt_state),
		  show_glock_flags(__entry->flags))
);

/* State change -> unlocked, glock is being deallocated */
TRACE_EVENT(gfs2_glock_put,

	TP_PROTO(const struct gfs2_glock *gl),

	TP_ARGS(gl),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	u64,	glnum			)
		__field(	u32,	gltype			)
		__field(	u8,	cur_state		)
		__field(	unsigned long,	flags		)
	),

	TP_fast_assign(
		__entry->dev		= gl->gl_name.ln_sbd->sd_vfs->s_dev;
		__entry->gltype		= gl->gl_name.ln_type;
		__entry->glnum		= gl->gl_name.ln_number;
		__entry->cur_state	= glock_trace_state(gl->gl_state);
		__entry->flags		= gl->gl_flags  | (gl->gl_object ? (1UL<<GLF_OBJECT) : 0);
	),

	TP_printk("%u,%u glock %d:%lld state %s => %s flags:%s",
		  MAJOR(__entry->dev), MINOR(__entry->dev),
                  __entry->gltype, (unsigned long long)__entry->glnum,
                  glock_trace_name(__entry->cur_state),
		  glock_trace_name(DLM_LOCK_IV),
		  show_glock_flags(__entry->flags))

);

/* Callback (local or remote) requesting lock demotion */
TRACE_EVENT(gfs2_demote_rq,

	TP_PROTO(const struct gfs2_glock *gl, bool remote),

	TP_ARGS(gl, remote),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	u64,	glnum			)
		__field(	u32,	gltype			)
		__field(	u8,	cur_state		)
		__field(	u8,	dmt_state		)
		__field(	unsigned long,	flags		)
		__field(	bool,	remote			)
	),

	TP_fast_assign(
		__entry->dev		= gl->gl_name.ln_sbd->sd_vfs->s_dev;
		__entry->gltype		= gl->gl_name.ln_type;
		__entry->glnum		= gl->gl_name.ln_number;
		__entry->cur_state	= glock_trace_state(gl->gl_state);
		__entry->dmt_state	= glock_trace_state(gl->gl_demote_state);
		__entry->flags		= gl->gl_flags  | (gl->gl_object ? (1UL<<GLF_OBJECT) : 0);
		__entry->remote		= remote;
	),

	TP_printk("%u,%u glock %d:%lld demote %s to %s flags:%s %s",
		  MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
		  (unsigned long long)__entry->glnum,
                  glock_trace_name(__entry->cur_state),
                  glock_trace_name(__entry->dmt_state),
		  show_glock_flags(__entry->flags),
		  __entry->remote ? "remote" : "local")

);

/* Promotion/grant of a glock */
TRACE_EVENT(gfs2_promote,

	TP_PROTO(const struct gfs2_holder *gh, int first),

	TP_ARGS(gh, first),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	u64,	glnum			)
		__field(	u32,	gltype			)
		__field(	int,	first			)
		__field(	u8,	state			)
	),

	TP_fast_assign(
		__entry->dev	= gh->gh_gl->gl_name.ln_sbd->sd_vfs->s_dev;
		__entry->glnum	= gh->gh_gl->gl_name.ln_number;
		__entry->gltype	= gh->gh_gl->gl_name.ln_type;
		__entry->first	= first;
		__entry->state	= glock_trace_state(gh->gh_state);
	),

	TP_printk("%u,%u glock %u:%llu promote %s %s",
		  MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
		  (unsigned long long)__entry->glnum,
		  __entry->first ? "first": "other",
		  glock_trace_name(__entry->state))
);

/* Queue/dequeue a lock request */
TRACE_EVENT(gfs2_glock_queue,

	TP_PROTO(const struct gfs2_holder *gh, int queue),

	TP_ARGS(gh, queue),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	u64,	glnum			)
		__field(	u32,	gltype			)
		__field(	int,	queue			)
		__field(	u8,	state			)
	),

	TP_fast_assign(
		__entry->dev	= gh->gh_gl->gl_name.ln_sbd->sd_vfs->s_dev;
		__entry->glnum	= gh->gh_gl->gl_name.ln_number;
		__entry->gltype	= gh->gh_gl->gl_name.ln_type;
		__entry->queue	= queue;
		__entry->state	= glock_trace_state(gh->gh_state);
	),

	TP_printk("%u,%u glock %u:%llu %squeue %s",
		  MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
		  (unsigned long long)__entry->glnum,
		  __entry->queue ? "" : "de",
		  glock_trace_name(__entry->state))
);

/* DLM sends a reply to GFS2 */
TRACE_EVENT(gfs2_glock_lock_time,

	TP_PROTO(const struct gfs2_glock *gl, s64 tdiff),

	TP_ARGS(gl, tdiff),

	TP_STRUCT__entry(
		__field(	dev_t,	dev		)
		__field(	u64,	glnum		)
		__field(	u32,	gltype		)
		__field(	int,	status		)
		__field(	char,	flags		)
		__field(	s64,	tdiff		)
		__field(	u64,	srtt		)
		__field(	u64,	srttvar		)
		__field(	u64,	srttb		)
		__field(	u64,	srttvarb	)
		__field(	u64,	sirt		)
		__field(	u64,	sirtvar		)
		__field(	u64,	dcount		)
		__field(	u64,	qcount		)
	),

	TP_fast_assign(
		__entry->dev            = gl->gl_name.ln_sbd->sd_vfs->s_dev;
		__entry->glnum          = gl->gl_name.ln_number;
		__entry->gltype         = gl->gl_name.ln_type;
		__entry->status		= gl->gl_lksb.sb_status;
		__entry->flags		= gl->gl_lksb.sb_flags;
		__entry->tdiff		= tdiff;
		__entry->srtt		= gl->gl_stats.stats[GFS2_LKS_SRTT];
		__entry->srttvar	= gl->gl_stats.stats[GFS2_LKS_SRTTVAR];
		__entry->srttb		= gl->gl_stats.stats[GFS2_LKS_SRTTB];
		__entry->srttvarb	= gl->gl_stats.stats[GFS2_LKS_SRTTVARB];
		__entry->sirt		= gl->gl_stats.stats[GFS2_LKS_SIRT];
		__entry->sirtvar	= gl->gl_stats.stats[GFS2_LKS_SIRTVAR];
		__entry->dcount		= gl->gl_stats.stats[GFS2_LKS_DCOUNT];
		__entry->qcount		= gl->gl_stats.stats[GFS2_LKS_QCOUNT];
	),

	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",
		  MAJOR(__entry->dev), MINOR(__entry->dev), __entry->gltype,
		  (unsigned long long)__entry->glnum,
		  __entry->status, __entry->flags,
		  (long long)__entry->tdiff,
		  (long long)__entry->srtt,
		  (long long)__entry->srttvar,
		  (long long)__entry->srttb,
		  (long long)__entry->srttvarb,
		  (long long)__entry->sirt,
		  (long long)__entry->sirtvar,
		  (long long)__entry->dcount,
		  (long long)__entry->qcount)
);

/* Section 2 - Log/journal
 *
 * Objectives:
 * Latency: Log flush time
 * Correctness: pin/unpin vs. disk I/O ordering
 * Performance: Log usage stats
 */

/* Pin/unpin a block in the log */
TRACE_EVENT(gfs2_pin,

	TP_PROTO(const struct gfs2_bufdata *bd, int pin),

	TP_ARGS(bd, pin),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	int,	pin			)
		__field(	u32,	len			)
		__field(	sector_t,	block		)
		__field(	u64,	ino			)
	),

	TP_fast_assign(
		__entry->dev		= bd->bd_gl->gl_name.ln_sbd->sd_vfs->s_dev;
		__entry->pin		= pin;
		__entry->len		= bd->bd_bh->b_size;
		__entry->block		= bd->bd_bh->b_blocknr;
		__entry->ino		= bd->bd_gl->gl_name.ln_number;
	),

	TP_printk("%u,%u log %s %llu/%lu inode %llu",
		  MAJOR(__entry->dev), MINOR(__entry->dev),
		  __entry->pin ? "pin" : "unpin",
		  (unsigned long long)__entry->block,
		  (unsigned long)__entry->len,
		  (unsigned long long)__entry->ino)
);

/* Flushing the log */
TRACE_EVENT(gfs2_log_flush,

	TP_PROTO(const struct gfs2_sbd *sdp, int start),

	TP_ARGS(sdp, start),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	int,	start			)
		__field(	u64,	log_seq			)
	),

	TP_fast_assign(
		__entry->dev            = sdp->sd_vfs->s_dev;
		__entry->start		= start;
		__entry->log_seq	= sdp->sd_log_sequence;
	),

	TP_printk("%u,%u log flush %s %llu",
		  MAJOR(__entry->dev), MINOR(__entry->dev),
		  __entry->start ? "start" : "end",
		  (unsigned long long)__entry->log_seq)
);

/* Reserving/releasing blocks in the log */
TRACE_EVENT(gfs2_log_blocks,

	TP_PROTO(const struct gfs2_sbd *sdp, int blocks),

	TP_ARGS(sdp, blocks),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	int,	blocks			)
	),

	TP_fast_assign(
		__entry->dev		= sdp->sd_vfs->s_dev;
		__entry->blocks		= blocks;
	),

	TP_printk("%u,%u log reserve %d", MAJOR(__entry->dev),
		  MINOR(__entry->dev), __entry->blocks)
);

/* Writing back the AIL */
TRACE_EVENT(gfs2_ail_flush,

	TP_PROTO(const struct gfs2_sbd *sdp, const struct writeback_control *wbc, int start),

	TP_ARGS(sdp, wbc, start),

	TP_STRUCT__entry(
		__field(	dev_t,	dev			)
		__field(	int, start			)
		__field(	int, sync_mode			)
		__field(	long, nr_to_write		)
	),

	TP_fast_assign(
		__entry->dev		= sdp->sd_vfs->s_dev;
		__entry->start		= start;
		__entry->sync_mode	= wbc->sync_mode;
		__entry->nr_to_write	= wbc->nr_to_write;
	),

	TP_printk("%u,%u ail flush %s %s %ld", MAJOR(__entry->dev),
		  MINOR(__entry->dev), __entry->start ? "start" : "end",
		  __entry->sync_mode == WB_SYNC_ALL ? "all" : "none",
		  __entry->nr_to_write)
);

/* Section 3 - bmap
 *
 * Objectives:
 * Latency: Bmap request time
 * Performance: Block allocator tracing
 * Correctness: Test of disard generation vs. blocks allocated
 */

/* Map an extent of blocks, possibly a new allocation */
TRACE_EVENT(gfs2_bmap,

	TP_PROTO(const struct gfs2_inode *ip, const struct buffer_head *bh,
		sector_t lblock, int create, int errno),

	TP_ARGS(ip, bh, lblock, create, errno),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	sector_t, lblock		)
		__field(	sector_t, pblock		)
		__field(	u64,	inum			)
		__field(	unsigned long, state		)
		__field(	u32,	len			)
		__field(	int,	create			)
		__field(	int,	errno			)
	),

	TP_fast_assign(
		__entry->dev            = ip->i_gl->gl_name.ln_sbd->sd_vfs->s_dev;
		__entry->lblock		= lblock;
		__entry->pblock		= buffer_mapped(bh) ?  bh->b_blocknr : 0;
		__entry->inum		= ip->i_no_addr;
		__entry->state		= bh->b_state;
		__entry->len		= bh->b_size;
		__entry->create		= create;
		__entry->errno		= errno;
	),

	TP_printk("%u,%u bmap %llu map %llu/%lu to %llu flags:%08lx %s %d",
		  MAJOR(__entry->dev), MINOR(__entry->dev),
		  (unsigned long long)__entry->inum,
		  (unsigned long long)__entry->lblock,
		  (unsigned long)__entry->len,
		  (unsigned long long)__entry->pblock,
		  __entry->state, __entry->create ? "create " : "nocreate",
		  __entry->errno)
);

/* Keep track of blocks as they are allocated/freed */
TRACE_EVENT(gfs2_block_alloc,

	TP_PROTO(const struct gfs2_inode *ip, struct gfs2_rgrpd *rgd,
		 u64 block, unsigned len, u8 block_state),

	TP_ARGS(ip, rgd, block, len, block_state),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	u64,	start			)
		__field(	u64,	inum			)
		__field(	u32,	len			)
		__field(	u8,	block_state		)
		__field(        u64,	rd_addr			)
		__field(        u32,	rd_free_clone		)
		__field(	u32,	rd_reserved		)
	),

	TP_fast_assign(
		__entry->dev		= rgd->rd_gl->gl_name.ln_sbd->sd_vfs->s_dev;
		__entry->start		= block;
		__entry->inum		= ip->i_no_addr;
		__entry->len		= len;
		__entry->block_state	= block_state;
		__entry->rd_addr	= rgd->rd_addr;
		__entry->rd_free_clone	= rgd->rd_free_clone;
		__entry->rd_reserved	= rgd->rd_reserved;
	),

	TP_printk("%u,%u bmap %llu alloc %llu/%lu %s rg:%llu rf:%u rr:%lu",
		  MAJOR(__entry->dev), MINOR(__entry->dev),
		  (unsigned long long)__entry->inum,
		  (unsigned long long)__entry->start,
		  (unsigned long)__entry->len,
		  block_state_name(__entry->block_state),
		  (unsigned long long)__entry->rd_addr,
		  __entry->rd_free_clone, (unsigned long)__entry->rd_reserved)
);

/* Keep track of multi-block reservations as they are allocated/freed */
TRACE_EVENT(gfs2_rs,

	TP_PROTO(const struct gfs2_blkreserv *rs, u8 func),

	TP_ARGS(rs, func),

	TP_STRUCT__entry(
		__field(        dev_t,  dev                     )
		__field(	u64,	rd_addr			)
		__field(	u32,	rd_free_clone		)
		__field(	u32,	rd_reserved		)
		__field(	u64,	inum			)
		__field(	u64,	start			)
		__field(	u32,	free			)
		__field(	u8,	func			)
	),

	TP_fast_assign(
		__entry->dev		= rs->rs_rbm.rgd->rd_sbd->sd_vfs->s_dev;
		__entry->rd_addr	= rs->rs_rbm.rgd->rd_addr;
		__entry->rd_free_clone	= rs->rs_rbm.rgd->rd_free_clone;
		__entry->rd_reserved	= rs->rs_rbm.rgd->rd_reserved;
		__entry->inum		= rs->rs_inum;
		__entry->start		= gfs2_rbm_to_block(&rs->rs_rbm);
		__entry->free		= rs->rs_free;
		__entry->func		= func;
	),

	TP_printk("%u,%u bmap %llu resrv %llu rg:%llu rf:%lu rr:%lu %s f:%lu",
		  MAJOR(__entry->dev), MINOR(__entry->dev),
		  (unsigned long long)__entry->inum,
		  (unsigned long long)__entry->start,
		  (unsigned long long)__entry->rd_addr,
		  (unsigned long)__entry->rd_free_clone,
		  (unsigned long)__entry->rd_reserved,
		  rs_func_name(__entry->func), (unsigned long)__entry->free)
);

#endif /* _TRACE_GFS2_H */

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