Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

zfs blocking everything, out of memory, and daily lockups #860

Closed
ryanolf opened this issue Aug 1, 2012 · 16 comments
Closed

zfs blocking everything, out of memory, and daily lockups #860

ryanolf opened this issue Aug 1, 2012 · 16 comments
Labels
Component: Memory Management kernel memory management
Milestone

Comments

@ryanolf
Copy link

ryanolf commented Aug 1, 2012

I've seen a number of bugs that look like this, but I'm not sure if this is the same bug. I understand many things can cause this sort of blocking behavior, and I don't think I've seen this particular combination. I'm using 0.6.0.65-0ubuntu1~precise1 version from the Ubuntu ppa-daily on a stock Ubuntu 12.04 server 64 bit install, though I've had problems like this (though perhaps not identical) going back to 11.10 and older versions of zfs on linux. I'm not sure it's relevant to this bug, but I should mention that issues with lockups and crashes and blocking seemed to begin about 6 months ago and may have been related to a bad stick of RAM which was later found a removed. Scrubs have been successful since and the backup array, which consists of SATA drives in an external enclosure, either eSATA or USB (seems to not make a difference), is not on a different system with ECC RAM. Problems continue.

Under a moderate load, the system locks up about once a day, probably related to the pattern of usage. Computers around our office back up to this system daily, typically overnight. Additionally, the system is resilvering a disk (and has been for weeks... it finished the resilver but there were data errors due to the old bad RAM. I have removed the offending files, and am letting it resilver again, since it seems to need to do that if it encounters data corruption it can't fix). If I don't have the pool mounted (and hence no backups to it are happening) I don't seem to have problems, at least not every day. However, if I try to use the pool, I get messages like this and, recently, out of memory messages:

Jul 27 08:22:33 scenic kernel: [71398.965015] INFO: task zfs:27489 blocked for more than 120 seconds.
Jul 27 08:22:33 scenic kernel: [71398.965020] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:22:33 scenic kernel: [71398.965023] zfs             D ffffffff81806080     0 27489  27488 0x00000000
Jul 27 08:22:33 scenic kernel: [71398.965029]  ffff88015cde1c38 0000000000000086 0000000000000020 0000000000000046
Jul 27 08:22:33 scenic kernel: [71398.965034]  ffff88015cde1fd8 ffff88015cde1fd8 ffff88015cde1fd8 0000000000013780
Jul 27 08:22:33 scenic kernel: [71398.965039]  ffff8802109c16f0 ffff8801c416c4d0 ffff88015cde1c18 ffff88020eee2258
Jul 27 08:22:33 scenic kernel: [71398.965044] Call Trace:
Jul 27 08:22:33 scenic kernel: [71398.965054]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:22:33 scenic kernel: [71398.965072]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:22:33 scenic kernel: [71398.965078]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:22:33 scenic kernel: [71398.965087]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:22:33 scenic kernel: [71398.965124]  [<ffffffffa02261e3>] txg_wait_synced+0xb3/0x190 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965153]  [<ffffffffa020ad7e>] dsl_sync_task_group_wait+0x14e/0x270 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965179]  [<ffffffffa01f6b70>] ? snaplist_destroy+0x100/0x100 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965203]  [<ffffffffa01fa3a0>] ? dsl_dataset_create_sync+0x280/0x280 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965229]  [<ffffffffa020b077>] dsl_sync_task_do+0x57/0x80 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965254]  [<ffffffffa01fc212>] dsl_dataset_destroy+0xb2/0x490 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965276]  [<ffffffffa01fb2fe>] ? dsl_dataset_tryown+0x4e/0x140 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965299]  [<ffffffffa01e46cb>] dmu_objset_destroy+0x3b/0x50 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965330]  [<ffffffffa0249168>] zfs_ioc_destroy+0x38/0x80 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965359]  [<ffffffffa024d35c>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Jul 27 08:22:33 scenic kernel: [71398.965364]  [<ffffffff8118a06a>] do_vfs_ioctl+0x8a/0x340
Jul 27 08:22:33 scenic kernel: [71398.965369]  [<ffffffff81142cf3>] ? do_munmap+0x1f3/0x2f0
Jul 27 08:22:33 scenic kernel: [71398.965373]  [<ffffffff8118a3b1>] sys_ioctl+0x91/0xa0
Jul 27 08:22:33 scenic kernel: [71398.965378]  [<ffffffff81661fc2>] system_call_fastpath+0x16/0x1b
Jul 27 08:24:33 scenic kernel: [71518.961929] INFO: task zfs:27489 blocked for more than 120 seconds.
Jul 27 08:24:33 scenic kernel: [71518.961933] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:24:33 scenic kernel: [71518.961937] zfs             D ffffffff81806080     0 27489  27488 0x00000000
Jul 27 08:24:33 scenic kernel: [71518.961942]  ffff88015cde1c38 0000000000000086 0000000000000020 0000000000000046
Jul 27 08:24:33 scenic kernel: [71518.961948]  ffff88015cde1fd8 ffff88015cde1fd8 ffff88015cde1fd8 0000000000013780
Jul 27 08:24:33 scenic kernel: [71518.961953]  ffff8802109c16f0 ffff8801c416c4d0 ffff88015cde1c18 ffff88020eee2258
Jul 27 08:24:33 scenic kernel: [71518.961958] Call Trace:
Jul 27 08:24:33 scenic kernel: [71518.961968]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:24:33 scenic kernel: [71518.961986]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:24:33 scenic kernel: [71518.961992]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:24:33 scenic kernel: [71518.962001]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:24:33 scenic kernel: [71518.962037]  [<ffffffffa02261e3>] txg_wait_synced+0xb3/0x190 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962067]  [<ffffffffa020ad7e>] dsl_sync_task_group_wait+0x14e/0x270 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962092]  [<ffffffffa01f6b70>] ? snaplist_destroy+0x100/0x100 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962116]  [<ffffffffa01fa3a0>] ? dsl_dataset_create_sync+0x280/0x280 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962143]  [<ffffffffa020b077>] dsl_sync_task_do+0x57/0x80 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962167]  [<ffffffffa01fc212>] dsl_dataset_destroy+0xb2/0x490 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962190]  [<ffffffffa01fb2fe>] ? dsl_dataset_tryown+0x4e/0x140 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962212]  [<ffffffffa01e46cb>] dmu_objset_destroy+0x3b/0x50 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962244]  [<ffffffffa0249168>] zfs_ioc_destroy+0x38/0x80 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962274]  [<ffffffffa024d35c>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Jul 27 08:24:33 scenic kernel: [71518.962279]  [<ffffffff8118a06a>] do_vfs_ioctl+0x8a/0x340
Jul 27 08:24:33 scenic kernel: [71518.962284]  [<ffffffff81142cf3>] ? do_munmap+0x1f3/0x2f0
Jul 27 08:24:33 scenic kernel: [71518.962288]  [<ffffffff8118a3b1>] sys_ioctl+0x91/0xa0
Jul 27 08:24:33 scenic kernel: [71518.962292]  [<ffffffff81661fc2>] system_call_fastpath+0x16/0x1b
Jul 27 08:26:33 scenic kernel: [71638.958837] INFO: task zfs:27489 blocked for more than 120 seconds.
Jul 27 08:26:33 scenic kernel: [71638.958841] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:26:33 scenic kernel: [71638.958844] zfs             D ffffffff81806080     0 27489  27488 0x00000000
Jul 27 08:26:33 scenic kernel: [71638.958850]  ffff88015cde1c38 0000000000000086 0000000000000020 0000000000000046
Jul 27 08:26:33 scenic kernel: [71638.958856]  ffff88015cde1fd8 ffff88015cde1fd8 ffff88015cde1fd8 0000000000013780
Jul 27 08:26:33 scenic kernel: [71638.958861]  ffff8802109c16f0 ffff8801c416c4d0 ffff88015cde1c18 ffff88020eee2258
Jul 27 08:26:33 scenic kernel: [71638.958866] Call Trace:
Jul 27 08:26:33 scenic kernel: [71638.958875]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:26:33 scenic kernel: [71638.958894]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:26:33 scenic kernel: [71638.958900]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:26:33 scenic kernel: [71638.958909]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:26:33 scenic kernel: [71638.958946]  [<ffffffffa02261e3>] txg_wait_synced+0xb3/0x190 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.958975]  [<ffffffffa020ad7e>] dsl_sync_task_group_wait+0x14e/0x270 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.959001]  [<ffffffffa01f6b70>] ? snaplist_destroy+0x100/0x100 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.959025]  [<ffffffffa01fa3a0>] ? dsl_dataset_create_sync+0x280/0x280 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.959051]  [<ffffffffa020b077>] dsl_sync_task_do+0x57/0x80 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.959076]  [<ffffffffa01fc212>] dsl_dataset_destroy+0xb2/0x490 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.959099]  [<ffffffffa01fb2fe>] ? dsl_dataset_tryown+0x4e/0x140 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.959121]  [<ffffffffa01e46cb>] dmu_objset_destroy+0x3b/0x50 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.959152]  [<ffffffffa0249168>] zfs_ioc_destroy+0x38/0x80 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.959182]  [<ffffffffa024d35c>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Jul 27 08:26:33 scenic kernel: [71638.959187]  [<ffffffff8118a06a>] do_vfs_ioctl+0x8a/0x340
Jul 27 08:26:33 scenic kernel: [71638.959192]  [<ffffffff81142cf3>] ? do_munmap+0x1f3/0x2f0
Jul 27 08:26:33 scenic kernel: [71638.959196]  [<ffffffff8118a3b1>] sys_ioctl+0x91/0xa0
Jul 27 08:26:33 scenic kernel: [71638.959201]  [<ffffffff81661fc2>] system_call_fastpath+0x16/0x1b
Jul 27 08:28:33 scenic kernel: [71758.955757] INFO: task zfs:27489 blocked for more than 120 seconds.
Jul 27 08:28:33 scenic kernel: [71758.955762] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:28:33 scenic kernel: [71758.955765] zfs             D ffffffff81806080     0 27489  27488 0x00000000
Jul 27 08:28:33 scenic kernel: [71758.955771]  ffff88015cde1c38 0000000000000086 0000000000000020 0000000000000046
Jul 27 08:28:33 scenic kernel: [71758.955777]  ffff88015cde1fd8 ffff88015cde1fd8 ffff88015cde1fd8 0000000000013780
Jul 27 08:28:33 scenic kernel: [71758.955782]  ffff8802109c16f0 ffff8801c416c4d0 ffff88015cde1c18 ffff88020eee2258
Jul 27 08:28:33 scenic kernel: [71758.955787] Call Trace:
Jul 27 08:28:33 scenic kernel: [71758.955796]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:28:33 scenic kernel: [71758.955815]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:28:33 scenic kernel: [71758.955820]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:28:33 scenic kernel: [71758.955830]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:28:33 scenic kernel: [71758.955867]  [<ffffffffa02261e3>] txg_wait_synced+0xb3/0x190 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.955896]  [<ffffffffa020ad7e>] dsl_sync_task_group_wait+0x14e/0x270 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.955922]  [<ffffffffa01f6b70>] ? snaplist_destroy+0x100/0x100 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.955946]  [<ffffffffa01fa3a0>] ? dsl_dataset_create_sync+0x280/0x280 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.955972]  [<ffffffffa020b077>] dsl_sync_task_do+0x57/0x80 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.955996]  [<ffffffffa01fc212>] dsl_dataset_destroy+0xb2/0x490 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.956019]  [<ffffffffa01fb2fe>] ? dsl_dataset_tryown+0x4e/0x140 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.956041]  [<ffffffffa01e46cb>] dmu_objset_destroy+0x3b/0x50 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.956073]  [<ffffffffa0249168>] zfs_ioc_destroy+0x38/0x80 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.956102]  [<ffffffffa024d35c>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Jul 27 08:28:33 scenic kernel: [71758.956107]  [<ffffffff8118a06a>] do_vfs_ioctl+0x8a/0x340
Jul 27 08:28:33 scenic kernel: [71758.956112]  [<ffffffff81142cf3>] ? do_munmap+0x1f3/0x2f0
Jul 27 08:28:33 scenic kernel: [71758.956116]  [<ffffffff8118a3b1>] sys_ioctl+0x91/0xa0
Jul 27 08:28:33 scenic kernel: [71758.956120]  [<ffffffff81661fc2>] system_call_fastpath+0x16/0x1b
Jul 27 08:30:33 scenic kernel: [71878.952670] INFO: task zfs:27489 blocked for more than 120 seconds.
Jul 27 08:30:33 scenic kernel: [71878.952675] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:30:33 scenic kernel: [71878.952678] zfs             D ffffffff81806080     0 27489  27488 0x00000000
Jul 27 08:30:33 scenic kernel: [71878.952684]  ffff88015cde1c38 0000000000000086 0000000000000020 0000000000000046
Jul 27 08:30:33 scenic kernel: [71878.952690]  ffff88015cde1fd8 ffff88015cde1fd8 ffff88015cde1fd8 0000000000013780
Jul 27 08:30:33 scenic kernel: [71878.952694]  ffff8802109c16f0 ffff8801c416c4d0 ffff88015cde1c18 ffff88020eee2258
Jul 27 08:30:33 scenic kernel: [71878.952699] Call Trace:
Jul 27 08:30:33 scenic kernel: [71878.952709]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:30:33 scenic kernel: [71878.952728]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:30:33 scenic kernel: [71878.952733]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:30:33 scenic kernel: [71878.952743]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:30:33 scenic kernel: [71878.952780]  [<ffffffffa02261e3>] txg_wait_synced+0xb3/0x190 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.952810]  [<ffffffffa020ad7e>] dsl_sync_task_group_wait+0x14e/0x270 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.952835]  [<ffffffffa01f6b70>] ? snaplist_destroy+0x100/0x100 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.952859]  [<ffffffffa01fa3a0>] ? dsl_dataset_create_sync+0x280/0x280 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.952885]  [<ffffffffa020b077>] dsl_sync_task_do+0x57/0x80 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.952909]  [<ffffffffa01fc212>] dsl_dataset_destroy+0xb2/0x490 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.952932]  [<ffffffffa01fb2fe>] ? dsl_dataset_tryown+0x4e/0x140 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.952954]  [<ffffffffa01e46cb>] dmu_objset_destroy+0x3b/0x50 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.952985]  [<ffffffffa0249168>] zfs_ioc_destroy+0x38/0x80 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.953015]  [<ffffffffa024d35c>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Jul 27 08:30:33 scenic kernel: [71878.953020]  [<ffffffff8118a06a>] do_vfs_ioctl+0x8a/0x340
Jul 27 08:30:33 scenic kernel: [71878.953025]  [<ffffffff81142cf3>] ? do_munmap+0x1f3/0x2f0
Jul 27 08:30:33 scenic kernel: [71878.953029]  [<ffffffff8118a3b1>] sys_ioctl+0x91/0xa0
Jul 27 08:30:33 scenic kernel: [71878.953033]  [<ffffffff81661fc2>] system_call_fastpath+0x16/0x1b
Jul 27 08:34:33 scenic kernel: [72118.946497] INFO: task zfs:27538 blocked for more than 120 seconds.
Jul 27 08:34:33 scenic kernel: [72118.946502] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:34:33 scenic kernel: [72118.946505] zfs             D ffffffff81806080     0 27538  27537 0x00000000
Jul 27 08:34:33 scenic kernel: [72118.946511]  ffff88009f643c38 0000000000000082 0000000000000020 0000000000000046
Jul 27 08:34:33 scenic kernel: [72118.946517]  ffff88009f643fd8 ffff88009f643fd8 ffff88009f643fd8 0000000000013780
Jul 27 08:34:33 scenic kernel: [72118.946521]  ffff8802109896f0 ffff880040cddbc0 ffff88009f643c18 ffff88020eee2258
Jul 27 08:34:33 scenic kernel: [72118.946526] Call Trace:
Jul 27 08:34:33 scenic kernel: [72118.946536]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:34:33 scenic kernel: [72118.946555]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:34:33 scenic kernel: [72118.946561]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:34:33 scenic kernel: [72118.946570]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:34:33 scenic kernel: [72118.946608]  [<ffffffffa02261e3>] txg_wait_synced+0xb3/0x190 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946638]  [<ffffffffa020ad7e>] dsl_sync_task_group_wait+0x14e/0x270 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946663]  [<ffffffffa01f6b70>] ? snaplist_destroy+0x100/0x100 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946687]  [<ffffffffa01fa3a0>] ? dsl_dataset_create_sync+0x280/0x280 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946713]  [<ffffffffa020b077>] dsl_sync_task_do+0x57/0x80 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946738]  [<ffffffffa01fc212>] dsl_dataset_destroy+0xb2/0x490 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946760]  [<ffffffffa01fb2fe>] ? dsl_dataset_tryown+0x4e/0x140 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946783]  [<ffffffffa01e46cb>] dmu_objset_destroy+0x3b/0x50 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946815]  [<ffffffffa0249168>] zfs_ioc_destroy+0x38/0x80 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946844]  [<ffffffffa024d35c>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Jul 27 08:34:33 scenic kernel: [72118.946849]  [<ffffffff8118a06a>] do_vfs_ioctl+0x8a/0x340
Jul 27 08:34:33 scenic kernel: [72118.946854]  [<ffffffff81142cf3>] ? do_munmap+0x1f3/0x2f0
Jul 27 08:34:33 scenic kernel: [72118.946858]  [<ffffffff8118a3b1>] sys_ioctl+0x91/0xa0
Jul 27 08:34:33 scenic kernel: [72118.946862]  [<ffffffff81661fc2>] system_call_fastpath+0x16/0x1b
Jul 27 08:40:33 scenic kernel: [72478.937232] INFO: task txg_sync:4413 blocked for more than 120 seconds.
Jul 27 08:40:33 scenic kernel: [72478.937236] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:40:33 scenic kernel: [72478.937240] txg_sync        D ffffffff81806080     0  4413      2 0x00000000
Jul 27 08:40:33 scenic kernel: [72478.937245]  ffff8801c7d27b90 0000000000000046 ffff8801c7d27b30 ffffffff8105fb02
Jul 27 08:40:33 scenic kernel: [72478.937251]  ffff8801c7d27fd8 ffff8801c7d27fd8 ffff8801c7d27fd8 0000000000013780
Jul 27 08:40:33 scenic kernel: [72478.937256]  ffff8802109896f0 ffff8801ec6dade0 ffff8801c7d27ba0 ffff880099f9ae08
Jul 27 08:40:33 scenic kernel: [72478.937261] Call Trace:
Jul 27 08:40:33 scenic kernel: [72478.937271]  [<ffffffff8105fb02>] ? default_wake_function+0x12/0x20
Jul 27 08:40:33 scenic kernel: [72478.937277]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:40:33 scenic kernel: [72478.937295]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:40:33 scenic kernel: [72478.937301]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:40:33 scenic kernel: [72478.937310]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:40:33 scenic kernel: [72478.937348]  [<ffffffffa0269a6b>] zio_wait+0xfb/0x170 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937377]  [<ffffffffa020a4f1>] dsl_scan_sync+0x481/0x8e0 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937381]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:40:33 scenic kernel: [72478.937410]  [<ffffffffa0216e91>] spa_sync+0x3f1/0xa00 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937415]  [<ffffffff816599ce>] ? _raw_spin_lock+0xe/0x20
Jul 27 08:40:33 scenic kernel: [72478.937446]  [<ffffffffa0226ef6>] txg_sync_thread+0x286/0x450 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937476]  [<ffffffffa0226c70>] ? txg_init+0x250/0x250 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937486]  [<ffffffffa01300b8>] thread_generic_wrapper+0x78/0x90 [spl]
Jul 27 08:40:33 scenic kernel: [72478.937494]  [<ffffffffa0130040>] ? __thread_create+0x310/0x310 [spl]
Jul 27 08:40:33 scenic kernel: [72478.937498]  [<ffffffff8108a59c>] kthread+0x8c/0xa0
Jul 27 08:40:33 scenic kernel: [72478.937503]  [<ffffffff81664134>] kernel_thread_helper+0x4/0x10
Jul 27 08:40:33 scenic kernel: [72478.937507]  [<ffffffff8108a510>] ? flush_kthread_worker+0xa0/0xa0
Jul 27 08:40:33 scenic kernel: [72478.937511]  [<ffffffff81664130>] ? gs_change+0x13/0x13
Jul 27 08:40:33 scenic kernel: [72478.937522] INFO: task zfs:27765 blocked for more than 120 seconds.
Jul 27 08:40:33 scenic kernel: [72478.937524] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:40:33 scenic kernel: [72478.937527] zfs             D ffffffff81806080     0 27765  27764 0x00000000
Jul 27 08:40:33 scenic kernel: [72478.937531]  ffff880125f7fc38 0000000000000082 ffff880125f7fc78 0000000000000046
Jul 27 08:40:33 scenic kernel: [72478.937536]  ffff880125f7ffd8 ffff880125f7ffd8 ffff880125f7ffd8 0000000000013780
Jul 27 08:40:33 scenic kernel: [72478.937541]  ffffffff81c0d020 ffff880040cd96f0 ffff880125f7fc18 ffff88020eee2258
Jul 27 08:40:33 scenic kernel: [72478.937546] Call Trace:
Jul 27 08:40:33 scenic kernel: [72478.937550]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:40:33 scenic kernel: [72478.937560]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:40:33 scenic kernel: [72478.937564]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:40:33 scenic kernel: [72478.937573]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:40:33 scenic kernel: [72478.937602]  [<ffffffffa02261e3>] txg_wait_synced+0xb3/0x190 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937630]  [<ffffffffa020ad7e>] dsl_sync_task_group_wait+0x14e/0x270 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937654]  [<ffffffffa01f6b70>] ? snaplist_destroy+0x100/0x100 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937679]  [<ffffffffa01fa3a0>] ? dsl_dataset_create_sync+0x280/0x280 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937705]  [<ffffffffa020b077>] dsl_sync_task_do+0x57/0x80 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937730]  [<ffffffffa01fc212>] dsl_dataset_destroy+0xb2/0x490 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937753]  [<ffffffffa01fb2fe>] ? dsl_dataset_tryown+0x4e/0x140 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937775]  [<ffffffffa01e46cb>] dmu_objset_destroy+0x3b/0x50 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937806]  [<ffffffffa0249168>] zfs_ioc_destroy+0x38/0x80 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937835]  [<ffffffffa024d35c>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Jul 27 08:40:33 scenic kernel: [72478.937840]  [<ffffffff8118a06a>] do_vfs_ioctl+0x8a/0x340
Jul 27 08:40:33 scenic kernel: [72478.937845]  [<ffffffff81142cf3>] ? do_munmap+0x1f3/0x2f0
Jul 27 08:40:33 scenic kernel: [72478.937849]  [<ffffffff8118a3b1>] sys_ioctl+0x91/0xa0
Jul 27 08:40:33 scenic kernel: [72478.937853]  [<ffffffff81661fc2>] system_call_fastpath+0x16/0x1b
Jul 27 08:42:33 scenic kernel: [72598.934145] INFO: task txg_sync:4413 blocked for more than 120 seconds.
Jul 27 08:42:33 scenic kernel: [72598.934149] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:42:33 scenic kernel: [72598.934153] txg_sync        D ffffffff81806080     0  4413      2 0x00000000
Jul 27 08:42:33 scenic kernel: [72598.934158]  ffff8801c7d27b90 0000000000000046 ffff8801c7d27b30 ffffffff8105fb02
Jul 27 08:42:33 scenic kernel: [72598.934164]  ffff8801c7d27fd8 ffff8801c7d27fd8 ffff8801c7d27fd8 0000000000013780
Jul 27 08:42:33 scenic kernel: [72598.934169]  ffff8802109896f0 ffff8801ec6dade0 ffff8801c7d27ba0 ffff880099f9ae08
Jul 27 08:42:33 scenic kernel: [72598.934174] Call Trace:
Jul 27 08:42:33 scenic kernel: [72598.934183]  [<ffffffff8105fb02>] ? default_wake_function+0x12/0x20
Jul 27 08:42:33 scenic kernel: [72598.934190]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:42:33 scenic kernel: [72598.934208]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:42:33 scenic kernel: [72598.934213]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:42:33 scenic kernel: [72598.934222]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:42:33 scenic kernel: [72598.934259]  [<ffffffffa0269a6b>] zio_wait+0xfb/0x170 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934288]  [<ffffffffa020a4f1>] dsl_scan_sync+0x481/0x8e0 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934292]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:42:33 scenic kernel: [72598.934322]  [<ffffffffa0216e91>] spa_sync+0x3f1/0xa00 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934326]  [<ffffffff816599ce>] ? _raw_spin_lock+0xe/0x20
Jul 27 08:42:33 scenic kernel: [72598.934357]  [<ffffffffa0226ef6>] txg_sync_thread+0x286/0x450 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934387]  [<ffffffffa0226c70>] ? txg_init+0x250/0x250 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934396]  [<ffffffffa01300b8>] thread_generic_wrapper+0x78/0x90 [spl]
Jul 27 08:42:33 scenic kernel: [72598.934405]  [<ffffffffa0130040>] ? __thread_create+0x310/0x310 [spl]
Jul 27 08:42:33 scenic kernel: [72598.934409]  [<ffffffff8108a59c>] kthread+0x8c/0xa0
Jul 27 08:42:33 scenic kernel: [72598.934414]  [<ffffffff81664134>] kernel_thread_helper+0x4/0x10
Jul 27 08:42:33 scenic kernel: [72598.934418]  [<ffffffff8108a510>] ? flush_kthread_worker+0xa0/0xa0
Jul 27 08:42:33 scenic kernel: [72598.934422]  [<ffffffff81664130>] ? gs_change+0x13/0x13
Jul 27 08:42:33 scenic kernel: [72598.934433] INFO: task zfs:27765 blocked for more than 120 seconds.
Jul 27 08:42:33 scenic kernel: [72598.934435] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Jul 27 08:42:33 scenic kernel: [72598.934438] zfs             D ffffffff81806080     0 27765  27764 0x00000000
Jul 27 08:42:33 scenic kernel: [72598.934442]  ffff880125f7fc38 0000000000000082 ffff880125f7fc78 0000000000000046
Jul 27 08:42:33 scenic kernel: [72598.934447]  ffff880125f7ffd8 ffff880125f7ffd8 ffff880125f7ffd8 0000000000013780
Jul 27 08:42:33 scenic kernel: [72598.934452]  ffffffff81c0d020 ffff880040cd96f0 ffff880125f7fc18 ffff88020eee2258
Jul 27 08:42:33 scenic kernel: [72598.934457] Call Trace:
Jul 27 08:42:33 scenic kernel: [72598.934461]  [<ffffffff81657abf>] schedule+0x3f/0x60
Jul 27 08:42:33 scenic kernel: [72598.934471]  [<ffffffffa0136ae8>] cv_wait_common+0x98/0x190 [spl]
Jul 27 08:42:33 scenic kernel: [72598.934475]  [<ffffffff8108b030>] ? add_wait_queue+0x60/0x60
Jul 27 08:42:33 scenic kernel: [72598.934484]  [<ffffffffa0136c13>] __cv_wait+0x13/0x20 [spl]
Jul 27 08:42:33 scenic kernel: [72598.934513]  [<ffffffffa02261e3>] txg_wait_synced+0xb3/0x190 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934541]  [<ffffffffa020ad7e>] dsl_sync_task_group_wait+0x14e/0x270 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934565]  [<ffffffffa01f6b70>] ? snaplist_destroy+0x100/0x100 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934589]  [<ffffffffa01fa3a0>] ? dsl_dataset_create_sync+0x280/0x280 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934615]  [<ffffffffa020b077>] dsl_sync_task_do+0x57/0x80 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934640]  [<ffffffffa01fc212>] dsl_dataset_destroy+0xb2/0x490 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934663]  [<ffffffffa01fb2fe>] ? dsl_dataset_tryown+0x4e/0x140 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934685]  [<ffffffffa01e46cb>] dmu_objset_destroy+0x3b/0x50 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934716]  [<ffffffffa0249168>] zfs_ioc_destroy+0x38/0x80 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934745]  [<ffffffffa024d35c>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Jul 27 08:42:33 scenic kernel: [72598.934750]  [<ffffffff8118a06a>] do_vfs_ioctl+0x8a/0x340
Jul 27 08:42:33 scenic kernel: [72598.934754]  [<ffffffff81142cf3>] ? do_munmap+0x1f3/0x2f0
Jul 27 08:42:33 scenic kernel: [72598.934758]  [<ffffffff8118a3b1>] sys_ioctl+0x91/0xa0
Jul 27 08:42:33 scenic kernel: [72598.934762]  [<ffffffff81661fc2>] system_call_fastpath+0x16/0x1b
Jul 27 12:30:48 scenic kernel: [86083.370769] oom_kill_process: 21 callbacks suppressed
Jul 27 12:30:48 scenic kernel: [86083.370772] sh invoked oom-killer: gfp_mask=0x200da, order=0, oom_adj=0, oom_score_adj=0
Jul 27 12:30:48 scenic kernel: [86083.370774] sh cpuset=/ mems_allowed=0
Jul 27 12:30:48 scenic kernel: [86083.370777] Pid: 3644, comm: sh Tainted: P         C O 3.2.0-26-generic #41-Ubuntu
Jul 27 12:30:48 scenic kernel: [86083.370779] Call Trace:
Jul 27 12:30:48 scenic kernel: [86083.370786]  [<ffffffff810bffdd>] ? cpuset_print_task_mems_allowed+0x9d/0xb0
Jul 27 12:30:48 scenic kernel: [86083.370790]  [<ffffffff8111adc1>] dump_header+0x91/0xe0
Jul 27 12:30:48 scenic kernel: [86083.370793]  [<ffffffff8111b145>] oom_kill_process+0x85/0xb0
Jul 27 12:30:48 scenic kernel: [86083.370795]  [<ffffffff8111b4ea>] out_of_memory+0xfa/0x220
Jul 27 12:30:48 scenic kernel: [86083.370798]  [<ffffffff81120f6f>] __alloc_pages_nodemask+0x80f/0x820
Jul 27 12:30:48 scenic kernel: [86083.370803]  [<ffffffff8115980a>] alloc_pages_vma+0x9a/0x150
Jul 27 12:30:48 scenic kernel: [86083.370806]  [<ffffffff8113a512>] __do_fault+0x362/0x550
Jul 27 12:30:48 scenic kernel: [86083.370808]  [<ffffffff8113d86a>] handle_pte_fault+0xfa/0x200
Jul 27 12:30:48 scenic kernel: [86083.370811]  [<ffffffff8113dd28>] handle_mm_fault+0x1f8/0x350
Jul 27 12:30:48 scenic kernel: [86083.370815]  [<ffffffff8165d4e0>] do_page_fault+0x150/0x520
Jul 27 12:30:48 scenic kernel: [86083.370818]  [<ffffffff81143bf8>] ? do_mmap_pgoff+0x348/0x360
Jul 27 12:30:48 scenic kernel: [86083.370821]  [<ffffffff81143cf1>] ? sys_mmap_pgoff+0xe1/0x230
Jul 27 12:30:48 scenic kernel: [86083.370823]  [<ffffffff81145610>] ? sys_mprotect+0x1f0/0x250
Jul 27 12:30:48 scenic kernel: [86083.370826]  [<ffffffff8165a135>] page_fault+0x25/0x30
Jul 27 12:30:48 scenic kernel: [86083.370828] Mem-Info:
Jul 27 12:30:48 scenic kernel: [86083.370829] Node 0 DMA per-cpu:
Jul 27 12:30:48 scenic kernel: [86083.370831] CPU    0: hi:    0, btch:   1 usd:   0
Jul 27 12:30:48 scenic kernel: [86083.370833] CPU    1: hi:    0, btch:   1 usd:   0
Jul 27 12:30:48 scenic kernel: [86083.370834] CPU    2: hi:    0, btch:   1 usd:   0
Jul 27 12:30:48 scenic kernel: [86083.370836] CPU    3: hi:    0, btch:   1 usd:   0
Jul 27 12:30:48 scenic kernel: [86083.370837] Node 0 DMA32 per-cpu:
Jul 27 12:30:48 scenic kernel: [86083.370839] CPU    0: hi:  186, btch:  31 usd:  55
Jul 27 12:30:48 scenic kernel: [86083.370840] CPU    1: hi:  186, btch:  31 usd:  59
Jul 27 12:30:48 scenic kernel: [86083.370842] CPU    2: hi:  186, btch:  31 usd:  12
Jul 27 12:30:48 scenic kernel: [86083.370843] CPU    3: hi:  186, btch:  31 usd:  16
Jul 27 12:30:48 scenic kernel: [86083.370845] Node 0 Normal per-cpu:
Jul 27 12:30:48 scenic kernel: [86083.370846] CPU    0: hi:  186, btch:  31 usd: 178
Jul 27 12:30:48 scenic kernel: [86083.370848] CPU    1: hi:  186, btch:  31 usd:   6
Jul 27 12:30:48 scenic kernel: [86083.370849] CPU    2: hi:  186, btch:  31 usd:  71
Jul 27 12:30:48 scenic kernel: [86083.370851] CPU    3: hi:  186, btch:  31 usd:   0
Jul 27 12:30:48 scenic kernel: [86083.370854] active_anon:46785 inactive_anon:9156 isolated_anon:32
Jul 27 12:30:48 scenic kernel: [86083.370855]  active_file:433 inactive_file:943 isolated_file:0
Jul 27 12:30:48 scenic kernel: [86083.370856]  unevictable:0 dirty:0 writeback:18 unstable:0
Jul 27 12:30:48 scenic kernel: [86083.370856]  free:71817 slab_reclaimable:4022 slab_unreclaimable:167874
Jul 27 12:30:48 scenic kernel: [86083.370857]  mapped:612 shmem:9193 pagetables:6791 bounce:0
Jul 27 12:30:48 scenic kernel: [86083.370859] Node 0 DMA free:15896kB min:504kB low:628kB high:756kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15648kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB slab_unreclaimable:8kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
Jul 27 12:30:48 scenic kernel: [86083.370866] lowmem_reserve[]: 0 3411 7921 7921
Jul 27 12:30:48 scenic kernel: [86083.370869] Node 0 DMA32 free:130792kB min:112668kB low:140832kB high:169000kB active_anon:136kB inactive_anon:276kB active_file:188kB inactive_file:1520kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:3493124kB mlocked:0kB dirty:0kB writeback:36kB mapped:384kB shmem:132kB slab_reclaimable:1276kB slab_unreclaimable:258176kB kernel_stack:912kB pagetables:3604kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:1811 all_unreclaimable? yes
Jul 27 12:30:48 scenic kernel: [86083.370877] lowmem_reserve[]: 0 0 4510 4510
Jul 27 12:30:48 scenic kernel: [86083.370880] Node 0 Normal free:140580kB min:148968kB low:186208kB high:223452kB active_anon:187004kB inactive_anon:36348kB active_file:1544kB inactive_file:2252kB unevictable:0kB isolated(anon):128kB isolated(file):0kB present:4618656kB mlocked:0kB dirty:0kB writeback:36kB mapped:2064kB shmem:36640kB slab_reclaimable:14812kB slab_unreclaimable:413312kB kernel_stack:4344kB pagetables:23560kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:822458 all_unreclaimable? yes
Jul 27 12:30:48 scenic kernel: [86083.370888] lowmem_reserve[]: 0 0 0 0
Jul 27 12:30:48 scenic kernel: [86083.370890] Node 0 DMA: 0*4kB 1*8kB 1*16kB 0*32kB 2*64kB 1*128kB 1*256kB 0*512kB 1*1024kB 1*2048kB 3*4096kB = 15896kB
Jul 27 12:30:48 scenic kernel: [86083.370898] Node 0 DMA32: 2369*4kB 35*8kB 141*16kB 114*32kB 89*64kB 48*128kB 25*256kB 21*512kB 16*1024kB 24*2048kB 5*4096kB = 130668kB
Jul 27 12:30:48 scenic kernel: [86083.370905] Node 0 Normal: 1417*4kB 3361*8kB 4612*16kB 642*32kB 74*64kB 28*128kB 5*256kB 0*512kB 0*1024kB 0*2048kB 1*4096kB = 140588kB
Jul 27 12:30:48 scenic kernel: [86083.370912] 15851 total pagecache pages
Jul 27 12:30:48 scenic kernel: [86083.370913] 5276 pages in swap cache
Jul 27 12:30:48 scenic kernel: [86083.370915] Swap cache stats: add 481084, delete 475808, find 138859/212034
Jul 27 12:30:48 scenic kernel: [86083.370916] Free swap  = 15327564kB
Jul 27 12:30:48 scenic kernel: [86083.370917] Total swap = 15627708kB
Jul 27 12:30:48 scenic kernel: [86083.389611] 2090480 pages RAM
Jul 27 12:30:48 scenic kernel: [86083.389613] 76328 pages reserved
Jul 27 12:30:48 scenic kernel: [86083.389614] 24972 pages shared
Jul 27 12:30:48 scenic kernel: [86083.389615] 1154384 pages non-shared
Jul 27 12:30:48 scenic kernel: [86083.389617] [ pid ]   uid  tgid total_vm      rss cpu oom_adj oom_score_adj name
Jul 27 12:30:48 scenic kernel: [86083.389628] [  861]     0   861     4306        0   1       0             0 upstart-udev-br
Jul 27 12:30:48 scenic kernel: [86083.389631] [  864]     0   864     5455        1   1     -17         -1000 udevd
Jul 27 12:30:48 scenic kernel: [86083.389634] [ 1117]     0  1117     3795        0   3       0             0 upstart-socket-
Jul 27 12:30:48 scenic kernel: [86083.389638] [ 1338]     0  1338    30597       62   0       0             0 smbd
Jul 27 12:30:48 scenic kernel: [86083.389640] [ 1349]     0  1349    30623       40   0       0             0 smbd
Jul 27 12:30:48 scenic kernel: [86083.389643] [ 1355]   101  1355    62366       58   1       0             0 rsyslogd
Jul 27 12:30:48 scenic kernel: [86083.389646] [ 1360]   103  1360     6211      188   0       0             0 dbus-daemon
Jul 27 12:30:48 scenic kernel: [86083.389648] [ 1370]     0  1370     5329        6   3       0             0 bluetoothd
Jul 27 12:30:48 scenic kernel: [86083.389651] [ 1401]     0  1401    26022       79   0       0             0 cupsd
Jul 27 12:30:48 scenic kernel: [86083.389654] [ 1530]     0  1530     1814       33   0       0             0 dhclient3
Jul 27 12:30:48 scenic kernel: [86083.389656] [ 1577]     0  1577    12487        8   3     -17         -1000 sshd
Jul 27 12:30:48 scenic kernel: [86083.389659] [ 1621]     0  1621    22834       94   1       0             0 nmbd
Jul 27 12:30:48 scenic kernel: [86083.389662] [ 1644]     0  1644    19759       65   0       0             0 modem-manager
Jul 27 12:30:48 scenic kernel: [86083.389664] [ 1652]     0  1652    41840      188   0       0             0 NetworkManager
Jul 27 12:30:48 scenic kernel: [86083.389667] [ 1660]     0  1660    48940      306   2       0             0 polkitd
Jul 27 12:30:48 scenic kernel: [86083.389669] [ 1718]     0  1718     3944        1   0       0             0 getty
Jul 27 12:30:48 scenic kernel: [86083.389672] [ 1725]     0  1725     3944        1   1       0             0 getty
Jul 27 12:30:48 scenic kernel: [86083.389675] [ 1740]     0  1740     3944        1   0       0             0 getty
Jul 27 12:30:48 scenic kernel: [86083.389677] [ 1743]     0  1743     3944        1   0       0             0 getty
Jul 27 12:30:48 scenic kernel: [86083.389679] [ 1750]     0  1750     3944        1   0       0             0 getty
Jul 27 12:30:48 scenic kernel: [86083.389682] [ 1765]     0  1765     4356        4   0       0             0 dovecot
Jul 27 12:30:48 scenic kernel: [86083.389684] [ 1771]     0  1771     1113        0   1       0             0 acpid
Jul 27 12:30:48 scenic kernel: [86083.389687] [ 1772]   104  1772    50530        0   0       0             0 whoopsie
Jul 27 12:30:48 scenic kernel: [86083.389690] [ 1776]     0  1776     3993       25   1       0             0 irqbalance
Jul 27 12:30:48 scenic kernel: [86083.389692] [ 1782]   102  1782   121067      298   0       0             0 mysqld
Jul 27 12:30:48 scenic kernel: [86083.389694] [ 1788]     0  1788     4776       22   3       0             0 cron
Jul 27 12:30:48 scenic kernel: [86083.389697] [ 1789]     0  1789     4225        0   0       0             0 atd
Jul 27 12:30:48 scenic kernel: [86083.389700] [ 1865]     0  1865    67664        0   0       0             0 lightdm
Jul 27 12:30:48 scenic kernel: [86083.389702] [ 1880]   106  1880     2236        0   2       0             0 anvil
Jul 27 12:30:48 scenic kernel: [86083.389704] [ 1881]     0  1881     2267        0   2       0             0 log
Jul 27 12:30:48 scenic kernel: [86083.389707] [ 1920]     0  1920    35919      977   3       0             0 Xorg
Jul 27 12:30:48 scenic kernel: [86083.389710] [ 1936]     0  1936    16283      582   3       0             0 python
Jul 27 12:30:48 scenic kernel: [86083.389712] [ 2055]     0  2055    30386       76   1       0             0 accounts-daemon
Jul 27 12:30:48 scenic kernel: [86083.389715] [ 2059]     0  2059     6274       13   0       0             0 master
Jul 27 12:30:48 scenic kernel: [86083.389717] [ 2092]     0  2092    25927       40   0       0             0 winbindd
Jul 27 12:30:48 scenic kernel: [86083.389720] [ 2110]     0  2110     3337        0   2       0             0 mdadm
Jul 27 12:30:48 scenic kernel: [86083.389723] [ 2118]     0  2118    25910       44   3       0             0 winbindd
Jul 27 12:30:48 scenic kernel: [86083.389725] [ 2154]     0  2154   522906        0   2       0             0 console-kit-dae
Jul 27 12:30:48 scenic kernel: [86083.389728] [ 2227]     0  2227    48351       10   2       0             0 apache2
Jul 27 12:30:48 scenic kernel: [86083.389731] [ 2233]    33  2233    48471       24   0       0             0 apache2
Jul 27 12:30:48 scenic kernel: [86083.389733] [ 2234]    33  2234    48369       85   0       0             0 apache2
Jul 27 12:30:48 scenic kernel: [86083.389735] [ 2235]    33  2235    48357        1   0       0             0 apache2
Jul 27 12:30:48 scenic kernel: [86083.389738] [ 2237]    33  2237    48357        1   0       0             0 apache2
Jul 27 12:30:48 scenic kernel: [86083.389741] [ 2238]    33  2238    48357        1   2       0             0 apache2
Jul 27 12:30:48 scenic kernel: [86083.389743] [ 2393]     0  2393     3944        1   3       0             0 getty
Jul 27 12:30:48 scenic kernel: [86083.389745] [ 2406]     0  2406    54972       70   0       0             0 upowerd
Jul 27 12:30:48 scenic kernel: [86083.389748] [ 2785]     0  2785    54492        0   1       0             0 lightdm
Jul 27 12:30:48 scenic kernel: [86083.389750] [ 3190]     0  3190    25927        0   0       0             0 winbindd
Jul 27 12:30:48 scenic kernel: [86083.389753] [ 3215]  1000  3215     1098        1   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389756] [ 3247]  1000  3247     3121       11   1       0             0 ssh-agent
Jul 27 12:30:48 scenic kernel: [86083.389758] [ 3250]  1000  3250     6637        0   1       0             0 dbus-launch
Jul 27 12:30:48 scenic kernel: [86083.389761] [ 3251]  1000  3251     6404      203   1       0             0 dbus-daemon
Jul 27 12:30:48 scenic kernel: [86083.389763] [ 3259]  1000  3259    11900       61   2       0             0 xfconfd
Jul 27 12:30:48 scenic kernel: [86083.389766] [ 3264]  1000  3264    14921       44   1       0             0 xscreensaver
Jul 27 12:30:48 scenic kernel: [86083.389769] [ 3266]  1000  3266    39456       78   1       0             0 xfce4-session
Jul 27 12:30:48 scenic kernel: [86083.389771] [ 3273]  1000  3273    38909      147   2       0             0 xfwm4
Jul 27 12:30:48 scenic kernel: [86083.389774] [ 3275]  1000  3275    73856      343   1       0             0 xfce4-panel
Jul 27 12:30:48 scenic kernel: [86083.389776] [ 3277]  1000  3277    56366       51   3       0             0 Thunar
Jul 27 12:30:48 scenic kernel: [86083.389778] [ 3279]  1000  3279    78298      163   1       0             0 xfdesktop
Jul 27 12:30:48 scenic kernel: [86083.389781] [ 3282]  1000  3282    31891       61   1       0             0 xfsettingsd
Jul 27 12:30:48 scenic kernel: [86083.389784] [ 3284]  1000  3284    12043        0   3       0             0 gvfsd
Jul 27 12:30:48 scenic kernel: [86083.389786] [ 3286]  1000  3286    68359        0   0       0             0 gvfs-fuse-daemo
Jul 27 12:30:48 scenic kernel: [86083.389789] [ 3288]  1000  3288    64723      174   0       0             0 applet.py
Jul 27 12:30:48 scenic kernel: [86083.389791] [ 3297]  1000  3297    84014      111   2       0             0 nm-applet
Jul 27 12:30:48 scenic kernel: [86083.389794] [ 3299]  1000  3299   145745        0   0       0             0 blueman-applet
Jul 27 12:30:48 scenic kernel: [86083.389796] [ 3300]  1000  3300   187672       70   2       0             0 xfce4-volumed
Jul 27 12:30:48 scenic kernel: [86083.389799] [ 3303]  1000  3303    53456      102   1       0             0 xfce4-power-man
Jul 27 12:30:48 scenic kernel: [86083.389801] [ 3308]  1000  3308    13220       27   0       0             0 gconfd-2
Jul 27 12:30:48 scenic kernel: [86083.389804] [ 3313]  1000  3313    46549       44   0       0             0 polkit-gnome-au
Jul 27 12:30:48 scenic kernel: [86083.389807] [ 3317]  1000  3317   103662      119   1       0             0 update-notifier
Jul 27 12:30:48 scenic kernel: [86083.389809] [ 3322]  1000  3322    37464       80   1       0             0 xfce4-settings-
Jul 27 12:30:48 scenic kernel: [86083.389812] [ 3327]  1000  3327    88104       25   0       0             0 pulseaudio
Jul 27 12:30:48 scenic kernel: [86083.389814] [ 3329]   117  3329    42216       24   3       0             0 rtkit-daemon
Jul 27 12:30:48 scenic kernel: [86083.389817] [ 3335]     0  3335    48396      113   0       0             0 udisks-daemon
Jul 27 12:30:48 scenic kernel: [86083.389819] [ 3336]     0  3336    11378        0   3       0             0 udisks-daemon
Jul 27 12:30:48 scenic kernel: [86083.389822] [ 3344]  1000  3344    37263      107   1       0             0 panel-4-systray
Jul 27 12:30:48 scenic kernel: [86083.389825] [ 3346]  1000  3346   100633       72   1       0             0 xfce4-indicator
Jul 27 12:30:48 scenic kernel: [86083.389827] [ 3347]  1000  3347    36935      276   1       0             0 panel-7-datetim
Jul 27 12:30:48 scenic kernel: [86083.389830] [ 3351]  1000  3351    43088       94   3       0             0 panel-9-xfsm-lo
Jul 27 12:30:48 scenic kernel: [86083.389832] [ 3365]  1000  3365    16588       75   1       0             0 gvfs-gdu-volume
Jul 27 12:30:48 scenic kernel: [86083.389835] [ 3367]  1000  3367    14030        0   0       0             0 gvfs-gphoto2-vo
Jul 27 12:30:48 scenic kernel: [86083.389838] [ 3370]  1000  3370    34465        0   3       0             0 gvfs-afc-volume
Jul 27 12:30:48 scenic kernel: [86083.389840] [ 3374]  1000  3374    46144      102   1       0             0 panel-24-thunar
Jul 27 12:30:48 scenic kernel: [86083.389843] [ 3377]  1000  3377    13199       99   0       0             0 gvfsd-trash
Jul 27 12:30:48 scenic kernel: [86083.389845] [ 3380]  1000  3380    86941        0   3       0             0 indicator-appli
Jul 27 12:30:48 scenic kernel: [86083.389848] [ 3382]  1000  3382   131326        0   2       0             0 indicator-sound
Jul 27 12:30:48 scenic kernel: [86083.389850] [ 3384]  1000  3384   128288        0   3       0             0 indicator-messa
Jul 27 12:30:48 scenic kernel: [86083.389853] [ 3414]  1000  3414    13393        1   2       0             0 obex-data-serve
Jul 27 12:30:48 scenic kernel: [86083.389856] [ 3422]  1000  3422    64272       88   0       0             0 xfce4-terminal
Jul 27 12:30:48 scenic kernel: [86083.389858] [ 3424]  1000  3424     5947       25   2       0             0 bash
Jul 27 12:30:48 scenic kernel: [86083.389861] [ 3715]     0  3715     5454        1   1     -17         -1000 udevd
Jul 27 12:30:48 scenic kernel: [86083.389869] [ 4419]  1000  4419     5955       77   0       0             0 bash
Jul 27 12:30:48 scenic kernel: [86083.389872] [ 4482]  1000  4482   211586    35467   1       0             0 firefox
Jul 27 12:30:48 scenic kernel: [86083.389875] [ 4494]  1000  4494    68432        0   3       0             0 at-spi-bus-laun
Jul 27 12:30:48 scenic kernel: [86083.389880] [19335]     0 19335     3184       48   2       0             0 anacron
Jul 27 12:30:48 scenic kernel: [86083.389882] [19350]     0 19350     1098       26   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389885] [19352]     0 19352     1073       24   1       0             0 run-parts
Jul 27 12:30:48 scenic kernel: [86083.389888] [19616]  1000 19616    72419      110   1       0             0 gnome-keyring-d
Jul 27 12:30:48 scenic kernel: [86083.389891] [19644]     0 19644     2351        1   1       0             0 mlocate
Jul 27 12:30:48 scenic kernel: [86083.389893] [19650]     0 19650     1090       51   1       0             0 updatedb.mlocat
Jul 27 12:30:48 scenic kernel: [86083.389896] [27172]     0 27172     5454        1   2     -17         -1000 udevd
Jul 27 12:30:48 scenic kernel: [86083.389899] [ 1947]   106  1947     3385        0   2       0             0 auth
Jul 27 12:30:48 scenic kernel: [86083.389903] [ 3115]    33  3115    48357        5   0       0             0 apache2
Jul 27 12:30:48 scenic kernel: [86083.389905] [ 3388]   105  3388     6790        0   0       0             0 pickup
Jul 27 12:30:48 scenic kernel: [86083.389908] [ 3389]   105  3389     6803        0   2       0             0 qmgr
Jul 27 12:30:48 scenic kernel: [86083.389910] [ 3413]     0  3413     1098        0   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389913] [ 3415]     0  3415     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389915] [ 3416]     0  3416     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389918] [ 3417]     0  3417     1098        0   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389920] [ 3418]     0  3418     3770        0   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389923] [ 3419]     0  3419     3770       69   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389925] [ 3421]     0  3421     1098        0   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389928] [ 3426]     0  3426     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389930] [ 3427]     0  3427     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389933] [ 3428]     0  3428     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389935] [ 3429]     0  3429     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389938] [ 3430]     0  3430     3770       15   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389940] [ 3432]     0  3432     3770       69   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389943] [ 3433]     0  3433     3770       22   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389945] [ 3434]     0  3434     3770       12   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389948] [ 3435]     0  3435     3770       17   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389950] [ 3436]     0  3436     3770       21   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389953] [ 3438]     0  3438     3770       70   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389955] [ 3439]     0  3439     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389958] [ 3441]     0  3441     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389961] [ 3442]     0  3442     3770       34   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389963] [ 3443]     0  3443     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389965] [ 3444]     0  3444     3770       33   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389968] [ 3445]     0  3445     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389971] [ 3446]     0  3446     3770       37   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389973] [ 3447]     0  3447     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389975] [ 3448]     0  3448     3770       23   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389978] [ 3450]     0  3450     3770       22   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389981] [ 3451]     0  3451     1098        0   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389984] [ 3452]     0  3452     1098        0   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389987] [ 3453]     0  3453     3770       25   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389989] [ 3455]     0  3455     3770       18   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389992] [ 3456]     0  3456     1098        0   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389994] [ 3458]     0  3458     1098        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.389996] [ 3459]     0  3459     3770       65   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.389999] [ 3460]     0  3460     1098        0   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390002] [ 3461]     0  3461     3770       19   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390004] [ 3462]     0  3462     1098        0   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390007] [ 3463]     0  3463     3770       16   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390009] [ 3464]     0  3464     1098       27   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390012] [ 3465]     0  3465     3770        0   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390014] [ 3484]     0  3484     7421      218   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390017] [ 3489]     0  3489     1098       27   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390019] [ 3490]     0  3490     1098       26   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390022] [ 3491]     0  3491     1098       26   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390024] [ 3492]     0  3492     1098       28   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390027] [ 3493]     0  3493     1098       27   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390029] [ 3494]     0  3494     7421      190   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390032] [ 3495]     0  3495     1098       26   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390034] [ 3496]     0  3496     1098       27   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390037] [ 3497]     0  3497     3770       38   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390040] [ 3498]     0  3498     3770       37   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390042] [ 3499]     0  3499     3770       38   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390044] [ 3500]     0  3500     3770       38   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390047] [ 3501]     0  3501     3770       37   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390049] [ 3502]     0  3502     3770       37   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390052] [ 3503]     0  3503     3770       37   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390054] [ 3504]     0  3504     7422      247   2       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390057] [ 3505]     0  3505     7422      245   2       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390060] [ 3506]     0  3506     7421      198   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390062] [ 3507]     0  3507     1098       29   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390064] [ 3508]     0  3508     3770       37   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390067] [ 3509]     0  3509     7422      262   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390070] [ 3510]     0  3510     7422      258   2       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390072] [ 3511]     0  3511     7422      315   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390075] [ 3512]     0  3512     1098       28   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390077] [ 3513]     0  3513     3770       38   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390080] [ 3514]     0  3514     7423      251   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390083] [ 3515]     0  3515     7421      187   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390085] [ 3516]     0  3516     1098       28   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390087] [ 3517]     0  3517     3770       37   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390090] [ 3518]     0  3518     7421      315   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390093] [ 3519]     0  3519     1098       28   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390095] [ 3520]     0  3520     3770       38   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390098] [ 3521]     0  3521     7422      268   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390101] [ 3522]     0  3522     1098       28   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390103] [ 3523]     0  3523     3770       38   3       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390106] [ 3524]     0  3524     7421      350   2       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390108] [ 3525]     0  3525     7422      268   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390111] [ 3527]     0  3527     7421      346   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390113] [ 3528]     0  3528     3770       38   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390116] [ 3529]     0  3529     1098       28   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390119] [ 3531]     0  3531     7422      248   2       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390121] [ 3532]     0  3532     3770       72   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390124] [ 3535]     0  3535     7421      194   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390126] [ 3536]     0  3536     7388      102   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390129] [ 3537]     0  3537     7388       90   2       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390131] [ 3538]     0  3538     7421      305   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390134] [ 3539]     0  3539     7403       79   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390136] [ 3540]     0  3540     7403      143   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390139] [ 3541]     0  3541     7403      112   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390141] [ 3542]     0  3542     7388      183   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390144] [ 3543]     0  3543     7403       68   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390146] [ 3544]     0  3544     7388      138   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390149] [ 3545]     0  3545     7422      271   2       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390151] [ 3546]     0  3546     7423      266   1       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390154] [ 3555]     0  3555     7388        0   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390157] [ 3562]     0  3562     1098      123   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390159] [ 3563]     0  3563     1077       41   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390162] [ 3564]     0  3564     1098       84   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390164] [ 3565]     0  3565     1098       97   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390166] [ 3566]     0  3566     1098       87   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390169] [ 3567]     0  3567     1098      111   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390171] [ 3573]     0  3573     1098       97   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390174] [ 3574]     0  3574     1098       97   3       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390176] [ 3575]     0  3575     3737       85   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390179] [ 3576]     0  3576     3737       74   1       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390181] [ 3578]     0  3578     1077       41   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390184] [ 3579]     0  3579     2715       61   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390186] [ 3580]     0  3580     3752       25   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390189] [ 3581]     0  3581     3770      182   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390191] [ 3583]     0  3583     1077       42   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390194] [ 3584]     0  3584     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390196] [ 3585]     0  3585     1077       40   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390199] [ 3586]     0  3586     1077       40   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390201] [ 3587]     0  3587     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390204] [ 3588]     0  3588     1077       41   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390206] [ 3589]     0  3589     1077        0   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390209] [ 3590]     0  3590     1077       41   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390211] [ 3591]     0  3591     1077       41   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390213] [ 3592]     0  3592     1077       42   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390216] [ 3593]     0  3593     1077       42   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390219] [ 3594]     0  3594     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390221] [ 3595]     0  3595     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390223] [ 3596]     0  3596     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390226] [ 3597]     0  3597     1077       40   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390229] [ 3598]     0  3598     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390231] [ 3599]     0  3599     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390233] [ 3600]     0  3600     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390236] [ 3601]     0  3601     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390238] [ 3602]     0  3602     1077       40   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390240] [ 3603]     0  3603     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390243] [ 3604]     0  3604     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390245] [ 3605]     0  3605     1077       40   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390248] [ 3606]     0  3606     1077       41   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390250] [ 3607]     0  3607     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390253] [ 3608]     0  3608     1077       40   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390255] [ 3609]     0  3609     1077       42   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390258] [ 3610]     0  3610     1077       42   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390260] [ 3611]     0  3611     1077       41   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390263] [ 3612]     0  3612     1077       41   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390265] [ 3613]     0  3613     1167       46   0       0             0 mount.zfs
Jul 27 12:30:48 scenic kernel: [86083.390267] [ 3614]     0  3614     1077       41   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390270] [ 3615]     0  3615     1077       41   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390273] [ 3616]     0  3616     1077       35   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390275] [ 3617]     0  3617     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390277] [ 3618]     0  3618     2715       53   0       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390280] [ 3619]     0  3619     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390282] [ 3620]     0  3620     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390285] [ 3621]     0  3621     1077       33   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390287] [ 3622]     0  3622     1077       33   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390290] [ 3623]     0  3623     1077       33   1       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390292] [ 3624]     0  3624     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390295] [ 3625]     0  3625     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390297] [ 3626]     0  3626     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390300] [ 3627]     0  3627     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390302] [ 3628]     0  3628     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390305] [ 3629]     0  3629     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390307] [ 3630]     0  3630     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390310] [ 3631]     0  3631     2715       53   2       0             0 mount
Jul 27 12:30:48 scenic kernel: [86083.390312] [ 3632]     0  3632     1077       33   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390315] [ 3633]     0  3633     1077       33   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390317] [ 3634]     0  3634     1077       33   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390320] [ 3635]     0  3635     1077       42   0       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390322] [ 3636]     0  3636     1077       42   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390325] [ 3637]     0  3637     1077       42   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390327] [ 3638]     0  3638     1077       43   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390330] [ 3639]     0  3639     1077       42   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390332] [ 3640]     0  3640     1077       43   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390335] [ 3641]     0  3641     1077       43   2       0             0 sh
Jul 27 12:30:48 scenic kernel: [86083.390337] [ 3642]     0  
@ryao
Copy link
Contributor

