From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from cn.fujitsu.com ([59.151.112.132]:45595 "EHLO heian.cn.fujitsu.com" rhost-flags-OK-FAIL-OK-FAIL) by vger.kernel.org with ESMTP id S933517AbcHYMJI (ORCPT ); Thu, 25 Aug 2016 08:09:08 -0400 Subject: Re: [PULL] Btrfs fixes, part 3 To: Chris Mason , David Sterba , References: <20160825105439.GA83439@clm-mbp.thefacebook.com> From: Wang Xiaoguang Message-ID: <57BEDEE9.2020607@cn.fujitsu.com> Date: Thu, 25 Aug 2016 20:04:57 +0800 MIME-Version: 1.0 In-Reply-To: <20160825105439.GA83439@clm-mbp.thefacebook.com> Content-Type: text/plain; charset="windows-1252"; format=flowed Sender: linux-btrfs-owner@vger.kernel.org List-ID: 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 > 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: > [] btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs] > [ 411.119283] but task is already holding lock: > [ 411.119283] (&fs_info->bg_delete_sem){++++..}, at: > [] __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: [] > __sb_start_write+0xd3/0xf0 > [ 411.119283] #1: (&fs_info->volume_mutex){+.+.+.}, at: > [] btrfs_ioctl_balance+0x118/0x370 [btrfs] > [ 411.119283] #2: (&fs_info->bg_delete_sem){++++..}, at: > [] __btrfs_balance+0x374/0xe70 [btrfs] > [ 411.119283] #3: (&fs_info->cleaner_mutex){+.+...}, at: > [] btrfs_relocate_block_group+0x17f/0x2a0 [btrfs] > [ 411.119283] #4: (sb_internal){.+.+.+}, at: [] > __sb_start_write+0x91/0xf0 > [ 411.119283] #5: (&cur_trans->cache_write_mutex){+.+...}, at: > [] 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] [] dump_stack+0x6b/0xa4 > [ 411.119283] [] __lock_acquire+0x73b/0x1800 > [ 411.119283] [] ? btrfs_free_path+0x2a/0x40 [btrfs] > [ 411.119283] [] ? btrfs_free_path+0x2a/0x40 [btrfs] > [ 411.119283] [] ? debug_smp_processor_id+0x17/0x20 > [ 411.119283] [] lock_acquire+0xb9/0x290 > [ 411.119283] [] ? > btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs] > [ 411.119283] [] ? > btrfs_alloc_data_chunk_ondemand+0x8b/0x560 [btrfs] > [ 411.119283] [] down_read+0x34/0x60 > [ 411.119283] [] ? > btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs] > [ 411.119283] [] ? _raw_spin_unlock+0x35/0x60 > [ 411.119283] [] > btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs] > [ 411.119283] [] ? cache_save_setup+0x1f9/0x390 > [btrfs] > [ 411.119283] [] > btrfs_check_data_free_space+0x48/0x70 [btrfs] > [ 411.119283] [] cache_save_setup+0x239/0x390 [btrfs] > [ 411.119283] [] > btrfs_start_dirty_block_groups+0x297/0x550 [btrfs] > [ 411.119283] [] ? > trace_hardirqs_on_caller+0x101/0x1d0 > [ 411.119283] [] ? mark_held_locks+0x6e/0x90 > [ 411.119283] [] ? > __mutex_unlock_slowpath+0x10a/0x1b0 > [ 411.119283] [] ? > trace_hardirqs_on_caller+0x101/0x1d0 > [ 411.119283] [] ? __mutex_unlock_slowpath+0xf3/0x1b0 > [ 411.119283] [] > btrfs_commit_transaction+0x16d/0xd80 [btrfs] > [ 411.119283] [] ? __this_cpu_preempt_check+0x13/0x20 > [ 411.119283] [] ? percpu_down_read_trylock+0x48/0xa0 > [ 411.119283] [] ? __sb_start_write+0x91/0xf0 > [ 411.119283] [] ? __sb_start_write+0x91/0xf0 > [ 411.119283] [] ? start_transaction+0xab/0x4b0 > [btrfs] > [ 411.119283] [] prepare_to_relocate+0xe4/0x110 > [btrfs] > [ 411.119283] [] relocate_block_group+0x47/0x7a0 > [btrfs] > [ 411.119283] [] ? trace_hardirqs_on+0xd/0x10 > [ 411.119283] [] ? mutex_lock_nested+0x2b1/0x400 > [ 411.119283] [] ? > btrfs_relocate_block_group+0x17f/0x2a0 [btrfs] > [ 411.119283] [] ? up_write+0x23/0x50 > [ 411.119283] [] > btrfs_relocate_block_group+0x187/0x2a0 [btrfs] > [ 411.119283] [] btrfs_relocate_chunk+0x8a/0x120 > [btrfs] > [ 411.119283] [] __btrfs_balance+0xa77/0xe70 [btrfs] > [ 411.119283] [] ? __mutex_unlock_slowpath+0xf3/0x1b0 > [ 411.119283] [] btrfs_balance+0x3f9/0x690 [btrfs] > [ 411.119283] [] ? btrfs_ioctl_balance+0x323/0x370 > [btrfs] > [ 411.119283] [] ? rcu_read_lock_sched_held+0x4f/0x90 > [ 411.119283] [] ? kzalloc+0xf/0x20 [btrfs] > [ 411.119283] [] btrfs_ioctl_balance+0x323/0x370 > [btrfs] > [ 411.119283] [] ? __schedule+0x341/0xc00 > [ 411.119283] [] ? > _raw_spin_unlock_irqrestore+0x67/0x80 > [ 411.119283] [] btrfs_ioctl+0x60b/0x1460 [btrfs] > [ 411.119283] [] ? __audit_syscall_entry+0xae/0x110 > [ 411.119283] [] ? current_kernel_time64+0x9f/0xb0 > [ 411.119283] [] ? > trace_hardirqs_on_caller+0x101/0x1d0 > [ 411.119283] [] ? trace_hardirqs_on+0xd/0x10 > [ 411.119283] [] ? __audit_syscall_entry+0xae/0x110 > [ 411.119283] [] vfs_ioctl+0x18/0x30 > [ 411.119283] [] do_vfs_ioctl+0x8e/0x670 > [ 411.119283] [] ? __audit_syscall_entry+0xae/0x110 > [ 411.119283] [] ? __audit_syscall_exit+0x228/0x2b0 > [ 411.119283] [] ? syscall_trace_enter+0x213/0x3e0 > [ 411.119283] [] SyS_ioctl+0x92/0xa0 > [ 411.119283] [] do_syscall_64+0x63/0x130 > [ 411.119283] [] ? trace_hardirqs_on_thunk+0x1a/0x1c > [ 411.119283] [] 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] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.319445] [] schedule+0x4a/0xc0 > [ 1721.319945] [] wait_current_trans+0xbd/0x130 > [btrfs] > [ 1721.320629] [] ? woken_wake_function+0x20/0x20 > [ 1721.321308] [] ? __sb_start_write+0xd3/0xf0 > [ 1721.321884] [] start_transaction+0x2ea/0x4b0 > [btrfs] > [ 1721.322593] [] btrfs_start_transaction+0x18/0x20 > [btrfs] > [ 1721.323329] [] > btrfs_start_transaction_fallback_global_rsv+0x2e/0xe0 [btrfs] > [ 1721.324276] [] > btrfs_start_trans_remove_block_group+0x84/0xc0 [btrfs] > [ 1721.325140] [] > btrfs_delete_unused_bgs+0x1fc/0x520 [btrfs] > [ 1721.325856] [] ? __mutex_unlock_slowpath+0xf3/0x1b0 > [ 1721.326613] [] cleaner_kthread+0x1a0/0x1e0 [btrfs] > [ 1721.327301] [] ? btree_readpage+0x30/0x30 [btrfs] > [ 1721.327929] [] kthread+0xde/0x100 > [ 1721.328413] [] ? schedule_tail+0x35/0xf0 > [ 1721.328979] [] ret_from_fork+0x1f/0x40 > [ 1721.329526] [] ? __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] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.336855] [] schedule+0x4a/0xc0 > [ 1721.337348] [] wait_current_trans+0xbd/0x130 > [btrfs] > [ 1721.338076] [] ? woken_wake_function+0x20/0x20 > [ 1721.338684] [] start_transaction+0x25a/0x4b0 > [btrfs] > [ 1721.339377] [] > btrfs_attach_transaction+0x17/0x20 [btrfs] > [ 1721.340312] [] transaction_kthread+0x19d/0x210 > [btrfs] > [ 1721.341142] [] ? close_ctree+0x380/0x380 [btrfs] > [ 1721.341757] [] kthread+0xde/0x100 > [ 1721.342406] [] ? schedule_tail+0x35/0xf0 > [ 1721.343047] [] ret_from_fork+0x1f/0x40 > [ 1721.343553] [] ? __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] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.350970] [] schedule+0x4a/0xc0 > [ 1721.351474] [] wait_current_trans+0xbd/0x130 > [btrfs] > [ 1721.352178] [] ? woken_wake_function+0x20/0x20 > [ 1721.352775] [] ? mutex_unlock+0xe/0x10 > [ 1721.353349] [] start_transaction+0x25a/0x4b0 > [btrfs] > [ 1721.354050] [] ? rwsem_down_read_failed+0xc5/0x120 > [ 1721.354682] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.355368] [] > btrfs_attach_transaction_barrier+0x27/0x60 [btrfs] > [ 1721.356210] [] btrfs_sync_fs+0x7a/0x250 [btrfs] > [ 1721.356813] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.357489] [] sync_fs_one_sb+0x20/0x30 > [ 1721.358132] [] iterate_supers+0xaf/0xe0 > [ 1721.358889] [] sys_sync+0x55/0x90 > [ 1721.359428] [] do_syscall_64+0x63/0x130 > [ 1721.360058] [] ? trace_hardirqs_on_thunk+0x1a/0x1c > [ 1721.360696] [] 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] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.368112] [] schedule+0x4a/0xc0 > [ 1721.368588] [] wait_current_trans+0xbd/0x130 > [btrfs] > [ 1721.369280] [] ? woken_wake_function+0x20/0x20 > [ 1721.369867] [] ? mutex_unlock+0xe/0x10 > [ 1721.370502] [] start_transaction+0x25a/0x4b0 > [btrfs] > [ 1721.371221] [] ? rwsem_down_read_failed+0xc5/0x120 > [ 1721.371846] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.372534] [] > btrfs_attach_transaction_barrier+0x27/0x60 [btrfs] > [ 1721.373372] [] btrfs_sync_fs+0x7a/0x250 [btrfs] > [ 1721.373986] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.374649] [] sync_fs_one_sb+0x20/0x30 > [ 1721.375291] [] iterate_supers+0xaf/0xe0 > [ 1721.375811] [] sys_sync+0x55/0x90 > [ 1721.376305] [] do_syscall_64+0x63/0x130 > [ 1721.376832] [] ? trace_hardirqs_on_thunk+0x1a/0x1c > [ 1721.377503] [] 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] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.384932] [] schedule+0x4a/0xc0 > [ 1721.385436] [] wait_current_trans+0xbd/0x130 > [btrfs] > [ 1721.386135] [] ? woken_wake_function+0x20/0x20 > [ 1721.386723] [] ? mutex_unlock+0xe/0x10 > [ 1721.387338] [] start_transaction+0x25a/0x4b0 > [btrfs] > [ 1721.388049] [] ? rwsem_down_read_failed+0xc5/0x120 > [ 1721.388675] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.389361] [] > btrfs_attach_transaction_barrier+0x27/0x60 [btrfs] > [ 1721.390257] [] btrfs_sync_fs+0x7a/0x250 [btrfs] > [ 1721.390875] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.391555] [] sync_fs_one_sb+0x20/0x30 > [ 1721.392115] [] iterate_supers+0xaf/0xe0 > [ 1721.392636] [] sys_sync+0x55/0x90 > [ 1721.393192] [] do_syscall_64+0x63/0x130 > [ 1721.393710] [] ? trace_hardirqs_on_thunk+0x1a/0x1c > [ 1721.394376] [] 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] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.401739] [] schedule+0x4a/0xc0 > [ 1721.402323] [] wait_current_trans+0xbd/0x130 > [btrfs] > [ 1721.402992] [] ? woken_wake_function+0x20/0x20 > [ 1721.403613] [] ? mutex_unlock+0xe/0x10 > [ 1721.404171] [] start_transaction+0x25a/0x4b0 > [btrfs] > [ 1721.404824] [] ? rwsem_down_read_failed+0xc5/0x120 > [ 1721.405548] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.406251] [] > btrfs_attach_transaction_barrier+0x27/0x60 [btrfs] > [ 1721.407094] [] btrfs_sync_fs+0x7a/0x250 [btrfs] > [ 1721.407694] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.408427] [] sync_fs_one_sb+0x20/0x30 > [ 1721.408954] [] iterate_supers+0xaf/0xe0 > [ 1721.409497] [] sys_sync+0x55/0x90 > [ 1721.409958] [] do_syscall_64+0x63/0x130 > [ 1721.410502] [] ? trace_hardirqs_on_thunk+0x1a/0x1c > [ 1721.411219] [] 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] [] schedule+0x4a/0xc0 > [ 1721.418614] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.419206] [] ? _raw_spin_unlock_irq+0x3b/0x60 > [ 1721.419813] [] rwsem_down_read_failed+0xc5/0x120 > [ 1721.420560] [] > call_rwsem_down_read_failed+0x18/0x30 > [ 1721.421261] [] down_read+0x42/0x60 > [ 1721.421763] [] ? > btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs] > [ 1721.422606] [] ? _raw_spin_unlock+0x35/0x60 > [ 1721.423212] [] > btrfs_alloc_data_chunk_ondemand+0x9f/0x560 [btrfs] > [ 1721.424070] [] ? > btrfs_truncate_free_space_cache+0x17d/0x1d0 [btrfs] > [ 1721.424901] [] > btrfs_check_data_free_space+0x48/0x70 [btrfs] > [ 1721.425779] [] cache_save_setup+0x239/0x390 [btrfs] > [ 1721.426486] [] ? free_extent_buffer+0x44/0xb0 > [btrfs] > [ 1721.427198] [] btrfs_setup_space_cache+0xc5/0xe0 > [btrfs] > [ 1721.427909] [] commit_cowonly_roots+0xfa/0x310 > [btrfs] > [ 1721.428706] [] ? commit_fs_roots+0x1b4/0x1d0 > [btrfs] > [ 1721.429426] [] ? > btrfs_qgroup_account_extents+0x4a/0x270 [btrfs] > [ 1721.430265] [] > btrfs_commit_transaction+0x5e8/0xd80 [btrfs] > [ 1721.431092] [] ? kmem_cache_alloc+0x1f4/0x320 > [ 1721.431685] [] ? start_transaction+0x12a/0x4b0 > [btrfs] > [ 1721.432391] [] ? mutex_unlock+0xe/0x10 > [ 1721.432911] [] ? start_transaction+0xab/0x4b0 > [btrfs] > [ 1721.433610] [] ? rwsem_down_read_failed+0xc5/0x120 > [ 1721.434349] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.434994] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.435667] [] btrfs_sync_fs+0x91/0x250 [btrfs] > [ 1721.436318] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.437040] [] sync_fs_one_sb+0x20/0x30 > [ 1721.437563] [] iterate_supers+0xaf/0xe0 > [ 1721.438115] [] sys_sync+0x55/0x90 > [ 1721.438570] [] do_syscall_64+0x63/0x130 > [ 1721.439123] [] ? trace_hardirqs_on_thunk+0x1a/0x1c > [ 1721.439758] [] 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] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.448208] [] schedule+0x4a/0xc0 > [ 1721.448674] [] wait_current_trans+0xbd/0x130 > [btrfs] > [ 1721.449349] [] ? woken_wake_function+0x20/0x20 > [ 1721.449913] [] ? mutex_unlock+0xe/0x10 > [ 1721.450454] [] start_transaction+0x25a/0x4b0 > [btrfs] > [ 1721.451119] [] ? rwsem_down_read_failed+0xc5/0x120 > [ 1721.451727] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.452506] [] > btrfs_attach_transaction_barrier+0x27/0x60 [btrfs] > [ 1721.453556] [] btrfs_sync_fs+0x7a/0x250 [btrfs] > [ 1721.454227] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.454868] [] sync_fs_one_sb+0x20/0x30 > [ 1721.455431] [] iterate_supers+0xaf/0xe0 > [ 1721.455959] [] sys_sync+0x55/0x90 > [ 1721.456450] [] do_syscall_64+0x63/0x130 > [ 1721.456982] [] ? trace_hardirqs_on_thunk+0x1a/0x1c > [ 1721.457653] [] 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] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.465225] [] schedule+0x4a/0xc0 > [ 1721.465726] [] wait_current_trans+0xbd/0x130 > [btrfs] > [ 1721.466427] [] ? woken_wake_function+0x20/0x20 > [ 1721.467059] [] ? mutex_unlock+0xe/0x10 > [ 1721.467587] [] start_transaction+0x25a/0x4b0 > [btrfs] > [ 1721.468365] [] ? rwsem_down_read_failed+0xc5/0x120 > [ 1721.469042] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.469703] [] > btrfs_attach_transaction_barrier+0x27/0x60 [btrfs] > [ 1721.470552] [] btrfs_sync_fs+0x7a/0x250 [btrfs] > [ 1721.471280] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.471947] [] sync_fs_one_sb+0x20/0x30 > [ 1721.472502] [] iterate_supers+0xaf/0xe0 > [ 1721.473066] [] sys_sync+0x55/0x90 > [ 1721.473528] [] do_syscall_64+0x63/0x130 > [ 1721.474141] [] ? trace_hardirqs_on_thunk+0x1a/0x1c > [ 1721.474802] [] 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] [] ? trace_hardirqs_on+0xd/0x10 > [ 1721.482366] [] schedule+0x4a/0xc0 > [ 1721.482846] [] wait_current_trans+0xbd/0x130 > [btrfs] > [ 1721.483620] [] ? woken_wake_function+0x20/0x20 > [ 1721.484260] [] ? mutex_unlock+0xe/0x10 > [ 1721.484794] [] start_transaction+0x25a/0x4b0 > [btrfs] > [ 1721.485496] [] ? rwsem_down_read_failed+0xc5/0x120 > [ 1721.486232] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.486917] [] > btrfs_attach_transaction_barrier+0x27/0x60 [btrfs] > [ 1721.487769] [] btrfs_sync_fs+0x7a/0x250 [btrfs] > [ 1721.488427] [] ? iter_file_splice_write+0x400/0x400 > [ 1721.489177] [] sync_fs_one_sb+0x20/0x30 > [ 1721.489706] [] iterate_supers+0xaf/0xe0 > [ 1721.490268] [] sys_sync+0x55/0x90 > [ 1721.490733] [] do_syscall_64+0x63/0x130 > [ 1721.491289] [] ? trace_hardirqs_on_thunk+0x1a/0x1c > [ 1721.491935] [] 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 > >