Project

General

Profile

Actions
Copy link

Bug #5162

closed

File is locked unexpected and not released anymore

Added by joe huang almost 11 years ago. Updated almost 8 years ago.

Status:
Can't reproduce
Priority:
High
Assignee:
-
Category:
-
Target version:
v0.61 - Cuttlefish
% Done:

0%

Source:
other
Tags:
Backport:
Regression:
Severity:
3 - minor
Reviewed:
Affected Versions:
ceph-qa-suite:
Component(FS):
libcephfs
Labels (FS):
Pull request ID:
Crash signature (v1):
Crash signature (v2):

Description

I deployed a ceph cluster and mount cephfs via kernel module. After using it few days later, when I ls a particular folder it hangs there. And then I found a file(owncloud.log) in that folder(/data) was locked but no one was using it. I issued # cat data/owncloud.log (and it hangs) and MDS's log shows

2013-05-24 13:56:26.332527 7f4ac1711700 10 mds.0.server ref is [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=32 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332643 7f4ac1711700 20 mds.0.locker must rdlock (iauth excl->sync) [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332670 7f4ac1711700 20 mds.0.locker must rdlock (ilink sync) [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332703 7f4ac1711700 20 mds.0.locker must rdlock (ifile excl->xsyn) [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332721 7f4ac1711700 20 mds.0.locker must rdlock (ixattr excl->sync) [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332738 7f4ac1711700 20 mds.0.locker must rdlock (isnap sync r=32) [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332817 7f4ac1711700 10 mds.0.locker must authpin [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332837 7f4ac1711700 10 mds.0.locker must authpin [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332858 7f4ac1711700 10 mds.0.locker must authpin [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332875 7f4ac1711700 10 mds.0.locker must authpin [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.332893 7f4ac1711700 10 mds.0.locker must authpin [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333051 7f4ac1711700 10 mds.0.locker auth_pinning [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=35+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333070 7f4ac1711700 10 mds.0.cache.ino(10000000072) auth_pin by 0x5af8800 on [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80] now 36+0

2013-05-24 13:56:26.333112 7f4ac1711700 10 mds.0.locker already auth_pinned [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333131 7f4ac1711700 10 mds.0.locker already auth_pinned [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333148 7f4ac1711700 10 mds.0.locker already auth_pinned [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333165 7f4ac1711700 10 mds.0.locker already auth_pinned [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333273 7f4ac1711700 7 mds.0.locker rdlock_start on (isnap sync r=32) on [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333293 7f4ac1711700 10 mds.0.locker got rdlock on (isnap sync r=33) [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333311 7f4ac1711700 7 mds.0.locker rdlock_start on (ifile excl->xsyn) on [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333331 7f4ac1711700 7 mds.0.locker rdlock_start waiting on (ifile excl->xsyn) on [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

2013-05-24 13:56:26.333356 7f4ac1711700 10 mds.0.locker nudge_log (ifile excl->xsyn) on [inode 10000000072 [2,head] /data/owncloud.log auth v1125157 ap=36+0 s=513713224 n(v0 b513713224 1=1+0) (iauth excl->sync) (ifile excl->xsyn) (ixattr excl->sync) (iversion lock) cr={5898=0-1073741824@1} caps={5898=pAsLsXsFcb/pAsxLsXsxFscrb/pAsxXsxFsxcrwb@8,6241=pLs/-@0,6859=pLsFcb/pFscr@11},l=5898(-1) | ptrwaiter=1 request=33 lock=1 caps=1 dirty=0 waiter=1 authpin=1 0x22bbd80]

Actions
Copy link

Also available in: Atom PDF