Re: xfs WARNING on v6.5-rc1 kernel

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



On Mon, Jul 10, 2023 at 12:29:29PM -0400, Jeff Layton wrote:
> I hit this this morning while running generic/013 (fsstress), with a
> kernel based on v6.5-rc1. The main changes on top of this are timestamp
> related, so I doubt they're a factor here.
> 
> Is this some of the flexarray hardening?

Yes.

https://lore.kernel.org/linux-xfs/ZI+3QXDHiohgv%2FPb@xxxxxxxxxxxxxxxxxxx/
https://lore.kernel.org/linux-xfs/bug-217522-201763-D34HpuP9xe@xxxxxxxxxxxxxxxxxxxxxxxxx%2F/
https://lore.kernel.org/linux-xfs/Y9xiYmVLRIKdpJcC@work/

--D

> [ 2704.665314] run fstests generic/013 at 2023-07-10 16:09:58
> [ 2705.646507] XFS (loop16): Unmounting Filesystem 3058c032-3f67-4fb9-b24e-c1414b0b532b
> [ 2705.820402] XFS (loop16): Mounting V5 Filesystem 3058c032-3f67-4fb9-b24e-c1414b0b532b
> [ 2705.838655] XFS (loop16): Ending clean mount
> [ 2705.916080] ------------[ cut here ]------------
> [ 2705.917615] memcpy: detected field-spanning write (size 2) of single field "(char *)name_loc->nameval" at fs/xfs/libxfs/xfs_attr_leaf.c:1559 (size 1)
> [ 2705.921569] WARNING: CPU: 6 PID: 48206 at fs/xfs/libxfs/xfs_attr_leaf.c:1559 xfs_attr3_leaf_add_work+0x4ee/0x530 [xfs]
> [ 2705.926783] Modules linked in: xfs nls_iso8859_1 nls_cp437 vfat fat ext4 9p crc16 mbcache netfs jbd2 kvm_intel cirrus virtio_net kvm joydev drm_shmem_helper net_failover pcspkr virtio_balloon 9pnet_virtio psmouse irqbypass failover drm_kms_helper evdev button drm loop dm_mod zram zsmalloc crct10dif_pclmul crc32_pclmul ghash_clmulni_intel sha512_ssse3 sha512_generic nvme virtio_blk nvme_core t10_pi aesni_intel virtio_pci crc64_rocksoft_generic crypto_simd cryptd crc64_rocksoft virtio i6300esb crc64 virtio_pci_legacy_dev virtio_pci_modern_dev virtio_ring serio_raw btrfs blake2b_generic libcrc32c crc32c_generic crc32c_intel xor raid6_pq autofs4
> [ 2705.942668] CPU: 6 PID: 48206 Comm: fsstress Not tainted 6.5.0-rc1+ #13
> [ 2705.945361] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-1.fc38 04/01/2014
> [ 2705.947758] RIP: 0010:xfs_attr3_leaf_add_work+0x4ee/0x530 [xfs]
> [ 2705.949442] Code: fe ff ff b9 01 00 00 00 4c 89 fe 48 c7 c2 c8 2e fc c0 48 c7 c7 10 2f fc c0 48 89 44 24 08 c6 05 ac 9c 0e 00 01 e8 a2 de 67 d0 <0f> 0b 48 8b 44 24 08 e9 88 fe ff ff 80 3d 93 9c 0e 00 00 0f 85 bd
> [ 2705.953536] RSP: 0018:ffffb52ac29bb8c0 EFLAGS: 00010282
> [ 2705.954925] RAX: 0000000000000000 RBX: ffffb52ac29bb990 RCX: 0000000000000000
> [ 2705.956604] RDX: 0000000000000002 RSI: ffffffff92612d95 RDI: 00000000ffffffff
> [ 2705.958998] RBP: ffffb52ac29bb924 R08: 0000000000000000 R09: ffffb52ac29bb760
> [ 2705.960903] R10: 0000000000000003 R11: ffffffff928c1aa8 R12: ffff8ecf3adc8050
> [ 2705.962537] R13: ffff8ecf3adc8000 R14: ffff8ecf3adc8fcc R15: 0000000000000002
> [ 2705.964083] FS:  00007fcb152f4740(0000) GS:ffff8ed077d80000(0000) knlGS:0000000000000000
> [ 2705.965752] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 2705.967124] CR2: 00007ff8022cd2d8 CR3: 000000015e1d2005 CR4: 0000000000060ee0
> [ 2705.969279] Call Trace:
> [ 2705.970318]  <TASK>
> [ 2705.971321]  ? xfs_attr3_leaf_add_work+0x4ee/0x530 [xfs]
> [ 2705.973729]  ? __warn+0x7d/0x130
> [ 2705.974822]  ? xfs_attr3_leaf_add_work+0x4ee/0x530 [xfs]
> [ 2705.976251]  ? report_bug+0x18d/0x1c0
> [ 2705.977242]  ? handle_bug+0x3c/0x80
> [ 2705.978282]  ? exc_invalid_op+0x13/0x60
> [ 2705.979310]  ? asm_exc_invalid_op+0x16/0x20
> [ 2705.980648]  ? xfs_attr3_leaf_add_work+0x4ee/0x530 [xfs]
> [ 2705.982645]  xfs_attr3_leaf_add+0x173/0x1e0 [xfs]
> [ 2705.984131]  xfs_attr_shortform_to_leaf+0x21d/0x230 [xfs]
> [ 2705.985415]  xfs_attr_set_iter+0x766/0x900 [xfs]
> [ 2705.986514]  ? path_setxattr+0xc7/0xe0
> [ 2705.987324]  xfs_xattri_finish_update+0x18/0x50 [xfs]
> [ 2705.988486]  xfs_attr_finish_item+0x1a/0xb0 [xfs]
> [ 2705.989566]  xfs_defer_finish_noroll+0x192/0x6e0 [xfs]
> [ 2705.990714]  __xfs_trans_commit+0x242/0x360 [xfs]
> [ 2705.991768]  xfs_attr_set+0x462/0x680 [xfs]
> [ 2705.993079]  xfs_xattr_set+0x89/0xe0 [xfs]
> [ 2705.994183]  __vfs_setxattr+0x95/0xd0
> [ 2705.995160]  __vfs_setxattr_noperm+0x73/0x1d0
> [ 2705.996536]  vfs_setxattr+0x9b/0x180
> [ 2705.997510]  setxattr+0x88/0xa0
> [ 2705.998249]  ? __pfx_free_object_rcu+0x10/0x10
> [ 2705.999190]  ? __call_rcu_common.constprop.0+0x107/0x220
> [ 2706.000196]  ? user_path_at_empty+0x40/0x50
> [ 2706.001045]  ? kmem_cache_free+0x160/0x380
> [ 2706.001881]  ? preempt_count_add+0x47/0xa0
> [ 2706.002719]  ? __mnt_want_write+0x61/0x90
> [ 2706.003528]  path_setxattr+0xc7/0xe0
> [ 2706.004277]  __x64_sys_setxattr+0x27/0x30
> [ 2706.005074]  do_syscall_64+0x3b/0x90
> [ 2706.005800]  entry_SYSCALL_64_after_hwframe+0x6e/0xd8
> [ 2706.006739] RIP: 0033:0x7fcb1540515e
> [ 2706.007628] Code: 48 8b 0d ad 6c 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 49 89 ca b8 bc 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 7a 6c 0c 00 f7 d8 64 89 01 48
> [ 2706.010450] RSP: 002b:00007fffe4df12d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000bc
> [ 2706.011976] RAX: ffffffffffffffda RBX: 0000000000000061 RCX: 00007fcb1540515e
> [ 2706.013103] RDX: 0000000001548010 RSI: 00007fffe4df1320 RDI: 000000000155cf50
> [ 2706.014103] RBP: 0000000001548071 R08: 0000000000000000 R09: 0000000000000000
> [ 2706.015177] R10: 0000000000000061 R11: 0000000000000246 R12: 0000000001548010
> [ 2706.016236] R13: 0000000001548071 R14: 0410410410410411 R15: 0000000001526450
> [ 2706.017238]  </TASK>
> [ 2706.017817] ---[ end trace 0000000000000000 ]---
> [ 2706.737755] ------------[ cut here ]------------
> [ 2706.739255] memmove: detected field-spanning write (size 24) of single field "entry" at fs/xfs/libxfs/xfs_attr_leaf.c:2235 (size 8)
> [ 2706.743694] WARNING: CPU: 1 PID: 48206 at fs/xfs/libxfs/xfs_attr_leaf.c:2235 xfs_attr3_leaf_remove+0x4a7/0x4d0 [xfs]
> [ 2706.747045] Modules linked in: xfs nls_iso8859_1 nls_cp437 vfat fat ext4 9p crc16 mbcache netfs jbd2 kvm_intel cirrus virtio_net kvm joydev drm_shmem_helper net_failover pcspkr virtio_balloon 9pnet_virtio psmouse irqbypass failover drm_kms_helper evdev button drm loop dm_mod zram zsmalloc crct10dif_pclmul crc32_pclmul ghash_clmulni_intel sha512_ssse3 sha512_generic nvme virtio_blk nvme_core t10_pi aesni_intel virtio_pci crc64_rocksoft_generic crypto_simd cryptd crc64_rocksoft virtio i6300esb crc64 virtio_pci_legacy_dev virtio_pci_modern_dev virtio_ring serio_raw btrfs blake2b_generic libcrc32c crc32c_generic crc32c_intel xor raid6_pq autofs4
> [ 2706.763271] CPU: 1 PID: 48206 Comm: fsstress Tainted: G        W          6.5.0-rc1+ #13
> [ 2706.765484] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-1.fc38 04/01/2014
> [ 2706.767821] RIP: 0010:xfs_attr3_leaf_remove+0x4a7/0x4d0 [xfs]
> [ 2706.769821] Code: c7 c2 00 30 fc c0 48 89 c6 48 c7 c7 08 2e fc c0 44 89 44 24 14 4c 89 4c 24 08 48 89 04 24 c6 05 30 6e 0e 00 01 e8 29 b0 67 d0 <0f> 0b 44 8b 44 24 14 4c 8b 4c 24 08 48 8b 04 24 e9 62 fd ff ff e8
> [ 2706.774843] RSP: 0018:ffffb52ac29bba20 EFLAGS: 00010282
> [ 2706.776465] RAX: 0000000000000000 RBX: 0000000000000f08 RCX: 0000000000000000
> [ 2706.778427] RDX: 0000000000000002 RSI: ffffffff92612d95 RDI: 00000000ffffffff
> [ 2706.780343] RBP: ffffb52ac29bbc70 R08: 0000000000000000 R09: ffffb52ac29bb8c0
> [ 2706.782170] R10: 0000000000000003 R11: ffffffff928c1aa8 R12: ffff8ecf3adc8050
> [ 2706.783934] R13: ffff8ecf61308900 R14: ffff8ecf3adc8000 R15: 0000000000000fcc
> [ 2706.786138] FS:  00007fcb152f4740(0000) GS:ffff8ed077c40000(0000) knlGS:0000000000000000
> [ 2706.788370] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 2706.790691] CR2: 0000000001606000 CR3: 000000015e1d2005 CR4: 0000000000060ee0
> [ 2706.792692] Call Trace:
> [ 2706.793721]  <TASK>
> [ 2706.794717]  ? xfs_attr3_leaf_remove+0x4a7/0x4d0 [xfs]
> [ 2706.796542]  ? __warn+0x7d/0x130
> [ 2706.797701]  ? xfs_attr3_leaf_remove+0x4a7/0x4d0 [xfs]
> [ 2706.799435]  ? report_bug+0x18d/0x1c0
> [ 2706.800684]  ? handle_bug+0x3c/0x80
> [ 2706.801938]  ? exc_invalid_op+0x13/0x60
> [ 2706.803272]  ? asm_exc_invalid_op+0x16/0x20
> [ 2706.804474]  ? xfs_attr3_leaf_remove+0x4a7/0x4d0 [xfs]
> [ 2706.806068]  xfs_attr_leaf_removename+0xad/0x110 [xfs]
> [ 2706.808661]  ? xfs_defer_add+0x57/0x160 [xfs]
> [ 2706.810164]  xfs_attr_set_iter+0x63/0x900 [xfs]
> [ 2706.811611]  ? removexattr+0x77/0x110
> [ 2706.812779]  xfs_xattri_finish_update+0x18/0x50 [xfs]
> [ 2706.814338]  xfs_attr_finish_item+0x1a/0xb0 [xfs]
> [ 2706.815799]  xfs_defer_finish_noroll+0x192/0x6e0 [xfs]
> [ 2706.817405]  __xfs_trans_commit+0x242/0x360 [xfs]
> [ 2706.818916]  xfs_attr_set+0x462/0x680 [xfs]
> [ 2706.820252]  xfs_xattr_set+0x89/0xe0 [xfs]
> [ 2706.822251]  __vfs_removexattr+0x7f/0xb0
> [ 2706.823463]  __vfs_removexattr_locked+0xb7/0x140
> [ 2706.824648]  vfs_removexattr+0x54/0x100
> [ 2706.825667]  removexattr+0x77/0x110
> [ 2706.826830]  ? __pfx_free_object_rcu+0x10/0x10
> [ 2706.827853]  ? __call_rcu_common.constprop.0+0x107/0x220
> [ 2706.828989]  ? user_path_at_empty+0x40/0x50
> [ 2706.830468]  ? kmem_cache_free+0x160/0x380
> [ 2706.831638]  ? preempt_count_add+0x47/0xa0
> [ 2706.832611]  ? __mnt_want_write+0x61/0x90
> [ 2706.833569]  path_removexattr+0x9f/0xc0
> [ 2706.834506]  __x64_sys_removexattr+0x17/0x20
> [ 2706.835443]  do_syscall_64+0x3b/0x90
> [ 2706.836267]  entry_SYSCALL_64_after_hwframe+0x6e/0xd8
> [ 2706.837302] RIP: 0033:0x7fcb1540512b
> [ 2706.838118] Code: f0 ff ff 73 01 c3 48 8b 0d da 6c 0c 00 f7 d8 64 89 01 48 83 c8 ff c3 0f 1f 84 00 00 00 00 00 f3 0f 1e fa b8 c5 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d ad 6c 0c 00 f7 d8 64 89 01 48
> [ 2706.841389] RSP: 002b:00007fffe4df1318 EFLAGS: 00000246 ORIG_RAX: 00000000000000c5
> [ 2706.842767] RAX: ffffffffffffffda RBX: 000000000000019e RCX: 00007fcb1540512b
> [ 2706.844024] RDX: 0000000000000000 RSI: 00007fffe4df1340 RDI: 000000000155d270
> [ 2706.845254] RBP: 00000000015345a0 R08: 0000000000000064 R09: 0000000000000000
> [ 2706.846557] R10: 0000000000000000 R11: 0000000000000246 R12: 028f5c28f5c28f5c
> [ 2706.847786] R13: 8f5c28f5c28f5c29 R14: 00000000004054b0 R15: 00007fcb152f46c8
> [ 2706.849029]  </TASK>
> [ 2706.849578] ---[ end trace 0000000000000000 ]---
> 
> 
> For reference:
> 
> ^1da177e4c3f4 fs/xfs/xfs_attr_leaf.c        (Linus Torvalds      2005-04-16 15:20:36 -0700 1555)        if (entry->flags & XFS_ATTR_LOCAL) {
> 517c22207b045 fs/xfs/xfs_attr_leaf.c        (Dave Chinner        2013-04-24 18:58:55 +1000 1556)                name_loc = xfs_attr3_leaf_name_local(leaf, args->index);
> ^1da177e4c3f4 fs/xfs/xfs_attr_leaf.c        (Linus Torvalds      2005-04-16 15:20:36 -0700 1557)                name_loc->namelen = args->namelen;
> 053b5758cbc09 fs/xfs/xfs_attr_leaf.c        (Nathan Scott        2006-03-17 17:29:09 +1100 1558)                name_loc->valuelen = cpu_to_be16(args->valuelen);
> ^1da177e4c3f4 fs/xfs/xfs_attr_leaf.c        (Linus Torvalds      2005-04-16 15:20:36 -0700 1559)                memcpy((char *)name_loc->nameval, args->name, args->namelen);
> ^1da177e4c3f4 fs/xfs/xfs_attr_leaf.c        (Linus Torvalds      2005-04-16 15:20:36 -0700 1560)                memcpy((char *)&name_loc->nameval[args->namelen], args->value,
> 053b5758cbc09 fs/xfs/xfs_attr_leaf.c        (Nathan Scott        2006-03-17 17:29:09 +1100 1561)                                   be16_to_cpu(name_loc->valuelen));
> ^1da177e4c3f4 fs/xfs/xfs_attr_leaf.c        (Linus Torvalds      2005-04-16 15:20:36 -0700 1562)        } else {
> 
> [...]
> 
> ^1da177e4c3f4 fs/xfs/xfs_attr_leaf.c        (Linus Torvalds      2005-04-16 15:20:36 -0700 2233) 
> 517c22207b045 fs/xfs/xfs_attr_leaf.c        (Dave Chinner        2013-04-24 18:58:55 +1000 2234)        tmp = (ichdr.count - args->index) * sizeof(xfs_attr_leaf_entry_t);
> 517c22207b045 fs/xfs/xfs_attr_leaf.c        (Dave Chinner        2013-04-24 18:58:55 +1000 2235)        memmove(entry, entry + 1, tmp);
> 517c22207b045 fs/xfs/xfs_attr_leaf.c        (Dave Chinner        2013-04-24 18:58:55 +1000 2236)        ichdr.count--;
> 1d9025e56143c fs/xfs/xfs_attr_leaf.c        (Dave Chinner        2012-06-22 18:50:14 +1000 2237)        xfs_trans_log_buf(args->trans, bp,
> 517c22207b045 fs/xfs/xfs_attr_leaf.c        (Dave Chinner        2013-04-24 18:58:55 +1000 2238)            XFS_DA_LOGRANGE(leaf, entry, tmp + sizeof(xfs_attr_leaf_entry_t)));
> 517c22207b045 fs/xfs/xfs_attr_leaf.c        (Dave Chinner        2013-04-24 18:58:55 +1000 2239) 
> 517c22207b045 fs/xfs/xfs_attr_leaf.c        (Dave Chinner        2013-04-24 18:58:55 +1000 2240)        entry = &xfs_attr3_leaf_entryp(leaf)[ichdr.count];
> 517c22207b045 fs/xfs/xfs_attr_leaf.c        (Dave Chinner        2013-04-24 18:58:55 +1000 2241)        memset(entry, 0, sizeof(xfs_attr_leaf_entry_t));
> ^1da177e4c3f4 fs/xfs/xfs_attr_leaf.c        (Linus Torvalds      2005-04-16 15:20:36 -0700 2242) 
> 
> 
> -- 
> Jeff Layton <jlayton@xxxxxxxxxx>



[Index of Archives]     [XFS Filesystem Development (older mail)]     [Linux Filesystem Development]     [Linux Audio Users]     [Yosemite Trails]     [Linux Kernel]     [Linux RAID]     [Linux SCSI]


  Powered by Linux