ryao commented Aug 16, 2012

Try using this kernel patch and rebuilding SPL:

https://bugs.gentoo.org/show_bug.cgi?id=416685

@behlendorf
Copy link
Contributor

Yes, if you can rebuild your kernel with the above patch that would help us prove that this is related to a known issue. We're looking for a way to resolve this without needing to patch the kernel, but we're not quite there yet.

@ryanolf
Copy link
Author

ryanolf commented Aug 17, 2012

Ok, I'll try this soon. I'm on Ubuntu now, and I haven't built a kernel
since back in my Gentoo days, so I have to figure out how to rebuild with
the patch.

On Fri, Aug 17, 2012 at 3:31 PM, Brian Behlendorf
notifications@github.comwrote:

Yes, if you can rebuild your kernel with the above patch that would help
us prove that this is related to a known issue. We're looking for a way to
resolve this without needing to patch the kernel, but we're not quite there
yet.


Reply to this email directly or view it on GitHubhttps://github.com//issues/860#issuecomment-7836993.

@behlendorf
Copy link
Contributor

Alternately, you could try openzfs/spl#155 which is the latest patch to try and address this issue without needing to patch your kernel. However, thus far it hasn't seen much testing on real systems just the regression test suites.

@ryanolf
Copy link
Author

ryanolf commented Aug 20, 2012

