Project

General

Profile

Bug #38452 » ceph-mds-0x100000367e5.log

Jérôme Poulin, 03/05/2019 02:49 PM

 
2019-03-04 09:09:50.426 7f58f478a700 12 mds.0.cache.dir(0x1000000088b.1*) link_primary_inode [dentry #0x1/pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst [head,head] auth NULL (dversion lock) v=145784 ino=(nil) state=1610612800|bottomlru | dirty=1 0x559340cc6a00] [inode 0x100000367e5 [head,head] #100000367e5 auth v145784 s=193 n(v0 rc2019-03-03 21:27:34.226503)/n() (iversion lock) 0x55933ec87100]
2019-03-04 09:09:50.426 7f58f478a700 10 mds.0.journal EMetaBlob.replay added [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145784 s=193 n(v0 rc2019-03-03 21:27:34.226503)/n() (iversion lock) 0x55933ec87100]
2019-03-04 09:09:50.426 7f58f478a700 10 mds.0.journal EMetaBlob.replay for [head,head] had [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) | dirty=1 0x55933ec87100]
2019-03-04 09:09:58.986 7f58f5f8d700 10 mds.0.cache.dir(0x1000000088b.1*) _fetched had underwater inode [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) | dirty=1 0x55933ec87100], marking clean
2019-03-04 09:09:58.986 7f58f5f8d700 10 mds.0.cache.ino(0x100000367e5) mark_clean [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) | dirty=1 0x55933ec87100]
2019-03-04 09:09:59.118 7f58f5f8d700 15 mds.0.cache chose lock states on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) 0x55933ec87100]
2019-03-04 09:09:59.262 7f58f5f8d700 7 mds.0.cache inode [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) 0x55933ec87100]
2019-03-04 09:10:00.843 7f58fbf99700 20 mds.0.locker check_inode_max_size no-op on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) 0x55933ec87100]
2019-03-04 09:10:01.787 7f58fbf99700 12 mds.0.server including inode [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) 0x55933ec87100]
2019-03-04 09:10:06.215 7f58fbf99700 12 mds.0.server including inode [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker process_cap_release client.25558652 pAsXsFscr on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsLsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker eval 2496 [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 7 mds.0.locker file_eval wanted= loner_wanted= other_wanted= filelock=(ifile sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker simple_eval (iauth sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker simple_eval (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker simple_eval (ixattr sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 7 mds.0.server dn links to [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 20 mds.0.locker must xlock (ilink sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 20 mds.0.locker must xlock (isnap sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 20 mds.0.locker must wrlock (iversion lock) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker must authpin [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker auth_pinning [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_pin by 0x55933e20d700 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100] now 1+0
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.server dn links to [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must xlock (ilink sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must xlock (isnap sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must wrlock (iversion lock) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker must authpin [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker already auth_pinned [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker local_wrlock_start on (iversion lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker got wrlock on (iversion lock w=1 last_client=25558652) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker xlock_start on (isnap sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_lock on (isnap sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_xlock on (isnap lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_pin by 0x55933ec87740 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100] now 2+0
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker got xlock on (isnap xlock x=1 by 0x55933e20d700) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker xlock_start on (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_lock on (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker issue_caps allowed=pAsXsFscr, xlocker allowed=pAsXsFscr on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_pin by 0x55933ec87690 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFscr/pAsLsXsFscr/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100] now 3+0
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker nudge_log (ilink sync->lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFscr/pAsLsXsFscr/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker head inode [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFscr/pAsLsXsFscr/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker follows 0 retains pAsXsFs dirty - on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker _do_cap_update dirty - issued pAsXsFs wanted - on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker eval 2496 [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker file_eval wanted= loner_wanted= other_wanted= filelock=(ifile sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker simple_eval (iauth sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker eval_gather (ilink sync->lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker eval_gather finished gather on (ilink sync->lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_unpin by 0x55933ec87690 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100] now 2+0
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker simple_eval (ilink lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_eval stable, syncing (ilink lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_sync on (ilink lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker simple_eval (ixattr sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.server dn links to [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must xlock (ilink sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must xlock (isnap xlock x=1 by 0x55933e20d700) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must wrlock (iversion lock w=1 last_client=25558652) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker must authpin [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker already auth_pinned [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker already wrlocked (iversion lock w=1 last_client=25558652) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker already xlocked (isnap xlock x=1 by 0x55933e20d700) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker xlock_start on (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_lock on (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_xlock on (ilink lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_pin by 0x55933ec87690 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100] now 3+0
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker got xlock on (ilink xlock x=1 by 0x55933e20d700) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 15 mds.0.cache.ino(0x100000367e5) project_inode 0x100000367e5
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache predirty_journal_parents do_parent_mtime linkunlink=-1 primary_dn follows head [inode 0x100000367e5 [head,head] ~mds0/stray0/100000367e5 auth v145794 pv1140595 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache projected_rstat_inode_to_frag first head linkunlink -1 [inode 0x100000367e5 [head,head] ~mds0/stray0/100000367e5 auth v145794 pv1140595 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache predirty_journal_parents do_parent_mtime linkunlink=1 primary_dn follows head [inode 0x100000367e5 [head,head] ~mds0/stray0/100000367e5 auth v145794 pv1140595 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache projected_rstat_inode_to_frag first head linkunlink 1 [inode 0x100000367e5 [head,head] ~mds0/stray0/100000367e5 auth v145794 pv1140595 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-543> 2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker process_cap_release client.25558652 pAsXsFscr on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsLsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-541> 2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker eval 2496 [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-540> 2019-03-04 09:10:07.115 7f58fbf99700 7 mds.0.locker file_eval wanted= loner_wanted= other_wanted= filelock=(ifile sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-539> 2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker simple_eval (iauth sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-538> 2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker simple_eval (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-537> 2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker simple_eval (ixattr sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-520> 2019-03-04 09:10:07.115 7f58fbf99700 7 mds.0.server dn links to [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-512> 2019-03-04 09:10:07.115 7f58fbf99700 20 mds.0.locker must xlock (ilink sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-511> 2019-03-04 09:10:07.115 7f58fbf99700 20 mds.0.locker must xlock (isnap sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-508> 2019-03-04 09:10:07.115 7f58fbf99700 20 mds.0.locker must wrlock (iversion lock) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-496> 2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker must authpin [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-487> 2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.locker auth_pinning [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 0x55933ec87100]
-486> 2019-03-04 09:10:07.115 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_pin by 0x55933e20d700 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100] now 1+0
-389> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.server dn links to [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
-386> 2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must xlock (ilink sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
-385> 2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must xlock (isnap sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
-382> 2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must wrlock (iversion lock) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
-370> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker must authpin [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
-362> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker already auth_pinned [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
-340> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker local_wrlock_start on (iversion lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | caps=1 dirty=0 authpin=1 0x55933ec87100]
-339> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker got wrlock on (iversion lock w=1 last_client=25558652) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100]
-338> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker xlock_start on (isnap sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100]
-337> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_lock on (isnap sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100]
-336> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_xlock on (isnap lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=1+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100]
-335> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_pin by 0x55933ec87740 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=1 caps=1 dirty=0 authpin=1 0x55933ec87100] now 2+0
-333> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker got xlock on (isnap xlock x=1 by 0x55933e20d700) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100]
-332> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker xlock_start on (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100]
-331> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_lock on (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100]
-330> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker issue_caps allowed=pAsXsFscr, xlocker allowed=pAsXsFscr on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsLsXsFscr/-@1,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100]
-323> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_pin by 0x55933ec87690 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFscr/pAsLsXsFscr/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 authpin=1 0x55933ec87100] now 3+0
-319> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker nudge_log (ilink sync->lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFscr/pAsLsXsFscr/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
-310> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker head inode [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFscr/pAsLsXsFscr/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
-309> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker follows 0 retains pAsXsFs dirty - on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
-308> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker _do_cap_update dirty - issued pAsXsFs wanted - on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
-306> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker eval 2496 [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
-305> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker file_eval wanted= loner_wanted= other_wanted= filelock=(ifile sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
-304> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker simple_eval (iauth sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
-303> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker eval_gather (ilink sync->lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
-301> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker eval_gather finished gather on (ilink sync->lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink sync->lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100]
-300> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_unpin by 0x55933ec87690 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=1 authpin=1 0x55933ec87100] now 2+0
-298> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker simple_eval (ilink lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-297> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_eval stable, syncing (ilink lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-296> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_sync on (ilink lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-295> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker simple_eval (ixattr sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-286> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.server dn links to [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-283> 2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must xlock (ilink sync) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-282> 2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must xlock (isnap xlock x=1 by 0x55933e20d700) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-279> 2019-03-04 09:10:07.119 7f58fbf99700 20 mds.0.locker must wrlock (iversion lock w=1 last_client=25558652) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-267> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker must authpin [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-259> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker already auth_pinned [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-240> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker already wrlocked (iversion lock w=1 last_client=25558652) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-239> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker already xlocked (isnap xlock x=1 by 0x55933e20d700) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-238> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker xlock_start on (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-237> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_lock on (ilink sync) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-236> 2019-03-04 09:10:07.119 7f58fbf99700 7 mds.0.locker simple_xlock on (ilink lock) on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=2+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-235> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache.ino(0x100000367e5) auth_pin by 0x55933ec87690 on [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink lock) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=2 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100] now 3+0
-233> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.locker got xlock on (ilink xlock x=1 by 0x55933e20d700) [inode 0x100000367e5 [head,head] /pg_xlog_archives/9.6/smobile/000000200000002C000000BB.00000028.backup.zst auth v145794 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-225> 2019-03-04 09:10:07.119 7f58fbf99700 15 mds.0.cache.ino(0x100000367e5) project_inode 0x100000367e5
-221> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache predirty_journal_parents do_parent_mtime linkunlink=-1 primary_dn follows head [inode 0x100000367e5 [head,head] ~mds0/stray0/100000367e5 auth v145794 pv1140595 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-213> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache projected_rstat_inode_to_frag first head linkunlink -1 [inode 0x100000367e5 [head,head] ~mds0/stray0/100000367e5 auth v145794 pv1140595 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-73> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache predirty_journal_parents do_parent_mtime linkunlink=1 primary_dn follows head [inode 0x100000367e5 [head,head] ~mds0/stray0/100000367e5 auth v145794 pv1140595 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
-65> 2019-03-04 09:10:07.119 7f58fbf99700 10 mds.0.cache projected_rstat_inode_to_frag first head linkunlink 1 [inode 0x100000367e5 [head,head] ~mds0/stray0/100000367e5 auth v145794 pv1140595 ap=3+0 s=193 n(v0 rc2019-03-03 21:27:34.226503) (ilink xlock x=1 by 0x55933e20d700) (iversion lock w=1 last_client=25558652) caps={25432667=pAsXsFs/-@2,25558652=pAsXsFscr/-@1} | request=1 lock=3 caps=1 dirty=0 waiter=0 authpin=1 0x55933ec87100]
(4-4/5)