From: Wang Xiaoguang <wangxg.fnst@cn.fujitsu.com>
To: Chris Mason <clm@fb.com>, David Sterba <dsterba@suse.com>,
<linux-btrfs@vger.kernel.org>
Subject: Re: [PULL] Btrfs fixes, part 3
Date: Thu, 25 Aug 2016 20:04:57 +0800 [thread overview]
Message-ID: <57BEDEE9.2020607@cn.fujitsu.com> (raw)
In-Reply-To: <20160825105439.GA83439@clm-mbp.thefacebook.com>
Hi david and chris,
On 08/25/2016 06:54 PM, Chris Mason wrote:
> On Wed, Aug 24, 2016 at 03:42:54PM +0200, David Sterba wrote:
>> Hi,
>>
>> this pull request contains part 2 and adds more that arrived in the
>> meantime
>> (new fixes or updated versions of patches). Assorted fixes. Please pull,
>> thanks.
>
> Looks like this commit is deadlocking btrfs/071, I'm double checking
> with it removed:
>
> commit 9711669d602004f7658cf7fe003181de69e5ae6d
> Author: Wang Xiaoguang <wangxg.fnst@cn.fujitsu.com>
> Date: Wed Jul 27 13:50:21 2016 +0800
>
> btrfs: should block unused block groups deletion work when
> allocating data space
I've reproduced this issue when running btrfs/071 multiple times, sorry
again.
Also I have known the reason, currently please ignore this patch.
Regards,
Xiaoguang Wang
>
> There's a lockdep splat:
>
> [ 400.354652] run fstests btrfs/013 at 2016-08-24 14:36:41
> [ 400.939980] BTRFS: device fsid 6dd1d6e7-e6a7-4754-b1da-6ae12db06087
> devid 1 transid 3 /dev/vdh
> [ 400.982129] BTRFS info (device vdh): disk space caching is enabled
> [ 400.983470] BTRFS info (device vdh): has skinny extents
> [ 400.984706] BTRFS info (device vdh): flagging fs with big metadata
> feature
> [ 400.993415] BTRFS info (device vdh): creating UUID tree
> [ 411.057401] BTRFS info (device vdh): relocating block group
> 29360128 flags 36
> [ 411.080278] BTRFS info (device vdh): found 2 extents
> [ 411.092704] BTRFS info (device vdh): relocating block group
> 20971520 flags 34
> [ 411.105887] BTRFS info (device vdh): found 1 extents
> [ 411.117528] BTRFS info (device vdh): relocating block group
> 12582912 flags 1
>
> [ 411.119023] =============================================
> [ 411.119283] [ INFO: possible recursive locking detected ]
> [ 411.119283] 4.8.0-rc3-13125-gff57150 #231 Not tainted
> [ 411.119283] ---------------------------------------------
> [ 411.119283] btrfs/12261 is trying to acquire lock:
> [ 411.119283] (&fs_info->bg_delete_sem){++++..}, at:
> [<ffffffffa0a2aeef>] btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs]
> [ 411.119283] but task is already holding lock:
> [ 411.119283] (&fs_info->bg_delete_sem){++++..}, at:
> [<ffffffffa0a727e4>] __btrfs_balance+0x374/0xe70 [btrfs]
> [ 411.119283] other info that might help us debug this:
> [ 411.119283] Possible unsafe locking scenario:
>
> [ 411.119283] CPU0
> [ 411.119283] ----
> [ 411.119283] lock(&fs_info->bg_delete_sem);
> [ 411.119283] lock(&fs_info->bg_delete_sem);
> [ 411.119283] *** DEADLOCK ***
>
> [ 411.119283] May be due to missing lock nesting notation
>
> [ 411.119283] 6 locks held by btrfs/12261:
> [ 411.119283] #0: (sb_writers#9){.+.+.+}, at: [<ffffffff8122f273>]
> __sb_start_write+0xd3/0xf0
> [ 411.119283] #1: (&fs_info->volume_mutex){+.+.+.}, at:
> [<ffffffffa0a7db28>] btrfs_ioctl_balance+0x118/0x370 [btrfs]
> [ 411.119283] #2: (&fs_info->bg_delete_sem){++++..}, at:
> [<ffffffffa0a727e4>] __btrfs_balance+0x374/0xe70 [btrfs]
> [ 411.119283] #3: (&fs_info->cleaner_mutex){+.+...}, at:
> [<ffffffffa0aa620f>] btrfs_relocate_block_group+0x17f/0x2a0 [btrfs]
> [ 411.119283] #4: (sb_internal){.+.+.+}, at: [<ffffffff8122f231>]
> __sb_start_write+0x91/0xf0
> [ 411.119283] #5: (&cur_trans->cache_write_mutex){+.+...}, at:
> [<ffffffffa0a2bf3c>] btrfs_start_dirty_block_groups+0x1ac/0x550 [btrfs]
> [ 411.119283] stack backtrace:
> [ 411.119283] CPU: 6 PID: 12261 Comm: btrfs Not tainted
> 4.8.0-rc3-13125-gff57150 #231
> [ 411.119283] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996),
> BIOS 1.9.0-1.fc24 04/01/2014
> [ 411.119283] 0000000000000000 ffff880fd72371f8 ffffffff8149def7
> 0000000000000007
> [ 411.119283] 0000000000000006 ffffffff8256a580 ffff880fe67a5d00
> ffff880fd72372f8
> [ 411.119283] ffffffff810c6a4b ffff880fd7237308 0000000000000046
> ffff880fe67a6580
> [ 411.119283] Call Trace:
> [ 411.119283] [<ffffffff8149def7>] dump_stack+0x6b/0xa4
> [ 411.119283] [<ffffffff810c6a4b>] __lock_acquire+0x73b/0x1800
> [ 411.119283] [<ffffffffa0a0cc5a>] ? btrfs_free_path+0x2a/0x40 [btrfs]
> [ 411.119283] [<ffffffffa0a0cc5a>] ? btrfs_free_path+0x2a/0x40 [btrfs]
> [ 411.119283] [<ffffffff814bcb47>] ? debug_smp_processor_id+0x17/0x20
> [ 411.119283] [<ffffffff810c7bc9>] lock_acquire+0xb9/0x290
> [ 411.119283] [<ffffffffa0a2aeef>] ?
> btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs]
> [ 411.119283] [<ffffffffa0a2aedb>] ?
> btrfs_alloc_data_chunk_ondemand+0x8b/0x560 [btrfs]
> [ 411.119283] [<ffffffff81992574>] down_read+0x34/0x60
> [ 411.119283] [<ffffffffa0a2aeef>] ?
> btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs]
> [ 411.119283] [<ffffffff819942d5>] ? _raw_spin_unlock+0x35/0x60
> [ 411.119283] [<ffffffffa0a2aeef>]
> btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs]
> [ 411.119283] [<ffffffffa0a2b619>] ? cache_save_setup+0x1f9/0x390
> [btrfs]
> [ 411.119283] [<ffffffffa0a2b3f8>]
> btrfs_check_data_free_space+0x48/0x70 [btrfs]
> [ 411.119283] [<ffffffffa0a2b659>] cache_save_setup+0x239/0x390 [btrfs]
> [ 411.119283] [<ffffffffa0a2c027>]
> btrfs_start_dirty_block_groups+0x297/0x550 [btrfs]
> [ 411.119283] [<ffffffff810c5f91>] ?
> trace_hardirqs_on_caller+0x101/0x1d0
> [ 411.119283] [<ffffffff810c5cbe>] ? mark_held_locks+0x6e/0x90
> [ 411.119283] [<ffffffff8198ff7a>] ?
> __mutex_unlock_slowpath+0x10a/0x1b0
> [ 411.119283] [<ffffffff810c5f91>] ?
> trace_hardirqs_on_caller+0x101/0x1d0
> [ 411.119283] [<ffffffff8198ff63>] ? __mutex_unlock_slowpath+0xf3/0x1b0
> [ 411.119283] [<ffffffffa0a40a4d>]
> btrfs_commit_transaction+0x16d/0xd80 [btrfs]
> [ 411.119283] [<ffffffff814bcb23>] ? __this_cpu_preempt_check+0x13/0x20
> [ 411.119283] [<ffffffff810c11a8>] ? percpu_down_read_trylock+0x48/0xa0
> [ 411.119283] [<ffffffff8122f231>] ? __sb_start_write+0x91/0xf0
> [ 411.119283] [<ffffffff8122f231>] ? __sb_start_write+0x91/0xf0
> [ 411.119283] [<ffffffffa0a41b8b>] ? start_transaction+0xab/0x4b0
> [btrfs]
> [ 411.119283] [<ffffffffa0a9e8d4>] prepare_to_relocate+0xe4/0x110
> [btrfs]
> [ 411.119283] [<ffffffffa0aa5337>] relocate_block_group+0x47/0x7a0
> [btrfs]
> [ 411.119283] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 411.119283] [<ffffffff81990791>] ? mutex_lock_nested+0x2b1/0x400
> [ 411.119283] [<ffffffffa0aa620f>] ?
> btrfs_relocate_block_group+0x17f/0x2a0 [btrfs]
> [ 411.119283] [<ffffffff810c0f23>] ? up_write+0x23/0x50
> [ 411.119283] [<ffffffffa0aa6217>]
> btrfs_relocate_block_group+0x187/0x2a0 [btrfs]
> [ 411.119283] [<ffffffffa0a71afa>] btrfs_relocate_chunk+0x8a/0x120
> [btrfs]
> [ 411.119283] [<ffffffffa0a72ee7>] __btrfs_balance+0xa77/0xe70 [btrfs]
> [ 411.119283] [<ffffffff8198ff63>] ? __mutex_unlock_slowpath+0xf3/0x1b0
> [ 411.119283] [<ffffffffa0a736d9>] btrfs_balance+0x3f9/0x690 [btrfs]
> [ 411.119283] [<ffffffffa0a7dd33>] ? btrfs_ioctl_balance+0x323/0x370
> [btrfs]
> [ 411.119283] [<ffffffff810dcb8f>] ? rcu_read_lock_sched_held+0x4f/0x90
> [ 411.119283] [<ffffffffa0a7d9ff>] ? kzalloc+0xf/0x20 [btrfs]
> [ 411.119283] [<ffffffffa0a7dd33>] btrfs_ioctl_balance+0x323/0x370
> [btrfs]
> [ 411.119283] [<ffffffff8198df11>] ? __schedule+0x341/0xc00
> [ 411.119283] [<ffffffff81994287>] ?
> _raw_spin_unlock_irqrestore+0x67/0x80
> [ 411.119283] [<ffffffffa0a852ab>] btrfs_ioctl+0x60b/0x1460 [btrfs]
> [ 411.119283] [<ffffffff8112cfde>] ? __audit_syscall_entry+0xae/0x110
> [ 411.119283] [<ffffffff810f2e2f>] ? current_kernel_time64+0x9f/0xb0
> [ 411.119283] [<ffffffff810c5f91>] ?
> trace_hardirqs_on_caller+0x101/0x1d0
> [ 411.119283] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 411.119283] [<ffffffff8112cfde>] ? __audit_syscall_entry+0xae/0x110
> [ 411.119283] [<ffffffff81240c38>] vfs_ioctl+0x18/0x30
> [ 411.119283] [<ffffffff8124135e>] do_vfs_ioctl+0x8e/0x670
> [ 411.119283] [<ffffffff8112cfde>] ? __audit_syscall_entry+0xae/0x110
> [ 411.119283] [<ffffffff8112d268>] ? __audit_syscall_exit+0x228/0x2b0
> [ 411.119283] [<ffffffff810024c3>] ? syscall_trace_enter+0x213/0x3e0
> [ 411.119283] [<ffffffff812419d2>] SyS_ioctl+0x92/0xa0
> [ 411.119283] [<ffffffff81003013>] do_syscall_64+0x63/0x130
> [ 411.119283] [<ffffffff8100201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
> [ 411.119283] [<ffffffff8199451a>] entry_SYSCALL64_slow_path+0x25/0x25
>
> But we make it through btrfs/013 up to btrfs/071 and then:
>
> [ 1721.312198] INFO: task btrfs-cleaner:28544 blocked for more than
> 120 seconds.
> [ 1721.312995] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.313710] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.315255] btrfs-cleaner D ffff880fd6753b28 0 28544 2
> 0x00000080
> [ 1721.316148] ffff880fd6753b28 0000000000000046 0000000000000046
> 0000000000000000
> [ 1721.316989] 0000000000000246 ffff880f12bdc5c0 ffff880fe476c5c0
> 0000000000000000
> [ 1721.317832] ffff880fdb48c500 0000000000000282 0000000000000282
> 0000000000000000
> [ 1721.318695] Call Trace:
> [ 1721.318857] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.319445] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.319945] [<ffffffffa0a3f07d>] wait_current_trans+0xbd/0x130
> [btrfs]
> [ 1721.320629] [<ffffffff810b97f0>] ? woken_wake_function+0x20/0x20
> [ 1721.321308] [<ffffffff8122f273>] ? __sb_start_write+0xd3/0xf0
> [ 1721.321884] [<ffffffffa0a41dca>] start_transaction+0x2ea/0x4b0
> [btrfs]
> [ 1721.322593] [<ffffffffa0a422d8>] btrfs_start_transaction+0x18/0x20
> [btrfs]
> [ 1721.323329] [<ffffffffa0a423ce>]
> btrfs_start_transaction_fallback_global_rsv+0x2e/0xe0 [btrfs]
> [ 1721.324276] [<ffffffffa0a182a4>]
> btrfs_start_trans_remove_block_group+0x84/0xc0 [btrfs]
> [ 1721.325140] [<ffffffffa0a1e6ac>]
> btrfs_delete_unused_bgs+0x1fc/0x520 [btrfs]
> [ 1721.325856] [<ffffffff8198ff63>] ? __mutex_unlock_slowpath+0xf3/0x1b0
> [ 1721.326613] [<ffffffffa0a36400>] cleaner_kthread+0x1a0/0x1e0 [btrfs]
> [ 1721.327301] [<ffffffffa0a36260>] ? btree_readpage+0x30/0x30 [btrfs]
> [ 1721.327929] [<ffffffff8109058e>] kthread+0xde/0x100
> [ 1721.328413] [<ffffffff8109b015>] ? schedule_tail+0x35/0xf0
> [ 1721.328979] [<ffffffff8199467f>] ret_from_fork+0x1f/0x40
> [ 1721.329526] [<ffffffff810904b0>] ? __init_kthread_worker+0x70/0x70
> [ 1721.330165] INFO: lockdep is turned off.
> [ 1721.330499] INFO: task btrfs-transacti:28545 blocked for more than
> 120 seconds.
> [ 1721.331254] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.331971] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.332804] btrfs-transacti D ffff880fd70bfc88 0 28545 2
> 0x00000080
> [ 1721.333571] ffff880fd70bfc88 0000000000000046 0000000000000046
> 0000000000000000
> [ 1721.334411] 0000000000000246 ffff880f12bddd00 ffff880fe84c0000
> ffff880f00000000
> [ 1721.335299] ffff880fdb48c500 0000000000000282 0000000000000282
> 0000000000000000
> [ 1721.336139] Call Trace:
> [ 1721.336298] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.336855] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.337348] [<ffffffffa0a3f07d>] wait_current_trans+0xbd/0x130
> [btrfs]
> [ 1721.338076] [<ffffffff810b97f0>] ? woken_wake_function+0x20/0x20
> [ 1721.338684] [<ffffffffa0a41d3a>] start_transaction+0x25a/0x4b0
> [btrfs]
> [ 1721.339377] [<ffffffffa0a41fa7>]
> btrfs_attach_transaction+0x17/0x20 [btrfs]
> [ 1721.340312] [<ffffffffa0a3987d>] transaction_kthread+0x19d/0x210
> [btrfs]
> [ 1721.341142] [<ffffffffa0a396e0>] ? close_ctree+0x380/0x380 [btrfs]
> [ 1721.341757] [<ffffffff8109058e>] kthread+0xde/0x100
> [ 1721.342406] [<ffffffff8109b015>] ? schedule_tail+0x35/0xf0
> [ 1721.343047] [<ffffffff8199467f>] ret_from_fork+0x1f/0x40
> [ 1721.343553] [<ffffffff810904b0>] ? __init_kthread_worker+0x70/0x70
> [ 1721.344243] INFO: lockdep is turned off.
> [ 1721.344582] INFO: task fsstress:28551 blocked for more than 120
> seconds.
> [ 1721.345270] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.345947] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.346757] fsstress D ffff880fd777fc78 0 28551 28548
> 0x00000080
> [ 1721.347590] ffff880fd777fc78 0000000000000046 0000000000000046
> 0000000000000000
> [ 1721.348506] 0000000000000246 ffff880ef7835d00 ffff880fe84845c0
> 0000000000000000
> [ 1721.349349] ffff880fdb48c500 0000000000000286 0000000000000286
> 0000000000000000
> [ 1721.350243] Call Trace:
> [ 1721.350404] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.350970] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.351474] [<ffffffffa0a3f07d>] wait_current_trans+0xbd/0x130
> [btrfs]
> [ 1721.352178] [<ffffffff810b97f0>] ? woken_wake_function+0x20/0x20
> [ 1721.352775] [<ffffffff819900de>] ? mutex_unlock+0xe/0x10
> [ 1721.353349] [<ffffffffa0a41d3a>] start_transaction+0x25a/0x4b0
> [btrfs]
> [ 1721.354050] [<ffffffff81992d15>] ? rwsem_down_read_failed+0xc5/0x120
> [ 1721.354682] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.355368] [<ffffffffa0a424a7>]
> btrfs_attach_transaction_barrier+0x27/0x60 [btrfs]
> [ 1721.356210] [<ffffffffa0a02d6a>] btrfs_sync_fs+0x7a/0x250 [btrfs]
> [ 1721.356813] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.357489] [<ffffffff81269210>] sync_fs_one_sb+0x20/0x30
> [ 1721.358132] [<ffffffff8122fe3f>] iterate_supers+0xaf/0xe0
> [ 1721.358889] [<ffffffff81269705>] sys_sync+0x55/0x90
> [ 1721.359428] [<ffffffff81003013>] do_syscall_64+0x63/0x130
> [ 1721.360058] [<ffffffff8100201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
> [ 1721.360696] [<ffffffff8199451a>] entry_SYSCALL64_slow_path+0x25/0x25
> [ 1721.361414] INFO: lockdep is turned off.
> [ 1721.361751] INFO: task fsstress:28552 blocked for more than 120
> seconds.
> [ 1721.362445] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.363210] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.364051] fsstress D ffff880fdb077c78 0 28552 28548
> 0x00000080
> [ 1721.364793] ffff880fdb077c78 0000000000000046 0000000000000046
> 0000000000000000
> [ 1721.365653] 0000000000000246 ffff880ef7830000 ffff880fe84a0000
> 0000000000000000
> [ 1721.366508] ffff880fdb48c500 0000000000000286 0000000000000286
> 0000000000000000
> [ 1721.367362] Call Trace:
> [ 1721.367521] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.368112] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.368588] [<ffffffffa0a3f07d>] wait_current_trans+0xbd/0x130
> [btrfs]
> [ 1721.369280] [<ffffffff810b97f0>] ? woken_wake_function+0x20/0x20
> [ 1721.369867] [<ffffffff819900de>] ? mutex_unlock+0xe/0x10
> [ 1721.370502] [<ffffffffa0a41d3a>] start_transaction+0x25a/0x4b0
> [btrfs]
> [ 1721.371221] [<ffffffff81992d15>] ? rwsem_down_read_failed+0xc5/0x120
> [ 1721.371846] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.372534] [<ffffffffa0a424a7>]
> btrfs_attach_transaction_barrier+0x27/0x60 [btrfs]
> [ 1721.373372] [<ffffffffa0a02d6a>] btrfs_sync_fs+0x7a/0x250 [btrfs]
> [ 1721.373986] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.374649] [<ffffffff81269210>] sync_fs_one_sb+0x20/0x30
> [ 1721.375291] [<ffffffff8122fe3f>] iterate_supers+0xaf/0xe0
> [ 1721.375811] [<ffffffff81269705>] sys_sync+0x55/0x90
> [ 1721.376305] [<ffffffff81003013>] do_syscall_64+0x63/0x130
> [ 1721.376832] [<ffffffff8100201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
> [ 1721.377503] [<ffffffff8199451a>] entry_SYSCALL64_slow_path+0x25/0x25
> [ 1721.378222] INFO: lockdep is turned off.
> [ 1721.378558] INFO: task fsstress:28553 blocked for more than 120
> seconds.
> [ 1721.379246] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.379914] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.380764] fsstress D ffff880f198efc78 0 28553 28548
> 0x00000080
> [ 1721.381599] ffff880f198efc78 0000000000000046 0000000000000046
> 0000000000000000
> [ 1721.382452] 0000000000000246 ffff880ef7832e80 ffff880f03029740
> 0000000000000000
> [ 1721.383302] ffff880fdb48c500 0000000000000286 0000000000000286
> 0000000000000000
> [ 1721.384209] Call Trace:
> [ 1721.384368] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.384932] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.385436] [<ffffffffa0a3f07d>] wait_current_trans+0xbd/0x130
> [btrfs]
> [ 1721.386135] [<ffffffff810b97f0>] ? woken_wake_function+0x20/0x20
> [ 1721.386723] [<ffffffff819900de>] ? mutex_unlock+0xe/0x10
> [ 1721.387338] [<ffffffffa0a41d3a>] start_transaction+0x25a/0x4b0
> [btrfs]
> [ 1721.388049] [<ffffffff81992d15>] ? rwsem_down_read_failed+0xc5/0x120
> [ 1721.388675] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.389361] [<ffffffffa0a424a7>]
> btrfs_attach_transaction_barrier+0x27/0x60 [btrfs]
> [ 1721.390257] [<ffffffffa0a02d6a>] btrfs_sync_fs+0x7a/0x250 [btrfs]
> [ 1721.390875] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.391555] [<ffffffff81269210>] sync_fs_one_sb+0x20/0x30
> [ 1721.392115] [<ffffffff8122fe3f>] iterate_supers+0xaf/0xe0
> [ 1721.392636] [<ffffffff81269705>] sys_sync+0x55/0x90
> [ 1721.393192] [<ffffffff81003013>] do_syscall_64+0x63/0x130
> [ 1721.393710] [<ffffffff8100201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
> [ 1721.394376] [<ffffffff8199451a>] entry_SYSCALL64_slow_path+0x25/0x25
> [ 1721.395038] INFO: lockdep is turned off.
> [ 1721.395378] INFO: task fsstress:28554 blocked for more than 120
> seconds.
> [ 1721.396076] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.396742] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.397572] fsstress D ffff880fd4953c78 0 28554 28548
> 0x00000080
> [ 1721.398360] ffff880fd4953c78 0000000000000046 0000000000000046
> 0000000000000000
> [ 1721.399295] 0000000000000246 ffff880ef7831740 ffff880fe2fb8000
> 0000000000000002
> [ 1721.400162] ffff880fdb48c500 0000000000000286 0000000000000286
> 0000000000000000
> [ 1721.400983] Call Trace:
> [ 1721.401180] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.401739] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.402323] [<ffffffffa0a3f07d>] wait_current_trans+0xbd/0x130
> [btrfs]
> [ 1721.402992] [<ffffffff810b97f0>] ? woken_wake_function+0x20/0x20
> [ 1721.403613] [<ffffffff819900de>] ? mutex_unlock+0xe/0x10
> [ 1721.404171] [<ffffffffa0a41d3a>] start_transaction+0x25a/0x4b0
> [btrfs]
> [ 1721.404824] [<ffffffff81992d15>] ? rwsem_down_read_failed+0xc5/0x120
> [ 1721.405548] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.406251] [<ffffffffa0a424a7>]
> btrfs_attach_transaction_barrier+0x27/0x60 [btrfs]
> [ 1721.407094] [<ffffffffa0a02d6a>] btrfs_sync_fs+0x7a/0x250 [btrfs]
> [ 1721.407694] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.408427] [<ffffffff81269210>] sync_fs_one_sb+0x20/0x30
> [ 1721.408954] [<ffffffff8122fe3f>] iterate_supers+0xaf/0xe0
> [ 1721.409497] [<ffffffff81269705>] sys_sync+0x55/0x90
> [ 1721.409958] [<ffffffff81003013>] do_syscall_64+0x63/0x130
> [ 1721.410502] [<ffffffff8100201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
> [ 1721.411219] [<ffffffff8199451a>] entry_SYSCALL64_slow_path+0x25/0x25
> [ 1721.411856] INFO: lockdep is turned off.
> [ 1721.412285] INFO: task fsstress:28555 blocked for more than 120
> seconds.
> [ 1721.412964] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.413667] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.414506] fsstress D ffff880fc5f93898 0 28555 28548
> 0x00000080
> [ 1721.415408] ffff880fc5f93898 0000000000000000 0000000000000000
> ffff880fc5f938c8
> [ 1721.416280] 0000000000000046 ffff880ef78345c0 ffff880fe2fbdd00
> ffff880f00000000
> [ 1721.417137] 0000000000000000 0000000000000246 ffffffff00000000
> 0000000000000000
> [ 1721.417961] Call Trace:
> [ 1721.418156] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.418614] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.419206] [<ffffffff819941fb>] ? _raw_spin_unlock_irq+0x3b/0x60
> [ 1721.419813] [<ffffffff81992d15>] rwsem_down_read_failed+0xc5/0x120
> [ 1721.420560] [<ffffffff814aceb8>]
> call_rwsem_down_read_failed+0x18/0x30
> [ 1721.421261] [<ffffffff81992582>] down_read+0x42/0x60
> [ 1721.421763] [<ffffffffa0a2aeef>] ?
> btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs]
> [ 1721.422606] [<ffffffff819942d5>] ? _raw_spin_unlock+0x35/0x60
> [ 1721.423212] [<ffffffffa0a2aeef>]
> btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs]
> [ 1721.424070] [<ffffffffa0a9470d>] ?
> btrfs_truncate_free_space_cache+0x17d/0x1d0 [btrfs]
> [ 1721.424901] [<ffffffffa0a2b3f8>]
> btrfs_check_data_free_space+0x48/0x70 [btrfs]
> [ 1721.425779] [<ffffffffa0a2b659>] cache_save_setup+0x239/0x390 [btrfs]
> [ 1721.426486] [<ffffffffa0a6b564>] ? free_extent_buffer+0x44/0xb0
> [btrfs]
> [ 1721.427198] [<ffffffffa0a2b875>] btrfs_setup_space_cache+0xc5/0xe0
> [btrfs]
> [ 1721.427909] [<ffffffffa0a3e0ca>] commit_cowonly_roots+0xfa/0x310
> [btrfs]
> [ 1721.428706] [<ffffffffa0a3dfb4>] ? commit_fs_roots+0x1b4/0x1d0
> [btrfs]
> [ 1721.429426] [<ffffffffa0aba38a>] ?
> btrfs_qgroup_account_extents+0x4a/0x270 [btrfs]
> [ 1721.430265] [<ffffffffa0a40ec8>]
> btrfs_commit_transaction+0x5e8/0xd80 [btrfs]
> [ 1721.431092] [<ffffffff812065e4>] ? kmem_cache_alloc+0x1f4/0x320
> [ 1721.431685] [<ffffffffa0a41c0a>] ? start_transaction+0x12a/0x4b0
> [btrfs]
> [ 1721.432391] [<ffffffff819900de>] ? mutex_unlock+0xe/0x10
> [ 1721.432911] [<ffffffffa0a41b8b>] ? start_transaction+0xab/0x4b0
> [btrfs]
> [ 1721.433610] [<ffffffff81992d15>] ? rwsem_down_read_failed+0xc5/0x120
> [ 1721.434349] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.434994] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.435667] [<ffffffffa0a02d81>] btrfs_sync_fs+0x91/0x250 [btrfs]
> [ 1721.436318] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.437040] [<ffffffff81269210>] sync_fs_one_sb+0x20/0x30
> [ 1721.437563] [<ffffffff8122fe3f>] iterate_supers+0xaf/0xe0
> [ 1721.438115] [<ffffffff81269705>] sys_sync+0x55/0x90
> [ 1721.438570] [<ffffffff81003013>] do_syscall_64+0x63/0x130
> [ 1721.439123] [<ffffffff8100201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
> [ 1721.439758] [<ffffffff8199451a>] entry_SYSCALL64_slow_path+0x25/0x25
> [ 1721.440669] INFO: lockdep is turned off.
> [ 1721.441200] INFO: task fsstress:28556 blocked for more than 120
> seconds.
> [ 1721.442201] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.443187] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.444087] fsstress D ffff880fd9c7bc78 0 28556 28548
> 0x00000080
> [ 1721.444842] ffff880fd9c7bc78 0000000000000046 0000000000000046
> 0000000000000000
> [ 1721.445707] 0000000000000246 ffff880fe2fbae80 ffff880ef7832e80
> 0000000000000000
> [ 1721.446568] ffff880fdb48c500 0000000000000286 0000000000000286
> 0000000000000000
> [ 1721.447466] Call Trace:
> [ 1721.447626] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.448208] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.448674] [<ffffffffa0a3f07d>] wait_current_trans+0xbd/0x130
> [btrfs]
> [ 1721.449349] [<ffffffff810b97f0>] ? woken_wake_function+0x20/0x20
> [ 1721.449913] [<ffffffff819900de>] ? mutex_unlock+0xe/0x10
> [ 1721.450454] [<ffffffffa0a41d3a>] start_transaction+0x25a/0x4b0
> [btrfs]
> [ 1721.451119] [<ffffffff81992d15>] ? rwsem_down_read_failed+0xc5/0x120
> [ 1721.451727] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.452506] [<ffffffffa0a424a7>]
> btrfs_attach_transaction_barrier+0x27/0x60 [btrfs]
> [ 1721.453556] [<ffffffffa0a02d6a>] btrfs_sync_fs+0x7a/0x250 [btrfs]
> [ 1721.454227] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.454868] [<ffffffff81269210>] sync_fs_one_sb+0x20/0x30
> [ 1721.455431] [<ffffffff8122fe3f>] iterate_supers+0xaf/0xe0
> [ 1721.455959] [<ffffffff81269705>] sys_sync+0x55/0x90
> [ 1721.456450] [<ffffffff81003013>] do_syscall_64+0x63/0x130
> [ 1721.456982] [<ffffffff8100201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
> [ 1721.457653] [<ffffffff8199451a>] entry_SYSCALL64_slow_path+0x25/0x25
> [ 1721.458327] INFO: lockdep is turned off.
> [ 1721.458666] INFO: task fsstress:28557 blocked for more than 120
> seconds.
> [ 1721.459371] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.460173] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.461042] fsstress D ffff880f18f67c78 0 28557 28548
> 0x00000080
> [ 1721.461798] ffff880f18f67c78 0000000000000046 0000000000000046
> 0000000000000000
> [ 1721.462663] 0000000000000246 ffff880fe2fb9740 ffff880ef7830000
> 0000000000000000
> [ 1721.463523] ffff880fdb48c500 0000000000000286 0000000000000286
> 0000000000000000
> [ 1721.464393] Call Trace:
> [ 1721.464553] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.465225] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.465726] [<ffffffffa0a3f07d>] wait_current_trans+0xbd/0x130
> [btrfs]
> [ 1721.466427] [<ffffffff810b97f0>] ? woken_wake_function+0x20/0x20
> [ 1721.467059] [<ffffffff819900de>] ? mutex_unlock+0xe/0x10
> [ 1721.467587] [<ffffffffa0a41d3a>] start_transaction+0x25a/0x4b0
> [btrfs]
> [ 1721.468365] [<ffffffff81992d15>] ? rwsem_down_read_failed+0xc5/0x120
> [ 1721.469042] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.469703] [<ffffffffa0a424a7>]
> btrfs_attach_transaction_barrier+0x27/0x60 [btrfs]
> [ 1721.470552] [<ffffffffa0a02d6a>] btrfs_sync_fs+0x7a/0x250 [btrfs]
> [ 1721.471280] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.471947] [<ffffffff81269210>] sync_fs_one_sb+0x20/0x30
> [ 1721.472502] [<ffffffff8122fe3f>] iterate_supers+0xaf/0xe0
> [ 1721.473066] [<ffffffff81269705>] sys_sync+0x55/0x90
> [ 1721.473528] [<ffffffff81003013>] do_syscall_64+0x63/0x130
> [ 1721.474141] [<ffffffff8100201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
> [ 1721.474802] [<ffffffff8199451a>] entry_SYSCALL64_slow_path+0x25/0x25
> [ 1721.475475] INFO: lockdep is turned off.
> [ 1721.475815] INFO: task fsstress:28558 blocked for more than 120
> seconds.
> [ 1721.476521] Tainted: G W 4.8.0-rc3-13125-gff57150 #231
> [ 1721.477316] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [ 1721.478166] fsstress D ffff880fdb39bc78 0 28558 28548
> 0x00000080
> [ 1721.478928] ffff880fdb39bc78 0000000000000046 0000000000000046
> 0000000000000000
> [ 1721.479791] 0000000000000246 ffff880fe2fb8000 ffff880fe5c31740
> 0000000000000000
> [ 1721.480730] ffff880fdb48c500 0000000000000286 0000000000000286
> 0000000000000000
> [ 1721.481600] Call Trace:
> [ 1721.481760] [<ffffffff810c606d>] ? trace_hardirqs_on+0xd/0x10
> [ 1721.482366] [<ffffffff8198eaba>] schedule+0x4a/0xc0
> [ 1721.482846] [<ffffffffa0a3f07d>] wait_current_trans+0xbd/0x130
> [btrfs]
> [ 1721.483620] [<ffffffff810b97f0>] ? woken_wake_function+0x20/0x20
> [ 1721.484260] [<ffffffff819900de>] ? mutex_unlock+0xe/0x10
> [ 1721.484794] [<ffffffffa0a41d3a>] start_transaction+0x25a/0x4b0
> [btrfs]
> [ 1721.485496] [<ffffffff81992d15>] ? rwsem_down_read_failed+0xc5/0x120
> [ 1721.486232] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.486917] [<ffffffffa0a424a7>]
> btrfs_attach_transaction_barrier+0x27/0x60 [btrfs]
> [ 1721.487769] [<ffffffffa0a02d6a>] btrfs_sync_fs+0x7a/0x250 [btrfs]
> [ 1721.488427] [<ffffffff812691f0>] ? iter_file_splice_write+0x400/0x400
> [ 1721.489177] [<ffffffff81269210>] sync_fs_one_sb+0x20/0x30
> [ 1721.489706] [<ffffffff8122fe3f>] iterate_supers+0xaf/0xe0
> [ 1721.490268] [<ffffffff81269705>] sys_sync+0x55/0x90
> [ 1721.490733] [<ffffffff81003013>] do_syscall_64+0x63/0x130
> [ 1721.491289] [<ffffffff8100201a>] ? trace_hardirqs_on_thunk+0x1a/0x1c
> [ 1721.491935] [<ffffffff8199451a>] entry_SYSCALL64_slow_path+0x25/0x25
> --
> To unsubscribe from this list: send the line "unsubscribe linux-btrfs" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at http://vger.kernel.org/majordomo-info.html
>
>
prev parent reply other threads:[~2016-08-25 12:09 UTC|newest]
Thread overview: 5+ messages / expand[flat|nested] mbox.gz Atom feed top
2016-08-24 13:42 [PULL] Btrfs fixes, part 3 David Sterba
2016-08-24 21:14 ` Chris Mason
2016-08-25 6:14 ` Wang Xiaoguang
2016-08-25 10:54 ` Chris Mason
2016-08-25 12:04 ` Wang Xiaoguang [this message]
Reply instructions:
You may reply publicly to this message via plain-text email
using any one of the following methods:
* Save the following mbox file, import it into your mail client,
and reply-to-all from there: mbox
Avoid top-posting and favor interleaved quoting:
https://en.wikipedia.org/wiki/Posting_style#Interleaved_style
* Reply using the --to, --cc, and --in-reply-to
switches of git-send-email(1):
git send-email \
--in-reply-to=57BEDEE9.2020607@cn.fujitsu.com \
--to=wangxg.fnst@cn.fujitsu.com \
--cc=clm@fb.com \
--cc=dsterba@suse.com \
--cc=linux-btrfs@vger.kernel.org \
/path/to/YOUR_REPLY
https://kernel.org/pub/software/scm/git/docs/git-send-email.html
* If your mail client supports setting the In-Reply-To header
via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line
before the message body.
This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.