Richard, will your kernel patch work with the 3.2 kernel series?

On Fri, Aug 17, 2012 at 4:52 PM, Brian Behlendorf
notifications@github.comwrote:

Alternately, you could try openzfs/spl#155https://github.com/zfsonlinux/spl/issues/155which is the latest patch to try and address this issue without needing to
patch your kernel. However, thus far it hasn't seen much testing on real
systems just the regression test suites.


Reply to this email directly or view it on GitHubhttps://github.com//issues/860#issuecomment-7839244.

@ryanolf
Copy link
Author

ryanolf commented Aug 21, 2012

I've patched the kernel on this machine (now running 3.5) and will report back.

On Aug 17, 2012, at 4:52 PM, Brian Behlendorf notifications@github.com wrote:

Alternately, you could try openzfs/spl#155 which is the latest patch to try and address this issue without needing to patch your kernel. However, thus far it hasn't seen much testing on real systems just the regression test suites.


Reply to this email directly or view it on GitHub.

@behlendorf
Copy link
Contributor

If you get a chance could you try the following patch stacks as well. They should also resolve the issue but without the need to patch your kernel.

openzfs/spl#161
#883

@ryanolf
Copy link
Author

ryanolf commented Aug 29, 2012

With the kernel patch, the system seems to be stable, and the issue is resolved. I have not had a chance to try the zfs/spl patches yet, though I plan to in the future. In the meantime, it's nice to have the system stable.

