LU-15367 llite: add iotrace to open/release

Add iotrace to open and release operations.

Test-Parameters: trivial
Signed-off-by: Patrick Farrell <pfarrell@whamcloud.com>
Change-Id: Idc20a05417398af20dee313531a3573a8aa4e4c0
Reviewed-on: https://review.whamcloud.com/c/fs/lustre-release/+/52006
Tested-by: jenkins <devops@whamcloud.com>
Tested-by: Maloo <maloo@whamcloud.com>
Reviewed-by: Oleg Drokin <green@whamcloud.com>
Reviewed-by: Andreas Dilger <adilger@whamcloud.com>
Reviewed-by: Sebastien Buisson <sbuisson@ddn.com>

diff --git a/lustre/llite/file.c b/lustre/llite/file.c
index 5d2121f..b5ec1e0 100644 (file)
--- a/lustre/llite/file.c
+++ b/lustre/llite/file.c
@@ -406,8 +406,10 @@ int ll_file_release(struct inode *inode, struct file *file)
 
        ENTRY;
 
-       CDEBUG(D_VFSTRACE, "VFS Op:inode="DFID"(%p)\n",
-              PFID(ll_inode2fid(inode)), inode);
+       CDEBUG(D_VFSTRACE|D_IOTRACE,
+              "START file %s:"DFID"(%p), flags %o\n",
+              file_dentry(file)->d_name.name,
+              PFID(ll_inode2fid(file_inode(file))), inode, file->f_flags);
 
        fd = file->private_data;
        LASSERT(fd != NULL);
@@ -443,6 +445,11 @@ out:
        if (!rc && !is_root_inode(inode))
                ll_stats_ops_tally(sbi, LPROC_LL_RELEASE,
                                   ktime_us_delta(ktime_get(), kstart));
+       CDEBUG(D_IOTRACE,
+              "COMPLETED file %s:"DFID"(%p), flags %o\n",
+              file_dentry(file)->d_name.name,
+              PFID(ll_inode2fid(file_inode(file))), inode, file->f_flags);
+
        RETURN(rc);
 }
 
@@ -838,8 +845,10 @@ int ll_file_open(struct inode *inode, struct file *file)
        int rc = 0;
        ENTRY;
 
-       CDEBUG(D_VFSTRACE, "VFS Op:inode="DFID"(%p), flags %o\n",
-              PFID(ll_inode2fid(inode)), inode, file->f_flags);
+       CDEBUG(D_VFSTRACE|D_IOTRACE,
+              "START file %s:"DFID"(%p), flags %o\n",
+              file_dentry(file)->d_name.name,
+              PFID(ll_inode2fid(file_inode(file))), inode, file->f_flags);
 
        it = file->private_data; /* XXX: compat macro */
        file->private_data = NULL; /* prevent ll_local_open assertion */
@@ -1061,6 +1070,11 @@ out_nofiledata:
                it_clear_disposition(it, DISP_ENQ_OPEN_REF);
        }
 
+       CDEBUG(D_IOTRACE,
+              "COMPLETED file %s:"DFID"(%p), flags %o\n",
+              file_dentry(file)->d_name.name,
+              PFID(ll_inode2fid(file_inode(file))), inode, file->f_flags);
+
        return rc;
 }
 
@@ -5566,6 +5580,11 @@ int ll_getattr_dentry(struct dentry *de, struct kstat *stat, u32 request_mask,
        ktime_t kstart = ktime_get();
        int rc;
 
+       CDEBUG(D_VFSTRACE|D_IOTRACE,
+              "START file %s:"DFID"(%p), request_mask %d, flags %u, foreign %d\n",
+              de->d_name.name, PFID(ll_inode2fid(inode)), inode,
+              request_mask, flags, foreign);
+
        /* The OST object(s) determine the file size, blocks and mtime. */
        if (!(request_mask & STATX_SIZE || request_mask & STATX_BLOCKS ||
              request_mask & STATX_MTIME))
@@ -5719,6 +5738,11 @@ fill_attr:
        ll_stats_ops_tally(sbi, LPROC_LL_GETATTR,
                           ktime_us_delta(ktime_get(), kstart));
 
+       CDEBUG(D_IOTRACE,
+              "COMPLETED file %s:"DFID"(%p), request_mask %d, flags %u, foreign %d\n",
+              de->d_name.name, PFID(ll_inode2fid(inode)), inode,
+              request_mask, flags, foreign);
+
        return 0;
 }