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) [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