@behlendorf
Copy link
Contributor

This issue was be resolved when issue #883 is merged, hopefully in a few days.

@behlendorf
Copy link
Contributor

The #883 changes have been merged in to master and will appear in -rc11. Since you had good luck with the kernel patch I full expect this issue will be resolved in the next update so I'm going to close it. We can easily reopen it if that's some some reason not the case.

@automaticgiant
Copy link

I am having this problem on 12.04.1 64-bit with rc12(0.6.0.86) from the stable ppa,.

I never seem to get the bitter end into my syslog, but I'm pretty sure it is OOM crashes. I usually REIUSB when it gets unresponsive.
I'm upgrading now to 0.6.0.88 from the daily, maybe that will do something useful. I'm not very hopeful. And yes, I do only have 1G ram which is bad, but it's what's in my server budget for now. I tried adding zfs_arc_max=536870912 to the module options to limit arc to half my ram but that doesn't fix the blocking and hanging.
There seem to be hints on here that there is some way to change the timeout for the hung_task_timeout. I'm going to do a search on that now.
EDIT: I found http://www.nico.schottelius.org/blog/reboot-linux-if-task-blocked-for-more-than-n-seconds/
and I'm going to add:

# Reboot 5 seconds after panic
kernel.panic = 5

# Panic if a hung task was found
kernel.hung_task_panic = 1

