openzfs / spl

A shim layer which adds the core interfaces required for OpenZFS.
https://zfsonlinux.org/
GNU General Public License v2.0
281 stars 179 forks source link

spl_kmem_cache uses 100% CPU; processes blocked in spl_kmem_cache_alloc #210

Closed akorn closed 11 years ago

akorn commented 11 years ago

Hi,

since I upgraded to 0.6.0.92 from 0.6.0.91, I see this in top:

  PID USER      PR  NI  VIRT  RES  SHR CODE DATA SWAP S P WCHAN      %MEM %CPU     TIME+ COMMAND                                                                      
 1577 root      39  19     0    0    0    0    0    0 R 0 -           0.0 25.6   1065:45 [spl_kmem_cache/]                                                            

(I have four cores and one of them is continually busy with spl_kmem_cache.)

Additionally, some operations take ridiculously long (if they finish at all).

I have some sysrq-w output:

09:08:30 kernel: SysRq : Show Blocked State
09:08:30 kernel:   task                        PC stack   pid father
09:08:30 kernel: kswapd0         D ffffffff81606a20     0   454      2 0x00000000
09:08:30 kernel:  ffff880211ae55a8 0000000000000046 ffff880211ae23c0 ffff880211ae5fd8
09:08:30 kernel:  0000000000012680 ffff880211ae5fd8 ffff8802130d6300 ffff880211ae23c0
09:08:30 kernel:  ffff880211ae5598 ffff880211ae4000 ffff880211ae23c0 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00fb603>] dbuf_find+0x8e/0xc6 [zfs]
09:08:30 kernel:  [<ffffffffa00fd0be>] __dbuf_hold_impl+0x3b/0x3ab [zfs]
09:08:30 kernel:  [<ffffffffa00fd4ab>] dbuf_hold_impl+0x7d/0x9f [zfs]
09:08:30 kernel:  [<ffffffffa00fd6e0>] dbuf_hold+0x1b/0x29 [zfs]
09:08:30 kernel:  [<ffffffffa0101f40>] dmu_buf_hold+0x6a/0xe9 [zfs]
09:08:30 kernel:  [<ffffffff810f2cbd>] ? __kmalloc+0xa1/0xb3
09:08:30 kernel:  [<ffffffffa0147ec8>] zap_idx_to_blk+0x7d/0x115 [zfs]
09:08:30 kernel:  [<ffffffffa01482cc>] zap_deref_leaf+0x45/0x6a [zfs]
09:08:30 kernel:  [<ffffffffa014c286>] ? zap_lockdir+0x2be/0x444 [zfs]
09:08:30 kernel:  [<ffffffffa0149319>] fzap_remove+0x29/0x76 [zfs]
09:08:30 kernel:  [<ffffffffa014bbd9>] ? zap_name_alloc+0xb8/0xcc [zfs]
09:08:30 kernel:  [<ffffffffa014ca28>] zap_remove_norm+0x75/0xf0 [zfs]
09:08:30 kernel:  [<ffffffffa014cab1>] zap_remove+0xe/0x10 [zfs]
09:08:30 kernel:  [<ffffffffa0149905>] zap_remove_int+0x4f/0x6e [zfs]
09:08:30 kernel:  [<ffffffffa0153c97>] zfs_rmnode+0x213/0x291 [zfs]
09:08:30 kernel:  [<ffffffffa016d9a8>] zfs_zinactive+0x90/0xc2 [zfs]
09:08:30 kernel:  [<ffffffffa0167aec>] zfs_inactive+0x109/0x13b [zfs]
09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffffa0178941>] zpl_evict_inode+0x20/0x24 [zfs]
09:08:30 kernel:  [<ffffffffa0178921>] ? zpl_put_super+0xb/0xb [zfs]
09:08:30 kernel:  [<ffffffff811143f0>] evict+0xa8/0x15d
09:08:30 kernel:  [<ffffffff81114a06>] iput+0x124/0x12d
09:08:30 kernel:  [<ffffffffa016b07d>] zfs_inode_destroy+0xc6/0xe6 [zfs]
09:08:30 kernel:  [<ffffffffa0178959>] zpl_inode_destroy+0x9/0xb [zfs]
09:08:30 kernel:  [<ffffffff811139fe>] destroy_inode+0x36/0x4f
09:08:30 kernel:  [<ffffffff8111449c>] evict+0x154/0x15d
09:08:30 kernel:  [<ffffffff811144d1>] dispose_list+0x2c/0x38
09:08:30 kernel:  [<ffffffff81115138>] prune_icache_sb+0x2a4/0x2bf
09:08:30 kernel:  [<ffffffff811025e8>] prune_super+0xcc/0x13c
09:08:30 kernel:  [<ffffffff810c96e8>] shrink_slab+0x187/0x28a
09:08:30 kernel:  [<ffffffff810cb7f1>] balance_pgdat+0x300/0x5a4
09:08:30 kernel:  [<ffffffff810cbd64>] kswapd+0x2cf/0x329
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffff810cba95>] ? balance_pgdat+0x5a4/0x5a4
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: khugepaged      D ffffffff81606a20     0   456      2 0x00000000
09:08:30 kernel:  ffff880211aef0a8 0000000000000046 ffff880211aec440 ffff880211aeffd8
09:08:30 kernel:  0000000000012680 ffff880211aeffd8 ffff8802130da340 ffff880211aec440
09:08:30 kernel:  ffffffffa00598da 00000001004ee300 ffff880212c65000 ffff880210bebcd0
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffffa00598da>] ? spl_kmem_cache_destroy+0x2de/0x2de [spl]
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
09:08:30 kernel:  [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
09:08:30 kernel:  [<ffffffffa00588ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffffa017303b>] zio_create+0x43/0x32c [zfs]
09:08:30 kernel:  [<ffffffffa01739f6>] zio_vdev_child_io+0xa8/0xaf [zfs]
09:08:30 kernel:  [<ffffffffa014498c>] ? vdev_config_sync+0x12f/0x12f [zfs]
09:08:30 kernel:  [<ffffffffa0144fee>] vdev_mirror_io_start+0x2fd/0x322 [zfs]
09:08:30 kernel:  [<ffffffffa014498c>] ? vdev_config_sync+0x12f/0x12f [zfs]
09:08:30 kernel:  [<ffffffffa013985b>] ? spa_config_enter+0xbf/0xd9 [zfs]
09:08:30 kernel:  [<ffffffffa0174d2b>] zio_vdev_io_start+0x4b/0x21e [zfs]
09:08:30 kernel:  [<ffffffffa0173ce5>] zio_nowait+0xf3/0x11a [zfs]
09:08:30 kernel:  [<ffffffffa00f853a>] arc_read_nolock+0x667/0x678 [zfs]
09:08:30 kernel:  [<ffffffffa00f85e3>] arc_read+0x98/0xfe [zfs]
09:08:30 kernel:  [<ffffffffa00fe849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
09:08:30 kernel:  [<ffffffffa0123276>] dsl_read+0x2c/0x2e [zfs]
09:08:30 kernel:  [<ffffffffa00fc062>] dbuf_read+0x392/0x4c7 [zfs]
09:08:30 kernel:  [<ffffffffa010b102>] dmu_tx_check_ioerr+0x78/0x98 [zfs]
09:08:30 kernel:  [<ffffffffa010b8f8>] dmu_tx_hold_free+0x14b/0x5f8 [zfs]
09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffffa0101c37>] dmu_free_long_range_impl+0x157/0x1f8 [zfs]
09:08:30 kernel:  [<ffffffffa0103004>] dmu_free_long_range+0x3f/0x60 [zfs]
09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffffa0153af4>] zfs_rmnode+0x70/0x291 [zfs]
09:08:30 kernel:  [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
09:08:30 kernel:  [<ffffffffa016d9a8>] zfs_zinactive+0x90/0xc2 [zfs]
09:08:30 kernel:  [<ffffffffa0167aec>] zfs_inactive+0x109/0x13b [zfs]
09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffffa0178941>] zpl_evict_inode+0x20/0x24 [zfs]
09:08:30 kernel:  [<ffffffffa0178921>] ? zpl_put_super+0xb/0xb [zfs]
09:08:30 kernel:  [<ffffffff811143f0>] evict+0xa8/0x15d
09:08:30 kernel:  [<ffffffff81114a06>] iput+0x124/0x12d
09:08:30 kernel:  [<ffffffffa016b07d>] zfs_inode_destroy+0xc6/0xe6 [zfs]
09:08:30 kernel:  [<ffffffffa0178959>] zpl_inode_destroy+0x9/0xb [zfs]
09:08:30 kernel:  [<ffffffff811139fe>] destroy_inode+0x36/0x4f
09:08:30 kernel:  [<ffffffff8111449c>] evict+0x154/0x15d
09:08:30 kernel:  [<ffffffff811144d1>] dispose_list+0x2c/0x38
09:08:30 kernel:  [<ffffffff81115138>] prune_icache_sb+0x2a4/0x2bf
09:08:30 kernel:  [<ffffffff811025e8>] prune_super+0xcc/0x13c
09:08:30 kernel:  [<ffffffff810c96e8>] shrink_slab+0x187/0x28a
09:08:30 kernel:  [<ffffffff810cc01e>] do_try_to_free_pages+0x260/0x3d3
09:08:30 kernel:  [<ffffffff810cc365>] try_to_free_pages+0xc8/0x124
09:08:30 kernel:  [<ffffffff810c2822>] __alloc_pages_nodemask+0x435/0x6ff
09:08:30 kernel:  [<ffffffff810f5970>] khugepaged+0xc1/0xed4
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffff810f58af>] ? defrag_show+0x3e/0x3e
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: arc_adapt       D ffffffff81606a20     0  1582      2 0x00000000
09:08:30 kernel:  ffff88020df3fcd8 0000000000000046 ffff8802107da380 ffff88020df3ffd8
09:08:30 kernel:  0000000000012680 ffff88020df3ffd8 ffff8802130da340 ffff8802107da380
09:08:30 kernel:  ffff88020df3fcd8 ffff88020df3e000 ffff8802107da380 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00605ca>] __cv_timedwait_common+0x134/0x1dd [spl]
09:08:30 kernel:  [<ffffffffa005951c>] ? spl_kmem_cache_free+0x2ba/0x32e [spl]
09:08:30 kernel:  [<ffffffffa00fc723>] ? dbuf_destroy+0x121/0x129 [zfs]
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffffa00f7a47>] ? arc_adjust_meta+0x177/0x177 [zfs]
09:08:30 kernel:  [<ffffffffa005a316>] ? __thread_create+0x2d6/0x2d6 [spl]
09:08:30 kernel:  [<ffffffffa0060681>] __cv_timedwait_interruptible+0xe/0x10 [spl]
09:08:30 kernel:  [<ffffffffa00f7acd>] arc_adapt_thread+0x86/0xb5 [zfs]
09:08:30 kernel:  [<ffffffffa005a382>] thread_generic_wrapper+0x6c/0x79 [spl]
09:08:30 kernel:  [<ffffffffa005a316>] ? __thread_create+0x2d6/0x2d6 [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: l2arc_feed      D ffffffff81606a20     0  1583      2 0x00000000
09:08:30 kernel:  ffff88020df41b68 0000000000000046 ffff880211a7a1c0 ffff88020df41fd8
09:08:30 kernel:  0000000000012680 ffff88020df41fd8 ffff8802130d6300 ffff880211a7a1c0
09:08:30 kernel:  ffffffffa00598da 00000001004ee342 ffff880212c65800 ffff880210bebcd0
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffffa00598da>] ? spl_kmem_cache_destroy+0x2de/0x2de [spl]
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
09:08:30 kernel:  [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
09:08:30 kernel:  [<ffffffffa00588ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
09:08:30 kernel:  [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffffa017303b>] zio_create+0x43/0x32c [zfs]
09:08:30 kernel:  [<ffffffffa01738fc>] zio_write_phys+0x75/0xc7 [zfs]
09:08:30 kernel:  [<ffffffffa00f5da8>] l2arc_feed_thread+0x4d3/0x671 [zfs]
09:08:30 kernel:  [<ffffffffa00f58d5>] ? l2arc_evict+0x217/0x217 [zfs]
09:08:30 kernel:  [<ffffffffa005a316>] ? __thread_create+0x2d6/0x2d6 [spl]
09:08:30 kernel:  [<ffffffffa005a382>] thread_generic_wrapper+0x6c/0x79 [spl]
09:08:30 kernel:  [<ffffffffa005a316>] ? __thread_create+0x2d6/0x2d6 [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_iss/0      D ffff88020edc4000     0  4708      2 0x00000000
09:08:30 kernel:  ffff880203a21ac8 0000000000000046 ffff8802038c0340 ffff880203a21fd8
09:08:30 kernel:  0000000000012680 ffff880203a21fd8 ffff880210598400 ffff8802038c0340
09:08:30 kernel:  ffffffffa00598da 00000001004ee2f0 ffff88020edc4000 ffff880210bebcd0
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffffa00598da>] ? spl_kmem_cache_destroy+0x2de/0x2de [spl]
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
09:08:30 kernel:  [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
09:08:30 kernel:  [<ffffffffa00588ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffffa017303b>] zio_create+0x43/0x32c [zfs]
09:08:30 kernel:  [<ffffffffa01739f6>] zio_vdev_child_io+0xa8/0xaf [zfs]
09:08:30 kernel:  [<ffffffffa014498c>] ? vdev_config_sync+0x12f/0x12f [zfs]
09:08:30 kernel:  [<ffffffffa0144fee>] vdev_mirror_io_start+0x2fd/0x322 [zfs]
09:08:30 kernel:  [<ffffffffa014498c>] ? vdev_config_sync+0x12f/0x12f [zfs]
09:08:30 kernel:  [<ffffffffa013985b>] ? spa_config_enter+0xbf/0xd9 [zfs]
09:08:30 kernel:  [<ffffffffa0174d2b>] zio_vdev_io_start+0x4b/0x21e [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_iss/1      D ffffffff81606a20     0  4709      2 0x00000000
09:08:30 kernel:  ffff880203a31ac8 0000000000000046 ffff88020390c2c0 ffff880203a31fd8
09:08:30 kernel:  0000000000012680 ffff880203a31fd8 ffff8802130d6300 ffff88020390c2c0
09:08:30 kernel:  ffff880210bebc60 00000001004ee346 ffff880212c65800 ffff880210bebcd0
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
09:08:30 kernel:  [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
09:08:30 kernel:  [<ffffffffa00588ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffffa017303b>] zio_create+0x43/0x32c [zfs]
09:08:30 kernel:  [<ffffffffa01739f6>] zio_vdev_child_io+0xa8/0xaf [zfs]
09:08:30 kernel:  [<ffffffffa014498c>] ? vdev_config_sync+0x12f/0x12f [zfs]
09:08:30 kernel:  [<ffffffffa0144fee>] vdev_mirror_io_start+0x2fd/0x322 [zfs]
09:08:30 kernel:  [<ffffffffa014498c>] ? vdev_config_sync+0x12f/0x12f [zfs]
09:08:30 kernel:  [<ffffffffa013985b>] ? spa_config_enter+0xbf/0xd9 [zfs]
09:08:30 kernel:  [<ffffffffa0174d2b>] zio_vdev_io_start+0x4b/0x21e [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff810548f0>] ? finish_task_switch+0x45/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_iss/2      D ffffffff81606a20     0  4710      2 0x00000000
09:08:30 kernel:  ffff880203a41978 0000000000000046 ffff8802038a2280 ffff880203a41fd8
09:08:30 kernel:  0000000000012680 ffff880203a41fd8 ffff8802130da340 ffff8802038a2280
09:08:30 kernel:  ffffffffa00598da 00000001004ee342 ffff880212c65000 ffff880210bebcd0
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffffa00598da>] ? spl_kmem_cache_destroy+0x2de/0x2de [spl]
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
09:08:30 kernel:  [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
09:08:30 kernel:  [<ffffffffa00588ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
09:08:30 kernel:  [<ffffffff810f2b5a>] ? ____cache_alloc+0x531/0x544
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffffa017303b>] zio_create+0x43/0x32c [zfs]
09:08:30 kernel:  [<ffffffffa01739f6>] zio_vdev_child_io+0xa8/0xaf [zfs]
09:08:30 kernel:  [<ffffffffa0145d6f>] ? vdev_raidz_asize+0x52/0x52 [zfs]
09:08:30 kernel:  [<ffffffffa0147392>] vdev_raidz_io_start+0x365/0x533 [zfs]
09:08:30 kernel:  [<ffffffffa0145d6f>] ? vdev_raidz_asize+0x52/0x52 [zfs]
09:08:30 kernel:  [<ffffffffa0174eec>] zio_vdev_io_start+0x20c/0x21e [zfs]
09:08:30 kernel:  [<ffffffffa0173ce5>] zio_nowait+0xf3/0x11a [zfs]
09:08:30 kernel:  [<ffffffffa0144ff6>] vdev_mirror_io_start+0x305/0x322 [zfs]
09:08:30 kernel:  [<ffffffffa014498c>] ? vdev_config_sync+0x12f/0x12f [zfs]
09:08:30 kernel:  [<ffffffffa013985b>] ? spa_config_enter+0xbf/0xd9 [zfs]
09:08:30 kernel:  [<ffffffffa0174d2b>] zio_vdev_io_start+0x4b/0x21e [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_iss/3      D ffff880212c64800     0  4711      2 0x00000000
09:08:30 kernel:  ffff880203a9b978 0000000000000046 ffff880207224180 ffff880203a9bfd8
09:08:30 kernel:  0000000000012680 ffff880203a9bfd8 ffff880195698740 ffff880207224180
09:08:30 kernel:  0000000000000020 00000001004ee323 ffff880212c64800 ffff880210bebcd0
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
09:08:30 kernel:  [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
09:08:30 kernel:  [<ffffffffa00588ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
09:08:30 kernel:  [<ffffffff8125b358>] ? submit_bio+0x115/0x147
09:08:30 kernel:  [<ffffffff8112aab5>] ? bio_alloc_bioset+0x7c/0xea
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffffa017303b>] zio_create+0x43/0x32c [zfs]
09:08:30 kernel:  [<ffffffffa01739f6>] zio_vdev_child_io+0xa8/0xaf [zfs]
09:08:30 kernel:  [<ffffffffa0147432>] vdev_raidz_io_start+0x405/0x533 [zfs]
09:08:30 kernel:  [<ffffffffa0174eec>] zio_vdev_io_start+0x20c/0x21e [zfs]
09:08:30 kernel:  [<ffffffffa0173ce5>] zio_nowait+0xf3/0x11a [zfs]
09:08:30 kernel:  [<ffffffffa0144ff6>] vdev_mirror_io_start+0x305/0x322 [zfs]
09:08:30 kernel:  [<ffffffffa014498c>] ? vdev_config_sync+0x12f/0x12f [zfs]
09:08:30 kernel:  [<ffffffffa013985b>] ? spa_config_enter+0xbf/0xd9 [zfs]
09:08:30 kernel:  [<ffffffffa0174d2b>] zio_vdev_io_start+0x4b/0x21e [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/0      D ffff880208fd28c0     0  4717      2 0x00000000
09:08:30 kernel:  ffff8802062e9a48 0000000000000046 ffff880208fd28c0 ffff8802062e9fd8
09:08:30 kernel:  0000000000012680 ffff8802062e9fd8 ffff88020387e180 ffff880208fd28c0
09:08:30 kernel:  0000000000001400 ffff8802062e8000 ffff880208fd28c0 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fcb8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
09:08:30 kernel:  [<ffffffffa00fcdd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/1      D ffffffff81606a20     0  4718      2 0x00000000
09:08:30 kernel:  ffff8802038d5a68 0000000000000046 ffff88020b74c140 ffff8802038d5fd8
09:08:30 kernel:  0000000000012680 ffff8802038d5fd8 ffff8802130d6300 ffff88020b74c140
09:08:30 kernel:  ffff8802038d5a88 ffff8802038d4000 ffff88020b74c140 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fccd6>] dbuf_write_done+0xc9/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/2      D ffffffff81606a20     0  4719      2 0x00000000
09:08:30 kernel:  ffff88020380ba68 0000000000000046 ffff880203a62140 ffff88020380bfd8
09:08:30 kernel:  0000000000012680 ffff88020380bfd8 ffff8802130da340 ffff880203a62140
09:08:30 kernel:  ffff88020380ba88 ffff88020380a000 ffff880203a62140 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fccd6>] dbuf_write_done+0xc9/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/3      D ffff8802061d2140     0  4720      2 0x00000000
09:08:30 kernel:  ffff880203a81a68 0000000000000046 ffff8802061d2140 ffff880203a81fd8
09:08:30 kernel:  0000000000012680 ffff880203a81fd8 ffff880207224180 ffff8802061d2140
09:08:30 kernel:  ffff880203a81a88 ffff880203a80000 ffff8802061d2140 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fccd6>] dbuf_write_done+0xc9/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/4      D ffff88020387e180     0  4721      2 0x00000000
09:08:30 kernel:  ffff88020628ba68 0000000000000046 ffff88020387e180 ffff88020628bfd8
09:08:30 kernel:  0000000000012680 ffff88020628bfd8 ffff880210598400 ffff88020387e180
09:08:30 kernel:  ffff88020628ba88 ffff88020628a000 ffff88020387e180 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fccd6>] dbuf_write_done+0xc9/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/5      D ffff880206238740     0  4722      2 0x00000000
09:08:30 kernel:  ffff880205cffa48 0000000000000046 ffff880206238740 ffff880205cfffd8
09:08:30 kernel:  0000000000012680 ffff880205cfffd8 ffff88020390c2c0 ffff880206238740
09:08:30 kernel:  0000000000001200 ffff880205cfe000 ffff880206238740 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fcb8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
09:08:30 kernel:  [<ffffffffa00fcdd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/6      D ffff880203a4e8c0     0  4723      2 0x00000000
09:08:30 kernel:  ffff880203887a68 0000000000000046 ffff880203a4e8c0 ffff880203887fd8
09:08:30 kernel:  0000000000012680 ffff880203887fd8 ffff8802038a2280 ffff880203a4e8c0
09:08:30 kernel:  ffff880203887a88 ffff880203886000 ffff880203a4e8c0 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fccd6>] dbuf_write_done+0xc9/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/7      D ffffffff81606a20     0  4724      2 0x00000000
09:08:30 kernel:  ffff880203897a48 0000000000000046 ffff880203820200 ffff880203897fd8
09:08:30 kernel:  0000000000012680 ffff880203897fd8 ffff8802130de380 ffff880203820200
09:08:30 kernel:  0000000000001c00 ffff880203896000 ffff880203820200 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fcb8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
09:08:30 kernel:  [<ffffffffa00fcdd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/8      D ffff8802038f81c0     0  4725      2 0x00000000
09:08:30 kernel:  ffff880203871a68 0000000000000046 ffff8802038f81c0 ffff880203871fd8
09:08:30 kernel:  0000000000012680 ffff880203871fd8 ffff88020387e180 ffff8802038f81c0
09:08:30 kernel:  ffff880203871a88 ffff880203870000 ffff8802038f81c0 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fccd6>] dbuf_write_done+0xc9/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/10     D ffffffff81606a20     0  4727      2 0x00000000
09:08:30 kernel:  ffff880204b399f8 0000000000000046 ffff880203a12540 ffff880204b39fd8
09:08:30 kernel:  0000000000012680 ffff880204b39fd8 ffff8802130da340 ffff880203a12540
09:08:30 kernel:  ffff880204b39a18 ffff880204b38000 ffff880203a12540 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fccd6>] dbuf_write_done+0xc9/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/11     D ffffffff81606a20     0  4728      2 0x00000000
09:08:30 kernel:  ffff880204beba48 0000000000000046 ffff880208010700 ffff880204bebfd8
09:08:30 kernel:  0000000000012680 ffff880204bebfd8 ffff8802130de380 ffff880208010700
09:08:30 kernel:  0000000000001400 ffff880204bea000 ffff880208010700 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fcb8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
09:08:30 kernel:  [<ffffffffa00fcdd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/12     D ffff880208e1e080     0  4729      2 0x00000000
09:08:30 kernel:  ffff8802038019f8 0000000000000046 ffff880208e1e080 ffff880203801fd8
09:08:30 kernel:  0000000000012680 ffff880203801fd8 ffff8802038f81c0 ffff880208e1e080
09:08:30 kernel:  ffff880203801a18 ffff880203800000 ffff880208e1e080 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fccd6>] dbuf_write_done+0xc9/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/13     D ffff880203a92440     0  4730      2 0x00000000
09:08:30 kernel:  ffff880203ffda48 0000000000000046 ffff880203a92440 ffff880203ffdfd8
09:08:30 kernel:  0000000000012680 ffff880203ffdfd8 ffff880206238740 ffff880203a92440
09:08:30 kernel:  0000000000001400 ffff880203ffc000 ffff880203a92440 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fcb8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
09:08:30 kernel:  [<ffffffffa00fcdd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: z_wr_int/15     D ffff88020725c500     0  4732      2 0x00000000
09:08:30 kernel:  ffff8802055aba68 0000000000000046 ffff88020725c500 ffff8802055abfd8
09:08:30 kernel:  0000000000012680 ffff8802055abfd8 ffff880208010700 ffff88020725c500
09:08:30 kernel:  ffff8802055aba88 ffff8802055aa000 ffff88020725c500 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4d46>] remove_reference.isra.9+0x51/0x91 [zfs]
09:08:30 kernel:  [<ffffffffa00f77ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00fccd6>] dbuf_write_done+0xc9/0x1d9 [zfs]
09:08:30 kernel:  [<ffffffffa00f63c9>] arc_write_done+0x1ac/0x1c6 [zfs]
09:08:30 kernel:  [<ffffffffa017598f>] zio_done+0x77a/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa013914d>] ? spa_config_exit+0x6a/0x7d [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0175b3a>] zio_done+0x925/0x9ad [zfs]
09:08:30 kernel:  [<ffffffffa0171325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
09:08:30 kernel:  [<ffffffffa0171cd1>] zio_execute+0xd0/0xf7 [zfs]
09:08:30 kernel:  [<ffffffffa005b993>] taskq_thread+0x2a6/0x41f [spl]
09:08:30 kernel:  [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
09:08:30 kernel:  [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
09:08:30 kernel:  [<ffffffffa005b6ed>] ? task_done+0x11e/0x11e [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: txg_sync        D ffffffff81606a20     0  4759      2 0x00000000
09:08:30 kernel:  ffff88020355f588 0000000000000046 ffff88020623c780 ffff88020355ffd8
09:08:30 kernel:  0000000000012680 ffff88020355ffd8 ffff8802130d6300 ffff88020623c780
09:08:30 kernel:  ffffffffa00598da 00000001004ee300 ffff880212c65800 ffff880210bebcd0
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffffa00598da>] ? spl_kmem_cache_destroy+0x2de/0x2de [spl]
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
09:08:30 kernel:  [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
09:08:30 kernel:  [<ffffffffa00588ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffffa017303b>] zio_create+0x43/0x32c [zfs]
09:08:30 kernel:  [<ffffffffa01734fe>] zio_write+0x77/0x98 [zfs]
09:08:30 kernel:  [<ffffffffa00f621d>] ? arc_evict_ghost+0x2d7/0x2d7 [zfs]
09:08:30 kernel:  [<ffffffffa00f8c98>] arc_write+0xbc/0xcb [zfs]
09:08:30 kernel:  [<ffffffffa00f4685>] ? arc_cksum_compute+0x83/0x83 [zfs]
09:08:30 kernel:  [<ffffffffa00f621d>] ? arc_evict_ghost+0x2d7/0x2d7 [zfs]
09:08:30 kernel:  [<ffffffffa00fc431>] dbuf_write.isra.11+0x29a/0x2b0 [zfs]
09:08:30 kernel:  [<ffffffffa00fb66a>] ? spl_rw_clear_owner+0x2f/0x2f [zfs]
09:08:30 kernel:  [<ffffffffa00fcc0d>] ? dbuf_rele_and_unlock+0x1ba/0x1ba [zfs]
09:08:30 kernel:  [<ffffffffa00f8b5e>] ? arc_release_bp+0x9/0xd [zfs]
09:08:30 kernel:  [<ffffffffa00feb41>] dbuf_sync_leaf+0x223/0x272 [zfs]
09:08:30 kernel:  [<ffffffffa00febdc>] dbuf_sync_list+0x4c/0x61 [zfs]
09:08:30 kernel:  [<ffffffffa00feca5>] dbuf_sync_indirect+0xb4/0xcd [zfs]
09:08:30 kernel:  [<ffffffffa00febd5>] dbuf_sync_list+0x45/0x61 [zfs]
09:08:30 kernel:  [<ffffffffa00feca5>] dbuf_sync_indirect+0xb4/0xcd [zfs]
09:08:30 kernel:  [<ffffffffa00febd5>] dbuf_sync_list+0x45/0x61 [zfs]
09:08:30 kernel:  [<ffffffffa0110b2b>] dnode_sync+0x757/0x77c [zfs]
09:08:30 kernel:  [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
09:08:30 kernel:  [<ffffffffa0110b2b>] ? dnode_sync+0x757/0x77c [zfs]
09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffffa01068c2>] dmu_objset_sync_dnodes+0x9b/0xbc [zfs]
09:08:30 kernel:  [<ffffffffa0106b28>] dmu_objset_sync+0x245/0x2e3 [zfs]
09:08:30 kernel:  [<ffffffffa0104720>] ? secondary_cache_changed_cb+0xd/0xd [zfs]
09:08:30 kernel:  [<ffffffffa0104a0b>] ? dmu_objset_userused_enabled+0x44/0x44 [zfs]
09:08:30 kernel:  [<ffffffffa0111b60>] dsl_dataset_sync+0x40/0x47 [zfs]
09:08:30 kernel:  [<ffffffffa011e39e>] dsl_pool_sync+0xae/0x480 [zfs]
09:08:30 kernel:  [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
09:08:30 kernel:  [<ffffffffa0130994>] spa_sync+0x490/0x87d [zfs]
09:08:30 kernel:  [<ffffffff8106e6cf>] ? ktime_get_ts+0x47/0x87
09:08:30 kernel:  [<ffffffffa013cab4>] txg_sync_thread+0x278/0x49b [zfs]
09:08:30 kernel:  [<ffffffffa013c83c>] ? txg_do_callbacks+0x4d/0x4d [zfs]
09:08:30 kernel:  [<ffffffffa005a316>] ? __thread_create+0x2d6/0x2d6 [spl]
09:08:30 kernel:  [<ffffffffa005a382>] thread_generic_wrapper+0x6c/0x79 [spl]
09:08:30 kernel:  [<ffffffffa005a316>] ? __thread_create+0x2d6/0x2d6 [spl]
09:08:30 kernel:  [<ffffffff810497f4>] kthread+0x88/0x90
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel:  [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
09:08:30 kernel:  [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
09:08:30 kernel: rsync           D ffff880123650500     0 21927  21918 0x00000000
09:08:30 kernel:  ffff8801e714f398 0000000000000086 ffff880123650500 ffff8801e714ffd8
09:08:30 kernel:  0000000000012680 ffff8801e714ffd8 ffff8802038a2280 ffff880123650500
09:08:30 kernel:  ffff8801e714f398 ffff8801e714e000 ffff880123650500 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4a91>] arc_change_state.isra.7+0x127/0x209 [zfs]
09:08:30 kernel:  [<ffffffffa00f51e6>] ? arc_space_consume+0x63/0x66 [zfs]
09:08:30 kernel:  [<ffffffffa00f4c61>] arc_access.isra.8+0xee/0x182 [zfs]
09:08:30 kernel:  [<ffffffffa00f8247>] arc_read_nolock+0x374/0x678 [zfs]
09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffffa00f85e3>] arc_read+0x98/0xfe [zfs]
09:08:30 kernel:  [<ffffffffa0056972>] ? kmem_free_debug+0xca/0x114 [spl]
09:08:30 kernel:  [<ffffffffa0123276>] dsl_read+0x2c/0x2e [zfs]
09:08:30 kernel:  [<ffffffffa00fd8f3>] dbuf_prefetch+0x205/0x22f [zfs]
09:08:30 kernel:  [<ffffffffa010ca8a>] dmu_zfetch_dofetch.isra.4+0xda/0x119 [zfs]
09:08:30 kernel:  [<ffffffffa010d179>] dmu_zfetch+0x468/0x98f [zfs]
09:08:30 kernel:  [<ffffffffa00fbdb0>] dbuf_read+0xe0/0x4c7 [zfs]
09:08:30 kernel:  [<ffffffff8149259d>] ? _raw_spin_trylock+0x1d/0x30
09:08:30 kernel:  [<ffffffff8149259d>] ? _raw_spin_trylock+0x1d/0x30
09:08:30 kernel:  [<ffffffffa010da61>] ? spl_rwsem_is_locked+0x3c/0x4f [zfs]
09:08:30 kernel:  [<ffffffffa010e511>] dnode_hold_impl+0x147/0x364 [zfs]
09:08:30 kernel:  [<ffffffffa010e742>] dnode_hold+0x14/0x16 [zfs]
09:08:30 kernel:  [<ffffffffa01029d9>] dmu_bonus_hold+0x20/0x171 [zfs]
09:08:30 kernel:  [<ffffffffa012a092>] sa_buf_hold+0x9/0xb [zfs]
09:08:30 kernel:  [<ffffffffa016d3d5>] zfs_zget+0x5f/0x19f [zfs]
09:08:30 kernel:  [<ffffffffa012c5cd>] ? sa_lookup_impl+0xe/0x10 [zfs]
09:08:30 kernel:  [<ffffffffa012c657>] ? sa_lookup+0x41/0x4b [zfs]
09:08:30 kernel:  [<ffffffffa0152d78>] zfs_dirent_lock+0x3da/0x408 [zfs]
09:08:30 kernel:  [<ffffffffa0153fb4>] zfs_get_xattrdir+0x6e/0x165 [zfs]
09:08:30 kernel:  [<ffffffff81112202>] ? __d_lookup_rcu+0x9d/0xc4
09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
09:08:30 kernel:  [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
09:08:30 kernel:  [<ffffffffa016739c>] zfs_lookup+0x158/0x29e [zfs]
09:08:30 kernel:  [<ffffffffa0178aae>] __zpl_xattr_get+0x67/0x19f [zfs]
09:08:30 kernel:  [<ffffffff8127ab18>] ? kvasprintf+0x64/0x74
09:08:30 kernel:  [<ffffffffa017929c>] zpl_xattr_get+0x57/0x93 [zfs]
09:08:30 kernel:  [<ffffffffa01793a0>] zpl_xattr_user_get+0x5e/0x85 [zfs]
09:08:30 kernel:  [<ffffffff8111b242>] generic_getxattr+0x47/0x59
09:08:30 kernel:  [<ffffffff8111b61f>] vfs_getxattr+0x98/0xa6
09:08:30 kernel:  [<ffffffff8111b9d0>] getxattr+0xba/0x18f
09:08:30 kernel:  [<ffffffff81108ed9>] ? putname+0x25/0x27
09:08:30 kernel:  [<ffffffff8110b0ab>] ? user_path_at_empty+0x88/0xbb
09:08:30 kernel:  [<ffffffff8111c142>] sys_lgetxattr+0x42/0x5e
09:08:30 kernel:  [<ffffffff81493216>] system_call_fastpath+0x1a/0x1f
09:08:30 kernel: sort            D ffffffff81606a20     0 15417      1 0x00000004
09:08:30 kernel:  ffff88017e5613d8 0000000000000086 ffff88016a984800 ffff88017e561fd8
09:08:30 kernel:  0000000000012680 ffff88017e561fd8 ffff8802130d6300 ffff88016a984800
09:08:30 kernel:  ffff88017e561468 ffff88017e560000 ffff88016a984800 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00fb629>] ? dbuf_find+0xb4/0xc6 [zfs]
09:08:30 kernel:  [<ffffffffa00f75c7>] arc_buf_add_ref+0x5e/0xdb [zfs]
09:08:30 kernel:  [<ffffffffa00fd310>] __dbuf_hold_impl+0x28d/0x3ab [zfs]
09:08:30 kernel:  [<ffffffffa00fd4ab>] dbuf_hold_impl+0x7d/0x9f [zfs]
09:08:30 kernel:  [<ffffffffa00fd6e0>] dbuf_hold+0x1b/0x29 [zfs]
09:08:30 kernel:  [<ffffffffa0101f40>] dmu_buf_hold+0x6a/0xe9 [zfs]
09:08:30 kernel:  [<ffffffff810f2cbd>] ? __kmalloc+0xa1/0xb3
09:08:30 kernel:  [<ffffffffa0147ec8>] zap_idx_to_blk+0x7d/0x115 [zfs]
09:08:30 kernel:  [<ffffffffa01482cc>] zap_deref_leaf+0x45/0x6a [zfs]
09:08:30 kernel:  [<ffffffffa014c286>] ? zap_lockdir+0x2be/0x444 [zfs]
09:08:30 kernel:  [<ffffffffa0149319>] fzap_remove+0x29/0x76 [zfs]
09:08:30 kernel:  [<ffffffffa014bbd9>] ? zap_name_alloc+0xb8/0xcc [zfs]
09:08:30 kernel:  [<ffffffffa014ca28>] zap_remove_norm+0x75/0xf0 [zfs]
09:08:30 kernel:  [<ffffffffa014cab1>] zap_remove+0xe/0x10 [zfs]
09:08:30 kernel:  [<ffffffffa0149905>] zap_remove_int+0x4f/0x6e [zfs]
09:08:30 kernel:  [<ffffffffa0153c97>] zfs_rmnode+0x213/0x291 [zfs]
09:08:30 kernel:  [<ffffffffa016d9a8>] zfs_zinactive+0x90/0xc2 [zfs]
09:08:30 kernel:  [<ffffffffa0167aec>] zfs_inactive+0x109/0x13b [zfs]
09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffffa0178941>] zpl_evict_inode+0x20/0x24 [zfs]
09:08:30 kernel:  [<ffffffffa0178921>] ? zpl_put_super+0xb/0xb [zfs]
09:08:30 kernel:  [<ffffffff811143f0>] evict+0xa8/0x15d
09:08:30 kernel:  [<ffffffff81114a06>] iput+0x124/0x12d
09:08:30 kernel:  [<ffffffffa016b07d>] zfs_inode_destroy+0xc6/0xe6 [zfs]
09:08:30 kernel:  [<ffffffffa0178959>] zpl_inode_destroy+0x9/0xb [zfs]
09:08:30 kernel:  [<ffffffff811139fe>] destroy_inode+0x36/0x4f
09:08:30 kernel:  [<ffffffff8111449c>] evict+0x154/0x15d
09:08:30 kernel:  [<ffffffff811144d1>] dispose_list+0x2c/0x38
09:08:30 kernel:  [<ffffffff81115138>] prune_icache_sb+0x2a4/0x2bf
09:08:30 kernel:  [<ffffffff811025e8>] prune_super+0xcc/0x13c
09:08:30 kernel:  [<ffffffff810c96e8>] shrink_slab+0x187/0x28a
09:08:30 kernel:  [<ffffffff810cc01e>] do_try_to_free_pages+0x260/0x3d3
09:08:30 kernel:  [<ffffffff810cc365>] try_to_free_pages+0xc8/0x124
09:08:30 kernel:  [<ffffffff810c2822>] __alloc_pages_nodemask+0x435/0x6ff
09:08:30 kernel:  [<ffffffff810f3200>] ? enable_cpucache+0x65/0xa9
09:08:30 kernel:  [<ffffffff810f7a05>] do_huge_pmd_anonymous_page+0x9f/0x233
09:08:30 kernel:  [<ffffffff810db790>] handle_mm_fault+0x11b/0x1b5
09:08:30 kernel:  [<ffffffff81023a78>] __do_page_fault+0x328/0x372
09:08:30 kernel:  [<ffffffff810ffdde>] ? vfs_read+0x98/0xfa
09:08:30 kernel:  [<ffffffff81023aea>] do_page_fault+0x9/0xb
09:08:30 kernel:  [<ffffffff81492cf2>] page_fault+0x22/0x30
09:08:30 kernel: sort            D ffffffff81606a20     0 30639      1 0x00000004
09:08:30 kernel:  ffff8801ce0ff398 0000000000000086 ffff880101dde480 ffff8801ce0fffd8
09:08:30 kernel:  0000000000012680 ffff8801ce0fffd8 ffff8802130da340 ffff880101dde480
09:08:30 kernel:  0000000000000000 ffff8801ce0fe000 ffff880101dde480 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f4def>] add_reference.isra.10+0x69/0xdc [zfs]
09:08:30 kernel:  [<ffffffffa00f75da>] arc_buf_add_ref+0x71/0xdb [zfs]
09:08:30 kernel:  [<ffffffffa00fd310>] __dbuf_hold_impl+0x28d/0x3ab [zfs]
09:08:30 kernel:  [<ffffffffa00fd4ab>] dbuf_hold_impl+0x7d/0x9f [zfs]
09:08:30 kernel:  [<ffffffffa00fd6e0>] dbuf_hold+0x1b/0x29 [zfs]
09:08:30 kernel:  [<ffffffffa0101f40>] dmu_buf_hold+0x6a/0xe9 [zfs]
09:08:30 kernel:  [<ffffffffa014812a>] zap_get_leaf_byblk+0x40/0x19d [zfs]
09:08:30 kernel:  [<ffffffffa01482e6>] zap_deref_leaf+0x5f/0x6a [zfs]
09:08:30 kernel:  [<ffffffffa0149319>] fzap_remove+0x29/0x76 [zfs]
09:08:30 kernel:  [<ffffffffa014bbd9>] ? zap_name_alloc+0xb8/0xcc [zfs]
09:08:30 kernel:  [<ffffffffa014ca28>] zap_remove_norm+0x75/0xf0 [zfs]
09:08:30 kernel:  [<ffffffffa014cab1>] zap_remove+0xe/0x10 [zfs]
09:08:30 kernel:  [<ffffffffa0149905>] zap_remove_int+0x4f/0x6e [zfs]
09:08:30 kernel:  [<ffffffffa0153c97>] zfs_rmnode+0x213/0x291 [zfs]
09:08:30 kernel:  [<ffffffffa016d9a8>] zfs_zinactive+0x90/0xc2 [zfs]
09:08:30 kernel:  [<ffffffffa0167aec>] zfs_inactive+0x109/0x13b [zfs]
09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffffa0178941>] zpl_evict_inode+0x20/0x24 [zfs]
09:08:30 kernel:  [<ffffffffa0178921>] ? zpl_put_super+0xb/0xb [zfs]
09:08:30 kernel:  [<ffffffff811143f0>] evict+0xa8/0x15d
09:08:30 kernel:  [<ffffffff81114a06>] iput+0x124/0x12d
09:08:30 kernel:  [<ffffffffa016b07d>] zfs_inode_destroy+0xc6/0xe6 [zfs]
09:08:30 kernel:  [<ffffffffa0178959>] zpl_inode_destroy+0x9/0xb [zfs]
09:08:30 kernel:  [<ffffffff811139fe>] destroy_inode+0x36/0x4f
09:08:30 kernel:  [<ffffffff8111449c>] evict+0x154/0x15d
09:08:30 kernel:  [<ffffffff811144d1>] dispose_list+0x2c/0x38
09:08:30 kernel:  [<ffffffff81115138>] prune_icache_sb+0x2a4/0x2bf
09:08:30 kernel:  [<ffffffff811025e8>] prune_super+0xcc/0x13c
09:08:30 kernel:  [<ffffffff810c96e8>] shrink_slab+0x187/0x28a
09:08:30 kernel:  [<ffffffff810cc01e>] do_try_to_free_pages+0x260/0x3d3
09:08:30 kernel:  [<ffffffff810cc365>] try_to_free_pages+0xc8/0x124
09:08:30 kernel:  [<ffffffff810c2822>] __alloc_pages_nodemask+0x435/0x6ff
09:08:30 kernel:  [<ffffffff810f3200>] ? enable_cpucache+0x65/0xa9
09:08:30 kernel:  [<ffffffff810f7a05>] do_huge_pmd_anonymous_page+0x9f/0x233
09:08:30 kernel:  [<ffffffff810db790>] handle_mm_fault+0x11b/0x1b5
09:08:30 kernel:  [<ffffffff81023a78>] __do_page_fault+0x328/0x372
09:08:30 kernel:  [<ffffffff810ffdde>] ? vfs_read+0x98/0xfa
09:08:30 kernel:  [<ffffffff81023aea>] do_page_fault+0x9/0xb
09:08:30 kernel:  [<ffffffff81492cf2>] page_fault+0x22/0x30
09:08:30 kernel: apt-get         D ffffffff81606a20     0  5279   5270 0x00000000
09:08:30 kernel:  ffff8801386c1838 0000000000000082 ffff8801ea40c400 ffff8801386c1fd8
09:08:30 kernel:  0000000000012680 ffff8801386c1fd8 ffff8802130de380 ffff8801ea40c400
09:08:30 kernel:  ffff8801386c1838 ffff8801386c0000 ffff8801ea40c400 0000000000000002
09:08:30 kernel: Call Trace:
09:08:30 kernel:  [<ffffffff81491bf0>] schedule+0x60/0x62
09:08:30 kernel:  [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
09:08:30 kernel:  [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
09:08:30 kernel:  [<ffffffff810f9fc4>] ? mem_cgroup_update_lru_size+0x17/0x2a
09:08:30 kernel:  [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
09:08:30 kernel:  [<ffffffff81490b38>] mutex_lock+0x2c/0x40
09:08:30 kernel:  [<ffffffffa00f644d>] arc_evict+0x6a/0x477 [zfs]
09:08:30 kernel:  [<ffffffffa0057d4e>] ? spl_slab_reclaim+0x20b/0x33c [spl]
09:08:30 kernel:  [<ffffffffa0057d4e>] ? spl_slab_reclaim+0x20b/0x33c [spl]
09:08:30 kernel:  [<ffffffff81049ec9>] ? bit_waitqueue+0x16/0x69
09:08:30 kernel:  [<ffffffff81049fc8>] ? wake_up_bit+0x20/0x25
09:08:30 kernel:  [<ffffffffa00f68ef>] arc_adjust+0x95/0x174 [zfs]
09:08:30 kernel:  [<ffffffffa00f7cef>] arc_shrink+0xc1/0xc3 [zfs]
09:08:30 kernel:  [<ffffffffa00f7d08>] arc_kmem_reap_now+0x17/0x8c [zfs]
09:08:30 kernel:  [<ffffffffa00f7de7>] arc_shrinker_func+0x6a/0xe9 [zfs]
09:08:30 kernel:  [<ffffffff810c96e8>] shrink_slab+0x187/0x28a
09:08:30 kernel:  [<ffffffff810cc01e>] do_try_to_free_pages+0x260/0x3d3
09:08:30 kernel:  [<ffffffff810cc365>] try_to_free_pages+0xc8/0x124
09:08:30 kernel:  [<ffffffff810c2822>] __alloc_pages_nodemask+0x435/0x6ff
09:08:30 kernel:  [<ffffffff810c6000>] ? lru_cache_add_lru+0xa/0x3e
09:08:30 kernel:  [<ffffffff810f7a05>] do_huge_pmd_anonymous_page+0x9f/0x233
09:08:30 kernel:  [<ffffffff810db790>] handle_mm_fault+0x11b/0x1b5
09:08:30 kernel:  [<ffffffff81023a78>] __do_page_fault+0x328/0x372
09:08:30 kernel:  [<ffffffff810e0974>] ? do_mmap_pgoff+0x26c/0x2bb
09:08:30 kernel:  [<ffffffff810d1c96>] ? vm_mmap_pgoff+0x78/0x8b
09:08:30 kernel:  [<ffffffff81023aea>] do_page_fault+0x9/0xb
09:08:30 kernel:  [<ffffffff81492cf2>] page_fault+0x22/0x30
09:08:30 kernel: Sched Debug Version: v0.10, 3.7.1-vs2.3.5.3-caribou #3
09:08:30 kernel: 
behlendorf commented 11 years ago

@akorn Could you try resizing spl_kmem_cache taskq, this was one significant changed between 0.6.0.90 and 0.6.0.91. Basically, these allocations used to be handled by the kworker threads (1 per cpu) but were moved to a single taskq (1 thread by default). So it's possible this might be a bottleneck on a larger or busy system.

You should be able to make this change to the DKMS sources on your Ubuntu system and just have DKMS rebuild everything. It increases the numbers of threads to 1 per cpu and effectively removes the queue depth limit form the taskq.

diff --git a/module/spl/spl-kmem.c b/module/spl/spl-kmem.c
index cc5961e..1f8839f 100644
--- a/module/spl/spl-kmem.c
+++ b/module/spl/spl-kmem.c
@@ -2409,7 +2409,8 @@ spl_kmem_init(void)
        init_rwsem(&spl_kmem_cache_sem);
        INIT_LIST_HEAD(&spl_kmem_cache_list);
        spl_kmem_cache_taskq = taskq_create("spl_kmem_cache",
-           1, maxclsyspri, 1, 32, TASKQ_PREPOPULATE);
+           100, maxclsyspri, 1, INT_MAX,
+           TASKQ_PREPOPULATE | TASKQ_THREADS_CPU_PCT);

        spl_register_shrinker(&spl_kmem_cache_shrinker);

akorn commented 11 years ago

Thanks; I applied this change and so far things are looking better. I'll let you know how the system fares if left running for a few days. (It's a Debian sid system, not Ubuntu, btw. :)

akorn commented 11 years ago

Sorry, the patch didn't help after all:

top - 20:02:37 up 1 day,  7:13,  2 users,  load average: 40.95, 40.47, 40.21
Tasks: 422 total,   3 running, 419 sleeping,   0 stopped,   0 zombie
%Cpu0  :  0.0 us,  1.3 sy,  0.0 ni, 95.9 id,  0.0 wa,  0.0 hi,  2.8 si,  0.0 st
%Cpu1  :  0.0 us,100.0 sy,  0.0 ni,  0.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
%Cpu2  :  0.7 us,  1.0 sy,  0.0 ni, 98.4 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
%Cpu3  :  0.0 us,100.0 sy,  0.0 ni,  0.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   8151304 total,  7856408 used,   294896 free,        0 buffers
KiB Swap:  2097148 total,    25248 used,  2071900 free,     3376 cached

  PID USER      PR  NI  VIRT  RES  SHR CODE DATA SWAP S P WCHAN      %MEM %CPU     TIME+ COMMAND                                     
 2097 root      39  19     0    0    0    0    0    0 R 1 -           0.0 24.9 571:27.00 [spl_kmem_cache/]                           
 2103 root      39  19     0    0    0    0    0    0 R 3 -           0.0 24.9 582:57.84 [spl_kmem_cache/]                           

Sometimes a third spl_kmem_cache thread appears in top, with a CPU usage of ~7%, but it's gone quickly. However, sorting by CPU time I see:

  PID USER      PR  NI  VIRT  RES  SHR CODE DATA SWAP S P WCHAN      %MEM %CPU     TIME+ COMMAND                                     
 2098 root      39  19     0    0    0    0    0    0 S 2 taskq_thr+  0.0  0.0 584:27.36 [spl_kmem_cache/]                           
 2103 root      39  19     0    0    0    0    0    0 R 3 -           0.0 24.9 583:38.90 [spl_kmem_cache/]                           
 2089 root      39  19     0    0    0    0    0    0 R 0 -           0.0 24.9 582:15.01 [spl_kmem_cache/]                           
 2097 root      39  19     0    0    0    0    0    0 R 1 taskq_thr+  0.0  0.0 572:04.38 [spl_kmem_cache/]                           

So apparently the other two rthreads use comparable CPU time and are simply not spinning at this precise moment.

Some sysrq-w output:

SysRq : Show Blocked State
  task                        PC stack   pid father
kswapd0         D ffffffff81606a20     0   455      2 0x00000000
 ffff880211aef238 0000000000000046 ffff880211aec3c0 ffff880211aeffd8
 0000000000012680 ffff880211aeffd8 ffff8802130d6300 ffff880211aec3c0
 ffff880211aef228 ffff88021dc92680 ffff880211aec3c0 ffff88019b9e5c38
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491c4d>] io_schedule+0x5b/0x75
 [<ffffffffa01a1b65>] cv_wait_common+0xd1/0x16c [spl]
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa01a1c13>] __cv_wait_io+0x13/0x15 [spl]
 [<ffffffffa0480bbb>] zio_wait+0x114/0x14b [zfs]
 [<ffffffffa04090d7>] dbuf_read+0x407/0x4c7 [zfs]
 [<ffffffffa040ef7b>] dmu_buf_hold+0xa5/0xe9 [zfs]
 [<ffffffffa045512a>] zap_get_leaf_byblk+0x40/0x19d [zfs]
 [<ffffffffa04552e6>] zap_deref_leaf+0x5f/0x6a [zfs]
 [<ffffffffa0456028>] fzap_add_cd+0x48/0xf9 [zfs]
 [<ffffffffa0197e01>] ? kmem_alloc_debug+0x160/0x2cc [spl]
 [<ffffffffa0459286>] ? zap_lockdir+0x2be/0x444 [zfs]
 [<ffffffffa04588ff>] ? zap_hashbits+0x9/0x1a [zfs]
 [<ffffffffa0456112>] fzap_add+0x39/0x6e [zfs]
 [<ffffffffa0459e83>] zap_add+0xca/0x11e [zfs]
 [<ffffffff81277cf5>] ? snprintf+0x34/0x36
 [<ffffffffa0456897>] zap_add_int+0x68/0x87 [zfs]
 [<ffffffffa04391ec>] ? sa_bulk_update_impl+0x64/0xcd [zfs]
 [<ffffffffa0460003>] zfs_unlinked_add+0x38/0x8b [zfs]
 [<ffffffffa046087a>] zfs_link_destroy+0x3c5/0x3dd [zfs]
 [<ffffffffa0460a06>] zfs_purgedir+0x174/0x1f2 [zfs]
 [<ffffffff810f1f00>] ? __cache_free.isra.41+0x5/0xff
 [<ffffffff810f1feb>] ? __cache_free.isra.41+0xf0/0xff
 [<ffffffffa0197972>] ? kmem_free_debug+0xca/0x114 [spl]
 [<ffffffffa0197972>] ? kmem_free_debug+0xca/0x114 [spl]
 [<ffffffffa0448f0f>] ? txg_rele_to_sync+0x43/0x53 [zfs]
 [<ffffffff810c6804>] ? truncate_inode_pages_range+0x26/0x343
 [<ffffffffa0460ad1>] zfs_rmnode+0x4d/0x291 [zfs]
 [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
 [<ffffffffa047a9a8>] zfs_zinactive+0x90/0xc2 [zfs]
 [<ffffffffa0474aec>] zfs_inactive+0x109/0x13b [zfs]
 [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
 [<ffffffffa0485941>] zpl_evict_inode+0x20/0x24 [zfs]
 [<ffffffffa0485921>] ? zpl_put_super+0xb/0xb [zfs]
 [<ffffffff811143f0>] evict+0xa8/0x15d
 [<ffffffff811144d1>] dispose_list+0x2c/0x38
 [<ffffffff81115138>] prune_icache_sb+0x2a4/0x2bf
 [<ffffffff811025e8>] prune_super+0xcc/0x13c
 [<ffffffff810c96e8>] shrink_slab+0x187/0x28a
 [<ffffffff810cb7f1>] balance_pgdat+0x300/0x5a4
 [<ffffffff810cbd64>] kswapd+0x2cf/0x329
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffff810cba95>] ? balance_pgdat+0x5a4/0x5a4
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
khugepaged      D ffff880211af6440     0   457      2 0x00000000
 ffff880211af8df8 0000000000000046 ffff880211af6440 ffff880211af9fd8
 0000000000012680 ffff880211af9fd8 ffff880209a168c0 ffff880211af6440
 ffff88021dd926f0 ffff880211af8000 ffff880211af6440 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa01979c7>] ? strfree+0xb/0xb [spl]
 [<ffffffffa040344d>] arc_evict+0x6a/0x477 [zfs]
 [<ffffffff81490a62>] ? __wait_on_bit+0x60/0x7a
 [<ffffffff814927dc>] ? _raw_spin_lock+0x18/0x1a
 [<ffffffffa01978a6>] ? spin_lock+0x9/0xb [spl]
 [<ffffffffa0199c78>] ? spl_kmem_cache_alloc+0xabd/0xb36 [spl]
 [<ffffffff81490ae9>] ? out_of_line_wait_on_bit+0x6d/0x78
 [<ffffffffa0403c7d>] arc_get_data_buf+0x2af/0x3f3 [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa040523f>] arc_read_nolock+0x36c/0x678 [zfs]
 [<ffffffffa04055e3>] arc_read+0x98/0xfe [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa0430276>] dsl_read+0x2c/0x2e [zfs]
 [<ffffffffa0409062>] dbuf_read+0x392/0x4c7 [zfs]
 [<ffffffffa040ef7b>] dmu_buf_hold+0xa5/0xe9 [zfs]
 [<ffffffffa0454ec8>] zap_idx_to_blk+0x7d/0x115 [zfs]
 [<ffffffffa04552cc>] zap_deref_leaf+0x45/0x6a [zfs]
 [<ffffffffa0456028>] fzap_add_cd+0x48/0xf9 [zfs]
 [<ffffffffa0197e01>] ? kmem_alloc_debug+0x160/0x2cc [spl]
 [<ffffffffa0459286>] ? zap_lockdir+0x2be/0x444 [zfs]
 [<ffffffffa04588ff>] ? zap_hashbits+0x9/0x1a [zfs]
 [<ffffffffa0456112>] fzap_add+0x39/0x6e [zfs]
 [<ffffffffa0459e83>] zap_add+0xca/0x11e [zfs]
 [<ffffffff81277cf5>] ? snprintf+0x34/0x36
 [<ffffffffa0456897>] zap_add_int+0x68/0x87 [zfs]
 [<ffffffffa04391ec>] ? sa_bulk_update_impl+0x64/0xcd [zfs]
 [<ffffffffa0460003>] zfs_unlinked_add+0x38/0x8b [zfs]
 [<ffffffffa046087a>] zfs_link_destroy+0x3c5/0x3dd [zfs]
 [<ffffffffa0460a06>] zfs_purgedir+0x174/0x1f2 [zfs]
 [<ffffffff810f1f00>] ? __cache_free.isra.41+0x5/0xff
 [<ffffffff810f1feb>] ? __cache_free.isra.41+0xf0/0xff
 [<ffffffffa0197972>] ? kmem_free_debug+0xca/0x114 [spl]
 [<ffffffffa0197972>] ? kmem_free_debug+0xca/0x114 [spl]
 [<ffffffffa0448f0f>] ? txg_rele_to_sync+0x43/0x53 [zfs]
 [<ffffffff810c6804>] ? truncate_inode_pages_range+0x26/0x343
 [<ffffffffa0460ad1>] zfs_rmnode+0x4d/0x291 [zfs]
 [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
 [<ffffffffa047a9a8>] zfs_zinactive+0x90/0xc2 [zfs]
 [<ffffffffa0474aec>] zfs_inactive+0x109/0x13b [zfs]
 [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
 [<ffffffffa0485941>] zpl_evict_inode+0x20/0x24 [zfs]
 [<ffffffffa0485921>] ? zpl_put_super+0xb/0xb [zfs]
 [<ffffffff811143f0>] evict+0xa8/0x15d
 [<ffffffff811144d1>] dispose_list+0x2c/0x38
 [<ffffffff81115138>] prune_icache_sb+0x2a4/0x2bf
 [<ffffffff811025e8>] prune_super+0xcc/0x13c
 [<ffffffff810c96e8>] shrink_slab+0x187/0x28a
 [<ffffffff810cc01e>] do_try_to_free_pages+0x260/0x3d3
 [<ffffffff810cc365>] try_to_free_pages+0xc8/0x124
 [<ffffffff810c2822>] __alloc_pages_nodemask+0x435/0x6ff
 [<ffffffff810f5970>] khugepaged+0xc1/0xed4
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffff810f58af>] ? defrag_show+0x3e/0x3e
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
arc_adapt       D ffff8802119e8080     0  2179      2 0x00000000
 ffff88020ab8fc88 0000000000000046 ffff8802119e8080 ffff88020ab8ffd8
 0000000000012680 ffff88020ab8ffd8 ffff88020e926300 ffff8802119e8080
 ffff88020ab8ffd8 ffff88020ab8e000 ffff8802119e8080 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa040344d>] arc_evict+0x6a/0x477 [zfs]
 [<ffffffff8104a273>] ? finish_wait+0x5a/0x63
 [<ffffffffa019a51c>] ? spl_kmem_cache_free+0x2ba/0x32e [spl]
 [<ffffffffa0409723>] ? dbuf_destroy+0x121/0x129 [zfs]
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa0404a47>] ? arc_adjust_meta+0x177/0x177 [zfs]
 [<ffffffffa019b316>] ? __thread_create+0x2d6/0x2d6 [spl]
 [<ffffffffa04038ef>] arc_adjust+0x95/0x174 [zfs]
 [<ffffffffa0404a47>] ? arc_adjust_meta+0x177/0x177 [zfs]
 [<ffffffffa0404aa0>] arc_adapt_thread+0x59/0xb5 [zfs]
 [<ffffffffa019b382>] thread_generic_wrapper+0x6c/0x79 [spl]
 [<ffffffffa019b316>] ? __thread_create+0x2d6/0x2d6 [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
l2arc_feed      D ffff88021221d000     0  2180      2 0x00000000
 ffff88020ab91b68 0000000000000046 ffff8802123fa2c0 ffff88020ab91fd8
 0000000000012680 ffff88020ab91fd8 ffff8802030ec5c0 ffff8802123fa2c0
 ffffffffa019a8da 0000000100ab6a3a ffff88021221d000 ffff880210bcc0d0
Call Trace:
 [<ffffffffa019a8da>] ? spl_kmem_cache_destroy+0x2de/0x2de [spl]
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa01998ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
 [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa048003b>] zio_create+0x43/0x32c [zfs]
 [<ffffffffa04808fc>] zio_write_phys+0x75/0xc7 [zfs]
 [<ffffffffa0402da8>] l2arc_feed_thread+0x4d3/0x671 [zfs]
 [<ffffffffa04028d5>] ? l2arc_evict+0x217/0x217 [zfs]
 [<ffffffffa019b316>] ? __thread_create+0x2d6/0x2d6 [spl]
 [<ffffffffa019b382>] thread_generic_wrapper+0x6c/0x79 [spl]
 [<ffffffffa019b316>] ? __thread_create+0x2d6/0x2d6 [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_null_int/0    D ffff88021221d000     0  6194      2 0x00000000
 ffff8802003d7aa8 0000000000000046 ffff880210136300 ffff8802003d7fd8
 0000000000012680 ffff8802003d7fd8 ffff8800a8e9c580 ffff880210136300
 0000000000000246 0000000100ab6a50 ffff88021221d000 ffff880210bcc0d0
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa01998ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
 [<ffffffff81055bc3>] ? check_preempt_curr+0x3b/0x69
 [<ffffffff81055c3e>] ? ttwu_do_wakeup+0x4d/0xde
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa048003b>] zio_create+0x43/0x32c [zfs]
 [<ffffffffa0480a67>] zio_vdev_delegated_io+0x6a/0x6c [zfs]
 [<ffffffffa045229e>] ? vdev_queue_offset_compare+0x32/0x32 [zfs]
 [<ffffffffa04526a8>] vdev_queue_io_to_issue+0x322/0x4c1 [zfs]
 [<ffffffffa045229e>] ? vdev_queue_offset_compare+0x32/0x32 [zfs]
 [<ffffffffa0452b7b>] vdev_queue_io_done+0x4e/0xab [zfs]
 [<ffffffffa047e763>] zio_vdev_io_done+0x62/0x13e [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_rd_int/2      D ffff8802030d6800     0  6205      2 0x00000000
 ffff880200dbbae8 0000000000000046 ffff8802030d6800 ffff880200dbbfd8
 0000000000012680 ffff880200dbbfd8 ffff88020e926300 ffff8802030d6800
 ffff880200dbbb08 ffff880200dba000 ffff8802030d6800 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0197972>] ? kmem_free_debug+0xca/0x114 [spl]
 [<ffffffffa040142f>] buf_hash_find+0x47/0xaa [zfs]
 [<ffffffffa0403ea0>] arc_read_done+0x35/0x225 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_iss/0      D ffff88021221d000     0  6207      2 0x00000000
 ffff880200d9f978 0000000000000046 ffff8802030ec5c0 ffff880200d9ffd8
 0000000000012680 ffff880200d9ffd8 ffff88020efc0580 ffff8802030ec5c0
 ffff880210bcc060 0000000100ab6a3a ffff88021221d000 ffff880210bcc0d0
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa01998ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
 [<ffffffff8125b358>] ? submit_bio+0x115/0x147
 [<ffffffff8112aab5>] ? bio_alloc_bioset+0x7c/0xea
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa048003b>] zio_create+0x43/0x32c [zfs]
 [<ffffffffa04809f6>] zio_vdev_child_io+0xa8/0xaf [zfs]
 [<ffffffffa0452d6f>] ? vdev_raidz_asize+0x52/0x52 [zfs]
 [<ffffffffa0454392>] vdev_raidz_io_start+0x365/0x533 [zfs]
 [<ffffffffa0452d6f>] ? vdev_raidz_asize+0x52/0x52 [zfs]
 [<ffffffffa0481eec>] zio_vdev_io_start+0x20c/0x21e [zfs]
 [<ffffffffa0480ce5>] zio_nowait+0xf3/0x11a [zfs]
 [<ffffffffa0451ff6>] vdev_mirror_io_start+0x305/0x322 [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa044685b>] ? spa_config_enter+0xbf/0xd9 [zfs]
 [<ffffffffa0481d2b>] zio_vdev_io_start+0x4b/0x21e [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_iss/1      D ffff8802123ab800     0  6208      2 0x00000000
 ffff880200e499b8 0000000000000046 ffff880201838500 ffff880200e49fd8
 0000000000012680 ffff880200e49fd8 ffff880211f00340 ffff880201838500
 0000000000000246 0000000100ab6a24 ffff8802123ab800 ffff880210bcc0d0
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa01998ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
 [<ffffffff8125b358>] ? submit_bio+0x115/0x147
 [<ffffffff8112aab5>] ? bio_alloc_bioset+0x7c/0xea
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa048003b>] zio_create+0x43/0x32c [zfs]
 [<ffffffffa04809f6>] zio_vdev_child_io+0xa8/0xaf [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa0451fee>] vdev_mirror_io_start+0x2fd/0x322 [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa047e25f>] ? zio_free_bp_init+0x25/0x25 [zfs]
 [<ffffffffa047e87e>] ? zio_push_transform+0x3f/0x89 [zfs]
 [<ffffffffa0481eec>] zio_vdev_io_start+0x20c/0x21e [zfs]
 [<ffffffffa0480ce5>] zio_nowait+0xf3/0x11a [zfs]
 [<ffffffffa0451ff6>] vdev_mirror_io_start+0x305/0x322 [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa044685b>] ? spa_config_enter+0xbf/0xd9 [zfs]
 [<ffffffffa0481d2b>] zio_vdev_io_start+0x4b/0x21e [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff810548f0>] ? finish_task_switch+0x45/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_iss/2      D ffff880211d6e000     0  6209      2 0x00000000
 ffff880200e4b9b8 0000000000000046 ffff88020313a200 ffff880200e4bfd8
 0000000000012680 ffff880200e4bfd8 ffff88021162a4c0 ffff88020313a200
 0000000000000246 0000000100ab6a42 ffff880211d6e000 ffff880210bcc0d0
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa01998ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
 [<ffffffffa04523dd>] ? vdev_queue_io_to_issue+0x57/0x4c1 [zfs]
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa048003b>] zio_create+0x43/0x32c [zfs]
 [<ffffffffa04809f6>] zio_vdev_child_io+0xa8/0xaf [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa0451fee>] vdev_mirror_io_start+0x2fd/0x322 [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa047e25f>] ? zio_free_bp_init+0x25/0x25 [zfs]
 [<ffffffffa047e87e>] ? zio_push_transform+0x3f/0x89 [zfs]
 [<ffffffffa0481eec>] zio_vdev_io_start+0x20c/0x21e [zfs]
 [<ffffffffa0480ce5>] zio_nowait+0xf3/0x11a [zfs]
 [<ffffffffa0451ff6>] vdev_mirror_io_start+0x305/0x322 [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa044685b>] ? spa_config_enter+0xbf/0xd9 [zfs]
 [<ffffffffa0481d2b>] zio_vdev_io_start+0x4b/0x21e [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_iss/3      D ffff880211e25000     0  6210      2 0x00000000
 ffff8801ff95d9b8 0000000000000046 ffff880203146280 ffff8801ff95dfd8
 0000000000012680 ffff8801ff95dfd8 ffff88015a64e7c0 ffff880203146280
 0000000000000246 0000000100ab6a3a ffff880211e25000 ffff880210bcc0d0
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa01998ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
 [<ffffffff8125b358>] ? submit_bio+0x115/0x147
 [<ffffffff8112aab5>] ? bio_alloc_bioset+0x7c/0xea
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa048003b>] zio_create+0x43/0x32c [zfs]
 [<ffffffffa04809f6>] zio_vdev_child_io+0xa8/0xaf [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa0451fee>] vdev_mirror_io_start+0x2fd/0x322 [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa047e25f>] ? zio_free_bp_init+0x25/0x25 [zfs]
 [<ffffffffa047e87e>] ? zio_push_transform+0x3f/0x89 [zfs]
 [<ffffffffa0481eec>] zio_vdev_io_start+0x20c/0x21e [zfs]
 [<ffffffffa0480ce5>] zio_nowait+0xf3/0x11a [zfs]
 [<ffffffffa0451ff6>] vdev_mirror_io_start+0x305/0x322 [zfs]
 [<ffffffffa045198c>] ? vdev_config_sync+0x12f/0x12f [zfs]
 [<ffffffffa044685b>] ? spa_config_enter+0xbf/0xd9 [zfs]
 [<ffffffffa0481d2b>] zio_vdev_io_start+0x4b/0x21e [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/0      D ffffffff81606a20     0  6216      2 0x00000000
 ffff8801ff969a48 0000000000000046 ffff880203086180 ffff8801ff969fd8
 0000000000012680 ffff8801ff969fd8 ffffffff81a143f0 ffff880203086180
 0000000000000e00 ffff8801ff968000 ffff880203086180 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa0452ff4>] ? vdev_raidz_map_free+0x8d/0x95 [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/1      D ffff8801ff8c6140     0  6217      2 0x00000000
 ffff8801ff96ba48 0000000000000046 ffff8801ff8c6140 ffff8801ff96bfd8
 0000000000012680 ffff8801ff96bfd8 ffff880201d68540 ffff8801ff8c6140
 0000000000001400 ffff8801ff96a000 ffff8801ff8c6140 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/2      D ffff880201a365c0     0  6218      2 0x00000000
 ffff8801ff96d9d8 0000000000000046 ffff880201a365c0 ffff8801ff96dfd8
 0000000000012680 ffff8801ff96dfd8 ffff880209a168c0 ffff880201a365c0
 0000000000001200 ffff8801ff96c000 ffff880201a365c0 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa0452ff4>] ? vdev_raidz_map_free+0x8d/0x95 [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/3      D ffff880211f64100     0  6219      2 0x00000000
 ffff8801ff96fa48 0000000000000046 ffff880211f64100 ffff8801ff96ffd8
 0000000000012680 ffff8801ff96ffd8 ffff880213392140 ffff880211f64100
 0000000000001000 ffff8801ff96e000 ffff880211f64100 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/4      D ffffffff81606a20     0  6220      2 0x00000000
 ffff8801ff971a48 0000000000000046 ffff88020313e240 ffff8801ff971fd8
 0000000000012680 ffff8801ff971fd8 ffffffff81a143f0 ffff88020313e240
 0000000000001000 ffff8801ff970000 ffff88020313e240 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/5      D ffffffff81606a20     0  6221      2 0x00000000
 ffff8801ff973a48 0000000000000046 ffff8801ff8bc0c0 ffff8801ff973fd8
 0000000000012680 ffff8801ff973fd8 ffff8802130d6300 ffff8801ff8bc0c0
 0000000000001400 ffff8801ff972000 ffff8801ff8bc0c0 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa0452ff4>] ? vdev_raidz_map_free+0x8d/0x95 [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/6      D ffffffff81606a20     0  6222      2 0x00000000
 ffff8801ff975a48 0000000000000046 ffff880201d5a400 ffff8801ff975fd8
 0000000000012680 ffff8801ff975fd8 ffff8802130da340 ffff880201d5a400
 0000000000001400 ffff8801ff974000 ffff880201d5a400 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa0452ff4>] ? vdev_raidz_map_free+0x8d/0x95 [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/7      D ffff880200cfe700     0  6223      2 0x00000000
 ffff8801ff977a48 0000000000000046 ffff880200cfe700 ffff8801ff977fd8
 0000000000012680 ffff8801ff977fd8 ffff880213392140 ffff880200cfe700
 0000000000001200 ffff8801ff976000 ffff880200cfe700 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/8      D ffffffff81606a20     0  6224      2 0x00000000
 ffff8801ff979a48 0000000000000046 ffff8802109fe8c0 ffff8801ff979fd8
 0000000000012680 ffff8801ff979fd8 ffffffff81a143f0 ffff8802109fe8c0
 0000000000000e00 ffff8801ff978000 ffff8802109fe8c0 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa0452ff4>] ? vdev_raidz_map_free+0x8d/0x95 [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/9      D ffff880203ba48c0     0  6225      2 0x00000000
 ffff8801ff97ba48 0000000000000046 ffff880203ba48c0 ffff8801ff97bfd8
 0000000000012680 ffff8801ff97bfd8 ffff8801ff8c6140 ffff880203ba48c0
 0000000000001400 ffff8801ff97a000 ffff880203ba48c0 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa0452ff4>] ? vdev_raidz_map_free+0x8d/0x95 [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/10     D ffffffff81606a20     0  6226      2 0x00000000
 ffff8801ff97da48 0000000000000046 ffff880209a168c0 ffff8801ff97dfd8
 0000000000012680 ffff8801ff97dfd8 ffff8802130da340 ffff880209a168c0
 0000000000001200 ffff8801ff97c000 ffff880209a168c0 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/11     D ffff880213392140     0  6227      2 0x00000000
 ffff8801ff97fa48 0000000000000046 ffff880213392140 ffff8801ff97ffd8
 0000000000012680 ffff8801ff97ffd8 ffff880211f80740 ffff880213392140
 0000000000000e00 ffff8801ff97e000 ffff880213392140 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/12     D ffff8802030dc680     0  6228      2 0x00000000
 ffff8801ff981a48 0000000000000046 ffff8802030dc680 ffff8801ff981fd8
 0000000000012680 ffff8801ff981fd8 ffff88020313e240 ffff8802030dc680
 0000000000001400 ffff8801ff980000 ffff8802030dc680 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa0452ff4>] ? vdev_raidz_map_free+0x8d/0x95 [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/13     D ffffffff81606a20     0  6229      2 0x00000000
 ffff8801ff983a48 0000000000000046 ffff880201d68540 ffff8801ff983fd8
 0000000000012680 ffff8801ff983fd8 ffff8802130d6300 ffff880201d68540
 0000000000001200 ffff8801ff982000 ffff880201d68540 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa0452ff4>] ? vdev_raidz_map_free+0x8d/0x95 [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/14     D ffff8802100ac080     0  6230      2 0x00000000
 ffff8801ff985a48 0000000000000046 ffff8802100ac080 ffff8801ff985fd8
 0000000000012680 ffff8801ff985fd8 ffff880201a365c0 ffff8802100ac080
 0000000000001200 ffff8801ff984000 ffff8802100ac080 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa0452ff4>] ? vdev_raidz_map_free+0x8d/0x95 [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
z_wr_int/15     D ffff880203168400     0  6231      2 0x00000000
 ffff8801ff987a48 0000000000000046 ffff880203168400 ffff8801ff987fd8
 0000000000012680 ffff8801ff987fd8 ffff88020f414600 ffff880203168400
 0000000000001200 ffff8801ff986000 ffff880203168400 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa0401d46>] remove_reference.isra.9+0x51/0x91 [zfs]
 [<ffffffffa04047ad>] arc_buf_remove_ref+0x7f/0xc3 [zfs]
 [<ffffffffa0409b8f>] dbuf_rele_and_unlock+0x13c/0x1ba [zfs]
 [<ffffffffa0409dd7>] dbuf_write_done+0x1ca/0x1d9 [zfs]
 [<ffffffffa04033c9>] arc_write_done+0x1ac/0x1c6 [zfs]
 [<ffffffffa048298f>] zio_done+0x77a/0x9ad [zfs]
 [<ffffffffa044614d>] ? spa_config_exit+0x6a/0x7d [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa0482b3a>] zio_done+0x925/0x9ad [zfs]
 [<ffffffffa047e325>] ? zio_wait_for_children+0x4e/0x5a [zfs]
 [<ffffffffa047ecd1>] zio_execute+0xd0/0xf7 [zfs]
 [<ffffffffa019c993>] taskq_thread+0x2a6/0x41f [spl]
 [<ffffffff81054929>] ? finish_task_switch+0x7e/0xad
 [<ffffffff81057dda>] ? try_to_wake_up+0x1cf/0x1cf
 [<ffffffffa019c6ed>] ? task_done+0x11e/0x11e [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
txg_sync        D ffffffff81606a20     0  6259      2 0x00000000
 ffff8801fe423588 0000000000000046 ffff8801ff9ca5c0 ffff8801fe423fd8
 0000000000012680 ffff8801fe423fd8 ffff8802130de380 ffff8801ff9ca5c0
 ffffffffa019a8da 0000000100ab6a23 ffff880211e25000 ffff880210bcc0d0
Call Trace:
 [<ffffffffa019a8da>] ? spl_kmem_cache_destroy+0x2de/0x2de [spl]
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa01998ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa048003b>] zio_create+0x43/0x32c [zfs]
 [<ffffffffa04804fe>] zio_write+0x77/0x98 [zfs]
 [<ffffffffa040321d>] ? arc_evict_ghost+0x2d7/0x2d7 [zfs]
 [<ffffffffa0405c98>] arc_write+0xbc/0xcb [zfs]
 [<ffffffffa0401685>] ? arc_cksum_compute+0x83/0x83 [zfs]
 [<ffffffffa040321d>] ? arc_evict_ghost+0x2d7/0x2d7 [zfs]
 [<ffffffffa0409431>] dbuf_write.isra.11+0x29a/0x2b0 [zfs]
 [<ffffffffa040866a>] ? spl_rw_clear_owner+0x2f/0x2f [zfs]
 [<ffffffffa0409c0d>] ? dbuf_rele_and_unlock+0x1ba/0x1ba [zfs]
 [<ffffffffa0405b5e>] ? arc_release_bp+0x9/0xd [zfs]
 [<ffffffffa040bb41>] dbuf_sync_leaf+0x223/0x272 [zfs]
 [<ffffffffa040bbdc>] dbuf_sync_list+0x4c/0x61 [zfs]
 [<ffffffffa040bca5>] dbuf_sync_indirect+0xb4/0xcd [zfs]
 [<ffffffffa040bbd5>] dbuf_sync_list+0x45/0x61 [zfs]
 [<ffffffffa040bca5>] dbuf_sync_indirect+0xb4/0xcd [zfs]
 [<ffffffffa040bbd5>] dbuf_sync_list+0x45/0x61 [zfs]
 [<ffffffffa041db2b>] dnode_sync+0x757/0x77c [zfs]
 [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
 [<ffffffffa041db2b>] ? dnode_sync+0x757/0x77c [zfs]
 [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
 [<ffffffffa0401685>] ? arc_cksum_compute+0x83/0x83 [zfs]
 [<ffffffffa040321d>] ? arc_evict_ghost+0x2d7/0x2d7 [zfs]
 [<ffffffffa04138c2>] dmu_objset_sync_dnodes+0x9b/0xbc [zfs]
 [<ffffffffa0413b28>] dmu_objset_sync+0x245/0x2e3 [zfs]
 [<ffffffffa0411720>] ? secondary_cache_changed_cb+0xd/0xd [zfs]
 [<ffffffffa0411a0b>] ? dmu_objset_userused_enabled+0x44/0x44 [zfs]
 [<ffffffffa041eb60>] dsl_dataset_sync+0x40/0x47 [zfs]
 [<ffffffffa042b39e>] dsl_pool_sync+0xae/0x480 [zfs]
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa043d994>] spa_sync+0x490/0x87d [zfs]
 [<ffffffff8106e6cf>] ? ktime_get_ts+0x47/0x87
 [<ffffffffa0449ab4>] txg_sync_thread+0x278/0x49b [zfs]
 [<ffffffffa044983c>] ? txg_do_callbacks+0x4d/0x4d [zfs]
 [<ffffffffa019b316>] ? __thread_create+0x2d6/0x2d6 [spl]
 [<ffffffffa019b382>] thread_generic_wrapper+0x6c/0x79 [spl]
 [<ffffffffa019b316>] ? __thread_create+0x2d6/0x2d6 [spl]
 [<ffffffff810497f4>] kthread+0x88/0x90
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
 [<ffffffff8149316c>] ret_from_fork+0x7c/0xb0
 [<ffffffff8104976c>] ? __kthread_parkme+0x60/0x60
rsync           D ffff88021228a000     0  1855   1854 0x00000000
 ffff880107f83618 0000000000000082 ffff880173c22280 ffff880107f83fd8
 0000000000012680 ffff880107f83fd8 ffff8800a8e9c580 ffff880173c22280
 ffffffffa019a8da 0000000100ab6a88 ffff88021228a000 ffff880210bcc6d0
Call Trace:
 [<ffffffffa019a8da>] ? spl_kmem_cache_destroy+0x2de/0x2de [spl]
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa01998ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
 [<ffffffffa0403789>] ? arc_evict+0x3a6/0x477 [zfs]
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa047f8bb>] zio_data_buf_alloc+0x1d/0x1f [zfs]
 [<ffffffffa0403cb5>] arc_get_data_buf+0x2e7/0x3f3 [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa040523f>] arc_read_nolock+0x36c/0x678 [zfs]
 [<ffffffffa0199c78>] ? spl_kmem_cache_alloc+0xabd/0xb36 [spl]
 [<ffffffff81049fc8>] ? wake_up_bit+0x20/0x25
 [<ffffffffa04055e3>] arc_read+0x98/0xfe [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa0430276>] dsl_read+0x2c/0x2e [zfs]
 [<ffffffffa0409062>] dbuf_read+0x392/0x4c7 [zfs]
 [<ffffffffa040f204>] dmu_buf_hold_array_by_dnode+0x1f5/0x320 [zfs]
 [<ffffffffa040f387>] dmu_buf_hold_array+0x58/0x79 [zfs]
 [<ffffffffa0410651>] dmu_read_uio+0x41/0xc1 [zfs]
 [<ffffffffa0439657>] ? sa_lookup+0x41/0x4b [zfs]
 [<ffffffffa04747fb>] zfs_read+0x319/0x3c2 [zfs]
 [<ffffffffa0484d63>] zpl_read_common+0x4d/0x65 [zfs]
 [<ffffffffa0485ba8>] __zpl_xattr_get+0x161/0x19f [zfs]
 [<ffffffff8127ab18>] ? kvasprintf+0x64/0x74
 [<ffffffffa048629c>] zpl_xattr_get+0x57/0x93 [zfs]
 [<ffffffffa04863a0>] zpl_xattr_user_get+0x5e/0x85 [zfs]
 [<ffffffff8111b242>] generic_getxattr+0x47/0x59
 [<ffffffff8111b61f>] vfs_getxattr+0x98/0xa6
 [<ffffffff8111b9d0>] getxattr+0xba/0x18f
 [<ffffffff81108ed9>] ? putname+0x25/0x27
 [<ffffffff8110b0ab>] ? user_path_at_empty+0x88/0xbb
 [<ffffffff8111c142>] sys_lgetxattr+0x42/0x5e
 [<ffffffff81493216>] system_call_fastpath+0x1a/0x1f
sort            D ffff8801e0c8e480     0 29971      1 0x00000004
 ffff8801187cd0c8 0000000000000082 ffff8801e0c8e480 ffff8801187cdfd8
 0000000000012680 ffff8801187cdfd8 ffff880200cfe700 ffff8801e0c8e480
 ffff8801bfc82000 ffff8801187cc000 ffff8801e0c8e480 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa040344d>] arc_evict+0x6a/0x477 [zfs]
 [<ffffffff814927dc>] ? _raw_spin_lock+0x18/0x1a
 [<ffffffffa01978a6>] ? spin_lock+0x9/0xb [spl]
 [<ffffffffa0199c78>] ? spl_kmem_cache_alloc+0xabd/0xb36 [spl]
 [<ffffffff8103af6a>] ? del_timer_sync+0x2d/0x4d
 [<ffffffffa0403c7d>] arc_get_data_buf+0x2af/0x3f3 [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa040523f>] arc_read_nolock+0x36c/0x678 [zfs]
 [<ffffffffa04055e3>] arc_read+0x98/0xfe [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa0430276>] dsl_read+0x2c/0x2e [zfs]
 [<ffffffffa0409062>] dbuf_read+0x392/0x4c7 [zfs]
 [<ffffffffa040ef7b>] dmu_buf_hold+0xa5/0xe9 [zfs]
 [<ffffffffa045900d>] zap_lockdir+0x45/0x444 [zfs]
 [<ffffffffa0459597>] zap_cursor_retrieve+0x53/0x1c5 [zfs]
 [<ffffffffa0460a3e>] zfs_purgedir+0x1ac/0x1f2 [zfs]
 [<ffffffff810c238a>] ? get_page_from_freelist+0x323/0x386
 [<ffffffff810c201c>] ? prep_new_page+0x101/0x14c
 [<ffffffff810f8b0f>] ? fatal_signal_pending+0xd/0x24
 [<ffffffff810c6804>] ? truncate_inode_pages_range+0x26/0x343
 [<ffffffffa0460ad1>] zfs_rmnode+0x4d/0x291 [zfs]
 [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
 [<ffffffffa047a9a8>] zfs_zinactive+0x90/0xc2 [zfs]
 [<ffffffffa0474aec>] zfs_inactive+0x109/0x13b [zfs]
 [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
 [<ffffffffa0485941>] zpl_evict_inode+0x20/0x24 [zfs]
 [<ffffffffa0485921>] ? zpl_put_super+0xb/0xb [zfs]
 [<ffffffff811143f0>] evict+0xa8/0x15d
 [<ffffffff811144d1>] dispose_list+0x2c/0x38
 [<ffffffff81115138>] prune_icache_sb+0x2a4/0x2bf
 [<ffffffff811025e8>] prune_super+0xcc/0x13c
 [<ffffffff810c96e8>] shrink_slab+0x187/0x28a
 [<ffffffff810cc01e>] do_try_to_free_pages+0x260/0x3d3
 [<ffffffff810cc365>] try_to_free_pages+0xc8/0x124
 [<ffffffff810c2822>] __alloc_pages_nodemask+0x435/0x6ff
 [<ffffffff810f3200>] ? enable_cpucache+0x65/0xa9
 [<ffffffff810f7a05>] do_huge_pmd_anonymous_page+0x9f/0x233
 [<ffffffff810db790>] handle_mm_fault+0x11b/0x1b5
 [<ffffffff81023a78>] __do_page_fault+0x328/0x372
 [<ffffffff810e0974>] ? do_mmap_pgoff+0x26c/0x2bb
 [<ffffffff810d1c96>] ? vm_mmap_pgoff+0x78/0x8b
 [<ffffffff81023aea>] do_page_fault+0x9/0xb
 [<ffffffff81492cf2>] page_fault+0x22/0x30
sort            D ffff880107ee87c0     0 19691      1 0x00000004
 ffff8801d79bd0c8 0000000000000086 ffff880107ee87c0 ffff8801d79bdfd8
 0000000000012680 ffff8801d79bdfd8 ffff8801ff8bc0c0 ffff880107ee87c0
 ffff880072f10000 ffff8801d79bc000 ffff880107ee87c0 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa040344d>] arc_evict+0x6a/0x477 [zfs]
 [<ffffffff814927dc>] ? _raw_spin_lock+0x18/0x1a
 [<ffffffffa01978a6>] ? spin_lock+0x9/0xb [spl]
 [<ffffffffa0199c78>] ? spl_kmem_cache_alloc+0xabd/0xb36 [spl]
 [<ffffffff8103af6a>] ? del_timer_sync+0x2d/0x4d
 [<ffffffffa0403c7d>] arc_get_data_buf+0x2af/0x3f3 [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa040523f>] arc_read_nolock+0x36c/0x678 [zfs]
 [<ffffffffa04055e3>] arc_read+0x98/0xfe [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa0430276>] dsl_read+0x2c/0x2e [zfs]
 [<ffffffffa0409062>] dbuf_read+0x392/0x4c7 [zfs]
 [<ffffffffa040ef7b>] dmu_buf_hold+0xa5/0xe9 [zfs]
 [<ffffffffa045900d>] zap_lockdir+0x45/0x444 [zfs]
 [<ffffffffa0459597>] zap_cursor_retrieve+0x53/0x1c5 [zfs]
 [<ffffffffa0460a3e>] zfs_purgedir+0x1ac/0x1f2 [zfs]
 [<ffffffff810c238a>] ? get_page_from_freelist+0x323/0x386
 [<ffffffff810c201c>] ? prep_new_page+0x101/0x14c
 [<ffffffff810f8b0f>] ? fatal_signal_pending+0xd/0x24
 [<ffffffff810c6804>] ? truncate_inode_pages_range+0x26/0x343
 [<ffffffffa0460ad1>] zfs_rmnode+0x4d/0x291 [zfs]
 [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
 [<ffffffffa047a9a8>] zfs_zinactive+0x90/0xc2 [zfs]
 [<ffffffffa0474aec>] zfs_inactive+0x109/0x13b [zfs]
 [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
 [<ffffffffa0485941>] zpl_evict_inode+0x20/0x24 [zfs]
 [<ffffffffa0485921>] ? zpl_put_super+0xb/0xb [zfs]
 [<ffffffff811143f0>] evict+0xa8/0x15d
 [<ffffffff811144d1>] dispose_list+0x2c/0x38
 [<ffffffff81115138>] prune_icache_sb+0x2a4/0x2bf
 [<ffffffff811025e8>] prune_super+0xcc/0x13c
 [<ffffffff810c96e8>] shrink_slab+0x187/0x28a
 [<ffffffff810cc01e>] do_try_to_free_pages+0x260/0x3d3
 [<ffffffff810cc365>] try_to_free_pages+0xc8/0x124
 [<ffffffff810c2822>] __alloc_pages_nodemask+0x435/0x6ff
 [<ffffffff810f3200>] ? enable_cpucache+0x65/0xa9
 [<ffffffff810f7a05>] do_huge_pmd_anonymous_page+0x9f/0x233
 [<ffffffff810db790>] handle_mm_fault+0x11b/0x1b5
 [<ffffffff81023a78>] __do_page_fault+0x328/0x372
 [<ffffffff810e0974>] ? do_mmap_pgoff+0x26c/0x2bb
 [<ffffffff810d1c96>] ? vm_mmap_pgoff+0x78/0x8b
 [<ffffffff81023aea>] do_page_fault+0x9/0xb
 [<ffffffff81492cf2>] page_fault+0x22/0x30
zfs             D ffff8801936866c0     0 24680  24676 0x00000000
 ffff88012b555408 0000000000000082 ffff8801936866c0 ffff88012b555fd8
 0000000000012680 ffff88012b555fd8 ffff8801ff9ca5c0 ffff8801936866c0
 ffff8802112b2a00 ffff88012b554000 ffff8801936866c0 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa040344d>] arc_evict+0x6a/0x477 [zfs]
 [<ffffffff814927dc>] ? _raw_spin_lock+0x18/0x1a
 [<ffffffffa01978a6>] ? spin_lock+0x9/0xb [spl]
 [<ffffffffa0199c78>] ? spl_kmem_cache_alloc+0xabd/0xb36 [spl]
 [<ffffffff8103af6a>] ? del_timer_sync+0x2d/0x4d
 [<ffffffffa0403c7d>] arc_get_data_buf+0x2af/0x3f3 [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa040523f>] arc_read_nolock+0x36c/0x678 [zfs]
 [<ffffffffa04055e3>] arc_read+0x98/0xfe [zfs]
 [<ffffffffa040b849>] ? dbuf_rm_spill+0x18/0x18 [zfs]
 [<ffffffffa0430276>] dsl_read+0x2c/0x2e [zfs]
 [<ffffffffa0409062>] dbuf_read+0x392/0x4c7 [zfs]
 [<ffffffffa040ef7b>] dmu_buf_hold+0xa5/0xe9 [zfs]
 [<ffffffffa045900d>] zap_lockdir+0x45/0x444 [zfs]
 [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
 [<ffffffffa0459597>] zap_cursor_retrieve+0x53/0x1c5 [zfs]
 [<ffffffffa026dbc8>] ? nvs_native_nvpair+0x15/0x58 [znvpair]
 [<ffffffffa026d6b8>] ? nvs_operation+0x8a/0x229 [znvpair]
 [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
 [<ffffffffa042ca31>] dsl_prop_get_all_impl+0x3c7/0x418 [zfs]
 [<ffffffffa041999d>] ? spl_rwsem_is_locked+0x3c/0x4f [zfs]
 [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
 [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
 [<ffffffffa0197c8f>] ? kmalloc_nofail+0x2c/0x3e [spl]
 [<ffffffff810f2cbd>] ? __kmalloc+0xa1/0xb3
 [<ffffffffa0197c8f>] ? kmalloc_nofail+0x2c/0x3e [spl]
 [<ffffffffa0197c8f>] ? kmalloc_nofail+0x2c/0x3e [spl]
 [<ffffffffa0197e01>] ? kmem_alloc_debug+0x160/0x2cc [spl]
 [<ffffffffa0197e01>] ? kmem_alloc_debug+0x160/0x2cc [spl]
 [<ffffffffa0408de2>] ? dbuf_read+0x112/0x4c7 [zfs]
 [<ffffffffa0409f01>] ? dmu_buf_rele+0x25/0x2c [zfs]
 [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
 [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
 [<ffffffffa042d9fc>] dsl_prop_get_all_ds+0x133/0x19f [zfs]
 [<ffffffffa042b7e4>] ? dsl_pool_sync_context+0x1e/0x30 [zfs]
 [<ffffffffa041f6be>] ? dsl_dataset_get_ref+0x639/0x660 [zfs]
 [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
 [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
 [<ffffffffa0409c08>] ? dbuf_rele_and_unlock+0x1b5/0x1ba [zfs]
 [<ffffffffa0409f01>] ? dmu_buf_rele+0x25/0x2c [zfs]
 [<ffffffff8149259d>] ? _raw_spin_trylock+0x1d/0x30
 [<ffffffffa041dc7a>] ? spl_rwsem_is_locked+0x3c/0x4f [zfs]
 [<ffffffffa041ee10>] ? RW_WRITE_HELD+0x30/0x48 [zfs]
 [<ffffffffa042da76>] dsl_prop_get_all+0xe/0x10 [zfs]
 [<ffffffffa0464ae6>] zfs_ioc_objset_stats_impl+0x3e/0xd7 [zfs]
 [<ffffffffa0464bb3>] zfs_ioc_objset_stats+0x34/0x51 [zfs]
 [<ffffffffa0469382>] ? pool_status_check+0x72/0x7b [zfs]
 [<ffffffffa0469466>] zfsdev_ioctl+0xdb/0x171 [zfs]
 [<ffffffff8110d749>] vfs_ioctl+0x21/0x34
 [<ffffffff8110e033>] do_vfs_ioctl+0x400/0x443
 [<ffffffff810de742>] ? vm_unacct_memory+0x1c/0x1e
 [<ffffffff810df8fe>] ? do_munmap+0x2c7/0x2e8
 [<ffffffff8110e0c8>] sys_ioctl+0x52/0x82
 [<ffffffff81493216>] system_call_fastpath+0x1a/0x1f
zfs             D ffffffff81606a20     0 13643  13641 0x00000000
 ffff8801126c16c8 0000000000000086 ffff8801247a2540 ffff8801126c1fd8
 0000000000012680 ffff8801126c1fd8 ffff8802130de380 ffff8801247a2540
 ffff8801126c16d8 ffff880108093890 ffff880108093858 ffff8801080938c8
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffffa01a1b6c>] cv_wait_common+0xd8/0x16c [spl]
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa01a1c37>] __cv_wait+0x10/0x15 [spl]
 [<ffffffffa0409157>] dbuf_read+0x487/0x4c7 [zfs]
 [<ffffffffa040a4bb>] ? dbuf_hold_impl+0x8d/0x9f [zfs]
 [<ffffffff8149259d>] ? _raw_spin_trylock+0x1d/0x30
 [<ffffffffa040ef7b>] dmu_buf_hold+0xa5/0xe9 [zfs]
 [<ffffffffa045900d>] zap_lockdir+0x45/0x444 [zfs]
 [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
 [<ffffffffa0459597>] zap_cursor_retrieve+0x53/0x1c5 [zfs]
 [<ffffffffa026dbc8>] ? nvs_native_nvpair+0x15/0x58 [znvpair]
 [<ffffffffa026d6b8>] ? nvs_operation+0x8a/0x229 [znvpair]
 [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
 [<ffffffffa042ca31>] dsl_prop_get_all_impl+0x3c7/0x418 [zfs]
 [<ffffffffa041999d>] ? spl_rwsem_is_locked+0x3c/0x4f [zfs]
 [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
 [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
 [<ffffffffa0197c8f>] ? kmalloc_nofail+0x2c/0x3e [spl]
 [<ffffffff810f2cbd>] ? __kmalloc+0xa1/0xb3
 [<ffffffffa0197c8f>] ? kmalloc_nofail+0x2c/0x3e [spl]
 [<ffffffffa0197c8f>] ? kmalloc_nofail+0x2c/0x3e [spl]
 [<ffffffffa0197e01>] ? kmem_alloc_debug+0x160/0x2cc [spl]
 [<ffffffffa0197e01>] ? kmem_alloc_debug+0x160/0x2cc [spl]
 [<ffffffffa0408de2>] ? dbuf_read+0x112/0x4c7 [zfs]
 [<ffffffffa0409f01>] ? dmu_buf_rele+0x25/0x2c [zfs]
 [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
 [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
 [<ffffffffa042d9fc>] dsl_prop_get_all_ds+0x133/0x19f [zfs]
 [<ffffffffa042b7e4>] ? dsl_pool_sync_context+0x1e/0x30 [zfs]
 [<ffffffffa041f6be>] ? dsl_dataset_get_ref+0x639/0x660 [zfs]
 [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
 [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
 [<ffffffffa0409c08>] ? dbuf_rele_and_unlock+0x1b5/0x1ba [zfs]
 [<ffffffffa0409f01>] ? dmu_buf_rele+0x25/0x2c [zfs]
 [<ffffffff8149259d>] ? _raw_spin_trylock+0x1d/0x30
 [<ffffffffa041dc7a>] ? spl_rwsem_is_locked+0x3c/0x4f [zfs]
 [<ffffffffa041ee10>] ? RW_WRITE_HELD+0x30/0x48 [zfs]
 [<ffffffffa042da76>] dsl_prop_get_all+0xe/0x10 [zfs]
 [<ffffffffa0464ae6>] zfs_ioc_objset_stats_impl+0x3e/0xd7 [zfs]
 [<ffffffffa0464bb3>] zfs_ioc_objset_stats+0x34/0x51 [zfs]
 [<ffffffffa0469382>] ? pool_status_check+0x72/0x7b [zfs]
 [<ffffffffa0469466>] zfsdev_ioctl+0xdb/0x171 [zfs]
 [<ffffffff8110d749>] vfs_ioctl+0x21/0x34
 [<ffffffff8110e033>] do_vfs_ioctl+0x400/0x443
 [<ffffffff810de742>] ? vm_unacct_memory+0x1c/0x1e
 [<ffffffff810df8fe>] ? do_munmap+0x2c7/0x2e8
 [<ffffffff8110e0c8>] sys_ioctl+0x52/0x82
 [<ffffffff81493216>] system_call_fastpath+0x1a/0x1f
sort            D ffff88021221d000     0  1458      1 0x00000004
 ffff880027a51218 0000000000000082 ffff8800157d61c0 ffff880027a51fd8
 0000000000012680 ffff880027a51fd8 ffff8800a8e9c580 ffff8800157d61c0
 ffff880210bcc060 0000000100ab6a34 ffff88021221d000 ffff880210bcc0d0
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa01998ac>] spl_kmem_cache_alloc+0x6f1/0xb36 [spl]
 [<ffffffff8149259d>] ? _raw_spin_trylock+0x1d/0x30
 [<ffffffffa041999d>] ? spl_rwsem_is_locked+0x3c/0x4f [zfs]
 [<ffffffff814927dc>] ? _raw_spin_lock+0x18/0x1a
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa048003b>] zio_create+0x43/0x32c [zfs]
 [<ffffffffa0480383>] zio_null+0x5f/0x61 [zfs]
 [<ffffffffa048039e>] zio_root+0x19/0x1b [zfs]
 [<ffffffffa0408e14>] dbuf_read+0x144/0x4c7 [zfs]
 [<ffffffffa040a4bb
akorn commented 11 years ago

Oh, it was too long. Do you need the rest? FWIW, I load zfs with

l2arc_norw=0 zfs_zevent_console=1 zfs_recover=1 zfs_arc_max=6000000000 zfs_arc_meta_limit=6000000000 zfs_dedup_prefetch=0 zfs_no_write_throttle=1 zfs_txg_synctime_ms=30000 zfs_txg_timeout=30
behlendorf commented 11 years ago

@akorn Can you please apply 27d26338ddc37ee9ee6a9a53a78873398da80b54 to your spl source and set the new module option spl_kmem_cache_aging=0. This will effectively disable the cache aging which I suspect is the major cpu time contributor. If it is you should see a large decrease in cpu usage.

akorn commented 11 years ago

Thanks, will try (likely tomorrow). Is this also expected to help avoid blocked processes like the above?

akorn commented 11 years ago

OK, I applied the patch and set the module option.

I'm not sure what's happening. spl_kmem_cache isn't using much CPU anymore, but the txg_sync thread is:

top - 23:58:41 up  5:13,  3 users,  load average: 3.91, 3.76, 3.66
Tasks: 406 total,   2 running, 404 sleeping,   0 stopped,   0 zombie
%Cpu0  :  0.0 us, 32.5 sy,  0.0 ni, 46.9 id,  0.0 wa,  0.0 hi, 20.5 si,  0.0 st
%Cpu1  :  0.0 us, 40.9 sy,  0.0 ni, 45.8 id,  9.1 wa,  0.0 hi,  4.2 si,  0.0 st
%Cpu2  :  0.3 us, 45.4 sy,  0.0 ni, 42.7 id, 11.6 wa,  0.0 hi,  0.0 si,  0.0 st
%Cpu3  :  1.0 us, 28.0 sy,  0.0 ni, 67.5 id,  3.5 wa,  0.0 hi,  0.0 si,  0.0 st
KiB Mem:   8151304 total,  7403344 used,   747960 free,    18380 buffers
KiB Swap:  2097148 total,        0 used,  2097148 free,   124632 cached

  PID USER      PR  NI  VIRT  RES  SHR CODE DATA SWAP S P WCHAN      %MEM %CPU     TIME+ COMMAND                                                                      
 6231 root       0 -20     0    0    0    0    0    0 R 3 -           0.0 19.5  90:55.20 [txg_sync]                                                                   
 6230 root       0 -20     0    0    0    0    0    0 S 0 cv_wait_c+  0.0  4.9  20:25.21 [txg_quiesce]                                                                
 6161 root      39  19     0    0    0    0    0    0 S 0 -           0.0  4.8  25:25.49 [z_null_iss/0]                                                               
 1272 nobody    20   0 26164 2324  644  384 3164    0 D 1 -           0.0  4.4  19:37.98 rsync --daemon --no-detach                                                   
 9129 nobody    20   0 25144 1092  676  384 2144    0 D 1 txg_hold_+  0.0  4.4  10:17.45 rsync --daemon --no-detach                                                   
 2156 root       0 -20     0    0    0    0    0    0 S 2 cv_timedw+  0.0  0.6   4:09.31 [l2arc_feed]                                                                 

This has now been going on for hours.

sysrq-w says:

ysRq : Show Blocked State
  task                        PC stack   pid father
rsync           D ffffffff81606a20     0  1272   1268 0x00000000
 ffff880050843a18 0000000000000086 ffff88006601a080 ffff880050843fd8
 0000000000012680 ffff880050843fd8 ffff8802130de380 ffff88006601a080
 ffff880050843aa8 ffff880050842000 ffff88006601a080 0000000000000002
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff81491d90>] schedule_preempt_disabled+0x1e/0x25
 [<ffffffff81490d81>] __mutex_lock_common.isra.6+0x18c/0x1ac
 [<ffffffff81490e0e>] __mutex_lock_slowpath+0xe/0x10
 [<ffffffff81490b38>] mutex_lock+0x2c/0x40
 [<ffffffffa041fdba>] ? dsl_dataset_prev_snap_txg+0x25/0x4b [zfs]
 [<ffffffffa0449e98>] txg_hold_open+0x46/0x6c [zfs]
 [<ffffffffa041a04e>] dmu_tx_assign+0x161/0x31c [zfs]
 [<ffffffffa0473521>] zfs_setattr+0xb06/0x142e [zfs]
 [<ffffffff81108211>] ? lookup_fast+0x3f/0x238
 [<ffffffff810f2cbd>] ? __kmalloc+0xa1/0xb3
 [<ffffffffa012fdfa>] ? kmem_alloc_debug+0x141/0x2cc [spl]
 [<ffffffffa012fdfa>] ? kmem_alloc_debug+0x141/0x2cc [spl]
 [<ffffffffa0486192>] zpl_setattr+0xd7/0xff [zfs]
 [<ffffffff81115657>] notify_change+0x1e4/0x2cd
 [<ffffffff81125831>] utimes_common.part.1+0x1e4/0x22c
 [<ffffffff8112596e>] do_utimes+0xf5/0x110
 [<ffffffff81125a66>] sys_utimensat+0x63/0x7b
 [<ffffffff81493216>] system_call_fastpath+0x1a/0x1f
rsync           D ffffffff81606a20     0  9129   9128 0x00000000
 ffff8801aeb01948 0000000000000082 ffff880170cee7c0 ffff8801aeb01fd8
 0000000000012680 ffff8801aeb01fd8 ffff8802130de380 ffff880170cee7c0
 0000000000000020 00000001001c4fe3 ffff880211ea5b10 ffff880211ea59f8
Call Trace:
 [<ffffffff81491bf0>] schedule+0x60/0x62
 [<ffffffff8149088c>] schedule_timeout+0xb5/0xd8
 [<ffffffff8103a366>] ? ftrace_raw_event_timer_class+0x92/0x92
 [<ffffffffa0139613>] __cv_timedwait_common+0x129/0x1dd [spl]
 [<ffffffff81049f1c>] ? bit_waitqueue+0x69/0x69
 [<ffffffffa01396e5>] __cv_timedwait+0xe/0x10 [spl]
 [<ffffffffa044a45c>] txg_delay+0x89/0xce [zfs]
 [<ffffffffa042a030>] dsl_dir_tempreserve_space+0xe9/0x192 [zfs]
 [<ffffffffa041a1df>] dmu_tx_assign+0x2f2/0x31c [zfs]
 [<ffffffffa0473521>] zfs_setattr+0xb06/0x142e [zfs]
 [<ffffffff81108211>] ? lookup_fast+0x3f/0x238
 [<ffffffff810f2cbd>] ? __kmalloc+0xa1/0xb3
 [<ffffffffa012fdfa>] ? kmem_alloc_debug+0x141/0x2cc [spl]
 [<ffffffffa012fdfa>] ? kmem_alloc_debug+0x141/0x2cc [spl]
 [<ffffffffa0486192>] zpl_setattr+0xd7/0xff [zfs]
 [<ffffffff81115657>] notify_change+0x1e4/0x2cd
 [<ffffffff81125831>] utimes_common.part.1+0x1e4/0x22c
 [<ffffffff8112596e>] do_utimes+0xf5/0x110
 [<ffffffff81125a66>] sys_utimensat+0x63/0x7b
 [<ffffffff81493216>] system_call_fastpath+0x1a/0x1f
Sched Debug Version: v0.10, 3.7.1-vs2.3.5.3-caribou #3
ktime                                   : 18854589.830530
sched_clk                               : 18820539.329696
cpu_clk                                 : 18820539.329783
jiffies                                 : 4296822754
sched_clock_stable                      : 1

sysctl_sched
  .sysctl_sched_latency                    : 18.000000
  .sysctl_sched_min_granularity            : 2.250000
  .sysctl_sched_wakeup_granularity         : 3.000000
  .sysctl_sched_child_runs_first           : 0
  .sysctl_sched_features                   : 12091
  .sysctl_sched_tunable_scaling            : 1 (logaritmic)

cpu#0, 2506.303 MHz
  .nr_running                    : 3
  .load                          : 1594
  .nr_switches                   : 395376860
  .nr_load_updates               : 884001
  .nr_uninterruptible            : 18888
  .next_balance                  : 4296.822766
  .curr->pid                     : 17284
  .clock                         : 18820539.629256
  .cpu_load[0]                   : 840
  .cpu_load[1]                   : 1019
  .cpu_load[2]                   : 4522
  .cpu_load[3]                   : 7687
  .cpu_load[4]                   : 6902
  .yld_count                     : 0
  .sched_count                   : 395395055
  .sched_goidle                  : 174633617
  .avg_idle                      : 1000000
  .ttwu_count                    : 188625874
  .ttwu_local                    : 1562121

cfs_rq[0]:/autogroup-5293
  .exec_clock                    : 474.100131
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 515.499310
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107966251.758666
  .nr_spread_over                : 7
  .nr_running                    : 1
  .load                          : 1024
  .load_avg                      : 0.000000
  .load_period                   : 9.999999
  .load_contrib                  : 0
  .load_tg                       : 1629
  .se->exec_start                : 18820539.629256
  .se->vruntime                  : 107966769.405003
  .se->sum_exec_runtime          : 480.053602
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 9.991862
  .se->statistics.slice_max      : 2.125978
  .se->statistics.wait_max       : 0.064963
  .se->statistics.wait_sum       : 2.036182
  .se->statistics.wait_count     : 123
  .se->load.weight               : 555

cfs_rq[0]:/autogroup-33
  .exec_clock                    : 691239.272626
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 690058.956948
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107276708.301028
  .nr_spread_over                : 80
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 1117.238712
  .load_period                   : 5.710510
  .load_contrib                  : 201
  .load_tg                       : 537
  .se->exec_start                : 18820529.865894
  .se->vruntime                  : 107966729.889380
  .se->sum_exec_runtime          : 691240.099123
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 10.204607
  .se->statistics.slice_max      : 15.933138
  .se->statistics.wait_max       : 56.789914
  .se->statistics.wait_sum       : 60682.418884
  .se->statistics.wait_count     : 43722676
  .se->load.weight               : 2

cfs_rq[0]:/
  .exec_clock                    : 2477120.052224
  .MIN_vruntime                  : 107966758.257976
  .min_vruntime                  : 107966767.257976
  .max_vruntime                  : 107966767.257976
  .spread                        : 9.000000
  .spread0                       : 0.000000
  .nr_spread_over                : 2508
  .nr_running                    : 3
  .load                          : 1594
  .load_avg                      : 0.000000
  .load_period                   : 0.000000
  .load_contrib                  : 0
  .load_tg                       : 0

rt_rq[0]:/
  .rt_nr_running                 : 0
  .rt_throttled                  : 0
  .rt_time                       : 0.066727
  .rt_runtime                    : 1000.000000

runnable tasks:
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep
----------------------------------------------------------------------------------------------------------
     kworker/0:1   428 107966758.257976    316057   120 107966758.257976      5394.016660  18754399.114720 /
    z_null_iss/0  6161 107966767.257976 172225383   139 107966767.257976   1538159.859364  16648860.170991 /
R            zsh 17284       515.499310       219   120       515.499310       248.991436    214559.089664 /autogroup-5293

cpu#1, 2506.303 MHz
  .nr_running                    : 1
  .load                          : 1024
  .nr_switches                   : 222633659
  .nr_load_updates               : 973650
  .nr_uninterruptible            : -9750
  .next_balance                  : 4296.822777
  .curr->pid                     : 2722
  .clock                         : 18820541.448326
  .cpu_load[0]                   : 0
  .cpu_load[1]                   : 17343
  .cpu_load[2]                   : 23217
  .cpu_load[3]                   : 22340
  .cpu_load[4]                   : 22842
  .yld_count                     : 0
  .sched_count                   : 222664236
  .sched_goidle                  : 110912514
  .avg_idle                      : 1000000
  .ttwu_count                    : 114715334
  .ttwu_local                    : 856173

cfs_rq[1]:/autogroup-5293
  .exec_clock                    : 115.074374
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 183.876892
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107966583.381084
  .nr_spread_over                : 8
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 2579.996928
  .load_period                   : 6.081395
  .load_contrib                  : 424
  .load_tg                       : 1629
  .se->exec_start                : 18820533.525181
  .se->vruntime                  : 1352984.377521
  .se->sum_exec_runtime          : 115.074374
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 9.984502
  .se->statistics.slice_max      : 0.098633
  .se->statistics.wait_max       : 0.090666
  .se->statistics.wait_sum       : 0.543245
  .se->statistics.wait_count     : 80
  .se->load.weight               : 2

cfs_rq[1]:/autogroup-3534
  .exec_clock                    : 5843.428699
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 5901.816127
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107960865.441849
  .nr_spread_over                : 9
  .nr_running                    : 1
  .load                          : 1024
  .load_avg                      : 3418.486016
  .load_period                   : 8.697871
  .load_contrib                  : 493
  .load_tg                       : 493
  .se->exec_start                : 18820541.448326
  .se->vruntime                  : 1352987.582650
  .se->sum_exec_runtime          : 5843.428699
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 9.897513
  .se->statistics.slice_max      : 8.195665
  .se->statistics.wait_max       : 0.247327
  .se->statistics.wait_sum       : 8.440457
  .se->statistics.wait_count     : 1449
  .se->load.weight               : 1024

cfs_rq[1]:/autogroup-33
  .exec_clock                    : 454094.122328
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 453304.220491
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107513463.037485
  .nr_spread_over                : 64
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 984.927466
  .load_period                   : 7.731603
  .load_contrib                  : 127
  .load_tg                       : 537
  .se->exec_start                : 18820539.650752
  .se->vruntime                  : 1352991.910556
  .se->sum_exec_runtime          : 454098.577189
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 9.996713
  .se->statistics.slice_max      : 0.898032
  .se->statistics.wait_max       : 24.451691
  .se->statistics.wait_sum       : 7772.428722
  .se->statistics.wait_count     : 29080728
  .se->load.weight               : 2

cfs_rq[1]:/
  .exec_clock                    : 2672058.548895
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 1352991.910556
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -106613775.347420
  .nr_spread_over                : 1728
  .nr_running                    : 1
  .load                          : 2048
  .load_avg                      : 0.000000
  .load_period                   : 0.000000
  .load_contrib                  : 0
  .load_tg                       : 0

rt_rq[1]:/
  .rt_nr_running                 : 0
  .rt_throttled                  : 0
  .rt_time                       : 0.000000
  .rt_runtime                    : 900.000000

runnable tasks:
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep
----------------------------------------------------------------------------------------------------------

cpu#2, 2506.303 MHz
  .nr_running                    : 0
  .load                          : 0
  .nr_switches                   : 235229740
  .nr_load_updates               : 926544
  .nr_uninterruptible            : -2841
  .next_balance                  : 4296.822757
  .curr->pid                     : 0
  .clock                         : 18820549.608082
  .cpu_load[0]                   : 0
  .cpu_load[1]                   : 222
  .cpu_load[2]                   : 4270
  .cpu_load[3]                   : 12722
  .cpu_load[4]                   : 21240
  .yld_count                     : 0
  .sched_count                   : 235284793
  .sched_goidle                  : 117203669
  .avg_idle                      : 768860
  .ttwu_count                    : 136160636
  .ttwu_local                    : 818005

cfs_rq[2]:/autogroup-5293
  .exec_clock                    : 388.687071
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 439.790404
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107966327.467572
  .nr_spread_over                : 7
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 2559.999744
  .load_period                   : 9.459850
  .load_contrib                  : 270
  .load_tg                       : 710
  .se->exec_start                : 18820532.039271
  .se->vruntime                  : 1260372.312441
  .se->sum_exec_runtime          : 388.720253
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 9.988659
  .se->statistics.slice_max      : 1.266687
  .se->statistics.wait_max       : 0.662368
  .se->statistics.wait_sum       : 6.644449
  .se->statistics.wait_count     : 197
  .se->load.weight               : 2

cfs_rq[2]:/autogroup-33
  .exec_clock                    : 425572.782592
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 425090.957388
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107541676.300588
  .nr_spread_over                : 66
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 401.603962
  .load_period                   : 9.267418
  .load_contrib                  : 43
  .load_tg                       : 353
  .se->exec_start                : 18820549.564050
  .se->vruntime                  : 1260380.843702
  .se->sum_exec_runtime          : 425577.205190
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 61.590801
  .se->statistics.slice_max      : 1.234318
  .se->statistics.wait_max       : 227.768771
  .se->statistics.wait_sum       : 7464.297857
  .se->statistics.wait_count     : 28694819
  .se->load.weight               : 2

cfs_rq[2]:/
  .exec_clock                    : 2996565.311687
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 1260380.843702
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -106706386.414274
  .nr_spread_over                : 2143
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 0.000000
  .load_period                   : 0.000000
  .load_contrib                  : 0
  .load_tg                       : 0

rt_rq[2]:/
  .rt_nr_running                 : 0
  .rt_throttled                  : 0
  .rt_time                       : 0.000000
  .rt_runtime                    : 950.000000

runnable tasks:
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep
----------------------------------------------------------------------------------------------------------

cpu#3, 2506.303 MHz
  .nr_running                    : 0
  .load                          : 0
  .nr_switches                   : 225158879
  .nr_load_updates               : 939868
  .nr_uninterruptible            : -6293
  .next_balance                  : 4296.822757
  .curr->pid                     : 0
  .clock                         : 18820549.607446
  .cpu_load[0]                   : 0
  .cpu_load[1]                   : 2255
  .cpu_load[2]                   : 12017
  .cpu_load[3]                   : 18266
  .cpu_load[4]                   : 18630
  .yld_count                     : 0
  .sched_count                   : 225267018
  .sched_goidle                  : 112196015
  .avg_idle                      : 674604
  .ttwu_count                    : 123988185
  .ttwu_local                    : 1114953

cfs_rq[3]:/autogroup-5293
  .exec_clock                    : 185.880564
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 294.542625
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107966472.715351
  .nr_spread_over                : 11
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 2559.999744
  .load_period                   : 9.073349
  .load_contrib                  : 282
  .load_tg                       : 710
  .se->exec_start                : 18820533.594225
  .se->vruntime                  : 1139171.608385
  .se->sum_exec_runtime          : 185.880564
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 9.988773
  .se->statistics.slice_max      : 0.589002
  .se->statistics.wait_max       : 0.070855
  .se->statistics.wait_sum       : 1.017983
  .se->statistics.wait_count     : 107
  .se->load.weight               : 2

cfs_rq[3]:/autogroup-166
  .exec_clock                    : 41.472866
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 40.424290
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107966726.833686
  .nr_spread_over                : 0
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 1305.768576
  .load_period                   : 8.512552
  .load_contrib                  : 153
  .load_tg                       : 153
  .se->exec_start                : 18820531.729717
  .se->vruntime                  : 1139170.164065
  .se->sum_exec_runtime          : 41.472866
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 4.107060
  .se->statistics.slice_max      : 1.524111
  .se->statistics.wait_max       : 0.127452
  .se->statistics.wait_sum       : 0.259065
  .se->statistics.wait_count     : 119
  .se->load.weight               : 2

cfs_rq[3]:/autogroup-33
  .exec_clock                    : 412400.551618
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 411034.355153
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -107555732.902823
  .nr_spread_over                : 54
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 434.795196
  .load_period                   : 8.451374
  .load_contrib                  : 51
  .load_tg                       : 353
  .se->exec_start                : 18820533.620295
  .se->vruntime                  : 1139179.081784
  .se->sum_exec_runtime          : 412403.826433
  .se->statistics.wait_start     : 0.000000
  .se->statistics.sleep_start    : 0.000000
  .se->statistics.block_start    : 0.000000
  .se->statistics.sleep_max      : 0.000000
  .se->statistics.block_max      : 0.000000
  .se->statistics.exec_max       : 26.239881
  .se->statistics.slice_max      : 1.206854
  .se->statistics.wait_max       : 197.786199
  .se->statistics.wait_sum       : 7733.686956
  .se->statistics.wait_count     : 27196293
  .se->load.weight               : 2

cfs_rq[3]:/
  .exec_clock                    : 2777997.867886
  .MIN_vruntime                  : 0.000001
  .min_vruntime                  : 1139179.081784
  .max_vruntime                  : 0.000001
  .spread                        : 0.000000
  .spread0                       : -106827588.176192
  .nr_spread_over                : 1543
  .nr_running                    : 0
  .load                          : 0
  .load_avg                      : 0.000000
  .load_period                   : 0.000000
  .load_contrib                  : 0
  .load_tg                       : 0

rt_rq[3]:/
  .rt_nr_running                 : 0
  .rt_throttled                  : 0
  .rt_time                       : 0.000000
  .rt_runtime                    : 950.000000

runnable tasks:
            task   PID         tree-key  switches  prio     exec-runtime         sum-exec        sum-sleep
----------------------------------------------------------------------------------------------------------

Meanwhile, nothing is happening in the pool:


                                                         capacity     operations    bandwidth
pool                                                  alloc   free   read  write   read  write
----------------------------------------------------  -----  -----  -----  -----  -----  -----
backup                                                1.37T  4.94T      0      0      0      0
  mirror                                              16.0G  1.80T      0      0      0      0
    ata-Hitachi_HDS723020BLA642_MN1221F3087RXD-part3      -      -      0      0      0      0
    2504734023117859732                                   -      -      0      0      0      0
  raidz1                                              1.36T  3.14T      0      0      0      0
    ata-ST31000528AS_6VP364X8-part4                       -      -      0      0      0      0
    ata-ST31000528AS_5VP3DG12-part4                       -      -      0      0      0      0
    ata-ST31000528AS_9VP47ZGV-part4                       -      -      0      0      0      0
    ata-ST31000528AS_6VP2REKC-part4                       -      -      0      0      0      0
    ata-SAMSUNG_HD103UJ_S13PJDWS114699-part4              -      -      0      0      0      0
logs                                                      -      -      -      -      -      -
  dm-13                                                308K   250M      0      0      0      0
cache                                                     -      -      -      -      -      -
  ssd-zfs_backup_cache                                 657M  7.35G      0      0      0      0
----------------------------------------------------  -----  -----  -----  -----  -----  -----

(I know the pool has an unusual layout; this has historical reasons.)

iostat also doesn't report any disk activity:

avg-cpu:  %user   %nice %system %iowait  %steal   %idle
           0.22    0.00   42.20    6.05    0.00   51.53

Device:         rrqm/s   wrqm/s     r/s     w/s    rkB/s    wkB/s avgrq-sz avgqu-sz   await r_await w_await  svctm  %util
sda               0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
sdb               0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
sdc               0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
sdd               0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
sde               0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
sdf               0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
sdg               0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00
sdh               0.00     0.00    0.00    0.00     0.00     0.00     0.00     0.00    0.00    0.00    0.00   0.00   0.00

(I don't know why it only reports ~50% of idle; I see no activity even if I include all block devices.)

The two rsync processes appear well and truly stuck.

behlendorf commented 11 years ago

@akorn that's encouraging news about the spl_kmem_cache cpu usage, it now appears your hitting one of the internal ZFS I/O throttles. Can you post in the contents of cat /proc/spl/kstat/zfs/dmu_tx

akorn commented 11 years ago

Sure:

3 1 0x01 12 576 18381840591 128483830278882
name                            type data
dmu_tx_assigned                 4    48882
dmu_tx_delay                    4    928202223
dmu_tx_error                    4    0
dmu_tx_suspended                4    0
dmu_tx_group                    4    0
dmu_tx_how                      4    0
dmu_tx_memory_reserve           4    0
dmu_tx_memory_reclaim           4    929441603
dmu_tx_memory_inflight          4    0
dmu_tx_dirty_throttle           4    0
dmu_tx_write_limit              4    0
dmu_tx_quota                    4    0
behlendorf commented 11 years ago

dmu_tx_memory_reclaim 4 929441603

That's why. ZFS believes (perhaps incorrectly) that you have less reclaimable memory than the maximum sized txg so it starts throttling. Something about your workload is probably triggering this. You can try setting the zfs_write_limit_max to a smaller value, by increasing zfs_write_limit_shift. By default zfs_write_limit_shift=3 or 1/8 of memory, try increasing it to 4 then 5 and see if it helps.

Honestly, long term these internal throttles are something we'll probably want to remove from ZoL. The evidence is mounting that it's probably preferable to rely on the existing Linux throttling mechanisms.

akorn commented 11 years ago

With zfs_write_limit_shift=4 I had the same behaviour. Now, with 5, I can reliably cause the kernel to panic by rsyncing a specific fs to the box.

Unfortunately I have so far been unable to capture the panic message in its entirety because it's not written to netconsole and I couldn't get ramoops to work. The end of the stack trace is identical to part of the above:

09:08:30 kernel:  [<ffffffff81053d19>] ? __might_sleep+0x39/0xf0
09:08:30 kernel:  [<ffffffff81490b2b>] ? mutex_lock+0x1f/0x40
09:08:30 kernel:  [<ffffffff810f2668>] ? ____cache_alloc+0x3f/0x544
09:08:30 kernel:  [<ffffffffa016739c>] zfs_lookup+0x158/0x29e [zfs]
09:08:30 kernel:  [<ffffffffa0178aae>] __zpl_xattr_get+0x67/0x19f [zfs]
09:08:30 kernel:  [<ffffffff8127ab18>] ? kvasprintf+0x64/0x74
09:08:30 kernel:  [<ffffffffa017929c>] zpl_xattr_get+0x57/0x93 [zfs]
09:08:30 kernel:  [<ffffffffa01793a0>] zpl_xattr_user_get+0x5e/0x85 [zfs]
09:08:30 kernel:  [<ffffffff8111b242>] generic_getxattr+0x47/0x59
09:08:30 kernel:  [<ffffffff8111b61f>] vfs_getxattr+0x98/0xa6
09:08:30 kernel:  [<ffffffff8111b9d0>] getxattr+0xba/0x18f
09:08:30 kernel:  [<ffffffff81108ed9>] ? putname+0x25/0x27
09:08:30 kernel:  [<ffffffff8110b0ab>] ? user_path_at_empty+0x88/0xbb
09:08:30 kernel:  [<ffffffff8111c142>] sys_lgetxattr+0x42/0x5e
09:08:30 kernel:  [<ffffffff81493216>] system_call_fastpath+0x1a/0x1f

I'm typing the rest from a physical screenshot taken with a camera, so there may be typos:

Code: 24 7f 48 89 8d 70 ff ff ff 89 45 80 65 8b 04 25 1c b0 00 00 48 98 4c 8b 64
 c3 10 41 8b 44 24 84 85 c0 74 1d ff c8 41 89 44 24 04 <4d> 8b 6c c4 28 48 8b 05
 0a e0 98 e1 49 89 44 24 18 e9 d2 09 00
RIP  [<ffffffffa010efea>] spl_kmem_cache_alloc+0xc0/0xb36 [spl]
 RSP <ffff88019018f358>
CR2: ffff8809e45b9018
---[ end trace 0b02b7dcef9f6b4d ]---
Kernel panic - not syncing: Fatal exception
Shutting down cpus with NMI
Rebooting in 60 seconds...

It then doesn't always boot on the first try, but I'm still looking into that.

I'll try to have a serial console hooked up so that we may capture all of the error output.

behlendorf commented 11 years ago

The original root causes of this issue have been addressed in the latest master. If you grab the latest source and are still having problems lets open new issue on the ZFS tracker for this. This issue is getting a bit to jumbled.