Project

General

Profile

Bug #45078 » mds.2.log.txt

Hikscale Team, 04/14/2020 06:43 AM

 
2020-04-12 23:24:29.271 7f5db84db700 10 mds.2.locker scatter_nudge replica, requesting scatter/unscatter of (inest mix->lock w=1 dirty) on [inode 0x1000005b571 [...10,head] /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ rep@0.1 fragtree_t(*^3) v327772 f(v17237 m2020-04-12 13:12:59.364283 29995=29988+7) n(v253 rc2020-04-12 12:20:33.179743 b3966471078483 user0 b3966471078483 user0 f29999 user0 d8 group0 b3966471078483 group0 f29999 group0 d8 30007=29999+8)/n(v252 rc2020-04-12 12:15:05.813743 b3966584925193 user0 b3966584925193 user0 f30000 user0 d8 group0 b3966584925193 group0 f30000 group0 d8 30008=30000+8) (iauth sync r=1) (isnap sync r=1) (inest mix->lock w=1 dirty) (ifile mix->sync w=1) (iversion lock) | dirtyscattered=1 request=1 lock=4 dirfrag=4 caps=0 dirwaiter=0 waiter=1 discoverbase=0 0x56277906c100]
2020-04-12 23:24:29.271 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800]
2020-04-12 23:24:29.271 7f5db84db700 10 mds.2.cache |____ 1 rep [dir 0x1000005b571.000* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] rep@1.1 REP dir_auth=1 state=0 f(v17235 m2020-04-12 12:20:33.179743 3645=3645+0) n(v252 rc2020-04-12 12:20:33.179743 b469143381258 user0 b469143381258 user0 f3645 group0 b469143381258 group0 f3645 3645=3645+0)/n(v252 rc2020-04-12 12:15:05.813743 b469276707494 user0 b469276707494 user0 f3647 group0 b469276707494 group0 f3647 3647=3647+0) hs=0+0,ss=0+0 | dnwaiter=0 child=0 subtree=1 0x562778a6c800]
2020-04-12 23:24:29.271 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 ap=0+2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+1,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000]
2020-04-12 23:24:29.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800]
2020-04-12 23:24:30.301 7f5dbaf69700 15 mds.2.bal map: i imported [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800] from 0
2020-04-12 23:24:30.301 7f5dbaf69700 15 mds.2.bal map: i imported [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 ap=0+2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+1,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000] from 0
2020-04-12 23:24:30.301 7f5dbaf69700 15 mds.2.bal map: i imported [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800] from 0
2020-04-12 23:24:34.271 7f5db84db700 10 mds.2.locker scatter_nudge replica, requesting scatter/unscatter of (inest mix->lock w=1 dirty) on [inode 0x1000005b571 [...10,head] /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ rep@0.1 fragtree_t(*^3) v327772 f(v17237 m2020-04-12 13:12:59.364283 29995=29988+7) n(v253 rc2020-04-12 12:20:33.179743 b3966471078483 user0 b3966471078483 user0 f29999 user0 d8 group0 b3966471078483 group0 f29999 group0 d8 30007=29999+8)/n(v252 rc2020-04-12 12:15:05.813743 b3966584925193 user0 b3966584925193 user0 f30000 user0 d8 group0 b3966584925193 group0 f30000 group0 d8 30008=30000+8) (iauth sync r=1) (isnap sync r=1) (inest mix->lock w=1 dirty) (ifile mix->sync w=1) (iversion lock) | dirtyscattered=1 request=1 lock=4 dirfrag=4 caps=0 dirwaiter=0 waiter=1 discoverbase=0 0x56277906c100]
2020-04-12 23:24:34.271 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800]
2020-04-12 23:24:34.271 7f5db84db700 10 mds.2.cache |____ 1 rep [dir 0x1000005b571.000* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] rep@1.1 REP dir_auth=1 state=0 f(v17235 m2020-04-12 12:20:33.179743 3645=3645+0) n(v252 rc2020-04-12 12:20:33.179743 b469143381258 user0 b469143381258 user0 f3645 group0 b469143381258 group0 f3645 3645=3645+0)/n(v252 rc2020-04-12 12:15:05.813743 b469276707494 user0 b469276707494 user0 f3647 group0 b469276707494 group0 f3647 3647=3647+0) hs=0+0,ss=0+0 | dnwaiter=0 child=0 subtree=1 0x562778a6c800]
2020-04-12 23:24:34.271 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 ap=0+2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+1,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000]
2020-04-12 23:24:34.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800]
2020-04-12 23:24:39.271 7f5db84db700 10 mds.2.locker scatter_nudge replica, requesting scatter/unscatter of (inest mix->lock w=1 dirty) on [inode 0x1000005b571 [...10,head] /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ rep@0.1 fragtree_t(*^3) v327772 f(v17237 m2020-04-12 13:12:59.364283 29995=29988+7) n(v253 rc2020-04-12 12:20:33.179743 b3966471078483 user0 b3966471078483 user0 f29999 user0 d8 group0 b3966471078483 group0 f29999 group0 d8 30007=29999+8)/n(v252 rc2020-04-12 12:15:05.813743 b3966584925193 user0 b3966584925193 user0 f30000 user0 d8 group0 b3966584925193 group0 f30000 group0 d8 30008=30000+8) (iauth sync r=1) (isnap sync r=1) (inest mix->lock w=1 dirty) (ifile mix->sync w=1) (iversion lock) | dirtyscattered=1 request=1 lock=4 dirfrag=4 caps=0 dirwaiter=0 waiter=1 discoverbase=0 0x56277906c100]
2020-04-12 23:24:39.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800]
2020-04-12 23:24:39.272 7f5db84db700 10 mds.2.cache |____ 1 rep [dir 0x1000005b571.000* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] rep@1.1 REP dir_auth=1 state=0 f(v17235 m2020-04-12 12:20:33.179743 3645=3645+0) n(v252 rc2020-04-12 12:20:33.179743 b469143381258 user0 b469143381258 user0 f3645 group0 b469143381258 group0 f3645 3645=3645+0)/n(v252 rc2020-04-12 12:15:05.813743 b469276707494 user0 b469276707494 user0 f3647 group0 b469276707494 group0 f3647 3647=3647+0) hs=0+0,ss=0+0 | dnwaiter=0 child=0 subtree=1 0x562778a6c800]
2020-04-12 23:24:39.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 ap=0+2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+1,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000]
2020-04-12 23:24:39.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800]
2020-04-12 23:24:44.271 7f5db84db700 10 mds.2.locker scatter_nudge replica, requesting scatter/unscatter of (inest mix->lock w=1 dirty) on [inode 0x1000005b571 [...10,head] /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ rep@0.1 fragtree_t(*^3) v327772 f(v17237 m2020-04-12 13:12:59.364283 29995=29988+7) n(v253 rc2020-04-12 12:20:33.179743 b3966471078483 user0 b3966471078483 user0 f29999 user0 d8 group0 b3966471078483 group0 f29999 group0 d8 30007=29999+8)/n(v252 rc2020-04-12 12:15:05.813743 b3966584925193 user0 b3966584925193 user0 f30000 user0 d8 group0 b3966584925193 group0 f30000 group0 d8 30008=30000+8) (iauth sync r=1) (isnap sync r=1) (inest mix->lock w=1 dirty) (ifile mix->sync w=1) (iversion lock) | dirtyscattered=1 request=1 lock=4 dirfrag=4 caps=0 dirwaiter=0 waiter=1 discoverbase=0 0x56277906c100]
2020-04-12 23:24:44.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800]
2020-04-12 23:24:44.272 7f5db84db700 10 mds.2.cache |____ 1 rep [dir 0x1000005b571.000* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] rep@1.1 REP dir_auth=1 state=0 f(v17235 m2020-04-12 12:20:33.179743 3645=3645+0) n(v252 rc2020-04-12 12:20:33.179743 b469143381258 user0 b469143381258 user0 f3645 group0 b469143381258 group0 f3645 3645=3645+0)/n(v252 rc2020-04-12 12:15:05.813743 b469276707494 user0 b469276707494 user0 f3647 group0 b469276707494 group0 f3647 3647=3647+0) hs=0+0,ss=0+0 | dnwaiter=0 child=0 subtree=1 0x562778a6c800]
2020-04-12 23:24:44.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 ap=0+2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+1,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000]
2020-04-12 23:24:44.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800]
2020-04-12 23:24:45.301 7f5dbaf69700 15 mds.2.bal map: i imported [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800] from 0
2020-04-12 23:24:45.301 7f5dbaf69700 15 mds.2.bal map: i imported [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 ap=0+2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+1,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000] from 0
2020-04-12 23:24:45.301 7f5dbaf69700 15 mds.2.bal map: i imported [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800] from 0
2020-04-12 23:24:49.272 7f5db84db700 10 mds.2.locker scatter_nudge replica, requesting scatter/unscatter of (inest mix->lock w=1 dirty) on [inode 0x1000005b571 [...10,head] /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ rep@0.1 fragtree_t(*^3) v327772 f(v17237 m2020-04-12 13:12:59.364283 29995=29988+7) n(v253 rc2020-04-12 12:20:33.179743 b3966471078483 user0 b3966471078483 user0 f29999 user0 d8 group0 b3966471078483 group0 f29999 group0 d8 30007=29999+8)/n(v252 rc2020-04-12 12:15:05.813743 b3966584925193 user0 b3966584925193 user0 f30000 user0 d8 group0 b3966584925193 group0 f30000 group0 d8 30008=30000+8) (iauth sync r=1) (isnap sync r=1) (inest mix->lock w=1 dirty) (ifile mix->sync w=1) (iversion lock) | dirtyscattered=1 request=1 lock=4 dirfrag=4 caps=0 dirwaiter=0 waiter=1 discoverbase=0 0x56277906c100]
2020-04-12 23:24:49.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800]
2020-04-12 23:24:49.272 7f5db84db700 10 mds.2.cache |____ 1 rep [dir 0x1000005b571.000* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] rep@1.1 REP dir_auth=1 state=0 f(v17235 m2020-04-12 12:20:33.179743 3645=3645+0) n(v252 rc2020-04-12 12:20:33.179743 b469143381258 user0 b469143381258 user0 f3645 group0 b469143381258 group0 f3645 3645=3645+0)/n(v252 rc2020-04-12 12:15:05.813743 b469276707494 user0 b469276707494 user0 f3647 group0 b469276707494 group0 f3647 3647=3647+0) hs=0+0,ss=0+0 | dnwaiter=0 child=0 subtree=1 0x562778a6c800]
2020-04-12 23:24:49.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 ap=0+2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+1,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000]
2020-04-12 23:24:49.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800]
2020-04-12 23:24:54.272 7f5db84db700 10 mds.2.locker scatter_nudge replica, requesting scatter/unscatter of (inest mix->lock w=1 dirty) on [inode 0x1000005b571 [...10,head] /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ rep@0.1 fragtree_t(*^3) v327772 f(v17237 m2020-04-12 13:12:59.364283 29995=29988+7) n(v253 rc2020-04-12 12:20:33.179743 b3966471078483 user0 b3966471078483 user0 f29999 user0 d8 group0 b3966471078483 group0 f29999 group0 d8 30007=29999+8)/n(v252 rc2020-04-12 12:15:05.813743 b3966584925193 user0 b3966584925193 user0 f30000 user0 d8 group0 b3966584925193 group0 f30000 group0 d8 30008=30000+8) (iauth sync r=1) (isnap sync r=1) (inest mix->lock w=1 dirty) (ifile mix->sync w=1) (iversion lock) | dirtyscattered=1 request=1 lock=4 dirfrag=4 caps=0 dirwaiter=0 waiter=1 discoverbase=0 0x56277906c100]
2020-04-12 23:24:54.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800]
2020-04-12 23:24:54.272 7f5db84db700 10 mds.2.cache |____ 1 rep [dir 0x1000005b571.000* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] rep@1.1 REP dir_auth=1 state=0 f(v17235 m2020-04-12 12:20:33.179743 3645=3645+0) n(v252 rc2020-04-12 12:20:33.179743 b469143381258 user0 b469143381258 user0 f3645 group0 b469143381258 group0 f3645 3645=3645+0)/n(v252 rc2020-04-12 12:15:05.813743 b469276707494 user0 b469276707494 user0 f3647 group0 b469276707494 group0 f3647 3647=3647+0) hs=0+0,ss=0+0 | dnwaiter=0 child=0 subtree=1 0x562778a6c800]
2020-04-12 23:24:54.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 ap=0+2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+1,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000]
2020-04-12 23:24:54.272 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610612737|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800]
2020-04-13 00:18:04.323 7f5db84db700 0 log_channel(cluster) log [WRN] : slow request 34.676115 seconds old, received at 2020-04-13 00:17:29.648169: client_request(client.383799584:249 mkdir #0x1000005b571/file26558 2020-04-12 16:21:11.423867 caller_uid=0, caller_gid=0{}) currently failed to wrlock, waiting
2020-04-13 00:18:34.323 7f5db84db700 0 log_channel(cluster) log [WRN] : slow request 64.676554 seconds old, received at 2020-04-13 00:17:29.648169: client_request(client.383799584:249 mkdir #0x1000005b571/file26558 2020-04-12 16:21:11.423867 caller_uid=0, caller_gid=0{}) currently failed to wrlock, waiting
2020-04-13 00:19:34.324 7f5db84db700 0 log_channel(cluster) log [WRN] : slow request 124.677426 seconds old, received at 2020-04-13 00:17:29.648169: client_request(client.383799584:249 mkdir #0x1000005b571/file26558 2020-04-12 16:21:11.423867 caller_uid=0, caller_gid=0{}) currently failed to wrlock, waiting
2020-04-13 00:21:34.327 7f5db84db700 0 log_channel(cluster) log [WRN] : slow request 244.680548 seconds old, received at 2020-04-13 00:17:29.648169: client_request(client.383799584:249 mkdir #0x1000005b571/file26558 2020-04-12 16:21:11.423867 caller_uid=0, caller_gid=0{}) currently failed to wrlock, waiting
2020-04-13 00:25:34.331 7f5db84db700 0 log_channel(cluster) log [WRN] : slow request 484.684018 seconds old, received at 2020-04-13 00:17:29.648169: client_request(client.383799584:249 mkdir #0x1000005b571/file26558 2020-04-12 16:21:11.423867 caller_uid=0, caller_gid=0{}) currently failed to wrlock, waiting
2020-04-13 00:26:09.331 7f5db84db700 0 log_channel(cluster) log [WRN] : slow request 7684.940179 seconds old, received at 2020-04-12 22:18:04.392458: client_request(client.383464847:258 mkdir #0x1000005b571/file26558 2020-04-12 14:23:53.645897 caller_uid=0, caller_gid=0{}) currently cleaned up request
2020-04-13 00:33:34.337 7f5db84db700 0 log_channel(cluster) log [WRN] : slow request 964.690763 seconds old, received at 2020-04-13 00:17:29.648169: client_request(client.383799584:249 mkdir #0x1000005b571/file26558 2020-04-12 16:21:11.423867 caller_uid=0, caller_gid=0{}) currently cleaned up request
2020-04-13 00:43:24.347 7f5db84db700 10 mds.2.locker scatter_nudge replica, requesting scatter/unscatter of (inest mix->lock w=1 dirty) on [inode 0x1000005b571 [...10,head] /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ rep@0.1 fragtree_t(*^3) v327772 f(v17237 m2020-04-12 13:12:59.364283 29995=29988+7) n(v253 rc2020-04-12 12:20:33.179743 b3966471078483 user0 b3966471078483 user0 f29999 user0 d8 group0 b3966471078483 group0 f29999 group0 d8 30007=29999+8)/n(v252 rc2020-04-12 12:15:05.813743 b3966584925193 user0 b3966584925193 user0 f30000 user0 d8 group0 b3966584925193 group0 f30000 group0 d8 30008=30000+8) (inest mix->lock w=1 dirty) (iversion lock) caps={383430026=pAsLsXsFs/-@9,383776451=pAsLsXsFs/-@9} | dirtyscattered=1 request=0 lock=1 dirfrag=4 caps=1 dirwaiter=0 waiter=1 discoverbase=0 0x56277906c100]
2020-04-13 00:43:24.347 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610874881|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800]
2020-04-13 00:43:24.347 7f5db84db700 10 mds.2.cache |____ 1 rep [dir 0x1000005b571.000* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] rep@1.1 REP dir_auth=1 state=0 f(v17235 m2020-04-12 12:20:33.179743 3645=3645+0) n(v252 rc2020-04-12 12:20:33.179743 b469143381258 user0 b469143381258 user0 f3645 group0 b469143381258 group0 f3645 3645=3645+0)/n(v252 rc2020-04-12 12:15:05.813743 b469276707494 user0 b469276707494 user0 f3647 group0 b469276707494 group0 f3647 3647=3647+0) hs=0+0,ss=0+0 | dnwaiter=0 child=0 subtree=1 0x562778a6c800]
2020-04-13 00:43:24.347 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000]
2020-04-13 00:43:24.348 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610874881|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800]
2020-04-13 00:43:29.346 7f5db84db700 10 mds.2.locker scatter_nudge replica, requesting scatter/unscatter of (inest mix->lock w=1 dirty) on [inode 0x1000005b571 [...10,head] /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ rep@0.1 fragtree_t(*^3) v327772 f(v17237 m2020-04-12 13:12:59.364283 29995=29988+7) n(v253 rc2020-04-12 12:20:33.179743 b3966471078483 user0 b3966471078483 user0 f29999 user0 d8 group0 b3966471078483 group0 f29999 group0 d8 30007=29999+8)/n(v252 rc2020-04-12 12:15:05.813743 b3966584925193 user0 b3966584925193 user0 f30000 user0 d8 group0 b3966584925193 group0 f30000 group0 d8 30008=30000+8) (inest mix->lock w=1 dirty) (iversion lock) caps={383430026=pAsLsXsFs/-@9,383776451=pAsLsXsFs/-@9} | dirtyscattered=1 request=0 lock=1 dirfrag=4 caps=1 dirwaiter=0 waiter=1 discoverbase=0 0x56277906c100]
2020-04-13 00:43:29.346 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610874881|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800]
2020-04-13 00:43:29.346 7f5db84db700 10 mds.2.cache |____ 1 rep [dir 0x1000005b571.000* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] rep@1.1 REP dir_auth=1 state=0 f(v17235 m2020-04-12 12:20:33.179743 3645=3645+0) n(v252 rc2020-04-12 12:20:33.179743 b469143381258 user0 b469143381258 user0 f3645 group0 b469143381258 group0 f3645 3645=3645+0)/n(v252 rc2020-04-12 12:15:05.813743 b469276707494 user0 b469276707494 user0 f3647 group0 b469276707494 group0 f3647 3647=3647+0) hs=0+0,ss=0+0 | dnwaiter=0 child=0 subtree=1 0x562778a6c800]
2020-04-13 00:43:29.347 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000]
2020-04-13 00:43:29.347 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610874881|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800]
2020-04-13 00:43:34.346 7f5db84db700 10 mds.2.locker scatter_nudge replica, requesting scatter/unscatter of (inest mix->lock w=1 dirty) on [inode 0x1000005b571 [...10,head] /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ rep@0.1 fragtree_t(*^3) v327772 f(v17237 m2020-04-12 13:12:59.364283 29995=29988+7) n(v253 rc2020-04-12 12:20:33.179743 b3966471078483 user0 b3966471078483 user0 f29999 user0 d8 group0 b3966471078483 group0 f29999 group0 d8 30007=29999+8)/n(v252 rc2020-04-12 12:15:05.813743 b3966584925193 user0 b3966584925193 user0 f30000 user0 d8 group0 b3966584925193 group0 f30000 group0 d8 30008=30000+8) (inest mix->lock w=1 dirty) (iversion lock) caps={383430026=pAsLsXsFs/-@9,383776451=pAsLsXsFs/-@9} | dirtyscattered=1 request=0 lock=1 dirfrag=4 caps=1 dirwaiter=0 waiter=1 discoverbase=0 0x56277906c100]
2020-04-13 00:43:34.347 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.010* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6} v=119312 cv=0/0 REP dir_auth=2 state=1610874881|complete f(v17237 m2020-04-10 18:55:08.473083 3795=3793+2) n(v253 rc2020-04-10 18:55:08.473083 b508208581093 user0 b508208581093 user0 f3795 user0 d2 group0 b508208581093 group0 f3795 group0 d2 3797=3795+2) hs=3795+0,ss=0+0 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562778b3f800]
2020-04-13 00:43:34.347 7f5db84db700 10 mds.2.cache |____ 1 rep [dir 0x1000005b571.000* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] rep@1.1 REP dir_auth=1 state=0 f(v17235 m2020-04-12 12:20:33.179743 3645=3645+0) n(v252 rc2020-04-12 12:20:33.179743 b469143381258 user0 b469143381258 user0 f3645 group0 b469143381258 group0 f3645 3645=3645+0)/n(v252 rc2020-04-12 12:15:05.813743 b469276707494 user0 b469276707494 user0 f3647 group0 b469276707494 group0 f3647 3647=3647+0) hs=0+0,ss=0+0 | dnwaiter=0 child=0 subtree=1 0x562778a6c800]
2020-04-13 00:43:34.347 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.110* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=2} v=118935 cv=0/0 REP dir_auth=2 state=1610874881|complete f(v17237 m2020-04-12 12:13:48.810743 3804=3803+1) n(v253 rc2020-04-12 12:20:33.179743 b504063684409 user0 b504063684409 user0 f3807 user0 d1 group0 b504063684409 group0 f3807 group0 d1 3808=3807+1)/n(v253 rc2020-04-12 12:15:08.831743 b503949837699 user0 b503949837699 user0 f3806 user0 d1 group0 b503949837699 group0 f3806 group0 d1 3807=3806+1) hs=3804+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x562772357000]
2020-04-13 00:43:34.347 7f5db84db700 10 mds.2.cache |____ 2 auth [dir 0x1000005b571.001* /zb_test/dir/input/creasd/importing/GF7/LLL/dirpath11/ [2,head] auth{0=6,1=1} v=119029 cv=0/0 REP dir_auth=2 state=1610874881|complete f(v17237 m2020-04-12 11:29:29.126743 3740=3739+1) n(v253 rc2020-04-12 13:12:59.364283 b496582084202 user0 b496582084202 user0 f3740 user0 d1 group0 b496582084202 group0 f3740 group0 d1 3741=3740+1)/n(v253 rc2020-04-12 11:29:29.126743 b496543525527 user0 b496543525527 user0 f3739 user0 d1 group0 b496543525527 group0 f3739 group0 d1 3740=3739+1) hs=3740+0,ss=0+0 dirty=1 | child=1 subtree=1 subtreetemp=0 replicated=1 dirty=1 authpin=0 0x56276ff29800]
(5-5/6)