Bug#782290: /lib/systemd/systemd-sleep: On suspend, "Freezing of tasks failed", systemd-sleep "blocked for more than 120 seconds"
nandhp
nandhp at gmail.com
Fri Apr 10 02:59:55 BST 2015
Package: systemd
Version: 215-14
File: /lib/systemd/systemd-sleep
Dear Maintainer,
At 17:45 today I put my laptop (MacbookAir5,2) into suspend by closing
the lid, and then I put it in my bag. When I removed it at 19:55, I
noticed the fan was running. I opened the lid, and the laptop appeared
to enter suspend almost immediately. When I tapped on the keyboard to
resume it, I was surprised to see no obvious evidence the system had
been running while in my bag -- for example, my thermal logger
recorded no data between 17:45 and 19:55. However, I found some
suspicicous messages in the kernel log:
Freezing of tasks failed after 20.002 seconds (1 tasks refusing to
freeze, wq_busy=0)
and:
INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
I have attached excerpts from daemon.log and kern.log, which include
stack traces for these messages.
This is the first time I have experienced this issue. However, I have
previously encountered issues with the system not entering suspend
correctly: after closing the lid it enters suspend, but then resumes a
few seconds later (this can be identified by the Apple logo lighting
up again). This behavior is different, because the system is fully
functional when it resumes and it does not try to reenter suspend when
I open the lid. This problem occurs with some regularity, but
intermittently, and has no characteristic messages in the error log.
Note that I have written two custom scripts that systemd runs on
resume from suspend:
- nandhp-wl-rescan is run on resume to cause the wireless adapter to
agressively rescan for Wi-Fi networks when resuming from suspend
(this improves the time to reconnection).
- nandhp-lid-check tries to work around the previously experienced
suspend issue by checking if the lid of the computer is closed and
tries to return to suspend if it is (unless the script has already
triggered recently). This script was installed about a month ago,
and the problem has mysteriously failed to occur ever since (the
script has not activated).
Neither of the scripts performed any interesting actions during this
event because the Wi-Fi was disabled and the lid open at the time that
they ran (see daemon.log). However, I'd be happy to provide more
details about these scripts if desired.
System uptime is 44 days, but systemd was upgraded from 215-12 to
215-14 yesterday.
Thanks.
-nandhp
-- Package-specific info:
-- System Information:
Debian Release: 8.0
APT prefers testing
APT policy: (500, 'testing')
Architecture: amd64 (x86_64)
Foreign Architectures: i386
Kernel: Linux 3.16.0-4-amd64 (SMP w/4 CPU cores)
Locale: LANG=en_US.utf8, LC_CTYPE=en_US.utf8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Init: systemd (via /run/systemd/system)
Versions of packages systemd depends on:
ii acl 2.2.52-2
ii adduser 3.113+nmu3
ii initscripts 2.88dsf-58
ii libacl1 2.2.52-2
ii libaudit1 1:2.4-1+b1
ii libblkid1 2.25.2-6
ii libc6 2.19-17
ii libcap2 1:2.24-8
ii libcap2-bin 1:2.24-8
ii libcryptsetup4 2:1.6.6-5
ii libgcrypt20 1.6.3-2
ii libkmod2 18-3
ii liblzma5 5.1.1alpha+20120614-2+b3
ii libpam0g 1.1.8-3.1
ii libselinux1 2.3-2
ii libsystemd0 215-14
ii mount 2.25.2-6
ii sysv-rc 2.88dsf-58
ii udev 215-14
ii util-linux 2.25.2-6
Versions of packages systemd recommends:
ii dbus 1.8.16-1
ii libpam-systemd 215-14
Versions of packages systemd suggests:
pn systemd-ui <none>
-- no debconf information
-------------- next part --------------
Apr 9 17:45:24 hostname ddclient[1203]: WARNING: file /var/cache/ddclient/ddclient.cache, line 1: program version mismatch; ignoring /var/cache/ddclient/ddclient.cache
Apr 9 17:47:40 hostname NetworkManager[12430]: <info> sleep requested (sleeping: no enabled: yes)
Apr 9 17:47:40 hostname NetworkManager[12430]: <info> sleeping...
Apr 9 17:47:40 hostname NetworkManager[12430]: <info> (wlan0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Apr 9 17:47:40 hostname NetworkManager[12430]: <info> (XX:XX:XX:XX:XX:XX): device state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
Apr 9 17:47:40 hostname NetworkManager[12430]: <info> NetworkManager state is now ASLEEP
Apr 9 17:47:40 hostname systemd-sleep[12830]: Suspending system...
Apr 9 19:54:50 hostname systemd[1]: systemd-logind.service watchdog timeout (limit 1min)!
Apr 9 19:54:50 hostname systemd[1]: Unit systemd-logind.service entered failed state.
Apr 9 19:54:50 hostname systemd-sleep[12830]: System resumed.
Apr 9 19:54:50 hostname systemd[1]: Requested transaction contradicts existing jobs: File exists
Apr 9 19:54:50 hostname ddclient[1203]: WARNING: file /var/cache/ddclient/ddclient.cache, line 1: program version mismatch; ignoring /var/cache/ddclient/ddclient.cache
Apr 9 19:54:50 hostname freshclam[789]: Received signal: wake up
Apr 9 19:54:50 hostname freshclam[789]: ClamAV update process started at Thu Apr 9 19:54:50 2015
Apr 9 19:54:50 hostname freshclam[789]: WARNING: Can't query current.cvd.clamav.net
Apr 9 19:54:50 hostname freshclam[789]: WARNING: Invalid DNS reply. Falling back to HTTP mode.
Apr 9 19:54:50 hostname freshclam[789]: Reading CVD header (main.cvd): WARNING: Can't get information about db.local.clamav.net: Name or service not known
Apr 9 19:54:50 hostname freshclam[789]: WARNING: Can't read main.cvd header from db.local.clamav.net (IP: )
Apr 9 19:54:50 hostname freshclam[789]: Trying again in 5 secs...
Apr 9 19:54:50 hostname nandhp-lid-check[13019]: Lid is not closed ('open'), ignoring
Apr 9 19:54:50 hostname nandhp-wl-rescan[13020]: Not scanning: network is down
Apr 9 19:54:53 hostname systemd-sleep[13043]: Suspending system...
Apr 9 19:55:10 hostname systemd[2135]: Time has been changed
Apr 9 19:55:10 hostname systemd[1543]: Time has been changed
Apr 9 19:55:10 hostname systemd-sleep[13043]: System resumed.
Apr 9 19:55:10 hostname systemd[1]: Requested transaction contradicts existing jobs: File exists
Apr 9 19:55:10 hostname NetworkManager[12430]: <info> wake requested (sleeping: yes enabled: yes)
Apr 9 19:55:10 hostname NetworkManager[12430]: <info> waking up...
Apr 9 19:55:10 hostname NetworkManager[12430]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Apr 9 19:55:10 hostname NetworkManager[12430]: <info> (wlan0): preparing device
Apr 9 19:55:10 hostname NetworkManager[12430]: <info> (XX:XX:XX:XX:XX:XX): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Apr 9 19:55:10 hostname NetworkManager[12430]: <info> (XX:XX:XX:XX:XX:XX): preparing device
Apr 9 19:55:10 hostname NetworkManager[12430]: <info> NetworkManager state is now DISCONNECTED
Apr 9 19:55:10 hostname NetworkManager[12430]: <info> (XX:XX:XX:XX:XX:XX): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Apr 9 19:55:10 hostname nandhp-lid-check[13056]: Lid is not closed ('open'), ignoring
Apr 9 19:55:10 hostname nandhp-wl-rescan[13057]: Not scanning: network is down
-------------- next part --------------
Apr 9 17:48:00 hostname kernel: [1030214.270342] PM: Syncing filesystems ... done.
Apr 9 17:48:00 hostname kernel: [1030214.297392] PM: Preparing system for mem sleep
Apr 9 17:48:00 hostname kernel: [1030214.297504] Freezing user space processes ...
Apr 9 17:48:00 hostname kernel: [1030234.290079] Freezing of tasks failed after 20.002 seconds (1 tasks refusing to freeze, wq_busy=0):
Apr 9 17:48:00 hostname kernel: [1030234.290206] pkexec D ffff880101f75808 0 12828 2249 0x00000004
Apr 9 17:48:00 hostname kernel: [1030234.290216] ffff880101f753b0 0000000000000082 0000000000013280 ffff88010367bfd8
Apr 9 17:48:00 hostname kernel: [1030234.290222] 0000000000013280 ffff880101f753b0 ffff8800072f6988 ffff88010367bd90
Apr 9 17:48:00 hostname kernel: [1030234.290228] ffff880101f753b0 ffff880103672940 ffff880101f753b0 ffff8800072f6180
Apr 9 17:48:00 hostname kernel: [1030234.290235] Call Trace:
Apr 9 17:48:00 hostname kernel: [1030234.290251] [<ffffffff811adeaf>] ? flush_old_exec+0xdf/0x7e0
Apr 9 17:48:00 hostname kernel: [1030234.290260] [<ffffffff811f70b2>] ? load_elf_binary+0x342/0x1880
Apr 9 17:48:00 hostname kernel: [1030234.290273] [<ffffffffa0f7005e>] ? load_misc_binary+0x5e/0x460 [binfmt_misc]
Apr 9 17:48:00 hostname kernel: [1030234.290281] [<ffffffff811ad85f>] ? copy_strings.isra.18+0x27f/0x2f0
Apr 9 17:48:00 hostname kernel: [1030234.290289] [<ffffffff811ad9a1>] ? search_binary_handler+0x91/0x1a0
Apr 9 17:48:00 hostname kernel: [1030234.290296] [<ffffffff811aecf7>] ? do_execve_common.isra.23+0x4d7/0x630
Apr 9 17:48:00 hostname kernel: [1030234.290304] [<ffffffff811af084>] ? SyS_execve+0x24/0x30
Apr 9 17:48:00 hostname kernel: [1030234.290313] [<ffffffff8150fd29>] ? stub_execve+0x69/0xa0
Apr 9 17:48:00 hostname kernel: [1030234.290317]
Apr 9 17:48:00 hostname kernel: [1030234.290320] Restarting tasks ... done.
Apr 9 19:54:49 hostname kernel: [1030234.406025] PM: Syncing filesystems ... done.
Apr 9 19:54:49 hostname kernel: [1030234.428369] PM: Preparing system for freeze sleep
Apr 9 19:54:50 hostname kernel: [1030234.428492] Freezing user space processes ... (elapsed 0.005 seconds) done.
Apr 9 19:54:50 hostname kernel: [1030234.434325] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Apr 9 19:54:50 hostname kernel: [1030234.435499] PM: Entering freeze sleep
Apr 9 19:54:50 hostname kernel: [1030234.435501] Suspending console(s) (use no_console_suspend to debug)
Apr 9 19:54:50 hostname kernel: [1030234.435864] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Apr 9 19:54:50 hostname kernel: [1030234.436015] wl_suspend: PCI Suspend handler
Apr 9 19:54:50 hostname kernel: [1030234.436016] wl_suspend: Not WOWL capable
Apr 9 19:54:50 hostname kernel: [1030234.437884] sd 0:0:0:0: [sda] Stopping disk
Apr 9 19:54:50 hostname kernel: [1030234.862811] PM: suspend of devices complete after 427.407 msecs
Apr 9 19:54:50 hostname kernel: [1030234.877282] PM: late suspend of devices complete after 14.469 msecs
Apr 9 19:54:50 hostname kernel: [1030234.878609] ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
Apr 9 19:54:50 hostname kernel: [1030234.878956] ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI
Apr 9 19:54:50 hostname kernel: [1030234.878961] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
Apr 9 19:54:50 hostname kernel: [1030234.893282] PM: noirq suspend of devices complete after 16.002 msecs
Apr 9 19:54:50 hostname kernel: [1030404.203896] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1030404.203900] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1030404.203901] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1030404.203909] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1030404.203915] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1030404.203918] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1030404.203922] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1030404.203924] Call Trace:
Apr 9 19:54:50 hostname kernel: [1030404.203940] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1030404.203945] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1030404.203951] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1030404.203958] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1030404.203965] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1030404.203973] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1030404.203980] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1030404.203987] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1030404.203993] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1030524.140750] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1030524.140754] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1030524.140755] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1030524.140762] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1030524.140767] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1030524.140771] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1030524.140774] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1030524.140776] Call Trace:
Apr 9 19:54:50 hostname kernel: [1030524.140787] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1030524.140793] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1030524.140799] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1030524.140805] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1030524.140810] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1030524.140817] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1030524.140823] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1030524.140828] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1030524.140833] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1030644.080914] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1030644.080918] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1030644.080919] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1030644.080926] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1030644.080931] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1030644.080935] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1030644.080939] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1030644.080940] Call Trace:
Apr 9 19:54:50 hostname kernel: [1030644.080952] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1030644.080957] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1030644.080963] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1030644.080969] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1030644.080975] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1030644.080981] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1030644.080987] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1030644.080992] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1030644.080997] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1030764.021103] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1030764.021107] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1030764.021108] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1030764.021115] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1030764.021120] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1030764.021124] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1030764.021127] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1030764.021129] Call Trace:
Apr 9 19:54:50 hostname kernel: [1030764.021140] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1030764.021145] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1030764.021151] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1030764.021157] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1030764.021162] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1030764.021168] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1030764.021174] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1030764.021180] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1030764.021185] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1030883.961210] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1030883.961213] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1030883.961214] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1030883.961221] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1030883.961226] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1030883.961230] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1030883.961234] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1030883.961235] Call Trace:
Apr 9 19:54:50 hostname kernel: [1030883.961247] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1030883.961252] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1030883.961258] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1030883.961264] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1030883.961269] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1030883.961275] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1030883.961281] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1030883.961287] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1030883.961292] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1031003.901391] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1031003.901395] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1031003.901396] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1031003.901403] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1031003.901408] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1031003.901412] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1031003.901415] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1031003.901417] Call Trace:
Apr 9 19:54:50 hostname kernel: [1031003.901428] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1031003.901433] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1031003.901440] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1031003.901446] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1031003.901451] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1031003.901457] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1031003.901464] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1031003.901469] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1031003.901474] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1031123.841596] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1031123.841600] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1031123.841601] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1031123.841608] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1031123.841613] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1031123.841617] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1031123.841620] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1031123.841622] Call Trace:
Apr 9 19:54:50 hostname kernel: [1031123.841633] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1031123.841638] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1031123.841645] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1031123.841650] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1031123.841655] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1031123.841662] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1031123.841668] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1031123.841674] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1031123.841679] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1031243.781716] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1031243.781719] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1031243.781720] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1031243.781727] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1031243.781732] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1031243.781736] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1031243.781740] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1031243.781741] Call Trace:
Apr 9 19:54:50 hostname kernel: [1031243.781752] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1031243.781757] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1031243.781763] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1031243.781769] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1031243.781774] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1031243.781780] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1031243.781786] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1031243.781792] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1031243.781797] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1031363.721913] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1031363.721917] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1031363.721918] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1031363.721925] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1031363.721930] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1031363.721933] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1031363.721937] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1031363.721938] Call Trace:
Apr 9 19:54:50 hostname kernel: [1031363.721950] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1031363.721955] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1031363.721961] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1031363.721966] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1031363.721972] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1031363.721978] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1031363.721984] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1031363.721990] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1031363.721995] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1031483.821297] INFO: task systemd-sleep:12830 blocked for more than 120 seconds.
Apr 9 19:54:50 hostname kernel: [1031483.821301] Tainted: P W O 3.16.0-4-amd64 #1
Apr 9 19:54:50 hostname kernel: [1031483.821302] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Apr 9 19:54:50 hostname kernel: [1031483.821309] systemd-sleep D ffff8801015ba6a8 0 12830 1 0x00000000
Apr 9 19:54:50 hostname kernel: [1031483.821314] ffff8801015ba250 0000000000000086 0000000000013280 ffff88010efd3fd8
Apr 9 19:54:50 hostname kernel: [1031483.821318] 0000000000013280 ffff8801015ba250 0000000000000000 0000000000000001
Apr 9 19:54:50 hostname kernel: [1031483.821322] ffff88010efd3e48 ffffffff81722d10 ffffffff81722ba8 0000000000000006
Apr 9 19:54:50 hostname kernel: [1031483.821323] Call Trace:
Apr 9 19:54:50 hostname kernel: [1031483.821334] [<ffffffff810af6c5>] ? suspend_devices_and_enter+0x2c5/0x530
Apr 9 19:54:50 hostname kernel: [1031483.821339] [<ffffffff810a7840>] ? prepare_to_wait_event+0xf0/0xf0
Apr 9 19:54:50 hostname kernel: [1031483.821346] [<ffffffff810afa1b>] ? pm_suspend+0xeb/0x370
Apr 9 19:54:50 hostname kernel: [1031483.821351] [<ffffffff810ae67b>] ? state_store+0x6b/0xe0
Apr 9 19:54:50 hostname kernel: [1031483.821356] [<ffffffff81216fba>] ? kernfs_fop_write+0xda/0x150
Apr 9 19:54:50 hostname kernel: [1031483.821363] [<ffffffff811a7b72>] ? vfs_write+0xb2/0x1f0
Apr 9 19:54:50 hostname kernel: [1031483.821369] [<ffffffff811a86b2>] ? SyS_write+0x42/0xa0
Apr 9 19:54:50 hostname kernel: [1031483.821375] [<ffffffff815117a8>] ? page_fault+0x28/0x30
Apr 9 19:54:50 hostname kernel: [1031483.821379] [<ffffffff8150f76d>] ? system_call_fast_compare_end+0x10/0x15
Apr 9 19:54:50 hostname kernel: [1037839.370267] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
Apr 9 19:54:50 hostname kernel: [1037839.370291] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
Apr 9 19:54:50 hostname kernel: [1037839.370499] ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI
Apr 9 19:54:50 hostname kernel: [1037839.370809] PM: noirq resume of devices complete after 15.436 msecs
Apr 9 19:54:50 hostname kernel: [1037839.371557] PM: early resume of devices complete after 0.706 msecs
Apr 9 19:54:50 hostname kernel: [1037839.371713] mei_me 0000:00:16.0: irq 45 for MSI/MSI-X
Apr 9 19:54:50 hostname kernel: [1037839.372147] wl_resume: PCI Resume handler
Apr 9 19:54:50 hostname kernel: [1037839.375148] snd_hda_intel 0000:00:1b.0: irq 46 for MSI/MSI-X
Apr 9 19:54:50 hostname kernel: [1037839.376656] sd 0:0:0:0: [sda] Starting disk
Apr 9 19:54:50 hostname kernel: [1037839.693819] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Apr 9 19:54:50 hostname kernel: [1037840.052585] PM: resume of devices complete after 681.360 msecs
Apr 9 19:54:50 hostname kernel: [1037840.053192] PM: Finishing wakeup.
Apr 9 19:54:50 hostname kernel: [1037840.053195] Restarting tasks ... done.
Apr 9 19:54:50 hostname kernel: [1037840.450935] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Apr 9 19:54:50 hostname kernel: [1037840.548583] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Apr 9 19:54:50 hostname kernel: [1037840.549079] ata1.00: configured for UDMA/133
Apr 9 19:54:50 hostname kernel: [1037841.025186] [drm] Enabling RC6 states: RC6 on, RC6p on, RC6pp off
Apr 9 19:55:10 hostname kernel: [1037844.272243] PM: Syncing filesystems ... done.
Apr 9 19:55:10 hostname kernel: [1037844.327731] PM: Preparing system for mem sleep
Apr 9 19:55:10 hostname kernel: [1037844.328027] Freezing user space processes ... (elapsed 0.002 seconds) done.
Apr 9 19:55:10 hostname kernel: [1037844.330653] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Apr 9 19:55:10 hostname kernel: [1037844.331665] PM: Entering mem sleep
Apr 9 19:55:10 hostname kernel: [1037844.331721] Suspending console(s) (use no_console_suspend to debug)
Apr 9 19:55:10 hostname kernel: [1037844.332576] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Apr 9 19:55:10 hostname kernel: [1037844.332823] wl_suspend: PCI Suspend handler
Apr 9 19:55:10 hostname kernel: [1037844.332826] wl_suspend: Not WOWL capable
Apr 9 19:55:10 hostname kernel: [1037844.333766] sd 0:0:0:0: [sda] Stopping disk
Apr 9 19:55:10 hostname kernel: [1037844.399752] PM: suspend of devices complete after 67.799 msecs
Apr 9 19:55:10 hostname kernel: [1037844.415544] PM: late suspend of devices complete after 15.791 msecs
Apr 9 19:55:10 hostname kernel: [1037844.416736] ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
Apr 9 19:55:10 hostname kernel: [1037844.416835] ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI
Apr 9 19:55:10 hostname kernel: [1037844.417006] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
Apr 9 19:55:10 hostname kernel: [1037844.431611] PM: noirq suspend of devices complete after 16.068 msecs
Apr 9 19:55:10 hostname kernel: [1037844.432272] ACPI: Preparing to enter system sleep state S3
Apr 9 19:55:10 hostname kernel: [1037844.743384] PM: Saving platform NVS memory
Apr 9 19:55:10 hostname kernel: [1037844.743948] Disabling non-boot CPUs ...
Apr 9 19:55:10 hostname kernel: [1037844.744069] intel_pstate CPU 1 exiting
Apr 9 19:55:10 hostname kernel: [1037844.745503] kvm: disabling virtualization on CPU1
Apr 9 19:55:10 hostname kernel: [1037844.847238] smpboot: CPU 1 is now offline
Apr 9 19:55:10 hostname kernel: [1037844.848099] intel_pstate CPU 2 exiting
Apr 9 19:55:10 hostname kernel: [1037844.849463] kvm: disabling virtualization on CPU2
Apr 9 19:55:10 hostname kernel: [1037845.363674] smpboot: CPU 2 is now offline
Apr 9 19:55:10 hostname kernel: [1037845.364531] intel_pstate CPU 3 exiting
Apr 9 19:55:10 hostname kernel: [1037845.365822] kvm: disabling virtualization on CPU3
Apr 9 19:55:10 hostname kernel: [1037845.466928] smpboot: CPU 3 is now offline
Apr 9 19:55:10 hostname kernel: [1037845.469476] ACPI: Low-level resume complete
Apr 9 19:55:10 hostname kernel: [1037845.469523] PM: Restoring platform NVS memory
Apr 9 19:55:10 hostname kernel: [1037845.469913] Enabling non-boot CPUs ...
Apr 9 19:55:10 hostname kernel: [1037845.470038] x86: Booting SMP configuration:
Apr 9 19:55:10 hostname kernel: [1037845.470039] smpboot: Booting Node 0 Processor 1 APIC 0x2
Apr 9 19:55:10 hostname kernel: [1037845.481819] kvm: enabling virtualization on CPU1
Apr 9 19:55:10 hostname kernel: [1037845.484083] Intel pstate controlling: cpu 1
Apr 9 19:55:10 hostname kernel: [1037845.484182] CPU1 is up
Apr 9 19:55:10 hostname kernel: [1037845.484275] smpboot: Booting Node 0 Processor 2 APIC 0x1
Apr 9 19:55:10 hostname kernel: [1037845.495929] kvm: enabling virtualization on CPU2
Apr 9 19:55:10 hostname kernel: [1037845.498155] Intel pstate controlling: cpu 2
Apr 9 19:55:10 hostname kernel: [1037845.498216] CPU2 is up
Apr 9 19:55:10 hostname kernel: [1037845.498318] smpboot: Booting Node 0 Processor 3 APIC 0x3
Apr 9 19:55:10 hostname kernel: [1037845.510195] kvm: enabling virtualization on CPU3
Apr 9 19:55:10 hostname kernel: [1037845.512494] Intel pstate controlling: cpu 3
Apr 9 19:55:10 hostname kernel: [1037845.512590] CPU3 is up
Apr 9 19:55:10 hostname kernel: [1037845.516527] ACPI: Waking up from system sleep state S3
Apr 9 19:55:10 hostname kernel: [1037845.752862] /dev/vmmon[0]: HostIFReadUptimeWork: detected settimeofday: fixed uptimeBase old 18445316484766081191 new 18445316484753124545 attempts 1
Apr 9 19:55:10 hostname kernel: [1037847.552201] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
Apr 9 19:55:10 hostname kernel: [1037847.552248] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
Apr 9 19:55:10 hostname kernel: [1037847.552444] ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI
Apr 9 19:55:10 hostname kernel: [1037847.552495] PM: noirq resume of devices complete after 15.955 msecs
Apr 9 19:55:10 hostname kernel: [1037847.552816] PM: early resume of devices complete after 0.294 msecs
Apr 9 19:55:10 hostname kernel: [1037847.552920] mei_me 0000:00:16.0: irq 45 for MSI/MSI-X
Apr 9 19:55:10 hostname kernel: [1037847.553082] wl_resume: PCI Resume handler
Apr 9 19:55:10 hostname kernel: [1037847.556032] sd 0:0:0:0: [sda] Starting disk
Apr 9 19:55:10 hostname kernel: [1037847.561452] snd_hda_intel 0000:00:1b.0: irq 46 for MSI/MSI-X
Apr 9 19:55:10 hostname kernel: [1037847.879832] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Apr 9 19:55:10 hostname kernel: [1037847.884265] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Apr 9 19:55:10 hostname kernel: [1037847.894212] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Apr 9 19:55:10 hostname kernel: [1037847.894945] ata1.00: configured for UDMA/133
Apr 9 19:55:10 hostname kernel: [1037848.224994] PM: resume of devices complete after 672.511 msecs
Apr 9 19:55:10 hostname kernel: [1037848.225144] PM: Finishing wakeup.
Apr 9 19:55:10 hostname kernel: [1037848.225145] Restarting tasks ... done.
Apr 9 19:55:11 hostname kernel: [1037849.023435] [drm] Enabling RC6 states: RC6 on, RC6p on, RC6pp off
More information about the Pkg-systemd-maintainers
mailing list