hibernate fails and says "/bin/echo: write error: Device or resource busy"

[spectromas]

I've had this problem since kernel 4.4.0 so it could be regression but it is also present in 4.4.0-r1. I've found quite a few old posts around on google about it but no solutions. This is all with hibernate-script.

 

I can hibernate-ram but when I hibernate the screen goes black with the flashing cursor, as expected, but then comes back with this message:

hibernate
Goodbye!
/bin/echo: write error: Device or resource busy
 * Running hdparm on /dev/sda ... [ ok ]
Welcome Back!

In my log I see this:

Jan 31 22:53:36 localhost kernel: hibernate (12071): drop_caches: 3
Jan 31 22:53:37 localhost kernel: usbcore: deregistering interface driver uvcvideo
Jan 31 22:53:37 localhost kernel: PM: Syncing filesystems ... done.
Jan 31 22:53:37 localhost kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Jan 31 22:53:37 localhost kernel: PM: Preallocating image memory... done (allocated 345927 pages)
Jan 31 22:53:37 localhost kernel: PM: Allocated 1383708 kbytes in 0.18 seconds (7687.26 MB/s)
Jan 31 22:53:37 localhost kernel: Freezing remaining freezable tasks ...~
Jan 31 22:53:57 localhost kernel: Freezing of tasks failed after 20.007 seconds (1 tasks refusing to freeze, wq_busy=0):
Jan 31 22:53:57 localhost kernel: xfsaild/sda3    S 0000000000013ec0     0    88      2 0x00000000
Jan 31 22:53:57 localhost kernel:  ffff8800dbb9be10 ffff8800dbb49f80 0000000000000001 ffff8800dbb49f80
Jan 31 22:53:57 localhost kernel:  ffff8800dbb9c000 0000000000000000 0000000000000000 0000000000000010
Jan 31 22:53:57 localhost kernel:  ffff8800db883d80 ffff8800dbb9bed0 ffffffff814fa883 ffff8800db883d90
Jan 31 22:53:57 localhost kernel: Call Trace:
Jan 31 22:53:57 localhost kernel:  [<ffffffff814fa883>] ? schedule+0x33/0x80
Jan 31 22:53:57 localhost kernel:  [<ffffffff81245a73>] ? xfsaild+0x503/0x530
Jan 31 22:53:57 localhost kernel:  [<ffffffff81245570>] ? xfs_trans_ail_cursor_first+0x80/0x80
Jan 31 22:53:57 localhost kernel:  [<ffffffff81245570>] ? xfs_trans_ail_cursor_first+0x80/0x80
Jan 31 22:53:57 localhost kernel:  [<ffffffff8105b808>] ? kthread+0xb8/0xd0
Jan 31 22:53:57 localhost kernel:  [<ffffffff8105b750>] ? kthread_create_on_node+0x170/0x170
Jan 31 22:53:57 localhost kernel:  [<ffffffff814fde6f>] ? ret_from_fork+0x3f/0x70
Jan 31 22:53:57 localhost kernel:  [<ffffffff8105b750>] ? kthread_create_on_node+0x170/0x170
Jan 31 22:53:57 localhost kernel:~
Jan 31 22:53:57 localhost kernel: Restarting kernel threads ... done.
Jan 31 22:53:57 localhost kernel: Restarting tasks ... done.
Jan 31 22:53:57 localhost kernel: atkbd serio0: Unknown key pressed (translated set 2, code 0x8d on isa0060/serio0).
Jan 31 22:53:57 localhost kernel: atkbd serio0: Use 'setkeycodes e00d <keycode>' to make it known.
Jan 31 22:53:58 localhost kernel: uvcvideo: Unable to create debugfs directory
Jan 31 22:53:58 localhost kernel: uvcvideo: Found UVC 1.00 device Integrated Webcam (05ca:180a)
Jan 31 22:53:58 localhost kernel: input: Integrated Webcam as /devices/pci0000:00/0000:00:1a.7/usb1/1-6/1-6:1.0/input/input26
Jan 31 22:53:58 localhost kernel: usbcore: registered new interface driver uvcvideo
Jan 31 22:53:58 localhost kernel: USB Video Class driver (1.1.1)

There is something in hibernate.log as well:

Starting suspend at Mon Feb 1 07:42:56 CST 2016
Feb 1 07:42:56.70 hibernate: [00] Executing MiscLaunchAuxFunc1 ...~
Executing sync; echo 3 > /proc/sys/vm/drop_caches...
Feb 1 07:42:56.94 hibernate: [01] Executing CheckLastResume ...~
Feb 1 07:42:56.98 hibernate: [01] Executing CheckRunlevel ...~
Feb 1 07:42:56.99 hibernate: [01] Executing LockFileGet ...~
Feb 1 07:42:56.99 hibernate: [01] Executing NewKernelFileCheck ...~
Feb 1 07:42:57.00 hibernate: [10] Executing EnsureSysfsPowerStateCapable ...~
Feb 1 07:42:57.02 hibernate: [11] Executing XHacksSuspendHook1 ...~
Feb 1 07:42:57.02 hibernate: [20] Executing MiscLaunchAuxFunc2 ...~
Executing echo "Goodbye!"...
Goodbye!
Feb 1 07:42:57.10 hibernate: [30] Executing ServicesStop ...~
Feb 1 07:42:57.22 hibernate: [59] Executing RemountXFSBootRO ...~
Feb 1 07:42:57.22 hibernate: [89] Executing SaveKernelModprobe ...~
Feb 1 07:42:57.23 hibernate: [90] Executing ModulesUnload ...~
Feb 1 07:42:57.24 hibernate: [91] Executing ModulesUnloadBlacklist ...~
Feb 1 07:42:57.66 hibernate: [95] Executing XHacksSuspendHook2 ...~
Feb 1 07:42:57.74 hibernate: [98] Executing CheckRunlevel ...~
Feb 1 07:42:57.75 hibernate: [98] Executing FullSpeedCPUSuspend ...~
Feb 1 07:42:57.80 hibernate: [99] Executing DoSysfsPowerStateSuspend ...~
Feb 1 07:42:57.80 hibernate: Activating sysfs power state disk ...
Feb 1 07:43:18.32 hibernate: [98] Executing FullSpeedCPUResume ...~
Feb 1 07:43:18.33 hibernate: [91] Executing GentooModulesAutoload ...~
Feb 1 07:43:18.54 hibernate: [90] Executing ModulesLoad ...~
Feb 1 07:43:18.61 hibernate: [89] Executing RestoreKernelModprobe ...~
Feb 1 07:43:18.62 hibernate: [85] Executing XHacksResumeHook2 ...~
Feb 1 07:43:18.63 hibernate: [70] Executing ClockRestore ...~
Feb 1 07:43:19.00 hibernate: [59] Executing RemountXFSBootRW ...~
Feb 1 07:43:19.01 hibernate: [30] Executing ServicesStart ...~
 * Running hdparm on /dev/sda ... [ ok ]
Feb 1 07:43:19.12 hibernate: [20] Executing MiscLaunchAuxFunc3 ...~
Executing echo "Welcome Back!"...
Welcome Back!
Feb 1 07:43:19.13 hibernate: [11] Executing XHacksResumeHook1 ...~
Feb 1 07:43:19.13 hibernate: [01] Executing NoteLastResume ...~
Feb 1 07:43:19.14 hibernate: [01] Executing LockFilePut ...~
Resumed at Mon Feb 1 07:43:19 CST 2016

I'm not sure how to find out what is the root cause of this though, I know it says that a task fails to stop but which task and why?