On Sun, 2020-06-14 at 15:17 +0200, Marc Roos wrote:
When rsyncing to a nfs-ganesha exported cephfs the process hangs, and escalates into "cache pressure" of other cephfs clients[1].
When testing the rsync with more debugging on, I noticed that rsync stalled at the 'set modtime of . '[2]
After restarting the nfs-ganesha and doing a few successful ls -al on the nfs mount. I was able to create this 'stall' by doing only a 'touch /var/www/cobbler/ks_mirror/CentOS7-x86_64/' on the directory. As mentioned in the post[1]. The same xlock message is in the mds log[4] the nfs-ganesha log during this touch[5].
nfs-ganesha-2.8.1.2-0.1.el7.x86_64 nfs-ganesha-ceph-2.8.1.2-0.1.el7.x86_64
ceph version 14.2.9 (581f22da52345dba46ee232b73b990f06029a2a0) nautilus (stable)
Until recently ganesha and ceph didn't have a mechanism to allow ceph to request that ganesha shrink entries from its caches. That was added recently to mainline ceph and ganesha, but has yet to be backported everywhere yet. See: https://tracker.ceph.com/issues/12334 If you're able to test versions of libcephfs and ganesha that have this fix, we'd welcome some feedback as to whether it helps. Cheers, Jeff
[1] https://www.mail-archive.com/ceph-users@ceph.io/msg04527.html
[2] recv_file_name(Packages/zziplib-devel-0.13.62-12.el7.i686.rpm) recv_file_name(Packages/zziplib-devel-0.13.62-12.el7.x86_64.rpm) recv_file_name(Packages/zziplib-utils-0.13.62-12.el7.x86_64.rpm) recv_file_name(Packages/centos-release-7-8.2003.0.el7.centos.x86_64.rpm) recv_file_name(Packages/lorax-19.7.26-1.el7.centos.x86_64.rpm) received 10070 names recv_file_list done recv_generator(.,0) set modtime of . to (1587553127) Wed Apr 22 12:58:47 2020
[3] 2020-06-14 14:36:26.133 7fb5edd82700 0 log_channel(cluster) log [WRN] : slow request 60.157532 seconds old, received at 2020-06-14 14:35:25.977231: client_request(client.4022202:52 setattr mtime=2020-06-14 14:35:25.976975 atime =2020-06-14 14:35:25.976974 #0x100001b9177 2020-06-14 14:35:25.977014 caller_uid=500, caller_gid=500{500,1,2,3,4,6,10,}) currently failed to xlock, waiting 2020-06-14 14:36:26.133 7fb5edd82700 0 log_channel(cluster) log [WRN] : client.4022217 isn't responding to mclientcaps(revoke), ino 0x100001b9177 pending pAsLsXs issued pAsLsXsFs, sent 60.158122 seconds ago
[4] 2020-06-14 14:35:56.133 7fb5edd82700 0 log_channel(cluster) log [WRN] : 1 slow requests, 1 included below; oldest blocked for > 30.156793 secs 2020-06-14 14:35:56.133 7fb5edd82700 0 log_channel(cluster) log [WRN] : slow request 30.156792 seconds old, received at 2020-06-14 14:35:25.977231: client_request(client.4022202:52 setattr mtime=2020-06-14 14:35:25.976975 atime =2020-06-14 14:35:25.976974 #0x100001b9177 2020-06-14 14:35:25.977014 caller_uid=500, caller_gid=500{500,1,2,3,4,6,10,}) currently failed to xlock, waiting
[5] 14/06/2020 14:35:23 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[reaper] reaper_run :CLIENT ID :DEBUG :Now checking NFS4 clients for expiration 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] alloc_nfs_request :DISP :DEBUG :0x7feb40000ce0 fd 166 context 0x7feb14004b60 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.2 for Program 100003, Version 4, Function 1 has xid=4153280463 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb14006c50, tag = NO TAG 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] process_one_op :NFS4 :DEBUG :Request 0: opcode 22 is OP_PUTFH 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 10 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 16063 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0x7feb2c006d70 type: 7, New type: 7 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 0 = NFS4_OK, op response size is 4 total response size is 40 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] process_one_op :NFS4 :DEBUG :Request 1: opcode 3 is OP_ACCESS 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs_access_op :NFS3 :DEBUG :NFS3: DEBUG: Requested ACCESS=READ,LOOKUP,MODIFY,EXTEND,DELETE,- 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs_access_op :NFS3 :DEBUG :NFS3: DEBUG: access_mask = mode(rwx) ACL(list_dir,add_file,execute,add_subdirectory,delete_child) 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs_access_op :NFS3 :DEBUG :NFS3: DEBUG: Supported ACCESS=READ,LOOKUP,MODIFY,EXTEND,DELETE,- 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs_access_op :NFS3 :DEBUG :NFS3: DEBUG: Granted ACCESS=READ,LOOKUP,MODIFY,EXTEND,DELETE,- 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] complete_op :NFS4 :DEBUG :Status of OP_ACCESS in position 1 = NFS4_OK, op response size is 12 total response size is 56 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0x7feb2c006da8 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] encode_fsid :NFS4 :DEBUG :fsid.major = 4294967295, fsid.minor = 4294967294 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 144 total response size is 204 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb40000ce0 fd 166 (::ffff:192.168.10.2:736) xid=4153280463 returned XPRT_IDLE 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] alloc_nfs_request :DISP :DEBUG :0x7feb40000ce0 fd 166 context 0x7feb4c005710 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.2 for Program 100003, Version 4, Function 1 has xid=4170057679 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb4c0078c0, tag = NO TAG 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] process_one_op :NFS4 :DEBUG :Request 0: opcode 22 is OP_PUTFH 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 10 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] cih_get_by_key_latch :HT CACHE :DEBUG :cih AVL hit slot 21342 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa8dd60 type: 7, New type: 7 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 0 = NFS4_OK, op response size is 4 total response size is 40 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] process_one_op :NFS4 :DEBUG :Request 1: opcode 34 is OP_SETATTR 14/06/2020 14:35:25 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_3] fsal_check_setattr_perms :FSAL :DEBUG :Access check returned No error (Ok for owner) 14/06/2020 14:35:33 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[reaper] reaper_run :CLIENT ID :DEBUG :Now checking NFS4 clients for expiration 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb18004c80 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4045722888 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb18003de0, tag = NO TAG 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 30 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] cih_get_by_key_latch :HT CACHE :DEBUG :cih AVL hit slot 11701 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91800 type: 7, New type: 7 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91838 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] encode_fsid :NFS4 :DEBUG :fsid.major = 4294967295, fsid.minor = 4294967294 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 144 total response size is 232 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4045722888 returned XPRT_IDLE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb24005ba0 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4062500104 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb24007110, tag = NO TAG 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 35 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] cih_get_by_key_latch :HT CACHE :DEBUG :cih AVL hit slot 11885 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91c70 type: 7, New type: 7 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91ca8 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] encode_fsid :NFS4 :DEBUG :fsid.major = 4294967295, fsid.minor = 4294967294 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 144 total response size is 232 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4062500104 returned XPRT_IDLE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb14004b60 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4079277320 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb14007e30, tag = NO TAG 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 30 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 11701 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91800 type: 7, New type: 7 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91838 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 52 total response size is 140 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4079277320 returned XPRT_IDLE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb5800c180 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4096054536 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb5800bdb0, tag = NO TAG 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 35 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 11885 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91c70 type: 7, New type: 7 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91ca8 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 52 total response size is 140 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4096054536 returned XPRT_IDLE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] alloc_nfs_request :DISP :DEBUG :0x7feb4c001060 fd 161 context 0x7feb40003150 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.153 for Program 100003, Version 4, Function 1 has xid=225276012 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 1 operations, res = 0x7feb40006d40, tag = NO TAG 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb38003da0 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb4c001060 fd 161 (::ffff:192.168.10.153:920) xid=225276012 returned XPRT_IDLE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] alloc_nfs_request :DISP :DEBUG :0x7feb48000c90 fd 160 context 0x7feb1c005c50 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.151 for Program 100003, Version 4, Function 1 has xid=937363790 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 1 operations, res = 0x7feb1c006d40, tag = NO TAG 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb50002630 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:34 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb48000c90 fd 160 (::ffff:192.168.10.151:998) xid=937363790 returned XPRT_IDLE 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb48005240 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4112831752 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb4800a860, tag = NO TAG 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 30 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 11701 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91800 type: 7, New type: 7 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91838 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 52 total response size is 140 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4112831752 returned XPRT_IDLE 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb34006230 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4129608968 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb34005270, tag = NO TAG 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 35 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 11885 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91c70 type: 7, New type: 7 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91ca8 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 52 total response size is 140 14/06/2020 14:35:35 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4129608968 returned XPRT_IDLE 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb2c0044d0 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4146386184 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb2c006120, tag = NO TAG 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 30 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 11701 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91800 type: 7, New type: 7 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91838 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 52 total response size is 140 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4146386184 returned XPRT_IDLE 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb18004b90 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4163163400 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb18003de0, tag = NO TAG 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 35 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 11885 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91c70 type: 7, New type: 7 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91ca8 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 52 total response size is 140 14/06/2020 14:35:36 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_16] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4163163400 returned XPRT_IDLE 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb24005ba0 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4179940616 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb24007110, tag = NO TAG 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 30 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 11701 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91800 type: 7, New type: 7 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91838 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 52 total response size is 140 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_13] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4179940616 returned XPRT_IDLE 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] alloc_nfs_request :DISP :DEBUG :0x7feb44001060 fd 162 context 0x7feb14004b60 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.113 for Program 100003, Version 4, Function 1 has xid=4196717832 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 3 operations, res = 0x7feb14007e30, tag = NO TAG 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb340023d0 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] process_one_op :NFS4 :DEBUG :Request 1: opcode 22 is OP_PUTFH 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 35 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] cih_get_by_key_latch :HT CACHE :DEBUG :fdcache MISS 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 11885 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] mdcache_new_entry :INODE :DEBUG :Trying to add an already existing entry. Found entry 0xa91c70 type: 7, New type: 7 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] complete_op :NFS4 :DEBUG :Status of OP_PUTFH in position 1 = NFS4_OK, op response size is 4 total response size is 84 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] process_one_op :NFS4 :DEBUG :Request 2: opcode 9 is OP_GETATTR 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] file_To_Fattr :NFS4 ACL :DEBUG :No permission check for ACL for obj 0xa91ca8 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] complete_op :NFS4 :DEBUG :Status of OP_GETATTR in position 2 = NFS4_OK, op response size is 52 total response size is 140 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_14] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb44001060 fd 162 (::ffff:192.168.10.113:967) xid=4196717832 returned XPRT_IDLE 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] alloc_nfs_request :DISP :DEBUG :0x7feb400008c0 fd 164 context 0x7feb5800bdb0 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.152 for Program 100003, Version 4, Function 1 has xid=1500681607 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 1 operations, res = 0x7feb5800c180, tag = NO TAG 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb34006410 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_2] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb400008c0 fd 164 (::ffff:192.168.10.152:747) xid=1500681607 returned XPRT_IDLE 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] alloc_nfs_request :DISP :DEBUG :0x7feb4c003690 fd 165 context 0x7feb40003150 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.111 for Program 100003, Version 4, Function 1 has xid=1915990987 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 1 operations, res = 0x7feb40002f50, tag = NO TAG 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb48006360 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:37 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_24] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb4c003690 fd 165 (::ffff:192.168.10.111:977) xid=1915990987 returned XPRT_IDLE 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] alloc_nfs_request :DISP :DEBUG :0x7feb58000c90 fd 152 context 0x7feb1c005c50 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.103 for Program 100003, Version 4, Function 1 has xid=113039167 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 1 operations, res = 0x7feb1c006d40, tag = NO TAG 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] process_one_op :NFS4 :DEBUG :Request 0: opcode 30 is OP_RENEW 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] complete_op :NFS4 :DEBUG :Status of OP_RENEW in position 0 = NFS4_OK, op response size is 4 total response size is 40 14/06/2020 14:35:39 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_15] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb58000c90 fd 152 (::ffff:192.168.10.103:945) xid=113039167 returned XPRT_IDLE 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] alloc_nfs_request :DISP :DEBUG :0x7feb2c000c90 fd 159 context 0x7feb48005240 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.114 for Program 100003, Version 4, Function 1 has xid=3613830069 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 1 operations, res = 0x7feb4800a860, tag = NO TAG 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb2c002070 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_23] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb2c000c90 fd 159 (::ffff:192.168.10.114:687) xid=3613830069 returned XPRT_IDLE 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] alloc_nfs_request :DISP :DEBUG :0x7feb140008c0 fd 163 context 0x7feb34006230 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.112 for Program 100003, Version 4, Function 1 has xid=3310701715 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 1 operations, res = 0x7feb34005270, tag = NO TAG 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] process_one_op :NFS4 :DEBUG :Request 0: opcode 53 is OP_SEQUENCE 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] nfs4_op_sequence :SESSIONS :DEBUG :SEQUENCE session=0x7feb480022f0 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] complete_op :NFS4 :DEBUG :Status of OP_SEQUENCE in position 0 = NFS4_OK, op response size is 40 total response size is 76 14/06/2020 14:35:40 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_18] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb140008c0 fd 163 (::ffff:192.168.10.112:729) xid=3310701715 returned XPRT_IDLE 14/06/2020 14:35:43 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[chunk_lru] chunk_lru_run :INODE LRU :DEBUG :After work, threadwait=90 totalwork=0 14/06/2020 14:35:43 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[cache_lru] lru_run :INODE LRU :DEBUG :FD count is 0 and low water mark is 2048: not reaping. 14/06/2020 14:35:43 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[cache_lru] lru_run :INODE LRU :DEBUG :After work, open_fd_count:0 count:41 fdrate:1 new_thread_wait=90 14/06/2020 14:35:43 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[reaper] reaper_run :CLIENT ID :DEBUG :Now checking NFS4 clients for expiration 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] alloc_nfs_request :DISP :DEBUG :0x7feb40000ce0 fd 166 context 0x7feb2c0044d0 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] nfs_rpc_process_request :DISP :DEBUG :Request from ::ffff:192.168.10.2 for Program 100003, Version 4, Function 1 has xid=4186834895 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 1 operations, res = 0x7feb2c006120, tag = NO TAG 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] process_one_op :NFS4 :DEBUG :Request 0: opcode 30 is OP_RENEW 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] complete_op :NFS4 :DEBUG :Status of OP_RENEW in position 0 = NFS4_OK, op response size is 4 total response size is 40 14/06/2020 14:35:45 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[svc_25] free_nfs_request :DISP :DEBUG :SVC_DECODE on 0x7feb40000ce0 fd 166 (::ffff:192.168.10.2:736) xid=4186834895 returned XPRT_IDLE 14/06/2020 14:35:53 : epoch 5ee617d5 : c01 : ganesha.nfsd-481476[reaper] reaper_run :CLIENT ID :DEBUG :Now checking NFS4 clients for expiration _______________________________________________ Support mailing list -- support@lists.nfs-ganesha.org To unsubscribe send an email to support-leave@lists.nfs-ganesha.org
-- Jeff Layton <jlayton@redhat.com>