Your message dated Mon, 19 Dec 2016 09:55:14 +0100
with message-id <[email protected]>
and subject line Re: Bug#782290: /lib/systemd/systemd-sleep: On suspend,
"Freezing of tasks failed", systemd-sleep "blocked for more than 120 seconds"
has caused the Debian Bug report #782290,
regarding /lib/systemd/systemd-sleep: On suspend, "Freezing of tasks failed",
systemd-sleep "blocked for more than 120 seconds"
to be marked as done.
This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.
(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact [email protected]
immediately.)
--
782290: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=782290
Debian Bug Tracking System
Contact [email protected] with problems
--- Begin Message ---
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
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
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
--- End Message ---
--- Begin Message ---
On Fri, 10 Apr 2015 09:07:13 +0200 Michael Biebl <[email protected]> wrote:
> Is this problem reproducible, can you trigger it again, even after a reboot?
>
> To me, this looks more like a kernel issue then a systemd-sleep issue.
> systemd-sleep basically just does 'echo "mem" > /sys/power/state'.
Closing due to lack of further feedback.
Please reopen if you still run into this issue on an up-to-date
sid/testing system.
Regards,
Michael
--
Why is it that all of the instruments seeking intelligent life in the
universe are pointed away from Earth?
signature.asc
Description: OpenPGP digital signature
--- End Message ---
_______________________________________________
Pkg-systemd-maintainers mailing list
[email protected]
http://lists.alioth.debian.org/cgi-bin/mailman/listinfo/pkg-systemd-maintainers
