On Fri, Jan 05, 2018 at 12:35:49PM +0800, Eryu Guan wrote: > On Wed, Jan 03, 2018 at 09:12:11AM -0800, Darrick J. Wong wrote: > > On Wed, Jan 03, 2018 at 04:48:01PM +0800, Eryu Guan wrote: > > > On Thu, Dec 14, 2017 at 06:07:31PM -0800, Darrick J. Wong wrote: > > > > From: Darrick J. Wong <darrick.wong@xxxxxxxxxx> > > > > > > > > Mix it up a bit by reflinking and deduping data blocks when possible. > > > > > > > > Signed-off-by: Darrick J. Wong <darrick.wong@xxxxxxxxxx> > > > > > > This looks fine overall, but I noticed a soft lockup bug in generic/083 > > > and generic/269 (both test exercise ENOSPC behavior), test config is > > > reflink+rmapbt XFS with 4k block size. Not sure if the soft lockup is > > > related to the clonerange/deduperange ops in fsstress yet, will confirm > > > without clone/dedupe ops. > > More testings showed that this may have something to do with the > deduperange operations. (I was testing with Fedora rawhide with > v4.15-rc5 kernel, I didn't see hang nor soft lockup with my RHEL7 base > host, because there's no FIDEDUPERANGE defined there). > > I reverted the whole clonerange/deduperange support and retested for two > rounds of full '-g auto' run without hitting any hang or soft lockup. > Then I commented out the deduperange ops and left clonerange ops there, > no hang/lockup either. At last I commented out the clonerange ops but > left deduperange ops there, I hit a different hang in generic/270 (still > a ENOSPC test). I pasted partial sysrq-w output here, if full output is > needed please let me know. > > [79200.901901] 14266.fsstress. D12200 14533 14460 0x00000000 > [79200.902419] Call Trace: > [79200.902655] ? __schedule+0x2e3/0xb90 > [79200.902969] ? _raw_spin_unlock_irqrestore+0x32/0x60 > [79200.903442] schedule+0x2f/0x90 > [79200.903727] schedule_timeout+0x1dd/0x540 > [79200.904114] ? __next_timer_interrupt+0xc0/0xc0 > [79200.904535] xfs_inode_ag_walk.isra.12+0x3cc/0x670 [xfs] > [79200.905009] ? __xfs_inode_clear_blocks_tag+0x120/0x120 [xfs] > [79200.905563] ? kvm_clock_read+0x21/0x30 > [79200.905891] ? sched_clock+0x5/0x10 > [79200.906243] ? sched_clock_local+0x12/0x80 > [79200.906598] ? kvm_clock_read+0x21/0x30 > [79200.906920] ? sched_clock+0x5/0x10 > [79200.907273] ? sched_clock_local+0x12/0x80 > [79200.907636] ? __lock_is_held+0x59/0xa0 > [79200.907988] ? xfs_inode_ag_iterator_tag+0x46/0xb0 [xfs] > [79200.908497] ? rcu_read_lock_sched_held+0x6b/0x80 > [79200.908926] ? xfs_perag_get_tag+0x28b/0x2f0 [xfs] > [79200.909416] ? __xfs_inode_clear_blocks_tag+0x120/0x120 [xfs] > [79200.909922] xfs_inode_ag_iterator_tag+0x73/0xb0 [xfs] > [79200.910446] xfs_file_buffered_aio_write+0x348/0x370 [xfs] > [79200.910948] xfs_file_write_iter+0x99/0x140 [xfs] > [79200.911400] __vfs_write+0xfc/0x170 > [79200.911726] vfs_write+0xc1/0x1b0 > [79200.912063] SyS_write+0x55/0xc0 > [79200.912347] entry_SYSCALL_64_fastpath+0x1f/0x96 > > Seems other hangning fsstress processes were all waiting for io > completion of writeback (sleeping in wb_wait_for_completion). Hmm, I'll badger it some more, though I did see: [ 4349.832516] XFS: Assertion failed: xfs_is_reflink_inode(ip), file: /storage/home/djwong/cdev/work/linux-xfs/fs/xfs/xfs_reflink.c, line: 651 [ 4349.847730] WARNING: CPU: 3 PID: 3600 at /storage/home/djwong/cdev/work/linux-xfs/fs/xfs/xfs_message.c:116 assfail+0x2e/0x60 [xfs] [ 4349.849142] Modules linked in: xfs libcrc32c dm_snapshot dm_bufio dax_pmem device_dax nd_pmem sch_fq_codel af_packet [last unloaded: xfs] [ 4349.850603] CPU: 3 PID: 3600 Comm: fsstress Not tainted 4.15.0-rc6-xfsx #9 [ 4349.851417] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.10.2-1ubuntu1djwong0 04/01/2014 [ 4349.852594] RIP: 0010:assfail+0x2e/0x60 [xfs] [ 4349.853156] RSP: 0018:ffffc90002d97a80 EFLAGS: 00010246 [ 4349.853785] RAX: 00000000ffffffea RBX: 0000000000000000 RCX: 0000000000000001 [ 4349.854621] RDX: 00000000ffffffc0 RSI: 000000000000000a RDI: ffffffffa0270585 [ 4349.855457] RBP: ffff88001d41d100 R08: 0000000000000000 R09: 0000000000000000 [ 4349.856296] R10: ffffc90002d97a28 R11: f000000000000000 R12: 0000000000000000 [ 4349.857142] R13: ffffffffffffffff R14: 0000000000000000 R15: 0000000000000008 [ 4349.857969] FS: 00007f0712dc8700(0000) GS:ffff88007f400000(0000) knlGS:0000000000000000 [ 4349.858918] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 4349.859596] CR2: 00007f0711e7e008 CR3: 0000000004265001 CR4: 00000000001606e0 [ 4349.860462] Call Trace: [ 4349.860860] xfs_reflink_cancel_cow_range+0x3f6/0x650 [xfs] [ 4349.861596] ? down_write_nested+0x94/0xb0 [ 4349.862165] ? xfs_ilock+0x2ac/0x450 [xfs] [ 4349.862719] xfs_inode_free_cowblocks+0x38e/0x620 [xfs] [ 4349.863376] xfs_inode_ag_walk+0x327/0xc30 [xfs] [ 4349.863972] ? xfs_inode_free_eofblocks+0x580/0x580 [xfs] [ 4349.864600] ? try_to_wake_up+0x30/0x560 [ 4349.865105] ? _raw_spin_unlock_irqrestore+0x46/0x70 [ 4349.865667] ? try_to_wake_up+0x49/0x560 [ 4349.866159] ? radix_tree_gang_lookup_tag+0xf4/0x150 [ 4349.866795] ? xfs_inode_free_eofblocks+0x580/0x580 [xfs] [ 4349.867438] ? xfs_perag_get_tag+0x205/0x470 [xfs] [ 4349.868042] ? xfs_perag_put+0x15f/0x2e0 [xfs] [ 4349.868573] ? xfs_inode_free_eofblocks+0x580/0x580 [xfs] [ 4349.869243] xfs_inode_ag_iterator_tag+0x65/0xa0 [xfs] [ 4349.869876] xfs_file_buffered_aio_write+0x203/0x5b0 [xfs] [ 4349.870575] xfs_file_write_iter+0x298/0x4f0 [xfs] [ 4349.871164] __vfs_write+0x130/0x1a0 [ 4349.871585] vfs_write+0xc8/0x1c0 [ 4349.872001] SyS_write+0x45/0xa0 [ 4349.872394] entry_SYSCALL_64_fastpath+0x1f/0x96 [ 4349.872950] RIP: 0033:0x7f071259c4a0 [ 4349.873377] RSP: 002b:00007ffea07c4588 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 4349.874226] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f071259c4a0 [ 4349.875066] RDX: 000000000000aebc RSI: 000000000111b260 RDI: 0000000000000003 [ 4349.875866] RBP: 0000000000000001 R08: 000000000000006e R09: 0000000000000004 [ 4349.876658] R10: 00007f0712586b78 R11: 0000000000000246 R12: 00000000000007a9 [ 4349.877433] R13: 0000000000000003 R14: 00000000001c6200 R15: 000000000001e000 [ 4349.878273] Code: 00 00 53 48 89 f1 41 89 d0 48 c7 c6 18 3e 28 a0 48 89 fa 31 ff e8 63 fa ff ff 0f b6 1d 28 44 29 00 80 fb 01 77 09 83 e3 01 75 15 <0f> ff 5b c3 0f b6 f3 48 c7 c7 90 bd 3d a0 e8 3f 13 1c e1 eb e6 [ 4349.880399] ---[ end trace 1e05700f283b7cc1 ]--- So maybe I need to take a closer look at all this machinery tomorrow... > > > > > > > [12968.100008] watchdog: BUG: soft lockup - CPU#2 stuck for 22s! [fsstress:6903] > > > [12968.100038] Modules linked in: loop dm_flakey xfs ip6t_rpfilter ip6t_REJECT nf_reject_ipv6 xt_conntrack ip_set nfnetlink ebtable_nat ebtable_broute bridge stp llc ip6table_nat nf_conntrack_ipv6 nf_defrag_ipv6 nf_nat_ipv6 ip6table_mangle ip6table_raw ip6table_security iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack libcrc32c iptable_mangle iptable_raw iptable_security ebtable_filter ebtables ip6table_filter ip6_tables sunrpc 8139too 8139cp i2c_piix4 joydev mii pcspkr virtio_balloon virtio_pci serio_raw virtio_ring virtio floppy ata_generic pata_acpi > > > [12968.104043] irq event stamp: 23222196 > > > [12968.104043] hardirqs last enabled at (23222195): [<000000007d0c2e75>] restore_regs_and_return_to_kernel+0x0/0x2e > > > [12968.105111] hardirqs last disabled at (23222196): [<000000008f80dc57>] apic_timer_interrupt+0xa7/0xc0 > > > [12968.105111] softirqs last enabled at (877594): [<0000000034c53d5e>] __do_softirq+0x392/0x502 > > > [12968.105111] softirqs last disabled at (877585): [<000000003f4d9e0b>] irq_exit+0x102/0x110 > > > [12968.105111] CPU: 2 PID: 6903 Comm: fsstress Tainted: G W L 4.15.0-rc5 #10 > > > [12968.105111] Hardware name: Red Hat KVM, BIOS 0.5.1 01/01/2007 > > > [12968.108043] RIP: 0010:xfs_bmapi_update_map+0xc/0xc0 [xfs] > > > > Hmmm, I haven't seen such a hang; I wonder if we're doing something > > we shouldn't be doing and looping in bmapi_write. In any case it's > > a bug with xfs, not fsstress. > > Agreed, I'm planning to pull this patch in this week's update, with the > following fix > > - inode_info(inoinfo2, sizeof(inoinfo2), &stat2, v1); > + inode_info(inoinfo2, sizeof(inoinfo2), &stat2, v2); > > Also I'd follow Dave's suggestion on xfs/068 fix, move the > FSSTRESS_AVOID handling to common/dump on commit. Please let me know if > you have a different plan now. I was just gonna go back to amending only xfs/068 to turn off clone/dedupe. --D > Thanks, > Eryu -- To unsubscribe from this list: send the line "unsubscribe fstests" in the body of a message to majordomo@xxxxxxxxxxxxxxx More majordomo info at http://vger.kernel.org/majordomo-info.html
