https://tracker.ceph.com/
https://tracker.ceph.com/favicon.ico
2020-10-21T07:25:41Z
Ceph
CephFS - Bug #47918: cephfs client and nfs-ganesha have inconsistent reference count after release cache
https://tracker.ceph.com/issues/47918?journal_id=177558
2020-10-21T07:25:41Z
wei liu
<ul></ul><p>Tracing to the client logļ¼ the ll_ref is increased 2 times each time lookup parent directory(..).</p>
<pre>
@2020-10-21 16:45:27.690 7fc9fb95f700 20 client.699904984 _ll_get 0x7fca37c67400 0x10003f85239 -> 169
2020-10-21 16:45:27.690 7fc9fb95f700 10 client.699904984 fill_statx on 0x10003f85239 snap/devhead mode 040755 mtime 2020-10-14 08:49:42.560573 ctime 2020-10-14 08:49:42.560573
2020-10-21 16:45:27.690 7fc9fb95f700 3 client.699904984 ll_getattrx 0x10003f85239.head = 0
2020-10-21 16:45:27.690 7fc9fb95f700 3 client.699904984 ll_lookupx 0x10003f85239.head ..
2020-10-21 16:45:27.690 7fc9fb95f700 20 client.699904984 may_lookup 0x10003f85239.head(faked_ino=0 ref=2 ll_ref=169 cap_refs={} open={} mode=40755 size=0/0 nlink=1 btime=2020-10-13 09:52:02.759385 mtime=2020-10-14 08:49:42.560573 ctime=2020-10-14 08:49:42.560573 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c67400); UserPerm(uid: 0, gid: 0)
2020-10-21 16:45:27.690 7fc9fb95f700 10 client.699904984 _getattr mask AsXs issued=1
2020-10-21 16:45:27.690 7fc9fb95f700 3 client.699904984 may_lookup 0x7fca37c67400 = 0
2020-10-21 16:45:27.690 7fc9fb95f700 10 client.699904984 choose_target_mds resend_mds specified as mds.0
2020-10-21 16:45:27.690 7fc9fb95f700 20 client.699904984 mds is 0
2020-10-21 16:45:27.690 7fc9fb95f700 10 client.699904984 send_request rebuilding request 362280 for mds.0
2020-10-21 16:45:27.690 7fc9fb95f700 20 client.699904984 encode_cap_releases enter (req: 0x7fca2e01c100, mds: 0)
2020-10-21 16:45:27.690 7fc9fb95f700 20 client.699904984 send_request set sent_stamp to 2020-10-21 16:45:27.692342
2020-10-21 16:45:27.690 7fc9fb95f700 10 client.699904984 send_request client_request(unknown.0:362280 lookupparent #0x10003f85239 2020-10-21 16:45:27.692331 caller_uid=0, caller_gid=0{0,}) v4 to mds.0
2020-10-21 16:45:27.690 7fc9fb95f700 20 client.699904984 awaiting reply|forward|kick on 0x7fc9fb95bb50
2020-10-21 16:45:27.691 7fca35bfb700 20 client.699904984 handle_client_reply got a reply. Safe:1 tid 362280
2020-10-21 16:45:27.691 7fca35bfb700 10 client.699904984 insert_trace from 2020-10-21 16:45:27.692342 mds.0 is_target=1 is_dentry=0
2020-10-21 16:45:27.691 7fca35bfb700 10 client.699904984 features 0xffffffffffffffff
2020-10-21 16:45:27.691 7fca35bfb700 10 client.699904984 update_snap_trace len 48
2020-10-21 16:45:27.691 7fca35bfb700 20 client.699904984 get_snap_realm 0x1 0x7fca37c281e0 40 -> 41
2020-10-21 16:45:27.691 7fca35bfb700 10 client.699904984 update_snap_trace snaprealm(0x1 nref=41 c=0 seq=1 parent=0x0 my_snaps=[] cached_snapc=1=[]) seq 1 <= 1 and same parent, SKIPPING
2020-10-21 16:45:27.691 7fca35bfb700 10 client.699904984 hrm is_target=1 is_dentry=0
2020-10-21 16:45:27.691 7fca35bfb700 12 client.699904984 add_update_inode had 0x10003bf25b7.head(faked_ino=0 ref=2 ll_ref=38 cap_refs={} open={} mode=40777 size=0/0 nlink=1 btime=2020-09-14 17:08:42.822517 mtime=2020-10-13 11:01:31.986878 ctime=2020-10-13 11:01:31.986878 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c64400) caps pAsLsXsFs
2020-10-21 16:45:27.691 7fca35bfb700 20 client.699904984 dir hash is 2
2020-10-21 16:45:27.691 7fca35bfb700 10 client.699904984 add_update_cap issued pAsLsXsFs -> pAsLsXsFs from mds.0 on 0x10003bf25b7.head(faked_ino=0 ref=2 ll_ref=38 cap_refs={} open={} mode=40777 size=0/0 nlink=1 btime=2020-09-14 17:08:42.822517 mtime=2020-10-13 11:01:31.986878 ctime=2020-10-13 11:01:31.986878 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c64400)
2020-10-21 16:45:27.692 7fca35bfb700 15 inode.get on 0x7fca37c64400 0x10003bf25b7.head now 3
2020-10-21 16:45:27.692 7fca35bfb700 20 client.699904984 put_snap_realm 0x1 0x7fca37c281e0 41 -> 40
2020-10-21 16:45:27.692 7fca35bfb700 15 inode.get on 0x7fca37c64400 0x10003bf25b7.head now 4
2020-10-21 16:45:27.692 7fca35bfb700 20 client.699904984 handle_client_reply signalling caller 0x7fc9fb95bb50
2020-10-21 16:45:27.692 7fca35bfb700 20 client.699904984 handle_client_reply awaiting kickback on tid 362280 0x7fca35bf8730
2020-10-21 16:45:27.692 7fc9fb95f700 20 client.699904984 sendrecv kickback on tid 362280 0x7fca35bf8730
2020-10-21 16:45:27.692 7fc9fb95f700 15 inode.get on 0x7fca37c64400 0x10003bf25b7.head now 5
2020-10-21 16:45:27.692 7fc9fb95f700 20 client.699904984 make_request target is 0x10003bf25b7.head(faked_ino=0 ref=5 ll_ref=38 cap_refs={} open={} mode=40777 size=0/0 nlink=1 btime=2020-09-14 17:08:42.822517 mtime=2020-10-13 11:01:31.986878 ctime=2020-10-13 11:01:31.986878 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c64400)
2020-10-21 16:45:27.692 7fc9fb95f700 20 client.699904984 lat 0.001131
2020-10-21 16:45:27.692 7fc9fb95f700 20 client.699904984 _ll_get 0x7fca37c64400 0x10003bf25b7 -> 39
2020-10-21 16:45:27.692 7fc9fb95f700 15 inode.get on 0x7fca37c64400 0x10003bf25b7.head now 6
2020-10-21 16:45:27.692 7fc9fb95f700 8 client.699904984 _lookup found target 0x10003bf25b7
2020-10-21 16:45:27.692 7fc9fb95f700 10 client.699904984 put_inode on 0x10003bf25b7.head(faked_ino=0 ref=6 ll_ref=39 cap_refs={} open={} mode=40777 size=0/0 nlink=1 btime=2020-09-14 17:08:42.822517 mtime=2020-10-13 11:01:31.986878 ctime=2020-10-13 11:01:31.986878 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c64400)
2020-10-21 16:45:27.692 7fc9fb95f700 15 inode.put on 0x7fca37c64400 0x10003bf25b7.head now 5
2020-10-21 16:45:27.692 7fc9fb95f700 10 client.699904984 _lookup 0x10003f85239.head(faked_ino=0 ref=2 ll_ref=169 cap_refs={} open={} mode=40755 size=0/0 nlink=1 btime=2020-10-13 09:52:02.759385 mtime=2020-10-14 08:49:42.560573 ctime=2020-10-14 08:49:42.560573 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c67400) .. = 0x10003bf25b7.head(faked_ino=0 ref=5 ll_ref=39 cap_refs={} open={} mode=40777 size=0/0 nlink=1 btime=2020-09-14 17:08:42.822517 mtime=2020-10-13 11:01:31.986878 ctime=2020-10-13 11:01:31.986878 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c64400)
2020-10-21 16:45:27.692 7fc9fb95f700 10 client.699904984 fill_statx on 0x10003bf25b7 snap/devhead mode 040777 mtime 2020-10-13 11:01:31.986878 ctime 2020-10-13 11:01:31.986878
2020-10-21 16:45:27.692 7fc9fb95f700 20 client.699904984 _ll_get 0x7fca37c64400 0x10003bf25b7 -> 40
2020-10-21 16:45:27.692 7fc9fb95f700 3 client.699904984 ll_lookupx 0x10003f85239.head .. -> 0 (10003bf25b7)
2020-10-21 16:45:27.692 7fc9fb95f700 10 client.699904984 put_inode on 0x10003bf25b7.head(faked_ino=0 ref=5 ll_ref=40 cap_refs={} open={} mode=40777 size=0/0 nlink=1 btime=2020-09-14 17:08:42.822517 mtime=2020-10-13 11:01:31.986878 ctime=2020-10-13 11:01:31.986878 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c64400)
2020-10-21 16:45:27.692 7fc9fb95f700 15 inode.put on 0x7fca37c64400 0x10003bf25b7.head now 4
2020-10-21 16:45:27.692 7fca35bfb700 10 client.699904984 put_inode on 0x10003bf25b7.head(faked_ino=0 ref=4 ll_ref=40 cap_refs={} open={} mode=40777 size=0/0 nlink=1 btime=2020-09-14 17:08:42.822517 mtime=2020-10-13 11:01:31.986878 ctime=2020-10-13 11:01:31.986878 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c64400)
2020-10-21 16:45:27.692 7fca35bfb700 15 inode.put on 0x7fca37c64400 0x10003bf25b7.head now 3
2020-10-21 16:45:27.692 7fca35bfb700 10 client.699904984 put_inode on 0x10003bf25b7.head(faked_ino=0 ref=3 ll_ref=40 cap_refs={} open={} mode=40777 size=0/0 nlink=1 btime=2020-09-14 17:08:42.822517 mtime=2020-10-13 11:01:31.986878 ctime=2020-10-13 11:01:31.986878 caps=pAsLsXsFs(0=pAsLsXsFs) 0x7fca37c64400)
2020-10-21 16:45:27.692 7fca35bfb700 15 inode.put on 0x7fca37c64400 0x10003bf25b7.head now 2
2020-10-21 16:45:27.692 7fc9fb95f700 8 client.699904984 _ll_forget 0x10003bf25b7 1
2020-10-21 16:45:27.692 7fc9fb95f700 20 client.699904984 _ll_put 0x7fca37c64400 0x10003bf25b7 1 -> 39
2020-10-21 16:45:27.693 7fc9a49fd700 10 client.699904984 fill_statx on 0x10003bf25b7 snap/devhead mode 040777 mtime 2020-10-13 11:01:31.986878 ctime 2020-10-13 11:01:31.986878
2020-10-21 16:45:27.693 7fc9a49fd700 3 client.699904984 ll_getattrx 0x10003bf25b7.head = 0
2020-10-21 16:45:27.693 7fc9a49fd700 10 client.699904984 fill_statx on 0x10003bf25b7 snap/devhead mode 040777 mtime 2020-10-13 11:01:31.986878 ctime 2020-10-13 11:01:31.986878
2020-10-21 16:45:27.693 7fc9a49fd700 3 client.699904984 ll_getattrx 0x10003bf25b7.head = 0
2020-10-21 16:45:27.898 7fca373fe700 20 client.699904984 trim_cache size 16 max 0
2020-10-21 16:45:28.898 7fca373fe700 20 client.699904984 trim_cache size 16 max 0
2020-10-21 16:45:29.653 7fca403ff700 8 client.699904984 _ll_forget 0x10003f85239 1
2020-10-21 16:45:29.653 7fca403ff700 20 client.699904984 _ll_put 0x7fca37c67400 0x10003f85239 1 -> 168@
</pre>
CephFS - Bug #47918: cephfs client and nfs-ganesha have inconsistent reference count after release cache
https://tracker.ceph.com/issues/47918?journal_id=177610
2020-10-21T14:46:11Z
Patrick Donnelly
pdonnell@redhat.com
<ul><li><strong>Status</strong> changed from <i>New</i> to <i>Fix Under Review</i></li><li><strong>Assignee</strong> set to <i>wei liu</i></li><li><strong>Source</strong> set to <i>Community (dev)</i></li><li><strong>Backport</strong> set to <i>octopus,nautilus</i></li><li><strong>Pull request ID</strong> set to <i>37735</i></li></ul>
CephFS - Bug #47918: cephfs client and nfs-ganesha have inconsistent reference count after release cache
https://tracker.ceph.com/issues/47918?journal_id=177901
2020-10-25T23:28:23Z
Patrick Donnelly
pdonnell@redhat.com
<ul><li><strong>Status</strong> changed from <i>Fix Under Review</i> to <i>Pending Backport</i></li></ul>
CephFS - Bug #47918: cephfs client and nfs-ganesha have inconsistent reference count after release cache
https://tracker.ceph.com/issues/47918?journal_id=177919
2020-10-26T10:33:06Z
Nathan Cutler
ncutler@suse.cz
<ul><li><strong>Copied to</strong> <i><a class="issue tracker-9 status-3 priority-4 priority-default closed" href="/issues/47988">Backport #47988</a>: nautilus: cephfs client and nfs-ganesha have inconsistent reference count after release cache</i> added</li></ul>
CephFS - Bug #47918: cephfs client and nfs-ganesha have inconsistent reference count after release cache
https://tracker.ceph.com/issues/47918?journal_id=177921
2020-10-26T10:33:13Z
Nathan Cutler
ncutler@suse.cz
<ul><li><strong>Copied to</strong> <i><a class="issue tracker-9 status-3 priority-4 priority-default closed" href="/issues/47989">Backport #47989</a>: octopus: cephfs client and nfs-ganesha have inconsistent reference count after release cache</i> added</li></ul>
CephFS - Bug #47918: cephfs client and nfs-ganesha have inconsistent reference count after release cache
https://tracker.ceph.com/issues/47918?journal_id=180393
2020-12-02T11:06:45Z
Nathan Cutler
ncutler@suse.cz
<ul><li><strong>Status</strong> changed from <i>Pending Backport</i> to <i>Resolved</i></li></ul><p>While running with --resolve-parent, the script "backport-create-issue" noticed that all backports of this issue are in status "Resolved" or "Rejected".</p>