# Setup timeout for hung task to 300 seconds
kernel.hung_task_timeout_secs = 300

to my config.

@automaticgiant
Copy link

I just put in 0.6.0.88 and it's chugging away. I don't expect to see the errors for a while because of the sysctl change.
Here is a sample of my errors from previous logs:

Dec  3 07:33:30 2d kernel: [  360.216404] INFO: task zpool:2279 blocked for more than 120 seconds.
Dec  3 07:33:30 2d kernel: [  360.216420] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 07:33:30 2d kernel: [  360.216425] zpool           D 0000000000000000     0  2279   2246 0x00000000
Dec  3 07:33:30 2d kernel: [  360.216434]  ffff880005611cc8 0000000000000082 0000002700000020 ffffffffa010ee70
Dec  3 07:33:30 2d kernel: [  360.216442]  ffff880005611fd8 ffff880005611fd8 ffff880005611fd8 00000000000137c0
Dec  3 07:33:30 2d kernel: [  360.216450]  ffff88002e66c500 ffff880026134500 ffff880005611cd8 ffff880037127ad8
Dec  3 07:33:30 2d kernel: [  360.216458] Call Trace:
Dec  3 07:33:30 2d kernel: [  360.216470]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 07:33:30 2d kernel: [  360.216491]  [<ffffffffa00efe6c>] cv_wait_common+0x9c/0x190 [spl]
Dec  3 07:33:30 2d kernel: [  360.216498]  [<ffffffff8108ad20>] ? add_wait_queue+0x60/0x60
Dec  3 07:33:30 2d kernel: [  360.216516]  [<ffffffffa00eff93>] __cv_wait+0x13/0x20 [spl]
Dec  3 07:33:30 2d kernel: [  360.216607]  [<ffffffffa01df572>] spa_config_enter+0x82/0x100 [zfs]
Dec  3 07:33:30 2d kernel: [  360.216678]  [<ffffffffa01dfe13>] spa_vdev_config_enter+0x23/0x40 [zfs]
Dec  3 07:33:30 2d kernel: [  360.216746]  [<ffffffffa01dfe61>] spa_vdev_enter+0x31/0x40 [zfs]
Dec  3 07:33:30 2d kernel: [  360.216820]  [<ffffffffa01d60e8>] spa_vdev_add+0x28/0x2d0 [zfs]
Dec  3 07:33:30 2d kernel: [  360.216839]  [<ffffffffa010c4a8>] ? nvlist_lookup_common+0x88/0xc0 [znvpair]
Dec  3 07:33:30 2d kernel: [  360.216913]  [<ffffffffa020512d>] zfs_ioc_vdev_add+0xed/0x130 [zfs]
Dec  3 07:33:30 2d kernel: [  360.216984]  [<ffffffffa020a1cc>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Dec  3 07:33:30 2d kernel: [  360.216993]  [<ffffffff8118a49a>] do_vfs_ioctl+0x8a/0x340
Dec  3 07:33:30 2d kernel: [  360.217000]  [<ffffffff81183565>] ? putname+0x35/0x50
Dec  3 07:33:30 2d kernel: [  360.217007]  [<ffffffff8117770c>] ? do_sys_open+0x17c/0x240
--
Dec  3 12:51:58 2d kernel: [ 3960.212065] INFO: task l2arc_feed:453 blocked for more than 120 seconds.
Dec  3 12:51:58 2d kernel: [ 3960.212073] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:51:58 2d kernel: [ 3960.212079] l2arc_feed      D ffffffff81806200     0   453      2 0x00000000
Dec  3 12:51:58 2d kernel: [ 3960.212089]  ffff880036dfdd10 0000000000000046 ffff880036dfdcb0 ffffffffa025bae0
Dec  3 12:51:58 2d kernel: [ 3960.212098]  ffff880036dfdfd8 ffff880036dfdfd8 ffff880036dfdfd8 00000000000137c0
Dec  3 12:51:58 2d kernel: [ 3960.212107]  ffffffff81c0d020 ffff880037382e00 ffff880036dfdd10 ffffffffa025ddc0
Dec  3 12:51:58 2d kernel: [ 3960.212115] Call Trace:
Dec  3 12:51:58 2d kernel: [ 3960.212145]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:51:58 2d kernel: [ 3960.212153]  [<ffffffff8165acc7>] __mutex_lock_slowpath+0xd7/0x150
Dec  3 12:51:58 2d kernel: [ 3960.212163]  [<ffffffff8165a8da>] mutex_lock+0x2a/0x50
Dec  3 12:51:58 2d kernel: [ 3960.212255]  [<ffffffffa018705a>] l2arc_feed_thread+0xaa/0x850 [zfs]
Dec  3 12:51:58 2d kernel: [ 3960.212306]  [<ffffffffa0186fb0>] ? l2arc_evict+0x310/0x310 [zfs]
Dec  3 12:51:58 2d kernel: [ 3960.212336]  [<ffffffffa00e0c98>] thread_generic_wrapper+0x78/0x90 [spl]
Dec  3 12:51:58 2d kernel: [ 3960.212354]  [<ffffffffa00e0c20>] ? __thread_create+0x310/0x310 [spl]
Dec  3 12:51:58 2d kernel: [ 3960.212363]  [<ffffffff8108a27c>] kthread+0x8c/0xa0
Dec  3 12:51:58 2d kernel: [ 3960.212373]  [<ffffffff81666534>] kernel_thread_helper+0x4/0x10
Dec  3 12:51:58 2d kernel: [ 3960.212380]  [<ffffffff8108a1f0>] ? flush_kthread_worker+0xa0/0xa0
Dec  3 12:51:59 2d kernel: [ 3960.212387]  [<ffffffff81666530>] ? gs_change+0x13/0x13
Dec  3 12:51:59 2d kernel: [ 3960.212442] INFO: task zpool:5518 blocked for more than 120 seconds.
Dec  3 12:51:59 2d kernel: [ 3960.212447] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:51:59 2d kernel: [ 3960.212452] zpool           D ffffffff81806200     0  5518   2950 0x00000000
Dec  3 12:51:59 2d kernel: [ 3960.212460]  ffff880015009cf8 0000000000000086 ffff88003ffab700 ffff88002e472968
Dec  3 12:51:59 2d kernel: [ 3960.212469]  ffff880015009fd8 ffff880015009fd8 ffff880015009fd8 00000000000137c0
Dec  3 12:51:59 2d kernel: [ 3960.212477]  ffffffff81c0d020 ffff8800396f1700 000000000000c2d0 ffffffffa025ddc0
Dec  3 12:51:59 2d kernel: [ 3960.212485] Call Trace:
Dec  3 12:51:59 2d kernel: [ 3960.212499]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:51:59 2d kernel: [ 3960.212505]  [<ffffffff8165acc7>] __mutex_lock_slowpath+0xd7/0x150
Dec  3 12:51:59 2d kernel: [ 3960.212514]  [<ffffffff8165a8da>] mutex_lock+0x2a/0x50
Dec  3 12:51:59 2d kernel: [ 3960.212600]  [<ffffffffa01d55dc>] spa_open_common+0x5c/0x340 [zfs]
Dec  3 12:51:59 2d kernel: [ 3960.212612]  [<ffffffff81163360>] ? __kmalloc+0x180/0x190
Dec  3 12:51:59 2d kernel: [ 3960.212619]  [<ffffffff8165c05e>] ? _raw_spin_lock_irqsave+0x2e/0x40
Dec  3 12:51:59 2d kernel: [ 3960.212688]  [<ffffffffa01d5939>] spa_get_stats+0x49/0x2b0 [zfs]
Dec  3 12:51:59 2d kernel: [ 3960.212761]  [<ffffffffa02024a1>] zfs_ioc_pool_stats+0x31/0x70 [zfs]
Dec  3 12:51:59 2d kernel: [ 3960.212832]  [<ffffffffa02071cc>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Dec  3 12:51:59 2d kernel: [ 3960.212878]  [<ffffffff8118a49a>] do_vfs_ioctl+0x8a/0x340
Dec  3 12:51:59 2d kernel: [ 3960.212887]  [<ffffffff8108f0c4>] ? hrtimer_start_range_ns+0x14/0x20
Dec  3 12:51:59 2d kernel: [ 3960.212894]  [<ffffffff8118a7e1>] sys_ioctl+0x91/0xa0
Dec  3 12:51:59 2d kernel: [ 3960.212903]  [<ffffffff816643c2>] system_call_fastpath+0x16/0x1b
Dec  3 12:51:59 2d kernel: [ 3960.212910] INFO: task zpool:5713 blocked for more than 120 seconds.
Dec  3 12:51:59 2d kernel: [ 3960.212914] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:51:59 2d kernel: [ 3960.212919] zpool           D 0000000000000000     0  5713   5519 0x00000000
Dec  3 12:51:59 2d kernel: [ 3960.212927]  ffff88000ff1dd08 0000000000000086 ffff88000ff1dd38 0000000000000046
Dec  3 12:51:59 2d kernel: [ 3960.212935]  ffff88000ff1dfd8 ffff88000ff1dfd8 ffff88000ff1dfd8 00000000000137c0
Dec  3 12:51:59 2d kernel: [ 3960.212943]  ffff880031190000 ffff880037169700 ffff88000ff1dd18 ffff88003c63da88
Dec  3 12:51:59 2d kernel: [ 3960.212951] Call Trace:
Dec  3 12:51:59 2d kernel: [ 3960.212960]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:51:59 2d kernel: [ 3960.212990]  [<ffffffffa00e7e6c>] cv_wait_common+0x9c/0x190 [spl]
Dec  3 12:51:59 2d kernel: [ 3960.212998]  [<ffffffff8108ad20>] ? add_wait_queue+0x60/0x60
Dec  3 12:51:59 2d kernel: [ 3960.213016]  [<ffffffffa00e7f93>] __cv_wait+0x13/0x20 [spl]
Dec  3 12:51:59 2d kernel: [ 3960.213107]  [<ffffffffa01df103>] txg_wait_synced+0xb3/0x190 [zfs]
Dec  3 12:51:59 2d kernel: [ 3960.213177]  [<ffffffffa01d188e>] spa_export_common+0x19e/0x310 [zfs]
Dec  3 12:51:59 2d kernel: [ 3960.213246]  [<ffffffffa01d1a3b>] spa_export+0x1b/0x20 [zfs]
Dec  3 12:51:59 2d kernel: [ 3960.213317]  [<ffffffffa020366f>] zfs_ioc_pool_export+0x3f/0x60 [zfs]
Dec  3 12:51:59 2d kernel: [ 3960.213388]  [<ffffffffa02071cc>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Dec  3 12:51:59 2d kernel: [ 3960.213397]  [<ffffffff8118a49a>] do_vfs_ioctl+0x8a/0x340
Dec  3 12:51:59 2d kernel: [ 3960.213403]  [<ffffffff81183565>] ? putname+0x35/0x50
Dec  3 12:51:59 2d kernel: [ 3960.213411]  [<ffffffff8117770c>] ? do_sys_open+0x17c/0x240
Dec  3 12:51:59 2d kernel: [ 3960.213417]  [<ffffffff8118a7e1>] sys_ioctl+0x91/0xa0
Dec  3 12:51:59 2d kernel: [ 3960.213426]  [<ffffffff816643c2>] system_call_fastpath+0x16/0x1b
Dec  3 12:53:58 2d kernel: [ 4080.212049] INFO: task l2arc_feed:453 blocked for more than 120 seconds.
Dec  3 12:53:58 2d kernel: [ 4080.212058] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:53:58 2d kernel: [ 4080.212064] l2arc_feed      D ffffffff81806200     0   453      2 0x00000000
Dec  3 12:53:58 2d kernel: [ 4080.212074]  ffff880036dfdd10 0000000000000046 ffff880036dfdcb0 ffffffffa025bae0
Dec  3 12:53:58 2d kernel: [ 4080.212084]  ffff880036dfdfd8 ffff880036dfdfd8 ffff880036dfdfd8 00000000000137c0
Dec  3 12:53:58 2d kernel: [ 4080.212092]  ffffffff81c0d020 ffff880037382e00 ffff880036dfdd10 ffffffffa025ddc0
Dec  3 12:53:58 2d kernel: [ 4080.212100] Call Trace:
Dec  3 12:53:58 2d kernel: [ 4080.212130]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:53:58 2d kernel: [ 4080.212138]  [<ffffffff8165acc7>] __mutex_lock_slowpath+0xd7/0x150
Dec  3 12:53:58 2d kernel: [ 4080.212149]  [<ffffffff8165a8da>] mutex_lock+0x2a/0x50
Dec  3 12:53:58 2d kernel: [ 4080.212241]  [<ffffffffa018705a>] l2arc_feed_thread+0xaa/0x850 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.212292]  [<ffffffffa0186fb0>] ? l2arc_evict+0x310/0x310 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.212323]  [<ffffffffa00e0c98>] thread_generic_wrapper+0x78/0x90 [spl]
Dec  3 12:53:58 2d kernel: [ 4080.212341]  [<ffffffffa00e0c20>] ? __thread_create+0x310/0x310 [spl]
Dec  3 12:53:58 2d kernel: [ 4080.212350]  [<ffffffff8108a27c>] kthread+0x8c/0xa0
Dec  3 12:53:58 2d kernel: [ 4080.212360]  [<ffffffff81666534>] kernel_thread_helper+0x4/0x10
Dec  3 12:53:58 2d kernel: [ 4080.212367]  [<ffffffff8108a1f0>] ? flush_kthread_worker+0xa0/0xa0
Dec  3 12:53:58 2d kernel: [ 4080.212374]  [<ffffffff81666530>] ? gs_change+0x13/0x13
Dec  3 12:53:58 2d kernel: [ 4080.212429] INFO: task zpool:5518 blocked for more than 120 seconds.
Dec  3 12:53:58 2d kernel: [ 4080.212434] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:53:58 2d kernel: [ 4080.212439] zpool           D ffffffff81806200     0  5518   2950 0x00000000
Dec  3 12:53:58 2d kernel: [ 4080.212448]  ffff880015009cf8 0000000000000086 ffff88003ffab700 ffff88002e472968
Dec  3 12:53:58 2d kernel: [ 4080.212457]  ffff880015009fd8 ffff880015009fd8 ffff880015009fd8 00000000000137c0
Dec  3 12:53:58 2d kernel: [ 4080.212464]  ffffffff81c0d020 ffff8800396f1700 000000000000c2d0 ffffffffa025ddc0
Dec  3 12:53:58 2d kernel: [ 4080.212472] Call Trace:
Dec  3 12:53:58 2d kernel: [ 4080.212487]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:53:58 2d kernel: [ 4080.212494]  [<ffffffff8165acc7>] __mutex_lock_slowpath+0xd7/0x150
Dec  3 12:53:58 2d kernel: [ 4080.212502]  [<ffffffff8165a8da>] mutex_lock+0x2a/0x50
Dec  3 12:53:58 2d kernel: [ 4080.212589]  [<ffffffffa01d55dc>] spa_open_common+0x5c/0x340 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.212601]  [<ffffffff81163360>] ? __kmalloc+0x180/0x190
Dec  3 12:53:58 2d kernel: [ 4080.212609]  [<ffffffff8165c05e>] ? _raw_spin_lock_irqsave+0x2e/0x40
Dec  3 12:53:58 2d kernel: [ 4080.212678]  [<ffffffffa01d5939>] spa_get_stats+0x49/0x2b0 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.212751]  [<ffffffffa02024a1>] zfs_ioc_pool_stats+0x31/0x70 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.212822]  [<ffffffffa02071cc>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.212831]  [<ffffffff8118a49a>] do_vfs_ioctl+0x8a/0x340
Dec  3 12:53:58 2d kernel: [ 4080.212840]  [<ffffffff8108f0c4>] ? hrtimer_start_range_ns+0x14/0x20
Dec  3 12:53:58 2d kernel: [ 4080.212847]  [<ffffffff8118a7e1>] sys_ioctl+0x91/0xa0
Dec  3 12:53:58 2d kernel: [ 4080.212855]  [<ffffffff816643c2>] system_call_fastpath+0x16/0x1b
Dec  3 12:53:58 2d kernel: [ 4080.212862] INFO: task zpool:5713 blocked for more than 120 seconds.
Dec  3 12:53:58 2d kernel: [ 4080.212867] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:53:58 2d kernel: [ 4080.212871] zpool           D 0000000000000000     0  5713   5519 0x00000000
Dec  3 12:53:58 2d kernel: [ 4080.212880]  ffff88000ff1dd08 0000000000000086 ffff88000ff1dd38 0000000000000046
Dec  3 12:53:58 2d kernel: [ 4080.212888]  ffff88000ff1dfd8 ffff88000ff1dfd8 ffff88000ff1dfd8 00000000000137c0
Dec  3 12:53:58 2d kernel: [ 4080.212896]  ffff880031190000 ffff880037169700 ffff88000ff1dd18 ffff88003c63da88
Dec  3 12:53:58 2d kernel: [ 4080.212904] Call Trace:
Dec  3 12:53:58 2d kernel: [ 4080.212913]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:53:58 2d kernel: [ 4080.212941]  [<ffffffffa00e7e6c>] cv_wait_common+0x9c/0x190 [spl]
Dec  3 12:53:58 2d kernel: [ 4080.212949]  [<ffffffff8108ad20>] ? add_wait_queue+0x60/0x60
Dec  3 12:53:58 2d kernel: [ 4080.212967]  [<ffffffffa00e7f93>] __cv_wait+0x13/0x20 [spl]
Dec  3 12:53:58 2d kernel: [ 4080.213049]  [<ffffffffa01df103>] txg_wait_synced+0xb3/0x190 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.213120]  [<ffffffffa01d188e>] spa_export_common+0x19e/0x310 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.213188]  [<ffffffffa01d1a3b>] spa_export+0x1b/0x20 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.213258]  [<ffffffffa020366f>] zfs_ioc_pool_export+0x3f/0x60 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.213330]  [<ffffffffa02071cc>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Dec  3 12:53:58 2d kernel: [ 4080.213339]  [<ffffffff8118a49a>] do_vfs_ioctl+0x8a/0x340
Dec  3 12:53:58 2d kernel: [ 4080.213345]  [<ffffffff81183565>] ? putname+0x35/0x50
Dec  3 12:53:58 2d kernel: [ 4080.213352]  [<ffffffff8117770c>] ? do_sys_open+0x17c/0x240
Dec  3 12:53:58 2d kernel: [ 4080.213359]  [<ffffffff8118a7e1>] sys_ioctl+0x91/0xa0
Dec  3 12:53:58 2d kernel: [ 4080.213367]  [<ffffffff816643c2>] system_call_fastpath+0x16/0x1b
--
Dec  3 12:55:58 2d kernel: [ 4200.212052] INFO: task l2arc_feed:453 blocked for more than 120 seconds.
Dec  3 12:55:58 2d kernel: [ 4200.212061] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:55:58 2d kernel: [ 4200.212067] l2arc_feed      D ffffffff81806200     0   453      2 0x00000000
Dec  3 12:55:58 2d kernel: [ 4200.212077]  ffff880036dfdd10 0000000000000046 ffff880036dfdcb0 ffffffffa025bae0
Dec  3 12:55:58 2d kernel: [ 4200.212086]  ffff880036dfdfd8 ffff880036dfdfd8 ffff880036dfdfd8 00000000000137c0
Dec  3 12:55:58 2d kernel: [ 4200.212095]  ffffffff81c0d020 ffff880037382e00 ffff880036dfdd10 ffffffffa025ddc0
Dec  3 12:55:58 2d kernel: [ 4200.212103] Call Trace:
Dec  3 12:55:58 2d kernel: [ 4200.212133]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:55:58 2d kernel: [ 4200.212141]  [<ffffffff8165acc7>] __mutex_lock_slowpath+0xd7/0x150
Dec  3 12:55:58 2d kernel: [ 4200.212151]  [<ffffffff8165a8da>] mutex_lock+0x2a/0x50
Dec  3 12:55:58 2d kernel: [ 4200.212243]  [<ffffffffa018705a>] l2arc_feed_thread+0xaa/0x850 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.212294]  [<ffffffffa0186fb0>] ? l2arc_evict+0x310/0x310 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.212325]  [<ffffffffa00e0c98>] thread_generic_wrapper+0x78/0x90 [spl]
Dec  3 12:55:58 2d kernel: [ 4200.212343]  [<ffffffffa00e0c20>] ? __thread_create+0x310/0x310 [spl]
Dec  3 12:55:58 2d kernel: [ 4200.212352]  [<ffffffff8108a27c>] kthread+0x8c/0xa0
Dec  3 12:55:58 2d kernel: [ 4200.212362]  [<ffffffff81666534>] kernel_thread_helper+0x4/0x10
Dec  3 12:55:58 2d kernel: [ 4200.212369]  [<ffffffff8108a1f0>] ? flush_kthread_worker+0xa0/0xa0
Dec  3 12:55:58 2d kernel: [ 4200.212376]  [<ffffffff81666530>] ? gs_change+0x13/0x13
Dec  3 12:55:58 2d kernel: [ 4200.212430] INFO: task zpool:5518 blocked for more than 120 seconds.
Dec  3 12:55:58 2d kernel: [ 4200.212435] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:55:58 2d kernel: [ 4200.212440] zpool           D ffffffff81806200     0  5518   2950 0x00000000
Dec  3 12:55:58 2d kernel: [ 4200.212449]  ffff880015009cf8 0000000000000086 ffff88003ffab700 ffff88002e472968
Dec  3 12:55:58 2d kernel: [ 4200.212457]  ffff880015009fd8 ffff880015009fd8 ffff880015009fd8 00000000000137c0
Dec  3 12:55:58 2d kernel: [ 4200.212465]  ffffffff81c0d020 ffff8800396f1700 000000000000c2d0 ffffffffa025ddc0
Dec  3 12:55:58 2d kernel: [ 4200.212473] Call Trace:
Dec  3 12:55:58 2d kernel: [ 4200.212487]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:55:58 2d kernel: [ 4200.212494]  [<ffffffff8165acc7>] __mutex_lock_slowpath+0xd7/0x150
Dec  3 12:55:58 2d kernel: [ 4200.212502]  [<ffffffff8165a8da>] mutex_lock+0x2a/0x50
Dec  3 12:55:58 2d kernel: [ 4200.212588]  [<ffffffffa01d55dc>] spa_open_common+0x5c/0x340 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.212600]  [<ffffffff81163360>] ? __kmalloc+0x180/0x190
Dec  3 12:55:58 2d kernel: [ 4200.212608]  [<ffffffff8165c05e>] ? _raw_spin_lock_irqsave+0x2e/0x40
Dec  3 12:55:58 2d kernel: [ 4200.212677]  [<ffffffffa01d5939>] spa_get_stats+0x49/0x2b0 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.212750]  [<ffffffffa02024a1>] zfs_ioc_pool_stats+0x31/0x70 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.212821]  [<ffffffffa02071cc>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.212830]  [<ffffffff8118a49a>] do_vfs_ioctl+0x8a/0x340
Dec  3 12:55:58 2d kernel: [ 4200.212839]  [<ffffffff8108f0c4>] ? hrtimer_start_range_ns+0x14/0x20
Dec  3 12:55:58 2d kernel: [ 4200.212846]  [<ffffffff8118a7e1>] sys_ioctl+0x91/0xa0
Dec  3 12:55:58 2d kernel: [ 4200.212854]  [<ffffffff816643c2>] system_call_fastpath+0x16/0x1b
Dec  3 12:55:58 2d kernel: [ 4200.212861] INFO: task zpool:5713 blocked for more than 120 seconds.
Dec  3 12:55:58 2d kernel: [ 4200.212866] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:55:58 2d kernel: [ 4200.212870] zpool           D 0000000000000000     0  5713   5519 0x00000000
Dec  3 12:55:58 2d kernel: [ 4200.212879]  ffff88000ff1dd08 0000000000000086 ffff88000ff1dd38 0000000000000046
Dec  3 12:55:58 2d kernel: [ 4200.212887]  ffff88000ff1dfd8 ffff88000ff1dfd8 ffff88000ff1dfd8 00000000000137c0
Dec  3 12:55:58 2d kernel: [ 4200.212895]  ffff880031190000 ffff880037169700 ffff88000ff1dd18 ffff88003c63da88
Dec  3 12:55:58 2d kernel: [ 4200.212903] Call Trace:
Dec  3 12:55:58 2d kernel: [ 4200.212912]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:55:58 2d kernel: [ 4200.212940]  [<ffffffffa00e7e6c>] cv_wait_common+0x9c/0x190 [spl]
Dec  3 12:55:58 2d kernel: [ 4200.212948]  [<ffffffff8108ad20>] ? add_wait_queue+0x60/0x60
Dec  3 12:55:58 2d kernel: [ 4200.212966]  [<ffffffffa00e7f93>] __cv_wait+0x13/0x20 [spl]
Dec  3 12:55:58 2d kernel: [ 4200.213047]  [<ffffffffa01df103>] txg_wait_synced+0xb3/0x190 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.213117]  [<ffffffffa01d188e>] spa_export_common+0x19e/0x310 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.213186]  [<ffffffffa01d1a3b>] spa_export+0x1b/0x20 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.213256]  [<ffffffffa020366f>] zfs_ioc_pool_export+0x3f/0x60 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.213327]  [<ffffffffa02071cc>] zfsdev_ioctl+0xdc/0x1b0 [zfs]
Dec  3 12:55:58 2d kernel: [ 4200.213336]  [<ffffffff8118a49a>] do_vfs_ioctl+0x8a/0x340
Dec  3 12:55:58 2d kernel: [ 4200.213343]  [<ffffffff81183565>] ? putname+0x35/0x50
Dec  3 12:55:58 2d kernel: [ 4200.213350]  [<ffffffff8117770c>] ? do_sys_open+0x17c/0x240
Dec  3 12:55:58 2d kernel: [ 4200.213357]  [<ffffffff8118a7e1>] sys_ioctl+0x91/0xa0
Dec  3 12:55:58 2d kernel: [ 4200.213364]  [<ffffffff816643c2>] system_call_fastpath+0x16/0x1b
Dec  3 12:57:58 2d kernel: [ 4320.212044] INFO: task l2arc_feed:453 blocked for more than 120 seconds.
Dec  3 12:57:58 2d kernel: [ 4320.212052] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec  3 12:57:58 2d kernel: [ 4320.212058] l2arc_feed      D ffffffff81806200     0   453      2 0x00000000
Dec  3 12:57:58 2d kernel: [ 4320.212068]  ffff880036dfdd10 0000000000000046 ffff880036dfdcb0 ffffffffa025bae0
Dec  3 12:57:58 2d kernel: [ 4320.212077]  ffff880036dfdfd8 ffff880036dfdfd8 ffff880036dfdfd8 00000000000137c0
Dec  3 12:57:58 2d kernel: [ 4320.212086]  ffffffff81c0d020 ffff880037382e00 ffff880036dfdd10 ffffffffa025ddc0
Dec  3 12:57:58 2d kernel: [ 4320.212094] Call Trace:
Dec  3 12:57:58 2d kernel: [ 4320.212124]  [<ffffffff81659ebf>] schedule+0x3f/0x60
Dec  3 12:57:58 2d kernel: [ 4320.212132]  [<ffffffff8165acc7>] __mutex_lock_slowpath+0xd7/0x150
Dec  3 12:57:58 2d kernel: [ 4320.212142]  [<ffffffff8165a8da>] mutex_lock+0x2a/0x50
Dec  3 12:57:58 2d kernel: [ 4320.212229]  [<ffffffffa018705a>] l2arc_feed_thread+0xaa/0x850 [zfs]
Dec  3 12:57:58 2d kernel: [ 4320.212280]  [<ffffffffa0186fb0>] ? l2arc_evict+0x310/0x310 [zfs]
Dec  3 12:57:58 2d kernel: [ 4320.212310]  [<ffffffffa00e0c98>] thread_generic_wrapper+0x78/0x90 [spl]
Dec  3 12:57:58 2d kernel: [ 4320.212328]  [<ffffffffa00e0c20>] ? __thread_create+0x310/0x310 [spl]
Dec  3 12:57:58 2d kernel: [ 4320.212337]  [<ffffffff8108a27c>] kthread+0x8c/0xa0
Dec  3 12:57:58 2d kernel: [ 4320.212347]  [<ffffffff81666534>] kernel_thread_helper+0x4/0x10
Dec  3 12:57:58 2d kernel: [ 4320.212354]  [<ffffffff8108a1f0>] ? flush_kthread_worker+0xa0/0xa0
Dec  3 12:57:58 2d kernel: [ 4320.212361]  [<ffffffff81666530>] ? gs_change+0x13/0x13

