On Wed, May 02, 2018 at 06:01:50PM +1000, Dave Chinner wrote:
> From: Dave Chinner <dchinner@xxxxxxxxxx>
>
> Because currently we have no idea what the transaction context we
> are operating in is, and I need to know that information to track
> down bugs in multiple log item joins to transactions.
>
> Signed-Off-By: Dave Chinner <dchinner@xxxxxxxxxx>
> ---
Reviewed-by: Brian Foster <bfoster@xxxxxxxxxx>
> fs/xfs/xfs_log_cil.c | 1 +
> fs/xfs/xfs_trace.h | 37 +++++++++++++++++++++++++++++++++++++
> fs/xfs/xfs_trans.c | 19 ++++++++++++++++++-
> 3 files changed, 56 insertions(+), 1 deletion(-)
>
> diff --git a/fs/xfs/xfs_log_cil.c b/fs/xfs/xfs_log_cil.c
> index de9da33866eb..8342f4ee83e7 100644
> --- a/fs/xfs/xfs_log_cil.c
> +++ b/fs/xfs/xfs_log_cil.c
> @@ -1026,6 +1026,7 @@ xfs_log_commit_cil(
> *commit_lsn = xc_commit_lsn;
>
> xfs_log_done(mp, tp->t_ticket, NULL, regrant);
> + tp->t_ticket = NULL;
> xfs_trans_unreserve_and_mod_sb(tp);
>
> /*
> diff --git a/fs/xfs/xfs_trace.h b/fs/xfs/xfs_trace.h
> index 7f4d961fae12..8136f2280173 100644
> --- a/fs/xfs/xfs_trace.h
> +++ b/fs/xfs/xfs_trace.h
> @@ -3346,6 +3346,43 @@ TRACE_EVENT(xfs_trans_resv_calc,
> __entry->logflags)
> );
>
> +DECLARE_EVENT_CLASS(xfs_trans_class,
> + TP_PROTO(struct xfs_trans *tp, unsigned long caller_ip),
> + TP_ARGS(tp, caller_ip),
> + TP_STRUCT__entry(
> + __field(dev_t, dev)
> + __field(uint32_t, tid)
> + __field(uint32_t, flags)
> + __field(unsigned long, caller_ip)
> + ),
> + TP_fast_assign(
> + __entry->dev = tp->t_mountp->m_super->s_dev;
> + __entry->tid = 0;
> + if (tp->t_ticket)
> + __entry->tid = tp->t_ticket->t_tid;
> + __entry->flags = tp->t_flags;
> + __entry->caller_ip = caller_ip;
> + ),
> + TP_printk("dev %d:%d trans %x flags 0x%x caller %pS",
> + MAJOR(__entry->dev), MINOR(__entry->dev),
> + __entry->tid,
> + __entry->flags,
> + (char *)__entry->caller_ip)
> +)
> +
> +#define DEFINE_TRANS_EVENT(name) \
> +DEFINE_EVENT(xfs_trans_class, name, \
> + TP_PROTO(struct xfs_trans *tp, unsigned long caller_ip), \
> + TP_ARGS(tp, caller_ip))
> +DEFINE_TRANS_EVENT(xfs_trans_alloc);
> +DEFINE_TRANS_EVENT(xfs_trans_cancel);
> +DEFINE_TRANS_EVENT(xfs_trans_commit);
> +DEFINE_TRANS_EVENT(xfs_trans_dup);
> +DEFINE_TRANS_EVENT(xfs_trans_free);
> +DEFINE_TRANS_EVENT(xfs_trans_roll);
> +DEFINE_TRANS_EVENT(xfs_trans_add_item);
> +DEFINE_TRANS_EVENT(xfs_trans_free_items);
> +
> #endif /* _TRACE_XFS_H */
>
> #undef TRACE_INCLUDE_PATH
> diff --git a/fs/xfs/xfs_trans.c b/fs/xfs/xfs_trans.c
> index 33c40788cffa..63c22aa02cd8 100644
> --- a/fs/xfs/xfs_trans.c
> +++ b/fs/xfs/xfs_trans.c
> @@ -79,6 +79,7 @@ xfs_trans_free(
> xfs_extent_busy_sort(&tp->t_busy);
> xfs_extent_busy_clear(tp->t_mountp, &tp->t_busy, false);
>
> + trace_xfs_trans_free(tp, _RET_IP_);
> atomic_dec(&tp->t_mountp->m_active_trans);
> if (!(tp->t_flags & XFS_TRANS_NO_WRITECOUNT))
> sb_end_intwrite(tp->t_mountp->m_super);
> @@ -100,6 +101,8 @@ xfs_trans_dup(
> {
> xfs_trans_t *ntp;
>
> + trace_xfs_trans_dup(tp, _RET_IP_);
> +
> ntp = kmem_zone_zalloc(xfs_trans_zone, KM_SLEEP);
>
> /*
> @@ -283,6 +286,8 @@ xfs_trans_alloc(
> return error;
> }
>
> + trace_xfs_trans_alloc(tp, _RET_IP_);
> +
> *tpp = tp;
> return 0;
> }
> @@ -751,6 +756,7 @@ xfs_trans_add_item(
>
> lip->li_desc = lidp;
> set_bit(XFS_LI_TRANS, &lip->li_flags);
> + trace_xfs_trans_add_item(tp, _RET_IP_);
> }
>
> STATIC void
> @@ -785,6 +791,8 @@ xfs_trans_free_items(
> {
> struct xfs_log_item_desc *lidp, *next;
>
> + trace_xfs_trans_free_items(tp, _RET_IP_);
> +
> list_for_each_entry_safe(lidp, next, &tp->t_items, lid_trans) {
> struct xfs_log_item *lip = lidp->lid_item;
>
> @@ -938,6 +946,8 @@ __xfs_trans_commit(
> int error = 0;
> int sync = tp->t_flags & XFS_TRANS_SYNC;
>
> + trace_xfs_trans_commit(tp, _RET_IP_);
> +
> /*
> * If there is nothing to be logged by the transaction,
> * then unlock all of the items associated with the
> @@ -993,6 +1003,7 @@ __xfs_trans_commit(
> commit_lsn = xfs_log_done(mp, tp->t_ticket, NULL, regrant);
> if (commit_lsn == -1 && !error)
> error = -EIO;
> + tp->t_ticket = NULL;
> }
> current_restore_flags_nested(&tp->t_pflags, PF_MEMALLOC_NOFS);
> xfs_trans_free_items(tp, NULLCOMMITLSN, !!error);
> @@ -1024,6 +1035,8 @@ xfs_trans_cancel(
> struct xfs_mount *mp = tp->t_mountp;
> bool dirty = (tp->t_flags & XFS_TRANS_DIRTY);
>
> + trace_xfs_trans_cancel(tp, _RET_IP_);
> +
> /*
> * See if the caller is relying on us to shut down the
> * filesystem. This happens in paths where we detect
> @@ -1044,8 +1057,10 @@ xfs_trans_cancel(
> xfs_trans_unreserve_and_mod_sb(tp);
> xfs_trans_unreserve_and_mod_dquots(tp);
>
> - if (tp->t_ticket)
> + if (tp->t_ticket) {
> xfs_log_done(mp, tp->t_ticket, NULL, false);
> + tp->t_ticket = NULL;
> + }
>
> /* mark this thread as no longer being in a transaction */
> current_restore_flags_nested(&tp->t_pflags, PF_MEMALLOC_NOFS);
> @@ -1069,6 +1084,8 @@ xfs_trans_roll(
> struct xfs_trans_res tres;
> int error;
>
> + trace_xfs_trans_roll(trans, _RET_IP_);
> +
> /*
> * Copy the critical parameters from one trans to the next.
> */
> --
> 2.17.0
>
> --
> To unsubscribe from this list: send the line "unsubscribe linux-xfs" in
> the body of a message to majordomo@xxxxxxxxxxxxxxx
> More majordomo info at http://vger.kernel.org/majordomo-info.html
--
To unsubscribe from this list: send the line "unsubscribe linux-xfs" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
