{"action":"closed","issue":{"url":"https://api.github.com/repos/nfs-ganesha/nfs-ganesha/issues/187","repository_url":"https://api.github.com/repos/nfs-ganesha/nfs-ganesha","labels_url":"https://api.github.com/repos/nfs-ganesha/nfs-ganesha/issues/187/labels{/name}","comments_url":"https://api.github.com/repos/nfs-ganesha/nfs-ganesha/issues/187/comments","events_url":"https://api.github.com/repos/nfs-ganesha/nfs-ganesha/issues/187/events","html_url":"https://github.com/nfs-ganesha/nfs-ganesha/issues/187","id":241670484,"number":187,"title":"cephfs open4_validate_claim failed; touch is very slow ","user":{"login":"junxfl","id":24217314,"avatar_url":"https://avatars1.githubusercontent.com/u/24217314?v=3","gravatar_id":"","url":"https://api.github.com/users/junxfl","html_url":"https://github.com/junxfl","followers_url":"https://api.github.com/users/junxfl/followers","following_url":"https://api.github.com/users/junxfl/following{/other_user}","gists_url":"https://api.github.com/users/junxfl/gists{/gist_id}","starred_url":"https://api.github.com/users/junxfl/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/junxfl/subscriptions","organizations_url":"https://api.github.com/users/junxfl/orgs","repos_url":"https://api.github.com/users/junxfl/repos","events_url":"https://api.github.com/users/junxfl/events{/privacy}","received_events_url":"https://api.github.com/users/junxfl/received_events","type":"User","site_admin":false},"labels":[],"state":"closed","locked":false,"assignee":null,"assignees":[],"milestone":null,"comments":4,"created_at":"2017-07-10T10:40:15Z","updated_at":"2017-07-13T04:27:45Z","closed_at":"2017-07-13T04:27:45Z","body":"I use nfs-gansha + cephfs， but  find a problem.  The steps is:\r\n1、 mount -t nfs 10.100.189.52:/volumes /mnt/\r\n2、touch /mnt/1 ; tocuh is very show,  after a long period of time，it is ok.  Log is：\r\n nfs_rpc_execute :DISP :DEBUG :Request from ::ffff:10.100.161.182 for Program 100003, Version 4, Function 1 has xid=3973493752\r\n dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf\r\n nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 5 operations, res = 0x7f7ed40009f0, tag = NO TAG\r\n nfs4_Compound :NFS4 :DEBUG :Request 0: opcode 22 is OP_PUTFH\r\n get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 1\r\n cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 15543\r\n nfs4_Compound :NFS4 :DEBUG :Request 1: opcode 18 is OP_OPEN\r\n nfs4_op_open :STATE :DEBUG :Entering NFS v4 OPEN handler -----------------------------\r\n nfs4_op_open :STATE :DEBUG :OPEN Client id = 5963475500000001\r\n nfs_in_grace :STATE :DEBUG :NFS Server IN GRACE\r\n nfs4_op_open :NFS4 :DEBUG :open4_validate_claim failed\r\n nfs4_op_open :STATE :DEBUG :failed with status NFS4ERR_GRACE\r\n nfs4_Compound :NFS4 :DEBUG :Status of OP_OPEN in position 1 = NFS4ERR_GRACE\r\n nfs4_Compound :NFS4 :DEBUG :End status = NFS4ERR_GRACE lastindex = 1\r\nnfs_in_grace :STATE :DEBUG :NFS Server IN GRACE\r\nreaper_run :CLIENT ID :DEBUG :Now checking NFS4 clients for expiration\r\ns_rpc_cond_stall_xprt :DISP :DEBUG :xprt 0x7f7f50001170 xp_refs 2 has 0 reqs active (max 512)\r\n thr_decode_rpc_request :DISP :DEBUG :0x7f7f50001170 context (nil)\r\n nfs_rpc_enqueue_req :DISP :DEBUG :enqueued req, q 0x7d7ff8 (REQ_Q_HIGH_LATENCY 0x7d7ff8:0x7d8060) size is 1 (enq 30 deq 29)\r\n thr_decode_rpc_requests :DISP :DEBUG :exiting, stat=XPRT_IDLE\r\n worker_run :DISP :DEBUG :NFS protocol request, reqdata=0x7f7f500008c0 xprt=0x7f7f50001170 requests=1\r\n get_gsh_client :HT CACHE :DEBUG :client_mgr cache hit slot 0\r\n nfs_rpc_execute :DISP :DEBUG :Request from ::ffff:10.100.161.182 for Program 100003, Version 4, Function 1 has xid=3990270968\r\n dupreq_tcp_cmpf :DUPREQ :DEBUG :dupreq_tcp_cmpf\r\n nfs4_Compound :NFS4 :DEBUG :COMPOUND: There are 5 operations, res = 0x7f7ed80009f0, tag = NO TAG\r\n nfs4_Compound :NFS4 :DEBUG :Request 0: opcode 22 is OP_PUTFH\r\n get_gsh_export :HT CACHE :DEBUG :export_mgr cache hit slot 1\r\n cih_get_by_key_latch :HT CACHE :DEBUG :cih cache hit slot 15543\r\n nfs4_Compound :NFS4 :DEBUG :Request 1: opcode 18 is OP_OPEN\r\n nfs4_op_open :STATE :DEBUG :Entering NFS v4 OPEN handler -----------------------------\r\n nfs4_op_open :STATE :DEBUG :OPEN Client id = 5963475500000001\r\n nfs_in_grace :STATE :DEBUG :NFS Server IN GRACE\r\n nfs4_op_open :NFS4 :DEBUG :open4_validate_claim failed\r\n nfs4_op_open :STATE :DEBUG :failed with status NFS4ERR_GRACE\r\n nfs4_Compound :NFS4 :DEBUG :Status of OP_OPEN in position 1 = NFS4ERR_GRACE\r\n nfs4_Compound :NFS4 :DEBUG :End status = NFS4ERR_GRACE lastindex = 1\r\n\r\ncongfig is:\r\nEXPORT\r\n{\r\n        Export_ID=1;\r\n        Path = /;\r\n        Pseudo = /volumes;\r\n        Disable_ACL = TRUE;\r\n        Access_Type = RW;\r\n        NFS_Protocols = 4;\r\n        Squash = No_Root_Squash;\r\n        Transport_Protocols = TCP;\r\n\r\n        FSAL {\r\n                Name = CEPH;\r\n                User_Id = \"cephfs\";\r\n                Secret_Access_Key = \"AQDqFilZ8VgEFBAAdRsmX+fmfKbPcirCDG4KCA==\";\r\n        }\r\n}\r\nCEPH {\r\n        ceph_conf = \"/etc/ceph/ceph.conf\";\r\n        cluster = \"ceph\";\r\n}\r\n\r\n"}}
{"actor":{"display_login":"junxfl"}}