@automaticgiant
Copy link

Ooh. There goes my first automatic reboot. Do you think it's better to leave the system running with hung tasks getting killed periodically and potentially an OOM crash because it might keep working on whatever zfs task it's doing that's hanging or keep the system rebooting so that it doesn't die? I think i'll disable the rebooting and let it run for the near future to see if the arc cap keeps it from dieing.
The reason I ask is that the last big zfs ordeal I went through was accidentally deleting a snapshot or some snapshots before moving the pool to a new system. I say accidentally because I realized later that that was what was keeping the pool from importing - it hadn't finished. I tried to bail on the delete because it was taking a while and I didn't wait for it to finish and so didn't export. I had to wait hours upon hours for it to finish the process on the new box when importing. So I guess it's possible that an automatically triggered snapshot removal from zfs-auto-snapshot is what it's working on, although I think the hangs are new.

@automaticgiant
Copy link

9647 sec later (2.6 hours), 8 OOM errors. killed mountall, upstart-udev-br, upstart-socket-, smbd(x2), dbus-daemon, dhclient3, rsyslogd, but interestingly enough, left dnsmasq running to interfere with other computers networking(should have disabled authoritative.) anyway, i didn't get a final kernel error on the tty, but it is unresponsive aside from REISUB, which unsurprisingly found no tasks left to kill. drive lights were finally dead too. sometimes, when i get partway through reisub, disk activity resumes like it's continuing the normal background zfs stuff.
i'm going to turn off dnsmasq authoritative (not especially relevant) and turn back on panic rebooting and hopefully, the pool will come back online one day. :(

@automaticgiant
Copy link

Tried something new. Booted to a FreeBSD 9 memdisk.img and it imported and exported fine. Figured it would wait a while, doing slow access, finishing whatever was keeping it from working and then be usable. It didn't really take long though. Then I boot back into Ubuntu 12.04.1, and have the same problems as before. ZFS for Ubuntu was great for a few months I think it was. I had migrated the pool from FreeBSD 8 so I would have an OS in common with my network and an easier time with software and operations in general. I can't imaging moving back to FreeBSD now. Well, I can, but I don't like it. Hmm... just remembered, I'm pretty sure that for a while, the pool was imported on Ubuntu and would block when trying to mount. After I got it more or less exported, it would hang on import. I guess I need to try importing and mounting on FreeBSD.

@automaticgiant
Copy link

Imported, mounted, exported on FreeBSD. Imported, mounted on Ubuntu. Everything looked wonderful. OOMx4, then, with my panic on hung setting, panicked about scsi_eh_0 blocking for 3600 sec.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Component: Memory Management kernel memory management
Projects
None yet
Development

No branches or pull requests

4 participants