Here is my work log on Linux xfs bug which I reported in March 2018. This page is to show that I found the bug in the latest Linux, wrote a reproducer and a patch all by myself at home while I took days off. My employer as of March 2018 has no intellectual property right over what I have provided to the bugzilla.
https://bugzilla.kernel.org/show_bug.cgi?id=199119
のための生データなど。メモ書き。
再現プログラムは、直されて、 xfstest に入りました。
https://www.spinics.net/lists/linux-xfs/msg17298.html - [PATCH] xfs: don't fail when converting shortform attr to long form during ATTR_REPLACE
アップストリームに入りました。
commit 7b38460dc8e4eafba06c78f8e37099d3b34d473c
Author: Darrick J. Wong <darrick.wong@oracle.com>
Date: Tue Apr 17 19:10:15 2018 -0700
以下、メモ書き。
長さが伸びて短形式からエクステントになる時に、 setxattr replace が 61 ENOATTR で失敗するのは、同じ。その時、属性が消えるのも同じ。
4.8.13-100.fc23 は、さらに、ファイルシステム corrupt になる。
結局、xfs_attr_shortform_addname で、元の名前を消してしまった後、replace で元の名前が無い時はエラー、の論理をそのまま使っているのが間違いだった。と、思うよ。
パッチ
[kanda@centos7 xfs]$ git diff .
diff --git a/libxfs/xfs_attr.c b/libxfs/xfs_attr.c
index 83b3f67..00323fe 100644
--- a/libxfs/xfs_attr.c
+++ b/libxfs/xfs_attr.c
@@ -487,6 +487,12 @@ xfs_attr_shortform_addname(xfs_da_args_t *args)
return retval;
retval = xfs_attr_shortform_remove(args);
ASSERT(retval == 0);
+ /*
+ * Since we have removed the old attr here,
+ * further lookup will fail with ENOATTR.
+ * Ignore this was a replace and go on creating new attr.
+ */
+ args->flags &= ~ATTR_REPLACE;
}
if (args->namelen >= XFS_ATTR_SF_ENTSIZE_MAX ||
4.16.0-rc5+ で再現した
[kanda@centos7 ~]$ uname -a
Linux centos7 4.16.0-rc5+ #2 SMP Thu Mar 15 12:29:21 JST 2018 x86_64 x86_64 x86_64 GNU/Linux
[kanda@centos7 ~]$ ./setattr
No data available
error=61 at line 43
[kanda@centos7 ~]$ attr -l /mnt/xfs/hello
attr_list: Input/output error
Could not list "(null)" for /mnt/xfs/hello
[root@centos7 kanda]# dmesg
[ 266.127606] XFS (sdb): Metadata corruption detected at xfs_attr3_leaf_verify+0x176/0x1c0 [xfs], xfs_attr3_leaf block 0x80
[ 266.127615] XFS (sdb): Unmount and run xfs_repair
[ 266.127619] XFS (sdb): First 128 bytes of corrupted metadata buffer:
[ 266.127625] 00000000c1ae7fd0: 00 00 00 00 00 00 00 00 3b ee 00 00 00 00 00 00 ........;.......
[ 266.127628] 00000000a08d03c3: 00 00 00 00 00 00 00 80 00 00 00 00 00 00 00 00 ................
[ 266.127631] 00000000d5fca99a: 9d 1e 97 53 a7 a1 40 63 ad 1d f7 95 ba 21 cc f5 ...S..@c.....!..
[ 266.127634] 00000000b10a02e2: 00 00 00 00 00 00 00 43 00 00 00 00 10 00 00 00 .......C........
[ 266.127637] 00000000d191bf38: 00 50 0f b0 00 00 00 00 00 00 00 00 00 00 00 00 .P..............
[ 266.127640] 000000008af0f949: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 266.127643] 00000000c077a317: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 266.127645] 000000004401acf4: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 266.127651] XFS (sdb): xfs_do_force_shutdown(0x8) called from line 1372 of file fs/xfs/xfs_buf.c. Return address = ffffffffc02b6589
[ 266.127659] XFS (sdb): Corruption of in-memory data detected. Shutting down filesystem
[ 266.127662] XFS (sdb): Please umount the filesystem and rectify the problem(s)
[root@centos7 kanda]# umount /mnt/xfs
[root@centos7 kanda]# mount /dev/sdb /mnt/xfs
[root@centos7 kanda]# attr -l /mnt/xfs/hello
attr_list: Structure needs cleaning
Could not list "(null)" for /mnt/xfs/hello
[ 444.886603] XFS (sdb): Mounting V5 Filesystem
[ 445.048578] XFS (sdb): Starting recovery (logdev: internal)
[ 445.137370] XFS (sdb): Ending recovery (logdev: internal)
[ 454.665040] XFS (sdb): Metadata corruption detected at xfs_attr3_leaf_verify+0x176/0x1c0 [xfs], xfs_attr3_leaf block 0x80
[ 454.665048] XFS (sdb): Unmount and run xfs_repair
[ 454.665050] XFS (sdb): First 128 bytes of corrupted metadata buffer:
[ 454.665054] 00000000681d1224: 00 00 00 00 00 00 00 00 3b ee 00 00 82 6f ec 3a ........;....o.:
[ 454.665056] 00000000e4e59faa: 00 00 00 00 00 00 00 80 00 00 00 01 00 00 00 ab ................
[ 454.665058] 00000000528d2f74: 9d 1e 97 53 a7 a1 40 63 ad 1d f7 95 ba 21 cc f5 ...S..@c.....!..
[ 454.665060] 00000000caadad98: 00 00 00 00 00 00 00 43 00 00 00 00 10 00 00 00 .......C........
[ 454.665062] 00000000a35d5c9c: 00 50 0f b0 00 00 00 00 00 00 00 00 00 00 00 00 .P..............
[ 454.665064] 00000000477a963b: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 454.665065] 000000005fe361a4: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 454.665067] 000000004cb094af: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 454.665080] XFS (sdb): metadata I/O error in "xfs_trans_read_buf_map" at daddr 0x80 len 8 error 117
[ 468.626900] XFS (sdb): Metadata corruption detected at xfs_attr3_leaf_verify+0x176/0x1c0 [xfs], xfs_attr3_leaf block 0x80
[ 468.626907] XFS (sdb): Unmount and run xfs_repair
[ 468.626909] XFS (sdb): First 128 bytes of corrupted metadata buffer:
[ 468.626913] 000000002a488d2e: 00 00 00 00 00 00 00 00 3b ee 00 00 82 6f ec 3a ........;....o.:
[ 468.626916] 000000007f58db04: 00 00 00 00 00 00 00 80 00 00 00 01 00 00 00 ab ................
[ 468.626918] 00000000c3a0f465: 9d 1e 97 53 a7 a1 40 63 ad 1d f7 95 ba 21 cc f5 ...S..@c.....!..
[ 468.626919] 0000000027f83166: 00 00 00 00 00 00 00 43 00 00 00 00 10 00 00 00 .......C........
[ 468.626921] 0000000041d060e2: 00 50 0f b0 00 00 00 00 00 00 00 00 00 00 00 00 .P..............
[ 468.626923] 0000000013737296: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 468.626925] 00000000afde2021: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 468.626927] 000000009ff4ba90: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 468.626939] XFS (sdb): metadata I/O error in "xfs_trans_read_buf_map" at daddr 0x80 len 8 error 117
[ 468.632699] XFS (sdb): Metadata corruption detected at xfs_attr3_leaf_verify+0x176/0x1c0 [xfs], xfs_attr3_leaf block 0x80
[ 468.632704] XFS (sdb): Unmount and run xfs_repair
[ 468.632706] XFS (sdb): First 128 bytes of corrupted metadata buffer:
[ 468.632710] 000000002a488d2e: 00 00 00 00 00 00 00 00 3b ee 00 00 82 6f ec 3a ........;....o.:
[ 468.632712] 000000007f58db04: 00 00 00 00 00 00 00 80 00 00 00 01 00 00 00 ab ................
[ 468.632714] 00000000c3a0f465: 9d 1e 97 53 a7 a1 40 63 ad 1d f7 95 ba 21 cc f5 ...S..@c.....!..
[ 468.632716] 0000000027f83166: 00 00 00 00 00 00 00 43 00 00 00 00 10 00 00 00 .......C........
[ 468.632718] 0000000041d060e2: 00 50 0f b0 00 00 00 00 00 00 00 00 00 00 00 00 .P..............
[ 468.632720] 0000000013737296: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 468.632722] 00000000afde2021: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 468.632723] 000000009ff4ba90: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
[ 468.632961] XFS (sdb): metadata I/O error in "xfs_trans_read_buf_map" at daddr 0x80 len 8 error 117
修正後
[root@centos7 xfs]# ls -l xfs.ko
-rw-rw-r-- 1 kanda kanda 33354536 Mar 15 12:55 xfs.ko
[root@centos7 xfs]# insmod xfs.ko
[ 1157.687708] SGI XFS with ACLs, security attributes, debug enabled
[kanda@centos7 ~]$ ./setattr /mnt/xfs/hello
[kanda@centos7 ~]$ attr -l /mnt/xfs/hello
Attribute "world" has a 2048 byte value for /mnt/xfs/hello
修正後の再現ログ
[root@centos7 xfs]# pwd
/usr/lib/modules/3.10.0-693.21.1.el7.x86_64/kernel/fs/xfs
[root@centos7 xfs]# insmod ./xfs.ko
[root@centos7 xfs]# dmesg | tail -1
[ 7896.460051] SGI XFS with ACLs, security attributes, debug enabled
[kanda@centos7 ~]$ ./setattr /mnt/xfs/hello
[kanda@centos7 ~]$ attr -l /mnt/xfs/hello
Attribute "world" has a 2048 byte value for /mnt/xfs/hello
[kanda@centos7 ~]$ gdb setattr
GNU gdb (GDB) Red Hat Enterprise Linux 7.6.1-100.el7_4.1
Copyright (C) 2013 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law. Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-redhat-linux-gnu".
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>...
Reading symbols from /home/kanda/setattr...done.
(gdb) b main
Breakpoint 1 at 0x4007b5: file setattr.c, line 20.
(gdb) run /mnt/xfs/world
Starting program: /home/kanda/setattr /mnt/xfs/world
Breakpoint 1, main (argc=2, argv=0x7fffffffe568) at setattr.c:20
20 char *path = "/mnt/xfs/hello";
Missing separate debuginfos, use: debuginfo-install glibc-2.17-196.el7_4.2.x86_64
(gdb) list
15
16 int main(int argc, char *argv[])
17 {
18 int ret;
19 int fd;
20 char *path = "/mnt/xfs/hello";
21 char *name = "user.world";
22 char value[2048];
23 size_t size = sizeof(value);
24
(gdb)
25 if (argc == 2)
26 path = argv[1];
27
28 fd = open(path, O_RDWR | O_CREAT, S_IRUSR | S_IWUSR);
29 if (fd < 0) die();
30
31 // First, create a small xattr.
32 memset(value, '0', 1);
33 ret = fsetxattr(fd, name, value, 1, XATTR_CREATE);
34 if (ret < 0) die();
(gdb)
35 close(fd);
36
37 fd = open(path, O_RDWR);
38 if (fd < 0) die();
39
40 // Then, replace it with bigger one, forcing short form to leaf conversion.
41 memset(value, '1', sizeof(value));
42 ret = fsetxattr(fd, name, value, size, XATTR_REPLACE);
43 if (ret < 0) die();
44 close(fd);
(gdb) b 37
Breakpoint 2 at 0x4008b5: file setattr.c, line 37.
(gdb) c
Continuing.
Breakpoint 2, main (argc=2, argv=0x7fffffffe568) at setattr.c:37
37 fd = open(path, O_RDWR);
[root@centos7 kanda]# attr -l /mnt/xfs/world
Attribute "world" has a 1 byte value for /mnt/xfs/world
u3.sfdir3.list[0].namelen = 5
u3.sfdir3.list[0].offset = 0x78
u3.sfdir3.list[0].name = "world"
u3.sfdir3.list[0].inumber.i4 = 68
u3.sfdir3.list[0].filetype = 1
xfs_db> inode 68
xfs_db> p
core.magic = 0x494e
core.mode = 0100600
core.version = 3
core.format = 2 (extents)
core.nlinkv2 = 1
core.onlink = 0
core.projid_lo = 0
core.projid_hi = 0
core.uid = 1000
core.gid = 1000
core.flushiter = 0
core.atime.sec = Wed Mar 14 23:16:27 2018
core.atime.nsec = 394061330
core.mtime.sec = Wed Mar 14 23:16:27 2018
core.mtime.nsec = 394061330
core.ctime.sec = Wed Mar 14 23:16:27 2018
core.ctime.nsec = 394061330
core.size = 0
core.nblocks = 0
core.extsize = 0
core.nextents = 0
core.naextents = 0
core.forkoff = 37
core.aformat = 1 (local)
core.dmevmask = 0
core.dmstate = 0
core.newrtbm = 0
core.prealloc = 0
core.realtime = 0
core.immutable = 0
core.append = 0
core.sync = 0
core.noatime = 0
core.nodump = 0
core.rtinherit = 0
core.projinherit = 0
core.nosymlinks = 0
core.extsz = 0
core.extszinherit = 0
core.nodefrag = 0
core.filestream = 0
core.gen = 4005857805
next_unlinked = null
v3.crc = 0x51ee7f9a (correct)
v3.change_count = 4
v3.lsn = 0x100000089
v3.flags2 = 0
v3.crtime.sec = Wed Mar 14 23:16:27 2018
v3.crtime.nsec = 394061330
v3.inumber = 68
v3.uuid = 9d1e9753-a7a1-4063-ad1d-f795ba21ccf5
u3 = (empty)
a.sfattr.hdr.totsize = 13
a.sfattr.hdr.count = 1
a.sfattr.list[0].namelen = 5
a.sfattr.list[0].valuelen = 1
a.sfattr.list[0].root = 0
a.sfattr.list[0].secure = 0
a.sfattr.list[0].name = "world"
a.sfattr.list[0].value = "0"
(gdb) n
38 if (fd < 0) die();
(gdb) n
41 memset(value, '1', sizeof(value));
(gdb) n
42 ret = fsetxattr(fd, name, value, size, XATTR_REPLACE);
(gdb) n
43 if (ret < 0) die();
(gdb) p ret
$2 = 0
[root@centos7 kanda]# attr -l /mnt/xfs/world
Attribute "world" has a 2048 byte value for /mnt/xfs/world
[root@centos7 kanda]# xfs_db -r /dev/sdb -c "inode 68" -c p
core.magic = 0x494e
core.mode = 0100600
core.version = 3
core.format = 2 (extents)
core.nlinkv2 = 1
core.onlink = 0
core.projid_lo = 0
core.projid_hi = 0
core.uid = 1000
core.gid = 1000
core.flushiter = 0
core.atime.sec = Wed Mar 14 23:16:27 2018
core.atime.nsec = 394061330
core.mtime.sec = Wed Mar 14 23:16:27 2018
core.mtime.nsec = 394061330
core.ctime.sec = Wed Mar 14 23:20:33 2018
core.ctime.nsec = 626775608
core.size = 0
core.nblocks = 1
core.extsize = 0
core.nextents = 0
core.naextents = 1
core.forkoff = 37
core.aformat = 2 (extents)
core.dmevmask = 0
core.dmstate = 0
core.newrtbm = 0
core.prealloc = 0
core.realtime = 0
core.immutable = 0
core.append = 0
core.sync = 0
core.noatime = 0
core.nodump = 0
core.rtinherit = 0
core.projinherit = 0
core.nosymlinks = 0
core.extsz = 0
core.extszinherit = 0
core.nodefrag = 0
core.filestream = 0
core.gen = 4005857805
next_unlinked = null
v3.crc = 0x5b8f0df7 (correct)
v3.change_count = 7
v3.lsn = 0x100000095
v3.flags2 = 0
v3.crtime.sec = Wed Mar 14 23:16:27 2018
v3.crtime.nsec = 394061330
v3.inumber = 68
v3.uuid = 9d1e9753-a7a1-4063-ad1d-f795ba21ccf5
u3 = (empty)
a.bmx[0] = [startoff,startblock,blockcount,extentflag] 0:[0,16,1,0]
xfs_db> p
hdr.info.hdr.forw = 0
hdr.info.hdr.back = 0
hdr.info.hdr.magic = 0x3bee
hdr.info.crc = 0x9cc7dc2d (correct)
hdr.info.bno = 128
hdr.info.lsn = 0x100000095
hdr.info.uuid = 9d1e9753-a7a1-4063-ad1d-f795ba21ccf5
hdr.info.owner = 68
hdr.count = 1
hdr.usedbytes = 2056
hdr.firstused = 2040
hdr.holes = 0
hdr.freemap[0-2] = [base,size] 0:[88,1952] 1:[0,0] 2:[0,0]
entries[0] = [hashval,nameidx,incomplete,root,secure,local] 0:[0x7dfcb663,2040,0,0,0,1]
nvlist[0].valuelen = 2048
nvlist[0].namelen = 5
nvlist[0].name = "world"
nvlist[0].value = "11111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111
[root@centos7 xfs]# echo "xfs:*" >/sys/kernel/debug/tracing/set_event
[root@centos7 kanda]# cat /sys/kernel/debug/tracing/trace > xfstrace
[root@centos7 kanda]# grep -v xfs_buf xfstrace | grep -v xfs_i | grep -v xfs_log | grep -v xfs_trans| grep -v xfs_perag | grep -v xfs_file
# tracer: nop
#
# entries-in-buffer/entries-written: 268/268 #P:1
#
# _-----=> irqs-off
# / _----=> need-resched
# | / _---=> hardirq/softirq
# || / _--=> preempt-depth
# ||| / delay
# TASK-PID CPU# |||| TIMESTAMP FUNCTION
# | | | |||| | |
kworker/0:1H-347 [000] .... 8084.352788: xfs_ail_insert: dev 8:16 lip 0xffff880076c88000 old lsn 0/0 new lsn 1/118 type XFS_LI_BUF flags IN_AIL
<...>-56658 [000] .... 8084.352826: xfs_ail_push: dev 8:16 lip 0xffff880076c88000 lsn 1/118 type XFS_LI_BUF flags IN_AIL
<...>-52382 [000] .... 8084.353479: xfs_ail_delete: dev 8:16 lip 0xffff880076c88000 old lsn 1/118 new lsn 1/118 type XFS_LI_BUF flags IN_AIL
<...>-56685 [000] .... 8101.707132: xfs_create: dev 8:16 dp ino 0x40 name hello
<...>-56685 [000] .... 8101.707148: xfs_read_agi: dev 8:16 agno 0
<...>-56685 [000] .... 8101.707302: xfs_dir2_sf_addname: dev 8:16 ino 0x40 name hello namelen 5 hashval 0x8cbb3669 inumber 0x43 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707304: xfs_dir2_sf_lookup: dev 8:16 ino 0x40 name hello namelen 5 hashval 0x8cbb3669 inumber 0x43 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707366: xfs_attr_sf_create: dev 8:16 ino 0x43 name world namelen 5 valuelen 1 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707366: xfs_attr_sf_addname: dev 8:16 ino 0x43 name world namelen 5 valuelen 1 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707367: xfs_attr_sf_lookup: dev 8:16 ino 0x43 name world namelen 5 valuelen 1 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707368: xfs_attr_sf_add: dev 8:16 ino 0x43 name world namelen 5 valuelen 1 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707390: xfs_attr_sf_addname: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707390: xfs_attr_sf_lookup: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707391: xfs_attr_sf_remove: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707392: xfs_attr_sf_to_leaf: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707394: xfs_da_grow_inode: dev 8:16 ino 0x43 name world namelen 5 hashval 0x7dfcb663 inumber 0x0 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707400: xfs_alloc_read_agf: dev 8:16 agno 0
<...>-56685 [000] .... 8101.707401: xfs_read_agf: dev 8:16 agno 0
<...>-56685 [000] .... 8101.707422: xfs_agf: dev 8:16 agno 0 flags LONGEST length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131056 longest 0 caller xfs_allocbt_update_lastrec [xfs]
<...>-56685 [000] .... 8101.707426: xfs_agf: dev 8:16 agno 0 flags LONGEST length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131056 longest 131055 caller xfs_allocbt_update_lastrec [xfs]
<...>-56685 [000] .... 8101.707427: xfs_alloc_near_greater: dev 8:16 agno 0 agbno 16 minlen 1 maxlen 1 mod 0 prod 1 minleft 1 total 30 alignment 1 minalignslop 0 len 1 type NEAR_BNO otype START_BNO wasdel 0 wasfromfl 0 isfl 0 userdata 0 firstblock 0xffffffffffffffff
<...>-56685 [000] .... 8101.707428: xfs_agf: dev 8:16 agno 0 flags FREEBLKS length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131055 longest 131055 caller xfs_alloc_update_counters [xfs]
<...>-56685 [000] .... 8101.707435: xfs_attr_leaf_create: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707462: xfs_attr_leaf_addname: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707465: xfs_attr_leaf_lookup: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707466: xfs_attr_leaf_add: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
<...>-56685 [000] .... 8101.707467: xfs_attr_leaf_add_work: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
[kanda@centos7 ~]$ ./setattr /mnt/xfs/world
[kanda@centos7 ~]$ attr -l /mnt/xfs/world
Attribute "world" has a 2048 byte value for /mnt/xfs/world
[kanda@centos7 ~]$ ./setattr /mnt/xfs/hello
[kanda@centos7 ~]$ attr -l /mnt/xfs/hello
Attribute "world" has a 2048 byte value for /mnt/xfs/hello
Attribute "selinux" has a 37 byte value for /mnt/xfs/hello
[kanda@centos7 ~]$ uname -a
Linux centos7 3.10.0-693.21.1.el7.x86_64 #1 SMP Wed Mar 7 19:03:37 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux
4.8.13-100.fc23 と、3.10.0-693.21.1.el7 の短形式を扱うソースは驚くほど差がない
[kanda@localhost libxfs]$ diff xfs_attr.c ~/xfs-3.10/libxfs/xfs_attr.c
26d25
< #include "xfs_defer.h"
134,136d132
< if (!xfs_inode_hasattr(ip))
< return -ENOATTR;
<
207c203
< struct xfs_defer_ops dfops;
---
> struct xfs_bmap_free flist;
225c221
< args.dfops = &dfops;
---
> args.flist = &flist;
320c316
< xfs_defer_init(args.dfops, args.firstblock);
---
> xfs_bmap_init(args.flist, args.firstblock);
323c319
< error = xfs_defer_finish(&args.trans, args.dfops, dp);
---
> error = xfs_bmap_finish(&args.trans, args.flist, dp);
326c322
< xfs_defer_cancel(&dfops);
---
> xfs_bmap_cancel(&flist);
386c382
< struct xfs_defer_ops dfops;
---
> struct xfs_bmap_free flist;
395,397d390
< if (!xfs_inode_hasattr(dp))
< return -ENOATTR;
<
403c396
< args.dfops = &dfops;
---
> args.flist = &flist;
588c581
< xfs_defer_init(args->dfops, args->firstblock);
---
> xfs_bmap_init(args->flist, args->firstblock);
591c584
< error = xfs_defer_finish(&args->trans, args->dfops, dp);
---
> error = xfs_bmap_finish(&args->trans, args->flist, dp);
594c587
< xfs_defer_cancel(args->dfops);
---
> xfs_bmap_cancel(args->flist);
678c671
< xfs_defer_init(args->dfops, args->firstblock);
---
> xfs_bmap_init(args->flist, args->firstblock);
682,683c675,676
< error = xfs_defer_finish(&args->trans,
< args->dfops, dp);
---
> error = xfs_bmap_finish(&args->trans,
> args->flist, dp);
686c679
< xfs_defer_cancel(args->dfops);
---
> xfs_bmap_cancel(args->flist);
741c734
< xfs_defer_init(args->dfops, args->firstblock);
---
> xfs_bmap_init(args->flist, args->firstblock);
745c738
< error = xfs_defer_finish(&args->trans, args->dfops, dp);
---
> error = xfs_bmap_finish(&args->trans, args->flist, dp);
748c741
< xfs_defer_cancel(args->dfops);
---
> xfs_bmap_cancel(args->flist);
867c860
< xfs_defer_init(args->dfops, args->firstblock);
---
> xfs_bmap_init(args->flist, args->firstblock);
870,871c863,864
< error = xfs_defer_finish(&args->trans,
< args->dfops, dp);
---
> error = xfs_bmap_finish(&args->trans,
> args->flist, dp);
874c867
< xfs_defer_cancel(args->dfops);
---
> xfs_bmap_cancel(args->flist);
895c888
< xfs_defer_init(args->dfops, args->firstblock);
---
> xfs_bmap_init(args->flist, args->firstblock);
898c891
< error = xfs_defer_finish(&args->trans, args->dfops, dp);
---
> error = xfs_bmap_finish(&args->trans, args->flist, dp);
901c894
< xfs_defer_cancel(args->dfops);
---
> xfs_bmap_cancel(args->flist);
994c987
< xfs_defer_init(args->dfops, args->firstblock);
---
> xfs_bmap_init(args->flist, args->firstblock);
997,998c990,991
< error = xfs_defer_finish(&args->trans,
< args->dfops, dp);
---
> error = xfs_bmap_finish(&args->trans,
> args->flist, dp);
1001c994
< xfs_defer_cancel(args->dfops);
---
> xfs_bmap_cancel(args->flist);
1117c1110
< xfs_defer_init(args->dfops, args->firstblock);
---
> xfs_bmap_init(args->flist, args->firstblock);
1120c1113
< error = xfs_defer_finish(&args->trans, args->dfops, dp);
---
> error = xfs_bmap_finish(&args->trans, args->flist, dp);
1123c1116
< xfs_defer_cancel(args->dfops);
---
> xfs_bmap_cancel(args->flist);
1150c1143
< xfs_defer_init(args->dfops, args->firstblock);
---
> xfs_bmap_init(args->flist, args->firstblock);
1154,1155c1147,1148
< error = xfs_defer_finish(&args->trans,
< args->dfops, dp);
---
> error = xfs_bmap_finish(&args->trans,
> args->flist, dp);
1158c1151
< xfs_defer_cancel(args->dfops);
---
> xfs_bmap_cancel(args->flist);
[kanda@localhost libxfs]$ diff xfs_attr_leaf.c ~/xfs-3.10/libxfs/xfs_attr_leaf.c
255a256
> struct xfs_perag *pag = bp->b_pag;
276c277,282
< if (ichdr.count == 0)
---
> /*
> * In recovery there is a transient state where count == 0 is valid
> * because we may have transitioned an empty shortform attr to a leaf
> * if the attr didn't fit in shortform.
> */
> if (pag && pag->pagf_init && ichdr.count == 0)
795c801
< nargs.dfops = args->dfops;
---
> nargs.flist = args->flist;
925c931
< nargs.dfops = args->dfops;
---
> nargs.flist = args->flist;
1168a1175
> gmb();
1171a1179
> gmb();
1522a1531
> gmb();
1545a1555
> gmb();
[kanda@localhost libxfs]$
再現プログラム
$ cat setattr.c
// setattr.c by kanda.motohiro@gmail.com
// xfs extended attribute corruption bug reproducer
#include <stdio.h>
#include <string.h>
#include <fcntl.h>
#include <errno.h>
#include <stdlib.h>
#include <unistd.h>
#include <sys/types.h>
#include <sys/xattr.h>
#define die() do { perror(""); \
fprintf(stderr, "error=%d at line %d\n", errno, __LINE__); \
exit(1); } while (0)
int main(int argc, char *argv[])
{
int ret;
int fd;
char *path = "/mnt/xfs/hello";
char *name = "user.world";
char value[2048];
size_t size = sizeof(value);
if (argc == 2)
path = argv[1];
fd = open(path, O_RDWR | O_CREAT, S_IRUSR | S_IWUSR);
if (fd < 0) die();
// First, create a small xattr.
memset(value, '0', 1);
ret = fsetxattr(fd, name, value, 1, XATTR_CREATE);
if (ret < 0) die();
close(fd);
fd = open(path, O_RDWR);
if (fd < 0) die();
// Then, replace it with bigger one, forcing short form to leaf conversion.
memset(value, '1', sizeof(value));
ret = fsetxattr(fd, name, value, size, XATTR_REPLACE);
if (ret < 0) die();
close(fd);
return 0;
}
xfs trace
再現した時 4.8.13-100.fc23
$ grep -v xfs_buf xfstrace | grep -v xfs_i | grep -v xfs_log | grep -v xfs_trans| grep -v xfs_perag | grep -v xfs_file
setattr-4205 [000] .... 12286.547623: xfs_attr_sf_addname: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547625: xfs_attr_sf_lookup: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547626: xfs_attr_sf_remove: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547630: xfs_defer_init: dev 0:0 ops e2739d80 committed 0 low 0
setattr-4205 [000] .... 12286.547632: xfs_attr_sf_to_leaf: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547635: xfs_da_grow_inode: dev 7:0 ino 0x63 name world namelen 5 hashval 0x7dfcb663 inumber 0x0 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547652: xfs_alloc_read_agf: dev 7:0 agno 0
setattr-4205 [000] .... 12286.547654: xfs_read_agf: dev 7:0 agno 0
setattr-4205 [000] .... 12286.547725: xfs_alloc_near_first: dev 7:0 agno 0 agbno 11 minlen 1 maxlen 1 mod 0 prod 1 minleft 1 total 30 alignment 1 minalignslop 0 len 1 type NEAR_BNO otype START_BNO wasdel 0 wasfromfl 0 isfl 0 userdata 0 firstblock 0xffffffffffffffff
setattr-4205 [000] .... 12286.547727: xfs_agf: dev 7:0 agno 0 flags FREEBLKS length 16384 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 16365 longest 16364 caller xfs_alloc_update_counters.isra.15 [xfs]
setattr-4205 [000] .... 12286.547741: xfs_attr_leaf_create: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547759: xfs_defer_finish: dev 7:0 ops e2739d80 committed 0 low 0
setattr-4205 [000] .... 12286.547761: xfs_defer_finish_done: dev 7:0 ops e2739d80 committed 0 low 0
setattr-4205 [000] .... 12286.547908: xfs_attr_leaf_addname: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547918: xfs_attr_leaf_lookup: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
再現しない時 3.10.0-693.21.1.el7
[kanda@centos7 ~]$ grep -v xfs_buf xfstrace | grep -v xfs_i | grep -v xfs_log | grep -v xfs_trans| grep -v xfs_perag | grep -v xfs_file
setattr-2628 [000] .... 445.825311: xfs_attr_sf_addname: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825312: xfs_attr_sf_lookup: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825313: xfs_attr_sf_remove: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825315: xfs_attr_sf_to_leaf: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825317: xfs_da_grow_inode: dev 8:16 ino 0x43 name world namelen 5 hashval 0x7dfcb663 inumber 0x0 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825323: xfs_alloc_read_agf: dev 8:16 agno 0
setattr-2628 [000] .... 445.825324: xfs_read_agf: dev 8:16 agno 0
setattr-2628 [000] .... 445.825352: xfs_agf: dev 8:16 agno 0 flags LONGEST length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131055 longest 0 caller xfs_allocbt_update_lastrec [xfs]
setattr-2628 [000] .... 445.825355: xfs_agf: dev 8:16 agno 0 flags LONGEST length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131055 longest 131054 caller xfs_allocbt_update_lastrec [xfs]
setattr-2628 [000] .... 445.825357: xfs_alloc_near_first: dev 8:16 agno 0 agbno 17 minlen 1 maxlen 1 mod 0 prod 1 minleft 1 total 30 alignment 1 minalignslop 0 len 1 type NEAR_BNO otype START_BNO wasdel 0 wasfromfl 0 isfl 0 userdata 0 firstblock 0xffffffffffffffff
setattr-2628 [000] .... 445.825358: xfs_agf: dev 8:16 agno 0 flags FREEBLKS length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131054 longest 131054 caller xfs_alloc_update_counters.isra.11 [xfs]
setattr-2628 [000] .... 445.825363: xfs_attr_leaf_create: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825372: xfs_attr_leaf_lookup: dev 8:16 ino 0x43 name selinux namelen 7 valuelen 37 hashval 0xcd3a75d6 op_flags OKNOENT
setattr-2628 [000] .... 445.825373: xfs_attr_leaf_add: dev 8:16 ino 0x43 name selinux namelen 7 valuelen 37 hashval 0xcd3a75d6 op_flags OKNOENT
setattr-2628 [000] .... 445.825374: xfs_attr_leaf_add_work: dev 8:16 ino 0x43 name selinux namelen 7 valuelen 37 hashval 0xcd3a75d6 op_flags OKNOENT
setattr-2628 [000] .... 445.825396: xfs_attr_leaf_addname: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825399: xfs_attr_leaf_lookup: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
# mkfs.xfs -f image
meta-data=image isize=512 agcount=4, agsize=16384 blks
= sectsz=512 attr=2, projid32bit=1
= crc=1 finobt=1, sparse=0
data = bsize=4096 blocks=65536, imaxpct=25
= sunit=0 swidth=0 blks
naming =version 2 bsize=4096 ascii-ci=0 ftype=1
log =internal log bsize=4096 blocks=855, version=2
= sectsz=512 sunit=0 blks, lazy-count=1
realtime =none extsz=4096 blocks=0, rtextents=0
# uname -a
Linux localhost.localdomain 4.8.13-100.fc23.i686 #1 SMP Fri Dec 9 17:03:00 UTC 2016 i686 i686 i386 GNU/Linux
$ date > /mnt/xfs/hello
$ attr -s world -V 0 /mnt/xfs/hello
Attribute "world" set to a 1 byte value for /mnt/xfs/hello:
0
$ ls -li /mnt/xfs/hello
99 -rw-rw-r-- 1 kanda kanda 43 3月 14 14:19 /mnt/xfs/hello
$ xfs_db -r image -c "inode 99" -c p
core.magic = 0x494e
core.mode = 0
core.version = 3
core.format = 0 (dev)
core.nlinkv2 = 0
core.onlink = 0
core.projid_lo = 0
core.projid_hi = 0
core.uid = 0
core.gid = 0
core.flushiter = 0
core.atime.sec = Thu Jan 1 09:00:00 1970
core.atime.nsec = 000000000
core.mtime.sec = Thu Jan 1 09:00:00 1970
core.mtime.nsec = 000000000
core.ctime.sec = Thu Jan 1 09:00:00 1970
core.ctime.nsec = 000000000
core.size = 0
core.nblocks = 0
core.extsize = 0
core.nextents = 0
core.naextents = 0
core.forkoff = 0
core.aformat = 0 (dev)
core.dmevmask = 0
core.dmstate = 0
core.newrtbm = 0
core.prealloc = 0
core.realtime = 0
core.immutable = 0
core.append = 0
core.sync = 0
core.noatime = 0
core.nodump = 0
core.rtinherit = 0
core.projinherit = 0
core.nosymlinks = 0
core.extsz = 0
core.extszinherit = 0
core.nodefrag = 0
core.filestream = 0
core.gen = 0
next_unlinked = null
v3.crc = 0x9dd9f3f (correct)
v3.change_count = 0
v3.lsn = 0
v3.flags2 = 0
v3.crtime.sec = Thu Jan 1 09:00:00 1970
v3.crtime.nsec = 000000000
v3.inumber = 99
v3.uuid = 50842188-8063-4ad5-8ede-5e787ecea993
u3.dev = 0
$ xfs_db -r image -c "inode 99" -c p
core.magic = 0x494e
core.mode = 0
core.version = 3
core.format = 0 (dev)
core.nlinkv2 = 0
core.onlink = 0
core.projid_lo = 0
core.projid_hi = 0
core.uid = 0
core.gid = 0
core.flushiter = 0
core.atime.sec = Thu Jan 1 09:00:00 1970
core.atime.nsec = 000000000
core.mtime.sec = Thu Jan 1 09:00:00 1970
core.mtime.nsec = 000000000
core.ctime.sec = Thu Jan 1 09:00:00 1970
core.ctime.nsec = 000000000
core.size = 0
core.nblocks = 0
core.extsize = 0
core.nextents = 0
core.naextents = 0
core.forkoff = 0
core.aformat = 0 (dev)
core.dmevmask = 0
core.dmstate = 0
core.newrtbm = 0
core.prealloc = 0
core.realtime = 0
core.immutable = 0
core.append = 0
core.sync = 0
core.noatime = 0
core.nodump = 0
core.rtinherit = 0
core.projinherit = 0
core.nosymlinks = 0
core.extsz = 0
core.extszinherit = 0
core.nodefrag = 0
core.filestream = 0
core.gen = 0
next_unlinked = null
v3.crc = 0x9dd9f3f (correct)
v3.change_count = 0
v3.lsn = 0
v3.flags2 = 0
v3.crtime.sec = Thu Jan 1 09:00:00 1970
v3.crtime.nsec = 000000000
v3.inumber = 99
v3.uuid = 50842188-8063-4ad5-8ede-5e787ecea993
u3.dev = 0
$ xfs_db -r image -c "inode 99" -c p
core.magic = 0x494e
core.mode = 0100664
core.version = 3
core.format = 2 (extents)
core.nlinkv2 = 1
core.onlink = 0
core.projid_lo = 0
core.projid_hi = 0
core.uid = 1000
core.gid = 1000
core.flushiter = 0
core.atime.sec = Wed Mar 14 14:19:51 2018
core.atime.nsec = 720203723
core.mtime.sec = Wed Mar 14 14:19:51 2018
core.mtime.nsec = 722204070
core.ctime.sec = Wed Mar 14 14:19:57 2018
core.ctime.nsec = 303172488
core.size = 43
core.nblocks = 1
core.extsize = 0
core.nextents = 1
core.naextents = 0
core.forkoff = 37
core.aformat = 1 (local)
core.dmevmask = 0
core.dmstate = 0
core.newrtbm = 0
core.prealloc = 0
core.realtime = 0
core.immutable = 0
core.append = 0
core.sync = 0
core.noatime = 0
core.nodump = 0
core.rtinherit = 0
core.projinherit = 0
core.nosymlinks = 0
core.extsz = 0
core.extszinherit = 0
core.nodefrag = 0
core.filestream = 0
core.gen = 2284245021
next_unlinked = null
v3.crc = 0x576dfc26 (correct)
v3.change_count = 9
v3.lsn = 0x100000007
v3.flags2 = 0
v3.crtime.sec = Wed Mar 14 14:19:51 2018
v3.crtime.nsec = 720203723
v3.inumber = 99
v3.uuid = 50842188-8063-4ad5-8ede-5e787ecea993
u3.bmx[0] = [startoff,startblock,blockcount,extentflag] 0:[0,9,1,0]
a.sfattr.hdr.totsize = 13
a.sfattr.hdr.count = 1
a.sfattr.list[0].namelen = 5
a.sfattr.list[0].valuelen = 1
a.sfattr.list[0].root = 0
a.sfattr.list[0].secure = 0
a.sfattr.list[0].name = "world"
a.sfattr.list[0].value = "0"
$
$ ./setattr
at line 26 error=61
# echo "xfs:*" >/sys/kernel/debug/tracing/set_event
# cat /sys/kernel/debug/tracing/trace > xfstrace
$ attr -l /mnt/xfs/hello
attr_list: 入力/出力エラーです
Could not list "(null)" for /mnt/xfs/hello
[12478.432363] XFS (loop0): Metadata corruption detected at xfs_attr3_leaf_write_verify+0xd3/0xe0 [xfs], xfs_attr3_leaf block 0x58
[12478.432376] XFS (loop0): Unmount and run xfs_repair
[12478.432379] XFS (loop0): First 64 bytes of corrupted metadata buffer:
[12478.432385] eafba000: 00 00 00 00 00 00 00 00 3b ee 00 00 00 00 00 00 ........;.......
[12478.432388] eafba010: 00 00 00 00 00 00 00 58 00 00 00 00 00 00 00 00 .......X........
[12478.432391] eafba020: 50 84 21 88 80 63 4a d5 8e de 5e 78 7e ce a9 93 P.!..cJ...^x~...
[12478.432395] eafba030: 00 00 00 00 00 00 00 63 00 00 00 00 10 00 00 00 .......c........
[12478.432400] XFS (loop0): xfs_do_force_shutdown(0x8) called from line 1322 of file fs/xfs/xfs_buf.c. Return address = 0xf7fae63d
[12478.432409] XFS (loop0): Corruption of in-memory data detected. Shutting down filesystem
[12478.432413] XFS (loop0): Please umount the filesystem and rectify the problem(s)
[12508.640059] XFS (loop0): xfs_log_force: error -5 returned.
setattr-4205 [000] .... 12286.546672: xfs_file_buffered_read: dev 253:2 ino 0x89298 size 0x2258 offset 0x0 count 0x80
setattr-4205 [000] .... 12286.546678: xfs_ilock: dev 253:2 ino 0x89298 flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.546689: xfs_update_time: dev 253:2 ino 0x89298
setattr-4205 [000] .... 12286.546696: xfs_log_reserve: dev 253:2 t_ocnt 0 t_cnt 0 t_curr_res 2856 t_unit_res 2856 t_flags XLOG_TIC_INITED reserveq empty writeq empty grant_reserve_cycle 2 grant_reserve_bytes 218348848 grant_write_cycle 2 grant_write_bytes 218348848 curr_cycle 2 curr_block 426458 tail_cycle 2 tail_block 426443
setattr-4205 [000] .... 12286.546698: xfs_log_reserve_exit: dev 253:2 t_ocnt 0 t_cnt 0 t_curr_res 2856 t_unit_res 2856 t_flags XLOG_TIC_INITED reserveq empty writeq empty grant_reserve_cycle 2 grant_reserve_bytes 218351704 grant_write_cycle 2 grant_write_bytes 218351704 curr_cycle 2 curr_block 426458 tail_cycle 2 tail_block 426443
setattr-4205 [000] .... 12286.546698: xfs_ilock: dev 253:2 ino 0x89298 flags ILOCK_EXCL caller xfs_vn_update_time [xfs]
setattr-4205 [000] .... 12286.546708: xfs_inode_pin: dev 253:2 ino 0x89298 count 1 pincount 0 caller xfs_cil_prepare_item.isra.2 [xfs]
setattr-4205 [000] .... 12286.546710: xfs_log_done_nonperm: dev 253:2 t_ocnt 0 t_cnt 0 t_curr_res 2604 t_unit_res 2856 t_flags XLOG_TIC_INITED reserveq empty writeq empty grant_reserve_cycle 2 grant_reserve_bytes 218351704 grant_write_cycle 2 grant_write_bytes 218351704 curr_cycle 2 curr_block 426458 tail_cycle 2 tail_block 426443
setattr-4205 [000] .... 12286.546711: xfs_log_ungrant_enter: dev 253:2 t_ocnt 0 t_cnt 0 t_curr_res 2604 t_unit_res 2856 t_flags XLOG_TIC_INITED reserveq empty writeq empty grant_reserve_cycle 2 grant_reserve_bytes 218351704 grant_write_cycle 2 grant_write_bytes 218351704 curr_cycle 2 curr_block 426458 tail_cycle 2 tail_block 426443
setattr-4205 [000] .... 12286.546712: xfs_log_ungrant_sub: dev 253:2 t_ocnt 0 t_cnt 0 t_curr_res 2604 t_unit_res 2856 t_flags XLOG_TIC_INITED reserveq empty writeq empty grant_reserve_cycle 2 grant_reserve_bytes 218351704 grant_write_cycle 2 grant_write_bytes 218351704 curr_cycle 2 curr_block 426458 tail_cycle 2 tail_block 426443
setattr-4205 [000] .... 12286.546713: xfs_log_ungrant_exit: dev 253:2 t_ocnt 0 t_cnt 0 t_curr_res 2604 t_unit_res 2856 t_flags XLOG_TIC_INITED reserveq empty writeq empty grant_reserve_cycle 2 grant_reserve_bytes 218349100 grant_write_cycle 2 grant_write_bytes 218349100 curr_cycle 2 curr_block 426458 tail_cycle 2 tail_block 426443
setattr-4205 [000] .... 12286.546717: xfs_iunlock: dev 253:2 ino 0x89298 flags ILOCK_EXCL caller xfs_inode_item_unlock [xfs]
setattr-4205 [000] .... 12286.546719: xfs_iunlock: dev 253:2 ino 0x89298 flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.546822: xfs_file_buffered_read: dev 253:2 ino 0x89298 size 0x2258 offset 0x34 count 0x120
setattr-4205 [000] .... 12286.546823: xfs_ilock: dev 253:2 ino 0x89298 flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.546826: xfs_iunlock: dev 253:2 ino 0x89298 flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.546827: xfs_file_buffered_read: dev 253:2 ino 0x89298 size 0x2258 offset 0x154 count 0x13
setattr-4205 [000] .... 12286.546828: xfs_ilock: dev 253:2 ino 0x89298 flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.546829: xfs_iunlock: dev 253:2 ino 0x89298 flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.546846: xfs_file_buffered_read: dev 253:0 ino 0x1dbefd size 0x2722c offset 0x0 count 0x34
setattr-4205 [000] .... 12286.546846: xfs_ilock: dev 253:0 ino 0x1dbefd flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.546848: xfs_iunlock: dev 253:0 ino 0x1dbefd flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.546850: xfs_file_buffered_read: dev 253:0 ino 0x1dbefd size 0x2722c offset 0x34 count 0xe0
setattr-4205 [000] .... 12286.546850: xfs_ilock: dev 253:0 ino 0x1dbefd flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.546851: xfs_iunlock: dev 253:0 ino 0x1dbefd flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.547050: xfs_filemap_fault: dev 253:2 ino 0x89298
setattr-4205 [000] .... 12286.547051: xfs_ilock: dev 253:2 ino 0x89298 flags MMAPLOCK_SHARED caller xfs_filemap_fault [xfs]
setattr-4205 [000] .... 12286.547053: xfs_iunlock: dev 253:2 ino 0x89298 flags MMAPLOCK_SHARED caller xfs_filemap_fault [xfs]
setattr-4205 [000] .... 12286.547086: xfs_filemap_fault: dev 253:0 ino 0x1dbefd
setattr-4205 [000] .... 12286.547086: xfs_ilock: dev 253:0 ino 0x1dbefd flags MMAPLOCK_SHARED caller xfs_filemap_fault [xfs]
setattr-4205 [000] .... 12286.547087: xfs_iunlock: dev 253:0 ino 0x1dbefd flags MMAPLOCK_SHARED caller xfs_filemap_fault [xfs]
setattr-4205 [000] .... 12286.547140: xfs_filemap_fault: dev 253:0 ino 0x1dbefd
setattr-4205 [000] .... 12286.547140: xfs_ilock: dev 253:0 ino 0x1dbefd flags MMAPLOCK_SHARED caller xfs_filemap_fault [xfs]
setattr-4205 [000] .... 12286.547141: xfs_iunlock: dev 253:0 ino 0x1dbefd flags MMAPLOCK_SHARED caller xfs_filemap_fault [xfs]
setattr-4205 [000] .... 12286.547226: xfs_getattr: dev 253:0 ino 0x601b263
setattr-4205 [000] .... 12286.547259: xfs_file_buffered_read: dev 253:0 ino 0x1d39 size 0x206f08 offset 0x0 count 0x200
setattr-4205 [000] .... 12286.547260: xfs_ilock: dev 253:0 ino 0x1d39 flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.547264: xfs_iunlock: dev 253:0 ino 0x1d39 flags IOLOCK_SHARED caller xfs_file_buffered_aio_read [xfs]
setattr-4205 [000] .... 12286.547268: xfs_getattr: dev 253:0 ino 0x1d39
setattr-4205 [000] .... 12286.547293: xfs_filemap_fault: dev 253:0 ino 0x1d39
setattr-4205 [000] .... 12286.547293: xfs_ilock: dev 253:0 ino 0x1d39 flags MMAPLOCK_SHARED caller xfs_filemap_fault [xfs]
setattr-4205 [000] .... 12286.547294: xfs_iunlock: dev 253:0 ino 0x1d39 flags MMAPLOCK_SHARED caller xfs_filemap_fault [xfs]
setattr-4205 [000] .... 12286.547522: xfs_ilock: dev 253:0 ino 0x601b263 flags ILOCK_SHARED caller xfs_free_eofblocks [xfs]
setattr-4205 [000] .... 12286.547527: xfs_iunlock: dev 253:0 ino 0x601b263 flags ILOCK_SHARED caller xfs_free_eofblocks [xfs]
setattr-4205 [000] .... 12286.547617: xfs_log_reserve: dev 7:0 t_ocnt 3 t_cnt 3 t_curr_res 431556 t_unit_res 431556 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 7168 grant_write_cycle 1 grant_write_bytes 7168 curr_cycle 1 curr_block 14 tail_cycle 1 tail_block 12
setattr-4205 [000] .... 12286.547619: xfs_log_reserve_exit: dev 7:0 t_ocnt 3 t_cnt 3 t_curr_res 431556 t_unit_res 431556 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 1301836 grant_write_cycle 1 grant_write_bytes 1301836 curr_cycle 1 curr_block 14 tail_cycle 1 tail_block 12
setattr-4205 [000] .... 12286.547619: xfs_ilock: dev 7:0 ino 0x63 flags ILOCK_EXCL caller xfs_attr_set [xfs]
setattr-4205 [000] .... 12286.547623: xfs_attr_sf_addname: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547625: xfs_attr_sf_lookup: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547626: xfs_attr_sf_remove: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547630: xfs_defer_init: dev 0:0 ops e2739d80 committed 0 low 0
setattr-4205 [000] .... 12286.547632: xfs_attr_sf_to_leaf: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547635: xfs_da_grow_inode: dev 7:0 ino 0x63 name world namelen 5 hashval 0x7dfcb663 inumber 0x0 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547645: xfs_perag_get: dev 7:0 agno 0 refcount 9 caller xfs_bmap_longest_free_extent [xfs]
setattr-4205 [000] .... 12286.547647: xfs_perag_put: dev 7:0 agno 0 refcount 8 caller xfs_bmap_longest_free_extent [xfs]
setattr-4205 [000] .... 12286.547649: xfs_perag_get: dev 7:0 agno 0 refcount 9 caller xfs_alloc_vextent [xfs]
setattr-4205 [000] .... 12286.547652: xfs_alloc_read_agf: dev 7:0 agno 0
setattr-4205 [000] .... 12286.547654: xfs_read_agf: dev 7:0 agno 0
setattr-4205 [000] .... 12286.547656: xfs_perag_get: dev 7:0 agno 0 refcount 10 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547657: xfs_perag_put: dev 7:0 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547660: xfs_buf_trylock: dev 7:0 bno 0x1 nblks 0x1 hold 2 pincount 0 lock 0 flags ASYNC|DONE|KMEM caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547661: xfs_buf_find: dev 7:0 bno 0x1 len 0x200 hold 2 pincount 0 lock 0 flags READ|TRYLOCK caller xfs_buf_get_map [xfs]
setattr-4205 [000] .... 12286.547662: xfs_buf_get: dev 7:0 bno 0x1 len 0x200 hold 2 pincount 0 lock 0 flags READ|TRYLOCK caller xfs_buf_read_map [xfs]
setattr-4205 [000] .... 12286.547663: xfs_buf_read: dev 7:0 bno 0x1 len 0x200 hold 2 pincount 0 lock 0 flags READ|TRYLOCK caller xfs_trans_read_buf_map [xfs]
setattr-4205 [000] .... 12286.547667: xfs_buf_hold: dev 7:0 bno 0x1 nblks 0x1 hold 2 pincount 0 lock 0 flags ASYNC|DONE|KMEM caller xfs_buf_item_init [xfs]
setattr-4205 [000] .... 12286.547669: xfs_trans_read_buf: dev 7:0 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xe2febf80 liflags
setattr-4205 [000] .... 12286.547669: xfs_perag_get: dev 7:0 agno 0 refcount 10 caller xfs_alloc_read_agf [xfs]
setattr-4205 [000] .... 12286.547670: xfs_perag_put: dev 7:0 agno 0 refcount 9 caller xfs_alloc_read_agf [xfs]
setattr-4205 [000] .... 12286.547671: xfs_perag_get: dev 7:0 agno 0 refcount 10 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547671: xfs_perag_put: dev 7:0 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547672: xfs_buf_trylock: dev 7:0 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 0 flags DONE|KMEM caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547673: xfs_buf_find: dev 7:0 bno 0x3 len 0x200 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_get_map [xfs]
setattr-4205 [000] .... 12286.547673: xfs_buf_get: dev 7:0 bno 0x3 len 0x200 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_read_map [xfs]
setattr-4205 [000] .... 12286.547673: xfs_buf_read: dev 7:0 bno 0x3 len 0x200 hold 2 pincount 0 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
setattr-4205 [000] .... 12286.547674: xfs_buf_hold: dev 7:0 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 0 flags DONE|KMEM caller xfs_buf_item_init [xfs]
setattr-4205 [000] .... 12286.547674: xfs_trans_read_buf: dev 7:0 bno 0x3 len 0x200 hold 3 pincount 0 lock 0 flags DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547679: xfs_trans_brelse: dev 7:0 bno 0x3 len 0x200 hold 3 pincount 0 lock 0 flags DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547681: xfs_buf_item_relse: dev 7:0 bno 0x3 nblks 0x1 hold 3 pincount 0 lock 0 flags DONE|KMEM caller xfs_trans_brelse [xfs]
setattr-4205 [000] .... 12286.547682: xfs_buf_rele: dev 7:0 bno 0x3 nblks 0x1 hold 3 pincount 0 lock 0 flags DONE|KMEM caller xfs_buf_item_relse [xfs]
setattr-4205 [000] .... 12286.547684: xfs_buf_unlock: dev 7:0 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 1 flags DONE|KMEM caller xfs_trans_brelse [xfs]
setattr-4205 [000] .... 12286.547684: xfs_buf_rele: dev 7:0 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 1 flags DONE|KMEM caller xfs_trans_brelse [xfs]
setattr-4205 [000] .... 12286.547690: xfs_perag_get: dev 7:0 agno 0 refcount 10 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547690: xfs_perag_put: dev 7:0 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547691: xfs_buf_trylock: dev 7:0 bno 0x10 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547691: xfs_buf_find: dev 7:0 bno 0x10 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_get_map [xfs]
setattr-4205 [000] .... 12286.547692: xfs_buf_get: dev 7:0 bno 0x10 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_read_map [xfs]
setattr-4205 [000] .... 12286.547692: xfs_buf_read: dev 7:0 bno 0x10 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
setattr-4205 [000] .... 12286.547693: xfs_buf_hold: dev 7:0 bno 0x10 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller xfs_buf_item_init [xfs]
setattr-4205 [000] .... 12286.547693: xfs_trans_read_buf: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547705: xfs_perag_get: dev 7:0 agno 0 refcount 10 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547706: xfs_perag_put: dev 7:0 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547706: xfs_buf_trylock: dev 7:0 bno 0x8 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547707: xfs_buf_find: dev 7:0 bno 0x8 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_get_map [xfs]
setattr-4205 [000] .... 12286.547707: xfs_buf_get: dev 7:0 bno 0x8 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_read_map [xfs]
setattr-4205 [000] .... 12286.547707: xfs_buf_read: dev 7:0 bno 0x8 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
setattr-4205 [000] .... 12286.547708: xfs_buf_hold: dev 7:0 bno 0x8 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller xfs_buf_item_init [xfs]
setattr-4205 [000] .... 12286.547709: xfs_trans_read_buf: dev 7:0 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xe2feb020 liflags
setattr-4205 [000] .... 12286.547713: xfs_trans_log_buf: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547715: xfs_trans_log_buf: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547719: xfs_trans_log_buf: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547719: xfs_trans_log_buf: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547722: xfs_trans_log_buf: dev 7:0 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xe2feb020 liflags
setattr-4205 [000] .... 12286.547723: xfs_trans_brelse: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547723: xfs_trans_brelse: dev 7:0 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb020 liflags
setattr-4205 [000] .... 12286.547725: xfs_alloc_near_first: dev 7:0 agno 0 agbno 11 minlen 1 maxlen 1 mod 0 prod 1 minleft 1 total 30 alignment 1 minalignslop 0 len 1 type NEAR_BNO otype START_BNO wasdel 0 wasfromfl 0 isfl 0 userdata 0 firstblock 0xffffffffffffffff
setattr-4205 [000] .... 12286.547727: xfs_agf: dev 7:0 agno 0 flags FREEBLKS length 16384 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 16365 longest 16364 caller xfs_alloc_update_counters.isra.15 [xfs]
setattr-4205 [000] .... 12286.547728: xfs_trans_log_buf: dev 7:0 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xe2febf80 liflags
setattr-4205 [000] .... 12286.547729: xfs_perag_put: dev 7:0 agno 0 refcount 8 caller xfs_alloc_vextent [xfs]
setattr-4205 [000] .... 12286.547734: xfs_iext_insert: dev 7:0 ino 0x63 state ATTR idx 0 offset 0 block 11 count 1 flag 0 caller xfs_bmap_add_extent_hole_real [xfs]
setattr-4205 [000] .... 12286.547741: xfs_attr_leaf_create: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547745: xfs_perag_get: dev 7:0 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547746: xfs_perag_put: dev 7:0 agno 0 refcount 8 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547748: xfs_buf_init: dev 7:0 bno 0x58 nblks 0x8 hold 1 pincount 0 lock 0 flags caller xfs_buf_get_map [xfs]
setattr-4205 [000] .... 12286.547750: xfs_perag_get: dev 7:0 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547752: xfs_buf_ioerror: dev 7:0 bno 0x58 len 0x1000 hold 1 pincount 0 lock 0 error 0 flags PAGES caller xfs_trans_get_buf_map [xfs]
setattr-4205 [000] .... 12286.547752: xfs_buf_get: dev 7:0 bno 0x58 len 0x1000 hold 1 pincount 0 lock 0 flags caller xfs_trans_get_buf_map [xfs]
setattr-4205 [000] .... 12286.547753: xfs_buf_hold: dev 7:0 bno 0x58 nblks 0x8 hold 1 pincount 0 lock 0 flags PAGES caller xfs_buf_item_init [xfs]
setattr-4205 [000] .... 12286.547754: xfs_trans_get_buf: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 0 lock 0 flags PAGES recur 0 refcount 1 bliflags lidesc 0xe2feb740 liflags
setattr-4205 [000] .... 12286.547756: xfs_trans_log_buf: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xe2feb740 liflags
setattr-4205 [000] .... 12286.547759: xfs_defer_finish: dev 7:0 ops e2739d80 committed 0 low 0
setattr-4205 [000] .... 12286.547761: xfs_defer_finish_done: dev 7:0 ops e2739d80 committed 0 low 0
setattr-4205 [000] .... 12286.547767: xfs_buf_item_size: dev 7:0 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2febf80 liflags
setattr-4205 [000] .... 12286.547768: xfs_buf_item_size: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .N.. 12286.547769: xfs_buf_item_size: dev 7:0 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb020 liflags
setattr-4205 [000] .... 12286.547873: xfs_buf_item_size: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb740 liflags
setattr-4205 [000] .... 12286.547879: xfs_inode_pin: dev 7:0 ino 0x63 count 1 pincount 0 caller xfs_cil_prepare_item.isra.2 [xfs]
setattr-4205 [000] .... 12286.547881: xfs_buf_item_format: dev 7:0 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2febf80 liflags
setattr-4205 [000] .... 12286.547883: xfs_buf_item_pin: dev 7:0 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2febf80 liflags
setattr-4205 [000] .... 12286.547884: xfs_buf_item_format: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547884: xfs_buf_item_pin: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb0e0 liflags
setattr-4205 [000] .... 12286.547885: xfs_buf_item_format: dev 7:0 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb020 liflags
setattr-4205 [000] .... 12286.547886: xfs_buf_item_pin: dev 7:0 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb020 liflags
setattr-4205 [000] .... 12286.547889: xfs_buf_item_format: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb740 liflags
setattr-4205 [000] .... 12286.547889: xfs_buf_item_pin: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xe2feb740 liflags
setattr-4205 [000] .... 12286.547892: xfs_log_done_perm: dev 7:0 t_ocnt 3 t_cnt 3 t_curr_res 424504 t_unit_res 431556 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 1301836 grant_write_cycle 1 grant_write_bytes 1301836 curr_cycle 1 curr_block 14 tail_cycle 1 tail_block 12
setattr-4205 [000] .... 12286.547893: xfs_log_regrant_reserve_enter: dev 7:0 t_ocnt 3 t_cnt 3 t_curr_res 424504 t_unit_res 431556 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 1301836 grant_write_cycle 1 grant_write_bytes 1301836 curr_cycle 1 curr_block 14 tail_cycle 1 tail_block 12
setattr-4205 [000] .... 12286.547894: xfs_log_regrant_reserve_sub: dev 7:0 t_ocnt 3 t_cnt 2 t_curr_res 431556 t_unit_res 431556 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 877332 grant_write_cycle 1 grant_write_bytes 877332 curr_cycle 1 curr_block 14 tail_cycle 1 tail_block 12
setattr-4205 [000] .... 12286.547897: xfs_buf_item_unlock: dev 7:0 bno 0x1 len 0x200 hold 3 pincount 1 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
setattr-4205 [000] .... 12286.547897: xfs_buf_unlock: dev 7:0 bno 0x1 nblks 0x1 hold 3 pincount 1 lock 1 flags ASYNC|DONE|KMEM caller xfs_buf_item_unlock [xfs]
setattr-4205 [000] .... 12286.547898: xfs_buf_rele: dev 7:0 bno 0x1 nblks 0x1 hold 3 pincount 1 lock 1 flags ASYNC|DONE|KMEM caller xfs_buf_item_unlock [xfs]
setattr-4205 [000] .... 12286.547898: xfs_buf_item_unlock: dev 7:0 bno 0x10 len 0x1000 hold 3 pincount 1 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
setattr-4205 [000] .... 12286.547899: xfs_buf_unlock: dev 7:0 bno 0x10 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-4205 [000] .... 12286.547899: xfs_buf_rele: dev 7:0 bno 0x10 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-4205 [000] .... 12286.547899: xfs_buf_item_unlock: dev 7:0 bno 0x8 len 0x1000 hold 3 pincount 1 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
setattr-4205 [000] .... 12286.547900: xfs_buf_unlock: dev 7:0 bno 0x8 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-4205 [000] .... 12286.547900: xfs_buf_rele: dev 7:0 bno 0x8 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-4205 [000] .... 12286.547901: xfs_buf_item_unlock: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
setattr-4205 [000] .... 12286.547901: xfs_buf_unlock: dev 7:0 bno 0x58 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-4205 [000] .... 12286.547902: xfs_buf_rele: dev 7:0 bno 0x58 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-4205 [000] .... 12286.547908: xfs_attr_leaf_addname: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547912: xfs_perag_get: dev 7:0 agno 0 refcount 10 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547912: xfs_perag_put: dev 7:0 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547913: xfs_buf_trylock: dev 7:0 bno 0x58 nblks 0x8 hold 2 pincount 1 lock 0 flags DONE|PAGES caller _xfs_buf_find [xfs]
setattr-4205 [000] .... 12286.547914: xfs_buf_find: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 1 lock 0 flags READ caller xfs_buf_get_map [xfs]
setattr-4205 [000] .... 12286.547914: xfs_buf_get: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 1 lock 0 flags READ caller xfs_buf_read_map [xfs]
setattr-4205 [000] .... 12286.547915: xfs_buf_read: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 1 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
setattr-4205 [000] .... 12286.547916: xfs_trans_read_buf: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0xe2feb020 liflags
setattr-4205 [000] .... 12286.547918: xfs_attr_leaf_lookup: dev 7:0 ino 0x63 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-4205 [000] .... 12286.547919: xfs_trans_brelse: dev 7:0 bno 0x58 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0xe2feb020 liflags
setattr-4205 [000] .... 12286.547919: xfs_buf_unlock: dev 7:0 bno 0x58 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_trans_brelse [xfs]
setattr-4205 [000] .... 12286.547919: xfs_buf_rele: dev 7:0 bno 0x58 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_trans_brelse [xfs]
setattr-4205 [000] .... 12286.547922: xfs_log_done_nonperm: dev 7:0 t_ocnt 3 t_cnt 2 t_curr_res 431556 t_unit_res 431556 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 877332 grant_write_cycle 1 grant_write_bytes 877332 curr_cycle 1 curr_block 14 tail_cycle 1 tail_block 12
setattr-4205 [000] .... 12286.547923: xfs_log_ungrant_enter: dev 7:0 t_ocnt 3 t_cnt 1 t_curr_res 431556 t_unit_res 431556 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 877332 grant_write_cycle 1 grant_write_bytes 877332 curr_cycle 1 curr_block 14 tail_cycle 1 tail_block 12
setattr-4205 [000] .... 12286.547924: xfs_log_ungrant_sub: dev 7:0 t_ocnt 3 t_cnt 1 t_curr_res 431556 t_unit_res 431556 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 877332 grant_write_cycle 1 grant_write_bytes 877332 curr_cycle 1 curr_block 14 tail_cycle 1 tail_block 12
setattr-4205 [000] .... 12286.547925: xfs_log_ungrant_exit: dev 7:0 t_ocnt 3 t_cnt 1 t_curr_res 431556 t_unit_res 431556 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 14220 grant_write_cycle 1 grant_write_bytes 14220 curr_cycle 1 curr_block 14 tail_cycle 1 tail_block 12
setattr-4205 [000] .... 12286.547927: xfs_iunlock: dev 7:0 ino 0x63 flags ILOCK_EXCL caller xfs_attr_set [xfs]
setattr-4205 [000] .... 12286.548147: xfs_ilock: dev 253:0 ino 0x1dbefd flags ILOCK_SHARED caller xfs_free_eofblocks [xfs]
setattr-4205 [000] .... 12286.548150: xfs_iunlock: dev 253:0 ino 0x1dbefd flags ILOCK_SHARED caller xfs_free_eofblocks [xfs]
setattr-4205 [000] .... 12286.548151: xfs_ilock: dev 253:0 ino 0x1d39 flags ILOCK_SHARED caller xfs_free_eofblocks [xfs]
setattr-4205 [000] .... 12286.548152: xfs_iunlock: dev 253:0 ino 0x1d39 flags ILOCK_SHARED caller xfs_free_eofblocks [xfs]
# umount /mnt/xfs
# mount -o loop image /mnt/xfs
mount: /dev/loop0 を /mnt/xfs にマウントする処理が失敗しました: 構造体を内容消去する必要があります
# xfs_repair -f image
Phase 1 - find and verify superblock...
Phase 2 - using internal log
- zero log...
ERROR: The filesystem has valuable metadata changes in a log which needs to
be replayed. Mount the filesystem to replay the log, and unmount it before
re-running xfs_repair. If you are unable to mount the filesystem, then use
the -L option to destroy the log and attempt a repair.
Note that destroying the log may cause corruption -- please attempt a mount
of the filesystem before doing this.
# xfs_repair -f image -L
Phase 1 - find and verify superblock...
Phase 2 - using internal log
- zero log...
ALERT: The filesystem has valuable metadata changes in a log which is being
destroyed because the -L option was used.
- scan filesystem freespace and inode maps...
sb_fdblocks 61376, counted 64651
- found root inode chunk
Phase 3 - for each AG...
- scan and clear agi unlinked lists...
- process known inodes and perform inode discovery...
- agno = 0
- agno = 1
- agno = 2
- agno = 3
- process newly discovered inodes...
Phase 4 - check for duplicate blocks...
- setting up duplicate extent list...
- check for inodes claiming duplicate blocks...
- agno = 0
- agno = 1
- agno = 2
- agno = 3
Phase 5 - rebuild AG headers and trees...
- reset superblock...
Phase 6 - check inode connectivity...
- resetting contents of realtime bitmap and summary inodes
- traversing filesystem ...
- traversal finished ...
- moving disconnected inodes to lost+found ...
Phase 7 - verify and correct link counts...
Maximum metadata LSN (1:14) is ahead of log (1:2).
Format log to cycle 4.
done
# mount -o loop image /mnt/xfs
#
[kanda@centos7 ~]$ date > /mnt/xfs/hello
[kanda@centos7 ~]$ attr -s world -V 0 /mnt/xfs/hello
Attribute "world" set to a 1 byte value for /mnt/xfs/hello:
0
[kanda@centos7 ~]$ ls -li /mnt/xfs/hello
67 -rw-rw-r--. 1 kanda kanda 43 3月 14 14:04 /mnt/xfs/hello
[kanda@centos7 ~]$ ./setattr
at line 26 error=61
a.bmx[0] = [startoff,startblock,blockcount,extentflag] 0:[0,17,1,0]
[root@centos7 kanda]# date
2018年 3月 14日 水曜日 14:08:23 JST
setattr-2628 [000] .... 445.825305: xfs_log_reserve: dev 8:16 t_ocnt 3 t_cnt 3 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 8192 grant_write_cycle 1 grant_write_bytes 8192 curr_cycle 1 curr_block 16 tail_cycle 1 tail_block 14
setattr-2628 [000] .... 445.825308: xfs_log_reserve_exit: dev 8:16 t_ocnt 3 t_cnt 3 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 1302872 grant_write_cycle 1 grant_write_bytes 1302872 curr_cycle 1 curr_block 16 tail_cycle 1 tail_block 14
setattr-2628 [000] .... 445.825309: xfs_ilock: dev 8:16 ino 0x43 flags ILOCK_EXCL caller xfs_attr_set [xfs]
setattr-2628 [000] .... 445.825311: xfs_attr_sf_addname: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825312: xfs_attr_sf_lookup: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825313: xfs_attr_sf_remove: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825315: xfs_attr_sf_to_leaf: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825317: xfs_da_grow_inode: dev 8:16 ino 0x43 name world namelen 5 hashval 0x7dfcb663 inumber 0x0 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825321: xfs_perag_get: dev 8:16 agno 0 refcount 8 caller xfs_bmap_longest_free_extent [xfs]
setattr-2628 [000] .... 445.825322: xfs_perag_put: dev 8:16 agno 0 refcount 7 caller xfs_bmap_longest_free_extent [xfs]
setattr-2628 [000] .... 445.825322: xfs_perag_get: dev 8:16 agno 0 refcount 8 caller xfs_alloc_vextent [xfs]
setattr-2628 [000] .... 445.825323: xfs_alloc_read_agf: dev 8:16 agno 0
setattr-2628 [000] .... 445.825324: xfs_read_agf: dev 8:16 agno 0
setattr-2628 [000] .... 445.825325: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825326: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825327: xfs_buf_trylock: dev 8:16 bno 0x1 nblks 0x1 hold 2 pincount 0 lock 0 flags ASYNC|DONE|KMEM caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825328: xfs_buf_find: dev 8:16 bno 0x1 len 0x200 hold 2 pincount 0 lock 0 flags READ|TRYLOCK caller xfs_buf_get_map [xfs]
setattr-2628 [000] .... 445.825328: xfs_buf_get: dev 8:16 bno 0x1 len 0x200 hold 2 pincount 0 lock 0 flags READ|TRYLOCK caller xfs_buf_read_map [xfs]
setattr-2628 [000] .... 445.825329: xfs_buf_read: dev 8:16 bno 0x1 len 0x200 hold 2 pincount 0 lock 0 flags READ|TRYLOCK caller xfs_trans_read_buf_map [xfs]
setattr-2628 [000] .... 445.825330: xfs_buf_hold: dev 8:16 bno 0x1 nblks 0x1 hold 2 pincount 0 lock 0 flags ASYNC|DONE|KMEM caller xfs_buf_item_init [xfs]
setattr-2628 [000] .... 445.825331: xfs_trans_read_buf: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xffff88007608d140 liflags
setattr-2628 [000] .... 445.825331: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller xfs_alloc_read_agf [xfs]
setattr-2628 [000] .... 445.825331: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller xfs_alloc_read_agf [xfs]
setattr-2628 [000] .... 445.825332: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825333: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825333: xfs_buf_trylock: dev 8:16 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 0 flags DONE|KMEM caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825333: xfs_buf_find: dev 8:16 bno 0x3 len 0x200 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_get_map [xfs]
setattr-2628 [000] .... 445.825333: xfs_buf_get: dev 8:16 bno 0x3 len 0x200 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_read_map [xfs]
setattr-2628 [000] .... 445.825333: xfs_buf_read: dev 8:16 bno 0x3 len 0x200 hold 2 pincount 0 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
setattr-2628 [000] .... 445.825334: xfs_buf_hold: dev 8:16 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 0 flags DONE|KMEM caller xfs_buf_item_init [xfs]
setattr-2628 [000] .... 445.825334: xfs_trans_read_buf: dev 8:16 bno 0x3 len 0x200 hold 3 pincount 0 lock 0 flags DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825334: xfs_trans_brelse: dev 8:16 bno 0x3 len 0x200 hold 3 pincount 0 lock 0 flags DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825335: xfs_buf_item_relse: dev 8:16 bno 0x3 nblks 0x1 hold 3 pincount 0 lock 0 flags DONE|KMEM caller xfs_trans_brelse [xfs]
setattr-2628 [000] .... 445.825336: xfs_buf_rele: dev 8:16 bno 0x3 nblks 0x1 hold 3 pincount 0 lock 0 flags DONE|KMEM caller xfs_buf_item_relse [xfs]
setattr-2628 [000] .... 445.825337: xfs_buf_unlock: dev 8:16 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 1 flags DONE|KMEM caller xfs_trans_brelse [xfs]
setattr-2628 [000] .... 445.825337: xfs_buf_rele: dev 8:16 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 1 flags DONE|KMEM caller xfs_trans_brelse [xfs]
setattr-2628 [000] .... 445.825339: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825340: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825340: xfs_buf_trylock: dev 8:16 bno 0x10 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825340: xfs_buf_find: dev 8:16 bno 0x10 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_get_map [xfs]
setattr-2628 [000] .... 445.825341: xfs_buf_get: dev 8:16 bno 0x10 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_read_map [xfs]
setattr-2628 [000] .... 445.825341: xfs_buf_read: dev 8:16 bno 0x10 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
setattr-2628 [000] .... 445.825341: xfs_buf_hold: dev 8:16 bno 0x10 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller xfs_buf_item_init [xfs]
setattr-2628 [000] .... 445.825341: xfs_trans_read_buf: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825346: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825346: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825346: xfs_buf_trylock: dev 8:16 bno 0x8 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825347: xfs_buf_find: dev 8:16 bno 0x8 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_get_map [xfs]
setattr-2628 [000] .... 445.825347: xfs_buf_get: dev 8:16 bno 0x8 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_read_map [xfs]
setattr-2628 [000] .... 445.825347: xfs_buf_read: dev 8:16 bno 0x8 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
setattr-2628 [000] .... 445.825348: xfs_buf_hold: dev 8:16 bno 0x8 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller xfs_buf_item_init [xfs]
setattr-2628 [000] .... 445.825348: xfs_trans_read_buf: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff88007608d6e0 liflags
setattr-2628 [000] .... 445.825350: xfs_trans_log_buf: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825351: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller xfs_allocbt_update_lastrec [xfs]
setattr-2628 [000] .... 445.825351: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller xfs_allocbt_update_lastrec [xfs]
setattr-2628 [000] .... 445.825352: xfs_agf: dev 8:16 agno 0 flags LONGEST length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131055 longest 0 caller xfs_allocbt_update_lastrec [xfs]
setattr-2628 [000] .... 445.825352: xfs_trans_log_buf: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xffff88007608d140 liflags
setattr-2628 [000] .... 445.825354: xfs_trans_log_buf: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825354: xfs_trans_log_buf: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825355: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller xfs_allocbt_update_lastrec [xfs]
setattr-2628 [000] .... 445.825355: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller xfs_allocbt_update_lastrec [xfs]
setattr-2628 [000] .... 445.825355: xfs_agf: dev 8:16 agno 0 flags LONGEST length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131055 longest 131054 caller xfs_allocbt_update_lastrec [xfs]
setattr-2628 [000] .... 445.825355: xfs_trans_log_buf: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d140 liflags
setattr-2628 [000] .... 445.825356: xfs_trans_log_buf: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff88007608d6e0 liflags
setattr-2628 [000] .... 445.825356: xfs_trans_brelse: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825357: xfs_trans_brelse: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d6e0 liflags
setattr-2628 [000] .... 445.825357: xfs_alloc_near_first: dev 8:16 agno 0 agbno 17 minlen 1 maxlen 1 mod 0 prod 1 minleft 1 total 30 alignment 1 minalignslop 0 len 1 type NEAR_BNO otype START_BNO wasdel 0 wasfromfl 0 isfl 0 userdata 0 firstblock 0xffffffffffffffff
setattr-2628 [000] .... 445.825358: xfs_agf: dev 8:16 agno 0 flags FREEBLKS length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131054 longest 131054 caller xfs_alloc_update_counters.isra.11 [xfs]
setattr-2628 [000] .... 445.825358: xfs_trans_log_buf: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d140 liflags
setattr-2628 [000] .... 445.825359: xfs_perag_put: dev 8:16 agno 0 refcount 7 caller xfs_alloc_vextent [xfs]
setattr-2628 [000] .... 445.825360: xfs_iext_insert: dev 8:16 ino 0x43 state ATTR idx 0 offset 0 block 17 count 1 flag 0 caller xfs_bmap_add_extent_hole_real [xfs]
setattr-2628 [000] .... 445.825363: xfs_attr_leaf_create: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825364: xfs_perag_get: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825364: xfs_perag_put: dev 8:16 agno 0 refcount 7 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825366: xfs_buf_init: dev 8:16 bno 0x88 nblks 0x8 hold 1 pincount 0 lock 0 flags caller xfs_buf_get_map [xfs]
setattr-2628 [000] .... 445.825368: xfs_perag_get: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825369: xfs_buf_ioerror: dev 8:16 bno 0x88 len 0x1000 hold 1 pincount 0 lock 0 error 0 flags PAGES caller xfs_trans_get_buf_map [xfs]
setattr-2628 [000] .... 445.825369: xfs_buf_get: dev 8:16 bno 0x88 len 0x1000 hold 1 pincount 0 lock 0 flags caller xfs_trans_get_buf_map [xfs]
setattr-2628 [000] .... 445.825370: xfs_buf_hold: dev 8:16 bno 0x88 nblks 0x8 hold 1 pincount 0 lock 0 flags PAGES caller xfs_buf_item_init [xfs]
setattr-2628 [000] .... 445.825370: xfs_trans_get_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags PAGES recur 0 refcount 1 bliflags lidesc 0xffff88007608de40 liflags
setattr-2628 [000] .... 445.825371: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff88007608de40 liflags
setattr-2628 [000] .... 445.825372: xfs_attr_leaf_lookup: dev 8:16 ino 0x43 name selinux namelen 7 valuelen 37 hashval 0xcd3a75d6 op_flags OKNOENT
setattr-2628 [000] .... 445.825373: xfs_attr_leaf_add: dev 8:16 ino 0x43 name selinux namelen 7 valuelen 37 hashval 0xcd3a75d6 op_flags OKNOENT
setattr-2628 [000] .... 445.825374: xfs_attr_leaf_add_work: dev 8:16 ino 0x43 name selinux namelen 7 valuelen 37 hashval 0xcd3a75d6 op_flags OKNOENT
setattr-2628 [000] .... 445.825375: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608de40 liflags
setattr-2628 [000] .... 445.825375: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608de40 liflags
setattr-2628 [000] .... 445.825375: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608de40 liflags
setattr-2628 [000] .... 445.825379: xfs_buf_item_size: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d140 liflags
setattr-2628 [000] .... 445.825380: xfs_buf_item_size: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825380: xfs_buf_item_size: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d6e0 liflags
setattr-2628 [000] .... 445.825381: xfs_buf_item_size: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608de40 liflags
setattr-2628 [000] .... 445.825384: xfs_inode_pin: dev 8:16 ino 0x43 count 1 pincount 0 caller xfs_cil_prepare_item.isra.2 [xfs]
setattr-2628 [000] .... 445.825385: xfs_buf_item_format: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d140 liflags
setattr-2628 [000] .... 445.825385: xfs_buf_item_pin: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d140 liflags
setattr-2628 [000] .... 445.825386: xfs_buf_item_format: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825386: xfs_buf_item_pin: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d0e0 liflags
setattr-2628 [000] .... 445.825386: xfs_buf_item_format: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d6e0 liflags
setattr-2628 [000] .... 445.825386: xfs_buf_item_pin: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608d6e0 liflags
setattr-2628 [000] .... 445.825388: xfs_buf_item_format: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608de40 liflags
setattr-2628 [000] .... 445.825388: xfs_buf_item_pin: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff88007608de40 liflags
setattr-2628 [000] .... 445.825389: xfs_log_done_perm: dev 8:16 t_ocnt 3 t_cnt 3 t_curr_res 424504 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 1302872 grant_write_cycle 1 grant_write_bytes 1302872 curr_cycle 1 curr_block 16 tail_cycle 1 tail_block 14
setattr-2628 [000] .... 445.825390: xfs_log_regrant_reserve_enter: dev 8:16 t_ocnt 3 t_cnt 3 t_curr_res 424504 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 1302872 grant_write_cycle 1 grant_write_bytes 1302872 curr_cycle 1 curr_block 16 tail_cycle 1 tail_block 14
setattr-2628 [000] .... 445.825390: xfs_log_regrant_reserve_sub: dev 8:16 t_ocnt 3 t_cnt 2 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 878368 grant_write_cycle 1 grant_write_bytes 878368 curr_cycle 1 curr_block 16 tail_cycle 1 tail_block 14
setattr-2628 [000] .... 445.825391: xfs_buf_item_unlock: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 1 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
setattr-2628 [000] .... 445.825392: xfs_buf_unlock: dev 8:16 bno 0x1 nblks 0x1 hold 3 pincount 1 lock 1 flags ASYNC|DONE|KMEM caller xfs_buf_item_unlock [xfs]
setattr-2628 [000] .... 445.825392: xfs_buf_rele: dev 8:16 bno 0x1 nblks 0x1 hold 3 pincount 1 lock 1 flags ASYNC|DONE|KMEM caller xfs_buf_item_unlock [xfs]
setattr-2628 [000] .... 445.825392: xfs_buf_item_unlock: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 1 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
setattr-2628 [000] .... 445.825393: xfs_buf_unlock: dev 8:16 bno 0x10 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-2628 [000] .... 445.825393: xfs_buf_rele: dev 8:16 bno 0x10 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-2628 [000] .... 445.825393: xfs_buf_item_unlock: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 1 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
setattr-2628 [000] .... 445.825393: xfs_buf_unlock: dev 8:16 bno 0x8 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-2628 [000] .... 445.825393: xfs_buf_rele: dev 8:16 bno 0x8 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-2628 [000] .... 445.825394: xfs_buf_item_unlock: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
setattr-2628 [000] .... 445.825394: xfs_buf_unlock: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-2628 [000] .... 445.825394: xfs_buf_rele: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_buf_item_unlock [xfs]
setattr-2628 [000] .... 445.825396: xfs_attr_leaf_addname: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825397: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825397: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825398: xfs_buf_trylock: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 0 flags DONE|PAGES caller _xfs_buf_find [xfs]
setattr-2628 [000] .... 445.825398: xfs_buf_find: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags READ caller xfs_buf_get_map [xfs]
setattr-2628 [000] .... 445.825398: xfs_buf_get: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags READ caller xfs_buf_read_map [xfs]
setattr-2628 [000] .... 445.825398: xfs_buf_read: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
setattr-2628 [000] .... 445.825399: xfs_trans_read_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0xffff88007608d6e0 liflags
setattr-2628 [000] .... 445.825399: xfs_attr_leaf_lookup: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
setattr-2628 [000] .... 445.825399: xfs_trans_brelse: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0xffff88007608d6e0 liflags
setattr-2628 [000] .... 445.825400: xfs_buf_unlock: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_trans_brelse [xfs]
setattr-2628 [000] .... 445.825400: xfs_buf_rele: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_trans_brelse [xfs]
setattr-2628 [000] .... 445.825401: xfs_log_done_nonperm: dev 8:16 t_ocnt 3 t_cnt 2 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 878368 grant_write_cycle 1 grant_write_bytes 878368 curr_cycle 1 curr_block 16 tail_cycle 1 tail_block 14
setattr-2628 [000] .... 445.825401: xfs_log_ungrant_enter: dev 8:16 t_ocnt 3 t_cnt 1 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 878368 grant_write_cycle 1 grant_write_bytes 878368 curr_cycle 1 curr_block 16 tail_cycle 1 tail_block 14
setattr-2628 [000] .... 445.825402: xfs_log_ungrant_sub: dev 8:16 t_ocnt 3 t_cnt 1 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 878368 grant_write_cycle 1 grant_write_bytes 878368 curr_cycle 1 curr_block 16 tail_cycle 1 tail_block 14
setattr-2628 [000] .... 445.825402: xfs_log_ungrant_exit: dev 8:16 t_ocnt 3 t_cnt 1 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 15248 grant_write_cycle 1 grant_write_bytes 15248 curr_cycle 1 curr_block 16 tail_cycle 1 tail_block 14
setattr-2628 [000] .... 445.825404: xfs_iunlock: dev 8:16 ino 0x43 flags ILOCK_EXCL caller xfs_attr_set [xfs]
[kanda@centos7 ~]$ uname -a
Linux centos7 3.10.0-693.21.1.el7.x86_64 #1 SMP Wed Mar 7 19:03:37 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux
[kanda@centos7 ~]$ date
2018年 3月 14日 水曜日 14:13:15 JST
# echo "xfs:*" > /sys/kernel/debug/tracing/set_event
$ attr -l /mnt/xfs/hello
Attribute "world" has a 1 byte value for /mnt/xfs/hello
commit 5a93790d4e2df73e30c965ec6e49be82fc3ccfce
Author: Brian Foster <bfoster@redhat.com>
Date: Wed Jan 25 07:53:43 2017 -0800
xfs: remove racy hasattr check from attr ops
xfs_attr_[get|remove]() have unlocked attribute fork checks to optimize
away a lock cycle in cases where the fork does not exist or is otherwise
empty. This check is not safe, however, because an attribute fork short
form to extent format conversion includes a transient state that causes
the xfs_inode_hasattr() check to fail. Specifically,
xfs_attr_shortform_to_leaf() creates an empty extent format attribute
fork and then adds the existing shortform attributes to it.
This means that lookup of an existing xattr can spuriously return
-ENOATTR when racing against a setxattr that causes the associated
format conversion.
# uname -a
Linux centos7 3.10.0-693.21.1.el7.x86_64 #1 SMP Wed Mar 7 19:03:37 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux
# mkfs.xfs /dev/sdb
meta-data=/dev/sdb isize=512 agcount=4, agsize=131072 blks
= sectsz=512 attr=2, projid32bit=1
= crc=1 finobt=0, sparse=0
data = bsize=4096 blocks=524288, imaxpct=25
= sunit=0 swidth=0 blks
naming =version 2 bsize=4096 ascii-ci=0 ftype=1
log =internal log bsize=4096 blocks=2560, version=2
= sectsz=512 sunit=0 blks, lazy-count=1
realtime =none extsz=4096 blocks=0, rtextents=0
# mount /dev/sdb /mnt/xfs
$ date > /mnt/xfs/hello
$ attr -s world -V 0 /mnt/xfs/hello
Attribute "world" set to a 1 byte value for /mnt/xfs/hello:
0
# xfs_db -r /dev/sdb -c "inode 67" -c p
core.magic = 0x494e
core.mode = 0100664
core.version = 3
core.format = 2 (extents)
core.nlinkv2 = 1
core.onlink = 0
core.projid_lo = 0
core.projid_hi = 0
core.uid = 1000
core.gid = 1000
core.flushiter = 0
core.atime.sec = Wed Mar 14 08:43:48 2018
core.atime.nsec = 368663459
core.mtime.sec = Wed Mar 14 08:43:48 2018
core.mtime.nsec = 369665753
core.ctime.sec = Wed Mar 14 08:45:12 2018
core.ctime.nsec = 667624570
core.size = 43
core.nblocks = 1
core.extsize = 0
core.nextents = 1
core.naextents = 0
core.forkoff = 34
core.aformat = 1 (local)
core.dmevmask = 0
core.dmstate = 0
core.newrtbm = 0
core.prealloc = 0
core.realtime = 0
core.immutable = 0
core.append = 0
core.sync = 0
core.noatime = 0
core.nodump = 0
core.rtinherit = 0
core.projinherit = 0
core.nosymlinks = 0
core.extsz = 0
core.extszinherit = 0
core.nodefrag = 0
core.filestream = 0
core.gen = 309020689
next_unlinked = null
v3.crc = 0xbb1138f (correct)
v3.change_count = 10
v3.lsn = 0x10000000c
v3.flags2 = 0
v3.crtime.sec = Wed Mar 14 08:43:48 2018
v3.crtime.nsec = 368663459
v3.inumber = 67
v3.uuid = e752de85-caef-4225-97d0-aadb3cc13313
u3.bmx[0] = [startoff,startblock,blockcount,extentflag] 0:[0,16,1,0]
a.sfattr.hdr.totsize = 60
a.sfattr.hdr.count = 2
a.sfattr.list[0].namelen = 7
a.sfattr.list[0].valuelen = 37
a.sfattr.list[0].root = 0
a.sfattr.list[0].secure = 1
a.sfattr.list[0].name = "selinux"
a.sfattr.list[0].value = "unconfined_u:object_r:unlabeled_t:s0\000"
a.sfattr.list[1].namelen = 5
a.sfattr.list[1].valuelen = 1
a.sfattr.list[1].root = 0
a.sfattr.list[1].secure = 0
a.sfattr.list[1].name = "world"
a.sfattr.list[1].value = "0"
$ attr -s world -V loooooooooooooooooooooooooooooooooooooooooooooooooooooooooooo
oooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooo
oooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooo
ooooooongValue /mnt/xfs/hello
Attribute "world" set to a 235 byte value for /mnt/xfs/hello:
looooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooooongValue
# blockdev --flushbufs /dev/sdb
# xfs_db -r /dev/sdb -c "inode 67" -c p
core.magic = 0x494e
core.mode = 0100664
core.version = 3
core.format = 2 (extents)
core.nlinkv2 = 1
core.onlink = 0
core.projid_lo = 0
core.projid_hi = 0
core.uid = 1000
core.gid = 1000
core.flushiter = 0
core.atime.sec = Wed Mar 14 08:43:48 2018
core.atime.nsec = 368663459
core.mtime.sec = Wed Mar 14 08:43:48 2018
core.mtime.nsec = 369665753
core.ctime.sec = Wed Mar 14 08:47:48 2018
core.ctime.nsec = 696772250
core.size = 43
core.nblocks = 2
core.extsize = 0
core.nextents = 1
core.naextents = 1
core.forkoff = 34
core.aformat = 2 (extents)
core.dmevmask = 0
core.dmstate = 0
core.newrtbm = 0
core.prealloc = 0
core.realtime = 0
core.immutable = 0
core.append = 0
core.sync = 0
core.noatime = 0
core.nodump = 0
core.rtinherit = 0
core.projinherit = 0
core.nosymlinks = 0
core.extsz = 0
core.extszinherit = 0
core.nodefrag = 0
core.filestream = 0
core.gen = 309020689
next_unlinked = null
v3.crc = 0x241c80f3 (correct)
v3.change_count = 13
v3.lsn = 0x100000012
v3.flags2 = 0
v3.crtime.sec = Wed Mar 14 08:43:48 2018
v3.crtime.nsec = 368663459
v3.inumber = 67
v3.uuid = e752de85-caef-4225-97d0-aadb3cc13313
u3.bmx[0] = [startoff,startblock,blockcount,extentflag] 0:[0,16,1,0]
a.bmx[0] = [startoff,startblock,blockcount,extentflag] 0:[0,17,1,0]
# umount /mnt/xfs
# mount /dev/sdb /mnt/xfs
$ attr -l /mnt/xfs/hello
Attribute "world" has a 235 byte value for /mnt/xfs/hello
Attribute "selinux" has a 37 byte value for /mnt/xfs/hello
[kanda@centos7 ~]$ attr -l /mnt/xfs/hello
Attribute "selinux" has a 37 byte value for /mnt/xfs/hello
Attribute "world" has a 1 byte value for /mnt/xfs/hello
[kanda@centos7 ~]$ python setattr.py
attr -s world -V 11111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111 /mnt/xfs/hello
Attribute "world" set to a 2048 byte value for /mnt/xfs/hello:
11111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111
[ 978.990858] XFS (sdb): Mounting V5 Filesystem
[ 979.079366] XFS (sdb): Ending clean mount
[root@centos7 kanda]# attr -l /mnt/xfs/hello
Attribute "world" has a 2048 byte value for /mnt/xfs/hello
Attribute "selinux" has a 37 byte value for /mnt/xfs/hello
[root@centos7 kanda]# cat /sys/kernel/debug/tracing/trace >/tmp/xfstrace
[root@centos7 kanda]# mv xfstrace xfssetattr.trace
[root@centos7 kanda]# cat setattr.py
import os
path = "/mnt/xfs/hello"
value = "1" * 2048
cmd = "attr -s world -V %s %s" % (value, path)
print cmd
os.system(cmd)
# tracer: nop
#
# entries-in-buffer/entries-written: 241/241 #P:1
#
# _-----=> irqs-off
# / _----=> need-resched
# | / _---=> hardirq/softirq
# || / _--=> preempt-depth
# ||| / delay
# TASK-PID CPU# |||| TIMESTAMP FUNCTION
# | | | |||| | |
attr-12053 [000] .... 1226.656099: xfs_log_reserve: dev 8:16 t_ocnt 3 t_cnt 3 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 20992 grant_write_cycle 1 grant_write_bytes 20992 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656102: xfs_log_reserve_exit: dev 8:16 t_ocnt 3 t_cnt 3 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 1315672 grant_write_cycle 1 grant_write_bytes 1315672 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656103: xfs_ilock: dev 8:16 ino 0x43 flags ILOCK_EXCL caller xfs_attr_set [xfs]
attr-12053 [000] .... 1226.656105: xfs_attr_sf_addname: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656106: xfs_attr_sf_lookup: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656107: xfs_attr_sf_remove: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656109: xfs_attr_sf_to_leaf: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656111: xfs_da_grow_inode: dev 8:16 ino 0x43 name world namelen 5 hashval 0x7dfcb663 inumber 0x0 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656115: xfs_perag_get: dev 8:16 agno 0 refcount 8 caller xfs_bmap_longest_free_extent [xfs]
attr-12053 [000] .... 1226.656116: xfs_perag_put: dev 8:16 agno 0 refcount 7 caller xfs_bmap_longest_free_extent [xfs]
attr-12053 [000] .... 1226.656117: xfs_perag_get: dev 8:16 agno 0 refcount 8 caller xfs_alloc_vextent [xfs]
attr-12053 [000] .... 1226.656118: xfs_alloc_read_agf: dev 8:16 agno 0
attr-12053 [000] .... 1226.656118: xfs_read_agf: dev 8:16 agno 0
attr-12053 [000] .... 1226.656119: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656120: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656121: xfs_buf_trylock: dev 8:16 bno 0x1 nblks 0x1 hold 2 pincount 0 lock 0 flags ASYNC|DONE|KMEM caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656122: xfs_buf_find: dev 8:16 bno 0x1 len 0x200 hold 2 pincount 0 lock 0 flags READ|TRYLOCK caller xfs_buf_get_map [xfs]
attr-12053 [000] .... 1226.656122: xfs_buf_get: dev 8:16 bno 0x1 len 0x200 hold 2 pincount 0 lock 0 flags READ|TRYLOCK caller xfs_buf_read_map [xfs]
attr-12053 [000] .... 1226.656123: xfs_buf_read: dev 8:16 bno 0x1 len 0x200 hold 2 pincount 0 lock 0 flags READ|TRYLOCK caller xfs_trans_read_buf_map [xfs]
attr-12053 [000] .... 1226.656125: xfs_buf_hold: dev 8:16 bno 0x1 nblks 0x1 hold 2 pincount 0 lock 0 flags ASYNC|DONE|KMEM caller xfs_buf_item_init [xfs]
attr-12053 [000] .... 1226.656126: xfs_trans_read_buf: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xffff8800640818c0 liflags
attr-12053 [000] .... 1226.656126: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller xfs_alloc_read_agf [xfs]
attr-12053 [000] .... 1226.656126: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller xfs_alloc_read_agf [xfs]
attr-12053 [000] .... 1226.656127: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656127: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656127: xfs_buf_trylock: dev 8:16 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 0 flags DONE|KMEM caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656128: xfs_buf_find: dev 8:16 bno 0x3 len 0x200 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_get_map [xfs]
attr-12053 [000] .... 1226.656128: xfs_buf_get: dev 8:16 bno 0x3 len 0x200 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_read_map [xfs]
attr-12053 [000] .... 1226.656128: xfs_buf_read: dev 8:16 bno 0x3 len 0x200 hold 2 pincount 0 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
attr-12053 [000] .... 1226.656128: xfs_buf_hold: dev 8:16 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 0 flags DONE|KMEM caller xfs_buf_item_init [xfs]
attr-12053 [000] .... 1226.656129: xfs_trans_read_buf: dev 8:16 bno 0x3 len 0x200 hold 3 pincount 0 lock 0 flags DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656130: xfs_trans_brelse: dev 8:16 bno 0x3 len 0x200 hold 3 pincount 0 lock 0 flags DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656131: xfs_buf_item_relse: dev 8:16 bno 0x3 nblks 0x1 hold 3 pincount 0 lock 0 flags DONE|KMEM caller xfs_trans_brelse [xfs]
attr-12053 [000] .... 1226.656131: xfs_buf_rele: dev 8:16 bno 0x3 nblks 0x1 hold 3 pincount 0 lock 0 flags DONE|KMEM caller xfs_buf_item_relse [xfs]
attr-12053 [000] .... 1226.656132: xfs_buf_unlock: dev 8:16 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 1 flags DONE|KMEM caller xfs_trans_brelse [xfs]
attr-12053 [000] .... 1226.656132: xfs_buf_rele: dev 8:16 bno 0x3 nblks 0x1 hold 2 pincount 0 lock 1 flags DONE|KMEM caller xfs_trans_brelse [xfs]
attr-12053 [000] .... 1226.656135: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656135: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656136: xfs_buf_trylock: dev 8:16 bno 0x10 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656136: xfs_buf_find: dev 8:16 bno 0x10 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_get_map [xfs]
attr-12053 [000] .... 1226.656136: xfs_buf_get: dev 8:16 bno 0x10 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_read_map [xfs]
attr-12053 [000] .... 1226.656136: xfs_buf_read: dev 8:16 bno 0x10 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
attr-12053 [000] .... 1226.656137: xfs_buf_hold: dev 8:16 bno 0x10 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller xfs_buf_item_init [xfs]
attr-12053 [000] .... 1226.656137: xfs_trans_read_buf: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656142: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656142: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656142: xfs_buf_trylock: dev 8:16 bno 0x8 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656143: xfs_buf_find: dev 8:16 bno 0x8 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_get_map [xfs]
attr-12053 [000] .... 1226.656143: xfs_buf_get: dev 8:16 bno 0x8 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_buf_read_map [xfs]
attr-12053 [000] .... 1226.656143: xfs_buf_read: dev 8:16 bno 0x8 len 0x1000 hold 2 pincount 0 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
attr-12053 [000] .... 1226.656143: xfs_buf_hold: dev 8:16 bno 0x8 nblks 0x8 hold 2 pincount 0 lock 0 flags ASYNC|DONE|PAGES caller xfs_buf_item_init [xfs]
attr-12053 [000] .... 1226.656144: xfs_trans_read_buf: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656146: xfs_trans_log_buf: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656147: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller xfs_allocbt_update_lastrec [xfs]
attr-12053 [000] .... 1226.656147: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller xfs_allocbt_update_lastrec [xfs]
attr-12053 [000] .... 1226.656148: xfs_agf: dev 8:16 agno 0 flags LONGEST length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131055 longest 0 caller xfs_allocbt_update_lastrec [xfs]
attr-12053 [000] .... 1226.656148: xfs_trans_log_buf: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags lidesc 0xffff8800640818c0 liflags
attr-12053 [000] .... 1226.656150: xfs_trans_log_buf: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656150: xfs_trans_log_buf: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656150: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller xfs_allocbt_update_lastrec [xfs]
attr-12053 [000] .... 1226.656151: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller xfs_allocbt_update_lastrec [xfs]
attr-12053 [000] .... 1226.656151: xfs_agf: dev 8:16 agno 0 flags LONGEST length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131055 longest 131054 caller xfs_allocbt_update_lastrec [xfs]
attr-12053 [000] .... 1226.656151: xfs_trans_log_buf: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818c0 liflags
attr-12053 [000] .... 1226.656151: xfs_trans_log_buf: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656152: xfs_trans_brelse: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656152: xfs_trans_brelse: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656153: xfs_alloc_near_first: dev 8:16 agno 0 agbno 17 minlen 1 maxlen 1 mod 0 prod 1 minleft 1 total 30 alignment 1 minalignslop 0 len 1 type NEAR_BNO otype START_BNO wasdel 0 wasfromfl 0 isfl 0 userdata 0 firstblock 0xffffffffffffffff
attr-12053 [000] .... 1226.656153: xfs_agf: dev 8:16 agno 0 flags FREEBLKS length 131072 roots b 1 c 2 levels b 1 c 1 flfirst 0 fllast 3 flcount 4 freeblks 131054 longest 131054 caller xfs_alloc_update_counters.isra.11 [xfs]
attr-12053 [000] .... 1226.656154: xfs_trans_log_buf: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818c0 liflags
attr-12053 [000] .... 1226.656154: xfs_perag_put: dev 8:16 agno 0 refcount 7 caller xfs_alloc_vextent [xfs]
attr-12053 [000] .... 1226.656156: xfs_iext_insert: dev 8:16 ino 0x43 state ATTR idx 0 offset 0 block 17 count 1 flag 0 caller xfs_bmap_add_extent_hole_real [xfs]
attr-12053 [000] .... 1226.656158: xfs_attr_leaf_create: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656159: xfs_perag_get: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656160: xfs_perag_put: dev 8:16 agno 0 refcount 7 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656161: xfs_buf_init: dev 8:16 bno 0x88 nblks 0x8 hold 1 pincount 0 lock 0 flags caller xfs_buf_get_map [xfs]
attr-12053 [000] .... 1226.656163: xfs_perag_get: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656164: xfs_buf_ioerror: dev 8:16 bno 0x88 len 0x1000 hold 1 pincount 0 lock 0 error 0 flags PAGES caller xfs_trans_get_buf_map [xfs]
attr-12053 [000] .... 1226.656164: xfs_buf_get: dev 8:16 bno 0x88 len 0x1000 hold 1 pincount 0 lock 0 flags caller xfs_trans_get_buf_map [xfs]
attr-12053 [000] .... 1226.656164: xfs_buf_hold: dev 8:16 bno 0x88 nblks 0x8 hold 1 pincount 0 lock 0 flags PAGES caller xfs_buf_item_init [xfs]
attr-12053 [000] .... 1226.656165: xfs_trans_get_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags PAGES recur 0 refcount 1 bliflags lidesc 0xffff880064081320 liflags
attr-12053 [000] .... 1226.656166: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags lidesc 0xffff880064081320 liflags
attr-12053 [000] .... 1226.656167: xfs_attr_leaf_lookup: dev 8:16 ino 0x43 name selinux namelen 7 valuelen 37 hashval 0xcd3a75d6 op_flags OKNOENT
attr-12053 [000] .... 1226.656168: xfs_attr_leaf_add: dev 8:16 ino 0x43 name selinux namelen 7 valuelen 37 hashval 0xcd3a75d6 op_flags OKNOENT
attr-12053 [000] .... 1226.656169: xfs_attr_leaf_add_work: dev 8:16 ino 0x43 name selinux namelen 7 valuelen 37 hashval 0xcd3a75d6 op_flags OKNOENT
attr-12053 [000] .... 1226.656169: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081320 liflags
attr-12053 [000] .... 1226.656169: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081320 liflags
attr-12053 [000] .... 1226.656169: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081320 liflags
attr-12053 [000] .... 1226.656173: xfs_buf_item_size: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818c0 liflags
attr-12053 [000] .... 1226.656173: xfs_buf_item_size: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656174: xfs_buf_item_size: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656175: xfs_buf_item_size: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081320 liflags
attr-12053 [000] .... 1226.656178: xfs_inode_pin: dev 8:16 ino 0x43 count 1 pincount 0 caller xfs_cil_prepare_item.isra.2 [xfs]
attr-12053 [000] .... 1226.656179: xfs_buf_item_format: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818c0 liflags
attr-12053 [000] .... 1226.656179: xfs_buf_item_pin: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 0 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818c0 liflags
attr-12053 [000] .... 1226.656180: xfs_buf_item_format: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656180: xfs_buf_item_pin: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff8800640818a0 liflags
attr-12053 [000] .... 1226.656180: xfs_buf_item_format: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656180: xfs_buf_item_pin: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 0 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656182: xfs_buf_item_format: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081320 liflags
attr-12053 [000] .... 1226.656182: xfs_buf_item_pin: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 0 lock 0 flags DONE|PAGES recur 0 refcount 1 bliflags DIRTY|LOGGED lidesc 0xffff880064081320 liflags
attr-12053 [000] .... 1226.656183: xfs_log_done_perm: dev 8:16 t_ocnt 3 t_cnt 3 t_curr_res 424504 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 1315672 grant_write_cycle 1 grant_write_bytes 1315672 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656184: xfs_log_regrant_reserve_enter: dev 8:16 t_ocnt 3 t_cnt 3 t_curr_res 424504 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 1315672 grant_write_cycle 1 grant_write_bytes 1315672 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656184: xfs_log_regrant_reserve_sub: dev 8:16 t_ocnt 3 t_cnt 2 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 891168 grant_write_cycle 1 grant_write_bytes 891168 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656185: xfs_buf_item_unlock: dev 8:16 bno 0x1 len 0x200 hold 3 pincount 1 lock 0 flags ASYNC|DONE|KMEM recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
attr-12053 [000] .... 1226.656186: xfs_buf_unlock: dev 8:16 bno 0x1 nblks 0x1 hold 3 pincount 1 lock 1 flags ASYNC|DONE|KMEM caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656186: xfs_buf_rele: dev 8:16 bno 0x1 nblks 0x1 hold 3 pincount 1 lock 1 flags ASYNC|DONE|KMEM caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656186: xfs_buf_item_unlock: dev 8:16 bno 0x10 len 0x1000 hold 3 pincount 1 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
attr-12053 [000] .... 1226.656186: xfs_buf_unlock: dev 8:16 bno 0x10 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656187: xfs_buf_rele: dev 8:16 bno 0x10 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656187: xfs_buf_item_unlock: dev 8:16 bno 0x8 len 0x1000 hold 3 pincount 1 lock 0 flags ASYNC|DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
attr-12053 [000] .... 1226.656187: xfs_buf_unlock: dev 8:16 bno 0x8 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656187: xfs_buf_rele: dev 8:16 bno 0x8 nblks 0x8 hold 3 pincount 1 lock 1 flags ASYNC|DONE|PAGES caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656187: xfs_buf_item_unlock: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
attr-12053 [000] .... 1226.656188: xfs_buf_unlock: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656188: xfs_buf_rele: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656190: xfs_attr_leaf_addname: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656191: xfs_perag_get: dev 8:16 agno 0 refcount 9 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656191: xfs_perag_put: dev 8:16 agno 0 refcount 8 caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656191: xfs_buf_trylock: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 0 flags DONE|PAGES caller _xfs_buf_find [xfs]
attr-12053 [000] .... 1226.656191: xfs_buf_find: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags READ caller xfs_buf_get_map [xfs]
attr-12053 [000] .... 1226.656192: xfs_buf_get: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags READ caller xfs_buf_read_map [xfs]
attr-12053 [000] .... 1226.656192: xfs_buf_read: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags READ caller xfs_trans_read_buf_map [xfs]
attr-12053 [000] .... 1226.656192: xfs_trans_read_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656192: xfs_attr_leaf_lookup: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656193: xfs_attr_leaf_add: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656193: xfs_attr_leaf_add_work: dev 8:16 ino 0x43 name world namelen 5 valuelen 2048 hashval 0x7dfcb663 op_flags ADDNAME|OKNOENT
attr-12053 [000] .... 1226.656193: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656193: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY|LOGGED lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656194: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY|LOGGED lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656194: xfs_trans_log_buf: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY|LOGGED lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656195: xfs_buf_item_size: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY|LOGGED lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656197: xfs_buf_item_format: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY|LOGGED lidesc 0xffff880064081880 liflags
attr-12053 [000] .... 1226.656197: xfs_log_done_perm: dev 8:16 t_ocnt 3 t_cnt 2 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 891168 grant_write_cycle 1 grant_write_bytes 891168 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656198: xfs_log_regrant_reserve_enter: dev 8:16 t_ocnt 3 t_cnt 2 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 891168 grant_write_cycle 1 grant_write_bytes 891168 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656198: xfs_log_regrant_reserve_sub: dev 8:16 t_ocnt 3 t_cnt 1 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 459608 grant_write_cycle 1 grant_write_bytes 459608 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656198: xfs_buf_item_unlock: dev 8:16 bno 0x88 len 0x1000 hold 2 pincount 1 lock 0 flags DONE|PAGES recur 0 refcount 2 bliflags DIRTY lidesc 0x (null) liflags
attr-12053 [000] .... 1226.656198: xfs_buf_unlock: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656199: xfs_buf_rele: dev 8:16 bno 0x88 nblks 0x8 hold 2 pincount 1 lock 1 flags DONE|PAGES caller xfs_buf_item_unlock [xfs]
attr-12053 [000] .... 1226.656200: xfs_log_done_nonperm: dev 8:16 t_ocnt 3 t_cnt 1 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 459608 grant_write_cycle 1 grant_write_bytes 459608 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656201: xfs_log_ungrant_enter: dev 8:16 t_ocnt 3 t_cnt 0 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 459608 grant_write_cycle 1 grant_write_bytes 459608 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656201: xfs_log_ungrant_sub: dev 8:16 t_ocnt 3 t_cnt 0 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 459608 grant_write_cycle 1 grant_write_bytes 459608 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656202: xfs_log_ungrant_exit: dev 8:16 t_ocnt 3 t_cnt 0 t_curr_res 431560 t_unit_res 431560 t_flags XLOG_TIC_INITED|XLOG_TIC_PERM_RESERV reserveq empty writeq empty grant_reserve_cycle 1 grant_reserve_bytes 28048 grant_write_cycle 1 grant_write_bytes 28048 curr_cycle 1 curr_block 41 tail_cycle 1 tail_block 39
attr-12053 [000] .... 1226.656204: xfs_iunlock: dev 8:16 ino 0x43 flags ILOCK_EXCL caller xfs_attr_set [xfs]
# uname -a
Linux localhost.localdomain 4.8.13-100.fc23.i686 #1 SMP Fri Dec 9 17:03:00 UTC 2016 i686 i686 i386 GNU/Linux
# dd if=/dev/zero of=image bs=1M count=256
256+0 レコード入力
256+0 レコード出力
268435456 バイト (268 MB) コピーされました、 6.84542 秒、 39.2 MB/秒
# mkfs.xfs image
meta-data=image isize=512 agcount=4, agsize=16384 blks
= sectsz=512 attr=2, projid32bit=1
= crc=1 finobt=1, sparse=0
data = bsize=4096 blocks=65536, imaxpct=25
= sunit=0 swidth=0 blks
naming =version 2 bsize=4096 ascii-ci=0 ftype=1
log =internal log bsize=4096 blocks=855, version=2
= sectsz=512 sunit=0 blks, lazy-count=1
realtime =none extsz=4096 blocks=0, rtextents=0
#
# mount -o loop image /mnt/xfs
$ date >/mnt/xfs/hello
$ attr -s world -V 1 /mnt/xfs/hello
Attribute "world" set to a 1 byte value for /mnt/xfs/hello:
1
$ ls -li /mnt/xfs/hello
99 -rw-rw-r-- 1 kanda kanda 43 3月 14 11:33 /mnt/xfs/hello
$ ls -l image
-rw-r--r-- 1 root root 268435456 3月 14 11:34 image
$ xfs_db -r image -c "inode 99" -c p
core.magic = 0x494e
core.mode = 0100664
core.version = 3
core.format = 2 (extents)
core.nlinkv2 = 1
core.onlink = 0
core.projid_lo = 0
core.projid_hi = 0
core.uid = 1000
core.gid = 1000
core.flushiter = 0
core.atime.sec = Wed Mar 14 11:33:08 2018
core.atime.nsec = 826946582
core.mtime.sec = Wed Mar 14 11:33:08 2018
core.mtime.nsec = 851950836
core.ctime.sec = Wed Mar 14 11:33:34 2018
core.ctime.nsec = 398297457
core.size = 43
core.nblocks = 1
core.extsize = 0
core.nextents = 1
core.naextents = 0
core.forkoff = 37
core.aformat = 1 (local)
core.dmevmask = 0
core.dmstate = 0
core.newrtbm = 0
core.prealloc = 0
core.realtime = 0
core.immutable = 0
core.append = 0
core.sync = 0
core.noatime = 0
core.nodump = 0
core.rtinherit = 0
core.projinherit = 0
core.nosymlinks = 0
core.extsz = 0
core.extszinherit = 0
core.nodefrag = 0
core.filestream = 0
core.gen = 3345353426
next_unlinked = null
v3.crc = 0x90bb95e8 (correct)
v3.change_count = 9
v3.lsn = 0x100000008
v3.flags2 = 0
v3.crtime.sec = Wed Mar 14 11:33:08 2018
v3.crtime.nsec = 826946582
v3.inumber = 99
v3.uuid = ecf31540-9273-46d6-830c-a424aefdf2d8
u3.bmx[0] = [startoff,startblock,blockcount,extentflag] 0:[0,9,1,0]
a.sfattr.hdr.totsize = 13
a.sfattr.hdr.count = 1
a.sfattr.list[0].namelen = 5
a.sfattr.list[0].valuelen = 1
a.sfattr.list[0].root = 0
a.sfattr.list[0].secure = 0
a.sfattr.list[0].name = "world"
a.sfattr.list[0].value = "1"
$
$ python setattr.py
attr -s world -V 11111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111 /mnt/xfs/hello
Attribute "world" set to a 2048 byte value for /mnt/xfs/hello:
11111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111111
$
$ attr -l /mnt/xfs/hello
Attribute "world" has a 2048 byte value for /mnt/xfs/hello
$ xfs_db -r image -c "inode 99" -c p
core.magic = 0x494e
core.mode = 0100664
core.version = 3
core.format = 2 (extents)
core.nlinkv2 = 1
core.onlink = 0
core.projid_lo = 0
core.projid_hi = 0
core.uid = 1000
core.gid = 1000
core.flushiter = 0
core.atime.sec = Wed Mar 14 11:33:08 2018
core.atime.nsec = 826946582
core.mtime.sec = Wed Mar 14 11:33:08 2018
core.mtime.nsec = 851950836
core.ctime.sec = Wed Mar 14 11:37:09 2018
core.ctime.nsec = 329992814
core.size = 43
core.nblocks = 2
core.extsize = 0
core.nextents = 1
core.naextents = 1
core.forkoff = 37
core.aformat = 2 (extents)
core.dmevmask = 0
core.dmstate = 0
core.newrtbm = 0
core.prealloc = 0
core.realtime = 0
core.immutable = 0
core.append = 0
core.sync = 0
core.noatime = 0
core.nodump = 0
core.rtinherit = 0
core.projinherit = 0
core.nosymlinks = 0
core.extsz = 0
core.extszinherit = 0
core.nodefrag = 0
core.filestream = 0
core.gen = 3345353426
next_unlinked = null
v3.crc = 0x13ae784d (correct)
v3.change_count = 12
v3.lsn = 0x100000011
v3.flags2 = 0
v3.crtime.sec = Wed Mar 14 11:33:08 2018
v3.crtime.nsec = 826946582
v3.inumber = 99
v3.uuid = ecf31540-9273-46d6-830c-a424aefdf2d8
u3.bmx[0] = [startoff,startblock,blockcount,extentflag] 0:[0,9,1,0]
a.bmx[0] = [startoff,startblock,blockcount,extentflag] 0:[0,11,1,0]
$