You are not logged in.

#1 2012-12-04 15:02:26

xinit
Member
Registered: 2011-11-12
Posts: 23

[SOLVED] suspend to ram - wake up via usb fails

Hello,

I own a Dell Vostro 1310.

Suspend systemctl suspend and hibernate systemctl hibernate works without any problems, also resuming from these.
But when I suspend my system to RAM I can wake up only via power button - wake up via USB fails.

In BIOS I enabled 'USB wake support (only AC mode)'.

http://ompldr.org/vZ2thaQ/bios.jpg

I also tried using another keyboard (the one of my brother), but it fails.
If I boot Windows 7, the system is able to wake up via USB.
If I plug my keyboard into my brother's desktop PC (Xubuntu 10.04) and suspend, I can wake up with my keyboard.
When I plug my SSD into my brother's desktop PC and start my system (3.6.8-1-ARCH) wake up via USB (keyboard) works flawlessly.
It also works with my keyboard.

Some informations:

# cat /proc/acpi/wakeup 
Device	S-state	  Status   Sysfs node
LID0	  S3	*enabled   
HDEF	  S3	*disabled  pci:0000:00:1b.0
PXSX	  S3	*disabled  
PXSX	  S5	*enabled   pci:0000:07:00.0
USB1	  S3	*enabled   pci:0000:00:1d.0
USB2	  S3	*enabled   pci:0000:00:1d.1
USB3	  S3	*enabled   pci:0000:00:1d.2
USB4	  S3	*enabled   pci:0000:00:1a.0
USB5	  S3	*enabled   pci:0000:00:1a.1
EHC1	  S3	*enabled   pci:0000:00:1d.7
EHC2	  S3	*enabled   pci:0000:00:1a.7
# lsusb
Bus 001 Device 002: ID 0c45:63e0 Microdia Sonix Integrated Webcam
Bus 006 Device 002: ID 045e:0768 Microsoft Corp. Sidewinder X4
Bus 006 Device 003: ID 046d:c051 Logitech, Inc. G3 (MX518) Optical Mouse
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 005 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 006 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 007 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
# lspci
lspci 
00:00.0 Host bridge: Intel Corporation Mobile PM965/GM965/GL960 Memory Controller Hub (rev 0c)
00:01.0 PCI bridge: Intel Corporation Mobile PM965/GM965/GL960 PCI Express Root Port (rev 0c)
00:1a.0 USB controller: Intel Corporation 82801H (ICH8 Family) USB UHCI Controller #4 (rev 03)
00:1a.1 USB controller: Intel Corporation 82801H (ICH8 Family) USB UHCI Controller #5 (rev 03)
00:1a.7 USB controller: Intel Corporation 82801H (ICH8 Family) USB2 EHCI Controller #2 (rev 03)
00:1b.0 Audio device: Intel Corporation 82801H (ICH8 Family) HD Audio Controller (rev 03)
00:1c.0 PCI bridge: Intel Corporation 82801H (ICH8 Family) PCI Express Port 1 (rev 03)
00:1c.1 PCI bridge: Intel Corporation 82801H (ICH8 Family) PCI Express Port 2 (rev 03)
00:1c.4 PCI bridge: Intel Corporation 82801H (ICH8 Family) PCI Express Port 5 (rev 03)
00:1d.0 USB controller: Intel Corporation 82801H (ICH8 Family) USB UHCI Controller #1 (rev 03)
00:1d.1 USB controller: Intel Corporation 82801H (ICH8 Family) USB UHCI Controller #2 (rev 03)
00:1d.2 USB controller: Intel Corporation 82801H (ICH8 Family) USB UHCI Controller #3 (rev 03)
00:1d.7 USB controller: Intel Corporation 82801H (ICH8 Family) USB2 EHCI Controller #1 (rev 03)
00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev f3)
00:1f.0 ISA bridge: Intel Corporation 82801HM (ICH8M) LPC Interface Controller (rev 03)
00:1f.1 IDE interface: Intel Corporation 82801HM/HEM (ICH8M/ICH8M-E) IDE Controller (rev 03)
00:1f.2 SATA controller: Intel Corporation 82801HM/HEM (ICH8M/ICH8M-E) SATA Controller [AHCI mode] (rev 03)
00:1f.3 SMBus: Intel Corporation 82801H (ICH8 Family) SMBus Controller (rev 03)
01:00.0 VGA compatible controller: NVIDIA Corporation G86 [GeForce 8400M GS] (rev a1)
07:00.0 Ethernet controller: Realtek Semiconductor Co., Ltd. RTL8111/8168B PCI Express Gigabit Ethernet controller (rev 02)
08:05.0 FireWire (IEEE 1394): O2 Micro, Inc. Firewire (IEEE 1394) (rev 02)
08:05.2 SD Host controller: O2 Micro, Inc. Integrated MMC/SD Controller (rev 02)
08:05.3 Mass storage controller: O2 Micro, Inc. Integrated MS/xD Controller (rev 01)

Output of systemctl suspend in kernel.log and wake up via power button:

# cat /var/log/kernel.log
Dec  4 16:31:00 v1310 kernel: [  134.581521] PM: Syncing filesystems ... done.
Dec  4 16:31:00 v1310 kernel: [  134.590403] PM: Preparing system for mem sleep
Dec  4 16:31:08 v1310 kernel: [  135.052990] Freezing user space processes ... (elapsed 0.01 seconds) done.
Dec  4 16:31:08 v1310 kernel: [  135.063408] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Dec  4 16:31:08 v1310 kernel: [  135.076871] PM: Entering mem sleep
Dec  4 16:31:08 v1310 kernel: [  135.076911] Suspending console(s) (use no_console_suspend to debug)
Dec  4 16:31:08 v1310 kernel: [  135.078103] sd 2:0:0:0: [sda] Synchronizing SCSI cache
Dec  4 16:31:08 v1310 kernel: [  135.079063] sd 2:0:0:0: [sda] Stopping disk
Dec  4 16:31:08 v1310 kernel: [  135.117372] ACPI handle has no context!
Dec  4 16:31:08 v1310 kernel: [  135.440231] PM: suspend of devices complete after 362.570 msecs
Dec  4 16:31:08 v1310 kernel: [  135.440809] PM: late suspend of devices complete after 0.570 msecs
Dec  4 16:31:08 v1310 kernel: [  135.441541] r8169 0000:07:00.0: wake-up capability enabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  135.486717] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  135.543383] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  135.586722] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  135.630050] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  135.660047] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  135.716717] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  135.760050] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  135.760219] PM: noirq suspend of devices complete after 319.402 msecs
Dec  4 16:31:08 v1310 kernel: [  135.761235] ACPI: Preparing to enter system sleep state S3
Dec  4 16:31:08 v1310 kernel: [  135.896749] PM: Saving platform NVS memory
Dec  4 16:31:08 v1310 kernel: [  135.896971] Disabling non-boot CPUs ...
Dec  4 16:31:08 v1310 kernel: [  136.000041] smpboot: CPU 1 is now offline
Dec  4 16:31:08 v1310 kernel: [  136.000782] Extended CMOS year: 2000
Dec  4 16:31:08 v1310 kernel: [  136.000782] ACPI: Low-level resume complete
Dec  4 16:31:08 v1310 kernel: [  136.000782] PM: Restoring platform NVS memory
Dec  4 16:31:08 v1310 kernel: [  136.000782] Extended CMOS year: 2000
Dec  4 16:31:08 v1310 kernel: [  136.000782] CPU0: Thermal monitoring handled by SMI
Dec  4 16:31:08 v1310 kernel: [  136.000782] Enabling non-boot CPUs ...
Dec  4 16:31:08 v1310 kernel: [  136.007619] smpboot: Booting Node 0 Processor 1 APIC 0x1
Dec  4 16:31:08 v1310 kernel: [  135.898563] CPU1: Thermal monitoring handled by SMI
Dec  4 16:31:08 v1310 kernel: [  136.020019] CPU1 is up
Dec  4 16:31:08 v1310 kernel: [  136.021949] ACPI: Waking up from system sleep state S3
Dec  4 16:31:08 v1310 kernel: [  136.033405] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  136.046746] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  136.060127] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  136.073879] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  136.086737] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  136.100068] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  136.113461] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  136.126852] nvidia 0000:01:00.0: power state changed by ACPI to D0
Dec  4 16:31:08 v1310 kernel: [  136.167173] PM: noirq resume of devices complete after 144.658 msecs
Dec  4 16:31:08 v1310 kernel: [  136.167498] PM: early resume of devices complete after 0.268 msecs
Dec  4 16:31:08 v1310 kernel: [  136.167570] uhci_hcd 0000:00:1a.0: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.167573] uhci_hcd 0000:00:1a.1: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.167621] usb usb3: root hub lost power or was reset
Dec  4 16:31:08 v1310 kernel: [  136.167623] usb usb4: root hub lost power or was reset
Dec  4 16:31:08 v1310 kernel: [  136.167662] ehci_hcd 0000:00:1a.7: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.167747] snd_hda_intel 0000:00:1b.0: irq 46 for MSI/MSI-X
Dec  4 16:31:08 v1310 kernel: [  136.167903] uhci_hcd 0000:00:1d.0: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.167939] usb usb5: root hub lost power or was reset
Dec  4 16:31:08 v1310 kernel: [  136.167965] uhci_hcd 0000:00:1d.1: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.168000] usb usb6: root hub lost power or was reset
Dec  4 16:31:08 v1310 kernel: [  136.168030] uhci_hcd 0000:00:1d.2: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.168067] usb usb7: root hub lost power or was reset
Dec  4 16:31:08 v1310 kernel: [  136.168095] ehci_hcd 0000:00:1d.7: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.168146] pci 0000:00:1e.0: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.168169] ata_piix 0000:00:1f.1: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.168190] ahci 0000:00:1f.2: setting latency timer to 64
Dec  4 16:31:08 v1310 kernel: [  136.170132] r8169 0000:07:00.0: wake-up capability disabled by ACPI
Dec  4 16:31:08 v1310 kernel: [  136.183713] r8169 0000:07:00.0: eth0: link down
Dec  4 16:31:08 v1310 kernel: [  136.339419] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
Dec  4 16:31:08 v1310 kernel: [  136.339421] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out
Dec  4 16:31:08 v1310 kernel: [  136.350527] ata1.00: configured for UDMA/33
Dec  4 16:31:08 v1310 kernel: [  136.406704] usb 1-3: reset high-speed USB device number 2 using ehci_hcd
Dec  4 16:31:08 v1310 kernel: [  136.490088] ata4: SATA link down (SStatus 0 SControl 300)
Dec  4 16:31:08 v1310 kernel: [  136.490140] ata5: SATA link down (SStatus 0 SControl 300)
Dec  4 16:31:08 v1310 kernel: [  136.493415] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec  4 16:31:08 v1310 kernel: [  136.494962] ata3.00: unexpected _GTF length (8)
Dec  4 16:31:08 v1310 kernel: [  136.504946] ata3.00: unexpected _GTF length (8)
Dec  4 16:31:08 v1310 kernel: [  136.505625] ata3.00: configured for UDMA/133
Dec  4 16:31:08 v1310 kernel: [  136.516754] sd 2:0:0:0: [sda] Starting disk
Dec  4 16:31:08 v1310 kernel: [  136.636819] usb 6-1: reset full-speed USB device number 2 using uhci_hcd
Dec  4 16:31:08 v1310 kernel: [  136.723810] firewire_core 0000:08:05.0: rediscovered device fw0
Dec  4 16:31:08 v1310 kernel: [  136.799160] usbfs 6-1:1.1: no reset_resume for driver usbfs?
Dec  4 16:31:08 v1310 kernel: [  136.833065] Extended CMOS year: 2000
Dec  4 16:31:08 v1310 kernel: [  137.027117] usb 6-2: reset low-speed USB device number 3 using uhci_hcd
Dec  4 16:31:08 v1310 kernel: [  137.342453] PM: resume of devices complete after 1174.945 msecs
Dec  4 16:31:08 v1310 kernel: [  137.361501] input: Microsoft Microsoft® SiderWinderTM X4 Keyboard as /devices/pci0000:00/0000:00:1d.1/usb6/6-1/6-1:1.1/input/input18
Dec  4 16:31:08 v1310 kernel: [  137.361961] hid-generic 0003:045E:0768.0005: input,hidraw1: USB HID v1.11 Keyboard [Microsoft Microsoft® SiderWinderTM X4 Keyboard] on usb-0000:00:1d.1-1/input1
Dec  4 16:31:08 v1310 kernel: [  137.362702] PM: Finishing wakeup.
Dec  4 16:31:08 v1310 kernel: [  137.372240] usb 6-1: usbfs: process 434 (x4daemon) did not claim interface 1 before use
Dec  4 16:31:08 v1310 kernel: [  137.372365] usb 6-1: usbfs: process 434 (x4daemon) did not claim interface 1 before use
Dec  4 16:31:08 v1310 kernel: [  137.362706] Restarting tasks ... done.
Dec  4 16:31:08 v1310 kernel: [  137.396718] video LNXVIDEO:00: Restoring backlight state
Dec  4 16:31:08 v1310 kernel: [  137.798243] r8169 0000:07:00.0: eth0: link up

I think it is an issue with the BIOS, but Windows 7 supports it.

Anyone an idea how i can wake up from suspend via USB?

EDIT:

I tried to add acpi_os_name=... to GRUB_CMDLINE_LINUX_DEFAULT like suggested here: https://wiki.archlinux.org/index.php/Suspend_to_RAM

As suggested in the https://wiki.archlinux.org/index.php/DSDT article:

# cat /sys/firmware/acpi/tables/DSDT > dsdt.dat

# iasl -d dsdt.dat

Intel ACPI Component Architecture
ASL Optimizing Compiler version 20121018-64 [Oct 26 2012]
Copyright (c) 2000 - 2012 Intel Corporation

Loading Acpi table from file dsdt.dat
Acpi table [DSDT] successfully installed and loaded
Pass 1 parse of [DSDT]
Pass 2 parse of [DSDT]
Parsing Deferred Opcodes (Methods/Buffers/Packages/Regions)
........................................................................................................................................................................................................................................................................................................................................................................................................................................................
Parsing completed
Disassembly completed
ASL Output:    dsdt.dsl - 272145 bytes

# grep Windows dsdt.dsl

                If (_OSI ("Windows 2001"))
                If (_OSI ("Windows 2001 SP1"))
                If (_OSI ("Windows 2001 SP2"))
                If (_OSI ("Windows 2006"))

But adding apci_os_name="Windows 2001" ends with kernel panic:

Syntax error: Unterminated Quoted string

I do not even know if this is the right way to go... tongue

Last edited by xinit (2012-12-16 11:45:32)

Offline

#2 2012-12-06 02:16:42

taclm55
Member
Registered: 2012-12-04
Posts: 4

Re: [SOLVED] suspend to ram - wake up via usb fails

Hi,
I'm having the same exact issue here since I upgraded to systemd.  It was working fine before and I was at latest kernel (have dealt with this issue and was able to resolve a couple of months ago thanks to this forum).  This is a home made ICore7 with an ASUS P6T motherboard.  The USB keyboard I'm trying to wake the machine with is on USB4-2, which is enabled for wakeup form previous settings prior to systemd.  It's frustrating since I've been able to fix this a number of times in the past with kernel changes, etc, but this time I'm at my wits end.  Searching the forums for hours is showing me very little.
Info:

[tom@kitchen system-sleep]$ lsusb
Bus 002 Device 002: ID 058f:6254 Alcor Micro Corp. USB Hub
Bus 004 Device 003: ID 045e:0745 Microsoft Corp. Nano Transceiver v1.0 for Bluetooth
Bus 005 Device 002: ID 046d:c52b Logitech, Inc. Unifying Receiver
Bus 005 Device 003: ID 0a5c:4500 Broadcom Corp. BCM2046B1 USB 2.0 Hub (part of BCM2046 Bluetooth)
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 005 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 006 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 007 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 008 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 002 Device 003: ID 13fd:0842 Initio Corporation 
Bus 005 Device 004: ID 0a5c:4502 Broadcom Corp. Keyboard (Boot Interface Subclass)
Bus 005 Device 005: ID 0a5c:4503 Broadcom Corp. Mouse (Boot Interface Subclass)
Bus 005 Device 006: ID 0a5c:2154 Broadcom Corp.
cat /proc/acpi/wakeup 
Device  S-state   Status   Sysfs node
NPE2      S4    *disabled  
NPE4      S4    *disabled  
NPE5      S4    *disabled  
NPE6      S4    *disabled  
NPE8      S4    *disabled  
NPE9      S4    *disabled  
NPEA      S4    *disabled  
P0P1      S4    *disabled  pci:0000:00:1e.0
USB0      S4    *enabled   pci:0000:00:1d.0
USB1      S4    *enabled   pci:0000:00:1d.1
USB2      S4    *enabled   pci:0000:00:1d.2
USB5      S4    *disabled  
EUSB      S4    *enabled   pci:0000:00:1d.7
USB3      S4    *enabled   pci:0000:00:1a.0
USB4      S4    *enabled   pci:0000:00:1a.1
USB6      S4    *enabled   pci:0000:00:1a.2
USBE      S4    *enabled   pci:0000:00:1a.7
P0P4      S4    *disabled  pci:0000:00:1c.0
P0P5      S4    *disabled  
P0P6      S4    *disabled  pci:0000:00:1c.2
P0P7      S4    *disabled  
P0P8      S4    *disabled  pci:0000:00:1c.4
P0P9      S4    *disabled  
NPE1      S4    *disabled  pci:0000:00:01.0
NPE3      S4    *disabled  pci:0000:00:03.0
NPE7      S4    *disabled  pci:0000:00:07.0
GBE       S4    *disabled
kernel.log:
Dec  4 13:36:09 localhost kernel: [50628.568769] PM: Finishing wakeup.
Dec  4 13:36:09 localhost kernel: [50628.568770] Restarting tasks ... done.
Dec  4 13:36:11 localhost kernel: [50630.201437] EXT4-fs (sda3): re-mounted. Opts: commit=0
Dec  4 13:36:11 localhost kernel: [50630.406885] EXT4-fs (sdc2): re-mounted. Opts: commit=0
Dec  4 13:36:11 localhost kernel: [50630.410473] EXT4-fs (sda1): re-mounted. Opts: data=ordered,commit=0
Dec  4 13:36:11 localhost kernel: [50630.412687] EXT4-fs (sda2): re-mounted. Opts: data=ordered,commit=0
Dec  4 13:36:11 localhost kernel: [50630.414674] EXT4-fs (sdd5): re-mounted. Opts: data=ordered,commit=0
Dec  4 13:36:11 localhost kernel: [50630.416436] EXT4-fs (sdd2): re-mounted. Opts: data=ordered,commit=0
Dec  4 15:23:42 localhost kernel: [57079.514008] EXT4-fs (sda3): re-mounted. Opts: commit=0
Dec  4 15:23:42 localhost kernel: [57079.722652] EXT4-fs (sdc2): re-mounted. Opts: commit=0
Dec  4 15:23:42 localhost kernel: [57079.726534] EXT4-fs (sda1): re-mounted. Opts: data=ordered,commit=0
Dec  4 15:23:42 localhost kernel: [57079.729583] EXT4-fs (sda2): re-mounted. Opts: data=ordered,commit=0
Dec  4 15:23:42 localhost kernel: [57079.732896] EXT4-fs (sdd5): re-mounted. Opts: data=ordered,commit=0
Dec  4 15:23:42 localhost kernel: [57079.735607] EXT4-fs (sdd2): re-mounted. Opts: data=ordered,commit=0
Dec  4 15:23:43 localhost kernel: [57080.281146] PM: Syncing filesystems ... done.
Dec  4 15:23:43 localhost kernel: [57080.295479] PM: Preparing system for mem sleep
Dec  4 15:33:18 localhost kernel: [57080.310058] Freezing user space processes ... (elapsed 0.01 seconds) done.
Dec  4 15:33:18 localhost kernel: [57080.322051] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Dec  4 15:33:18 localhost kernel: [57080.335453] PM: Entering mem sleep
Dec  4 15:33:18 localhost kernel: [57080.335490] Suspending console(s) (use no_console_suspend to debug)
Dec  4 15:33:18 localhost kernel: [57080.337350] sd 5:0:0:0: [sdd] Synchronizing SCSI cache
Dec  4 15:33:18 localhost kernel: [57080.337396] sd 4:0:0:0: [sdc] Synchronizing SCSI cache
Dec  4 15:33:18 localhost kernel: [57080.337459] sd 3:0:0:0: [sdb] Synchronizing SCSI cache
Dec  4 15:33:18 localhost kernel: [57080.337514] sd 2:0:0:0: [sda] Synchronizing SCSI cache
Dec  4 15:33:18 localhost kernel: [57080.338774] sd 4:0:0:0: [sdc] Stopping disk
Dec  4 15:33:18 localhost kernel: [57080.338805] sd 5:0:0:0: [sdd] Stopping disk
Dec  4 15:33:18 localhost kernel: [57080.339049] sd 3:0:0:0: [sdb] Stopping disk
Dec  4 15:33:18 localhost kernel: [57080.339089] [drm] nouveau 0000:02:00.0: Disabling display...
Dec  4 15:33:18 localhost kernel: [57080.356434] sd 2:0:0:0: [sda] Stopping disk
Dec  4 15:33:18 localhost kernel: [57080.393031] [drm] nouveau 0000:02:00.0: Disabling fbcon...
Dec  4 15:33:18 localhost kernel: [57080.393032] [drm] nouveau 0000:02:00.0: Unpinning framebuffer(s)...
Dec  4 15:33:18 localhost kernel: [57080.393066] [drm] nouveau 0000:02:00.0: Evicting buffers...
Dec  4 15:33:18 localhost kernel: [57080.726879] [drm] nouveau 0000:02:00.0: Idling channels...
Dec  4 15:33:18 localhost kernel: [57080.727873] [drm] nouveau 0000:02:00.0: Suspending GPU objects...
Dec  4 15:33:18 localhost kernel: [57082.529806] [drm] nouveau 0000:02:00.0: And we're gone!
Dec  4 15:33:18 localhost kernel: [57082.541419] PM: suspend of devices complete after 2206.392 msecs
Dec  4 15:33:18 localhost kernel: [57082.541700] PM: late suspend of devices complete after 0.279 msecs
Dec  4 15:33:18 localhost kernel: [57082.542081] pcieport 0000:00:1c.2: wake-up capability enabled by ACPI
Dec  4 15:33:18 localhost kernel: [57082.554656] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI
Dec  4 15:33:18 localhost kernel: [57082.567923] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI
Dec  4 15:33:18 localhost kernel: [57082.567965] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI
Dec  4 15:33:18 localhost kernel: [57082.567998] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
Dec  4 15:33:18 localhost kernel: [57082.568108] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI
Dec  4 15:33:18 localhost kernel: [57082.581237] uhci_hcd 0000:00:1a.2: wake-up capability enabled by ACPI
Dec  4 15:33:18 localhost kernel: [57082.581277] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI
Dec  4 15:33:18 localhost kernel: [57082.581312] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
Dec  4 15:33:18 localhost kernel: [57082.581515] PM: noirq suspend of devices complete after 39.826 msecs
Dec  4 15:33:18 localhost kernel: [57082.581865] ACPI: Preparing to enter system sleep state S3
Dec  4 15:33:18 localhost kernel: [57082.582099] PM: Saving platform NVS memory
Dec  4 15:33:18 localhost kernel: [57082.582492] Disabling non-boot CPUs ...
Dec  4 15:33:18 localhost kernel: [57082.684500] smpboot: CPU 1 is now offline
Dec  4 15:33:18 localhost kernel: [57082.787792] smpboot: CPU 2 is now offline
Dec  4 15:33:18 localhost kernel: [57082.891094] smpboot: CPU 3 is now offline
Dec  4 15:33:18 localhost kernel: [57082.994390] smpboot: CPU 4 is now offline
Dec  4 15:33:18 localhost kernel: [57083.097691] smpboot: CPU 5 is now offline
Dec  4 15:33:18 localhost kernel: [57083.200987] smpboot: CPU 6 is now offline
Dec  4 15:33:18 localhost kernel: [57083.304278] smpboot: CPU 7 is now offline
Dec  4 15:33:18 localhost kernel: [57083.305690] ACPI: Low-level resume complete
Dec  4 15:33:18 localhost kernel: [57083.305733] PM: Restoring platform NVS memory
Dec  4 15:33:18 localhost kernel: [57083.306145] Enabling non-boot CPUs ...
Dec  4 15:33:18 localhost kernel: [57083.306216] smpboot: Booting Node 0 Processor 1 APIC 0x2
Dec  4 15:33:18 localhost kernel: [57083.322438] CPU1 is up
Dec  4 15:33:18 localhost kernel: [57083.322494] smpboot: Booting Node 0 Processor 2 APIC 0x4
Dec  4 15:33:18 localhost kernel: [57083.339092] CPU2 is up
Dec  4 15:33:18 localhost kernel: [57083.339137] smpboot: Booting Node 0 Processor 3 APIC 0x6
Dec  4 15:33:18 localhost kernel: [57083.355754] CPU3 is up
Dec  4 15:33:18 localhost kernel: [57083.355814] smpboot: Booting Node 0 Processor 4 APIC 0x1
Dec  4 15:33:18 localhost kernel: [57083.372416] CPU4 is up
Dec  4 15:33:18 localhost kernel: [57083.372459] smpboot: Booting Node 0 Processor 5 APIC 0x3
Dec  4 15:33:18 localhost kernel: [57083.389077] CPU5 is up
Dec  4 15:33:18 localhost kernel: [57083.389119] smpboot: Booting Node 0 Processor 6 APIC 0x5
Dec  4 15:33:18 localhost kernel: [57083.405738] CPU6 is up
Dec  4 15:33:18 localhost kernel: [57083.405792] smpboot: Booting Node 0 Processor 7 APIC 0x7
Dec  4 15:33:18 localhost kernel: [57083.422398] CPU7 is up
Dec  4 15:33:18 localhost kernel: [57083.428163] ACPI: Waking up from system sleep state S3
Dec  4 15:33:18 localhost kernel: [57083.428692] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
Dec  4 15:33:18 localhost kernel: [57083.428728] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI
Dec  4 15:33:18 localhost kernel: [57083.428763] uhci_hcd 0000:00:1a.2: wake-up capability disabled by ACPI
Dec  4 15:33:18 localhost kernel: [57083.439110] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI
Dec  4 15:33:18 localhost kernel: [57083.452608] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
Dec  4 15:33:18 localhost kernel: [57083.452643] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI
Dec  4 15:33:18 localhost kernel: [57083.452677] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI
Dec  4 15:33:18 localhost kernel: [57083.465762] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI
Dec  4 15:33:18 localhost kernel: [57083.532656] PM: noirq resume of devices complete after 104.295 msecs
Dec  4 15:33:18 localhost kernel: [57083.532803] PM: early resume of devices complete after 0.113 msecs
Dec  4 15:33:18 localhost kernel: [57083.532849] uhci_hcd 0000:00:1a.1: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532851] uhci_hcd 0000:00:1a.0: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532854] uhci_hcd 0000:00:1a.2: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532856] ehci_hcd 0000:00:1a.7: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532866] uhci_hcd 0000:00:1d.0: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532895] usb usb4: root hub lost power or was reset
Dec  4 15:33:18 localhost kernel: [57083.532900] usb usb3: root hub lost power or was reset
Dec  4 15:33:18 localhost kernel: [57083.532902] usb usb5: root hub lost power or was reset
Dec  4 15:33:18 localhost kernel: [57083.532908] usb usb6: root hub lost power or was reset
Dec  4 15:33:18 localhost kernel: [57083.532915] uhci_hcd 0000:00:1d.1: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532927] uhci_hcd 0000:00:1d.2: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532934] pci 0000:00:1e.0: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532946] ehci_hcd 0000:00:1d.7: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532947] ahci 0000:00:1f.2: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.532951] [drm] nouveau 0000:02:00.0: We're back, enabling device...
Dec  4 15:33:18 localhost kernel: [57083.532958] usb usb7: root hub lost power or was reset
Dec  4 15:33:18 localhost kernel: [57083.532960] [drm] nouveau 0000:02:00.0: POSTing device...
Dec  4 15:33:18 localhost kernel: [57083.532962] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 0 at offset 0xD0D3
Dec  4 15:33:18 localhost kernel: [57083.532969] usb usb8: root hub lost power or was reset
Dec  4 15:33:18 localhost kernel: [57083.532971] snd_hda_intel 0000:00:1b.0: irq 69 for MSI/MSI-X
Dec  4 15:33:18 localhost kernel: [57083.532982] pcieport 0000:00:1c.2: wake-up capability disabled by ACPI
Dec  4 15:33:18 localhost kernel: [57083.533006] pata_jmicron 0000:04:00.1: setting latency timer to 64
Dec  4 15:33:18 localhost kernel: [57083.572639] r8169 0000:05:00.0: eth0: link down
Dec  4 15:33:18 localhost kernel: [57083.574136] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 1 at offset 0xD4FE
Dec  4 15:33:18 localhost kernel: [57083.622968] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 2 at offset 0xE99B
Dec  4 15:33:18 localhost kernel: [57083.622999] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 3 at offset 0xEA78
Dec  4 15:33:18 localhost kernel: [57083.624016] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 4 at offset 0xEB4A
Dec  4 15:33:18 localhost kernel: [57083.624016] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table at offset 0xEBAF
Dec  4 15:33:18 localhost kernel: [57083.644077] [drm] nouveau 0000:02:00.0: Restoring GPU objects...
Dec  4 15:33:18 localhost kernel: [57083.859022] usb 4-2: reset full-speed USB device number 2 using uhci_hcd
Dec  4 15:33:18 localhost kernel: [57083.862377] ata7: SATA link down (SStatus 0 SControl 300)
Dec  4 15:33:18 localhost kernel: [57083.865722] ata10: SATA link down (SStatus 0 SControl 300)
Dec  4 15:33:18 localhost kernel: [57083.865782] ata9: SATA link down (SStatus 0 SControl 300)
Dec  4 15:33:18 localhost kernel: [57083.869040] ata8: SATA link down (SStatus 0 SControl 300)
Dec  4 15:33:18 localhost kernel: [57083.924968] [drm] nouveau 0000:02:00.0: Reinitialising engines...
Dec  4 15:33:18 localhost kernel: [57083.925517] [drm] nouveau 0000:02:00.0: Restoring mode...
Dec  4 15:33:18 localhost kernel: [57084.095605] usb 5-2: reset full-speed USB device number 3 using uhci_hcd
Dec  4 15:33:18 localhost kernel: [57084.332180] usb 5-1: reset full-speed USB device number 2 using uhci_hcd
Dec  4 15:33:18 localhost kernel: [57084.533383] usb 5-2.2: reset full-speed USB device number 5 using uhci_hcd
Dec  4 15:33:18 localhost kernel: [57084.693333] usb 5-2.1: reset full-speed USB device number 4 using uhci_hcd
Dec  4 15:33:18 localhost kernel: [57084.853274] usb 5-2.3: reset full-speed USB device number 6 using uhci_hcd
Dec  4 15:33:18 localhost kernel: [57084.958233] btusb 5-2.3:1.0: no reset_resume for driver btusb?
Dec  4 15:33:18 localhost kernel: [57084.958235] btusb 5-2.3:1.1: no reset_resume for driver btusb?
Dec  4 15:33:18 localhost kernel: [57085.684528] r8169 0000:05:00.0: eth0: link up
Dec  4 15:33:18 localhost kernel: [57086.544703] 
Dec  4 15:33:18 localhost kernel: [57086.544703] floppy driver state
Dec  4 15:33:18 localhost kernel: [57086.544704] -------------------
Dec  4 15:33:18 localhost kernel: [57086.544721] now=4312009004 last interrupt=4294877446 diff=17131558 last called handler=reset_interrupt [floppy]
Dec  4 15:33:18 localhost kernel: [57086.544721] timeout_message=lock fdc
Dec  4 15:33:18 localhost kernel: [57086.544722] last output bytes:
Dec  4 15:33:18 localhost kernel: [57086.544723]  8 80 4294877441
Dec  4 15:33:18 localhost kernel: [57086.544724]  8 80 4294877441
Dec  4 15:33:18 localhost kernel: [57086.544725]  8 90 4294877441
Dec  4 15:33:18 localhost kernel: [57086.544725]  8 80 4294877445
Dec  4 15:33:18 localhost kernel: [57086.544726]  8 80 4294877445
Dec  4 15:33:18 localhost kernel: [57086.544727]  8 90 4294877445
Dec  4 15:33:18 localhost kernel: [57086.544728]  8 80 4294877445
Dec  4 15:33:18 localhost kernel: [57086.544728]  e 80 4294877445
Dec  4 15:33:18 localhost kernel: [57086.544729] 13 90 4294877445
Dec  4 15:33:18 localhost kernel: [57086.544730]  0 90 4294877445
Dec  4 15:33:18 localhost kernel: [57086.544731] 1a 90 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544731]  0 90 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544732] 12 90 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544733]  0 90 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544734] 14 90 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544734] 18 80 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544735]  8 80 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544736]  8 80 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544737]  8 80 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544737]  8 80 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544738] last result at 4294877446
Dec  4 15:33:18 localhost kernel: [57086.544738] last redo_fd_request at 4310068648
Dec  4 15:33:18 localhost kernel: [57086.544744] status=10
Dec  4 15:33:18 localhost kernel: [57086.544745] fdc_busy=1
Dec  4 15:33:18 localhost kernel: [57086.544748] do_floppy=reset_interrupt [floppy]
Dec  4 15:33:18 localhost kernel: [57086.544749] cont=ffffffffa0009680
Dec  4 15:33:18 localhost kernel: [57086.544750] current_req=          (null)
Dec  4 15:33:18 localhost kernel: [57086.544751] command_status=-1
Dec  4 15:33:18 localhost kernel: [57086.544751] 
Dec  4 15:33:18 localhost kernel: [57086.544756] floppy0: floppy timeout called
Dec  4 15:33:18 localhost kernel: [57093.529133] ata3: softreset failed (1st FIS failed)
Dec  4 15:33:18 localhost kernel: [57093.532433] ata6: softreset failed (1st FIS failed)
Dec  4 15:33:18 localhost kernel: [57093.535815] ata5: softreset failed (1st FIS failed)
Dec  4 15:33:18 localhost kernel: [57093.542516] ata4: softreset failed (1st FIS failed)
Dec  4 15:33:18 localhost kernel: [57094.015607] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec  4 15:33:18 localhost kernel: [57094.018933] ata6: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec  4 15:33:18 localhost kernel: [57094.022270] ata5: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec  4 15:33:18 localhost kernel: [57094.028891] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec  4 15:33:18 localhost kernel: [57094.086430] ata3.00: configured for UDMA/133
Dec  4 15:33:18 localhost kernel: [57094.089688] ata6.00: configured for UDMA/133
Dec  4 15:33:18 localhost kernel: [57094.092943] ata5.00: configured for UDMA/133
Dec  4 15:33:18 localhost kernel: [57094.103088] ata4.00: configured for UDMA/133
Dec  4 15:33:18 localhost kernel: [57094.109055] sd 2:0:0:0: [sda] Starting disk
Dec  4 15:33:18 localhost kernel: [57094.112377] sd 5:0:0:0: [sdd] Starting disk
Dec  4 15:33:18 localhost kernel: [57094.115718] sd 4:0:0:0: [sdc] Starting disk
Dec  4 15:33:18 localhost kernel: [57094.125813] sd 3:0:0:0: [sdb] Starting disk
Dec  4 15:33:18 localhost kernel: [57094.151542] PM: resume of devices complete after 10622.329 msecs
Dec  4 15:33:18 localhost kernel: [57094.152315] PM: Finishing wakeup.
Dec  4 15:33:18 localhost kernel: [57094.152316] Restarting tasks ... done.
Dec  4 15:33:19 localhost kernel: [57094.899346] EXT4-fs (sda3): re-mounted. Opts: commit=0
Dec  4 15:33:19 localhost kernel: [57095.175214] EXT4-fs (sdc2): re-mounted. Opts: commit=0
Dec  4 15:33:19 localhost kernel: [57095.179011] EXT4-fs (sda1): re-mounted. Opts: data=ordered,commit=0
Dec  4 15:33:19 localhost kernel: [57095.181896] EXT4-fs (sda2): re-mounted. Opts: data=ordered,commit=0
Dec  4 15:33:19 localhost kernel: [57095.184469] EXT4-fs (sdd5): re-mounted. Opts: data=ordered,commit=0
Dec  4 15:33:19 localhost kernel: [57095.186625] EXT4-fs (sdd2): re-mounted. Opts: data=ordered,commit=0
Dec  4 19:57:11 localhost kernel: [72921.924840] EXT4-fs (sda3): re-mounted. Opts: commit=0
Dec  4 19:57:11 localhost kernel: [72922.489409] EXT4-fs (sdc2): re-mounted. Opts: commit=0
Dec  4 19:57:12 localhost kernel: [72922.643842] EXT4-fs (sda1): re-mounted. Opts: data=ordered,commit=0
Dec  4 19:57:12 localhost kernel: [72922.646471] EXT4-fs (sda2): re-mounted. Opts: data=ordered,commit=0
Dec  4 19:57:12 localhost kernel: [72922.740146] EXT4-fs (sdd5): re-mounted. Opts: data=ordered,commit=0
Dec  4 19:57:12 localhost kernel: [72922.866244] EXT4-fs (sdd2): re-mounted. Opts: data=ordered,commit=0
Dec  4 19:57:13 localhost kernel: [72923.499907] PM: Syncing filesystems ... done.
Dec  4 19:57:13 localhost kernel: [72923.554948] PM: Preparing system for mem sleep
Dec  4 20:02:30 localhost kernel: [72923.587113] Freezing user space processes ... (elapsed 0.01 seconds) done.
Dec  4 20:02:30 localhost kernel: [72923.599329] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Dec  4 20:02:30 localhost kernel: [72923.612683] PM: Entering mem sleep
Dec  4 20:02:30 localhost kernel: [72923.612718] Suspending console(s) (use no_console_suspend to debug)
Dec  4 20:02:30 localhost kernel: [72923.615356] sd 5:0:0:0: [sdd] Synchronizing SCSI cache
Dec  4 20:02:30 localhost kernel: [72923.615705] sd 5:0:0:0: [sdd] Stopping disk
Dec  4 20:02:30 localhost kernel: [72923.615719] sd 4:0:0:0: [sdc] Synchronizing SCSI cache
Dec  4 20:02:30 localhost kernel: [72923.616036] sd 4:0:0:0: [sdc] Stopping disk
Dec  4 20:02:30 localhost kernel: [72923.616074] sd 3:0:0:0: [sdb] Synchronizing SCSI cache
Dec  4 20:02:30 localhost kernel: [72923.616155] sd 2:0:0:0: [sda] Synchronizing SCSI cache
Dec  4 20:02:30 localhost kernel: [72923.616444] sd 2:0:0:0: [sda] Stopping disk
Dec  4 20:02:30 localhost kernel: [72923.616675] [drm] nouveau 0000:02:00.0: Disabling display...
Dec  4 20:02:30 localhost kernel: [72923.617116] sd 3:0:0:0: [sdb] Stopping disk
Dec  4 20:02:30 localhost kernel: [72923.670112] [drm] nouveau 0000:02:00.0: Disabling fbcon...
Dec  4 20:02:30 localhost kernel: [72923.670114] [drm] nouveau 0000:02:00.0: Unpinning framebuffer(s)...
Dec  4 20:02:30 localhost kernel: [72923.670149] [drm] nouveau 0000:02:00.0: Evicting buffers...
Dec  4 20:02:30 localhost kernel: [72924.153432] [drm] nouveau 0000:02:00.0: Idling channels...
Dec  4 20:02:30 localhost kernel: [72924.154420] [drm] nouveau 0000:02:00.0: Suspending GPU objects...
Dec  4 20:02:30 localhost kernel: [72925.948839] [drm] nouveau 0000:02:00.0: And we're gone!
Dec  4 20:02:30 localhost kernel: [72925.961878] PM: suspend of devices complete after 2349.646 msecs
Dec  4 20:02:30 localhost kernel: [72925.962153] PM: late suspend of devices complete after 0.273 msecs
Dec  4 20:02:30 localhost kernel: [72925.962532] pcieport 0000:00:1c.2: wake-up capability enabled by ACPI
Dec  4 20:02:30 localhost kernel: [72925.975164] ehci_hcd 0000:00:1d.7: wake-up capability enabled by ACPI
Dec  4 20:02:30 localhost kernel: [72925.988437] uhci_hcd 0000:00:1d.2: wake-up capability enabled by ACPI
Dec  4 20:02:30 localhost kernel: [72925.988480] uhci_hcd 0000:00:1d.1: wake-up capability enabled by ACPI
Dec  4 20:02:30 localhost kernel: [72925.988510] uhci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
Dec  4 20:02:30 localhost kernel: [72925.988620] ehci_hcd 0000:00:1a.7: wake-up capability enabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.001746] uhci_hcd 0000:00:1a.2: wake-up capability enabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.001786] uhci_hcd 0000:00:1a.1: wake-up capability enabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.001821] uhci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.002022] PM: noirq suspend of devices complete after 39.881 msecs
Dec  4 20:02:30 localhost kernel: [72926.002383] ACPI: Preparing to enter system sleep state S3
Dec  4 20:02:30 localhost kernel: [72926.002619] PM: Saving platform NVS memory
Dec  4 20:02:30 localhost kernel: [72926.003014] Disabling non-boot CPUs ...
Dec  4 20:02:30 localhost kernel: [72926.105003] smpboot: CPU 1 is now offline
Dec  4 20:02:30 localhost kernel: [72926.208298] smpboot: CPU 2 is now offline
Dec  4 20:02:30 localhost kernel: [72926.311602] smpboot: CPU 3 is now offline
Dec  4 20:02:30 localhost kernel: [72926.414900] smpboot: CPU 4 is now offline
Dec  4 20:02:30 localhost kernel: [72926.518193] smpboot: CPU 5 is now offline
Dec  4 20:02:30 localhost kernel: [72926.621497] smpboot: CPU 6 is now offline
Dec  4 20:02:30 localhost kernel: [72926.724787] smpboot: CPU 7 is now offline
Dec  4 20:02:30 localhost kernel: [72926.726209] ACPI: Low-level resume complete
Dec  4 20:02:30 localhost kernel: [72926.726252] PM: Restoring platform NVS memory
Dec  4 20:02:30 localhost kernel: [72926.726664] Enabling non-boot CPUs ...
Dec  4 20:02:30 localhost kernel: [72926.726737] smpboot: Booting Node 0 Processor 1 APIC 0x2
Dec  4 20:02:30 localhost kernel: [72926.742944] CPU1 is up
Dec  4 20:02:30 localhost kernel: [72926.742999] smpboot: Booting Node 0 Processor 2 APIC 0x4
Dec  4 20:02:30 localhost kernel: [72926.759603] CPU2 is up
Dec  4 20:02:30 localhost kernel: [72926.759651] smpboot: Booting Node 0 Processor 3 APIC 0x6
Dec  4 20:02:30 localhost kernel: [72926.776265] CPU3 is up
Dec  4 20:02:30 localhost kernel: [72926.776325] smpboot: Booting Node 0 Processor 4 APIC 0x1
Dec  4 20:02:30 localhost kernel: [72926.792927] CPU4 is up
Dec  4 20:02:30 localhost kernel: [72926.792971] smpboot: Booting Node 0 Processor 5 APIC 0x3
Dec  4 20:02:30 localhost kernel: [72926.809593] CPU5 is up
Dec  4 20:02:30 localhost kernel: [72926.809635] smpboot: Booting Node 0 Processor 6 APIC 0x5
Dec  4 20:02:30 localhost kernel: [72926.826246] CPU6 is up
Dec  4 20:02:30 localhost kernel: [72926.826288] smpboot: Booting Node 0 Processor 7 APIC 0x7
Dec  4 20:02:30 localhost kernel: [72926.842906] CPU7 is up
Dec  4 20:02:30 localhost kernel: [72926.848670] ACPI: Waking up from system sleep state S3
Dec  4 20:02:30 localhost kernel: [72926.849205] uhci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.849241] uhci_hcd 0000:00:1a.1: wake-up capability disabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.849275] uhci_hcd 0000:00:1a.2: wake-up capability disabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.859618] ehci_hcd 0000:00:1a.7: wake-up capability disabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.873118] uhci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.873153] uhci_hcd 0000:00:1d.1: wake-up capability disabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.873187] uhci_hcd 0000:00:1d.2: wake-up capability disabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.886272] ehci_hcd 0000:00:1d.7: wake-up capability disabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.953169] PM: noirq resume of devices complete after 104.300 msecs
Dec  4 20:02:30 localhost kernel: [72926.953318] PM: early resume of devices complete after 0.114 msecs
Dec  4 20:02:30 localhost kernel: [72926.953365] uhci_hcd 0000:00:1a.0: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953366] uhci_hcd 0000:00:1a.1: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953368] uhci_hcd 0000:00:1a.2: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953372] ehci_hcd 0000:00:1a.7: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953386] uhci_hcd 0000:00:1d.0: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953390] uhci_hcd 0000:00:1d.1: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953416] usb usb3: root hub lost power or was reset
Dec  4 20:02:30 localhost kernel: [72926.953419] usb usb4: root hub lost power or was reset
Dec  4 20:02:30 localhost kernel: [72926.953427] usb usb5: root hub lost power or was reset
Dec  4 20:02:30 localhost kernel: [72926.953436] usb usb6: root hub lost power or was reset
Dec  4 20:02:30 localhost kernel: [72926.953442] usb usb7: root hub lost power or was reset
Dec  4 20:02:30 localhost kernel: [72926.953444] uhci_hcd 0000:00:1d.2: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953449] [drm] nouveau 0000:02:00.0: We're back, enabling device...
Dec  4 20:02:30 localhost kernel: [72926.953451] pci 0000:00:1e.0: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953459] pcieport 0000:00:1c.2: wake-up capability disabled by ACPI
Dec  4 20:02:30 localhost kernel: [72926.953464] ehci_hcd 0000:00:1d.7: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953472] ahci 0000:00:1f.2: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953477] [drm] nouveau 0000:02:00.0: POSTing device...
Dec  4 20:02:30 localhost kernel: [72926.953479] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 0 at offset 0xD0D3
Dec  4 20:02:30 localhost kernel: [72926.953491] usb usb8: root hub lost power or was reset
Dec  4 20:02:30 localhost kernel: [72926.953504] pata_jmicron 0000:04:00.1: setting latency timer to 64
Dec  4 20:02:30 localhost kernel: [72926.953514] snd_hda_intel 0000:00:1b.0: irq 69 for MSI/MSI-X
Dec  4 20:02:30 localhost kernel: [72926.994774] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 1 at offset 0xD4FE
Dec  4 20:02:30 localhost kernel: [72927.013212] r8169 0000:05:00.0: eth0: link down
Dec  4 20:02:30 localhost kernel: [72927.043608] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 2 at offset 0xE99B
Dec  4 20:02:30 localhost kernel: [72927.043639] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 3 at offset 0xEA78
Dec  4 20:02:30 localhost kernel: [72927.044656] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table 4 at offset 0xEB4A
Dec  4 20:02:30 localhost kernel: [72927.044657] [drm] nouveau 0000:02:00.0: Parsing VBIOS init table at offset 0xEBAF
Dec  4 20:02:30 localhost kernel: [72927.064663] [drm] nouveau 0000:02:00.0: Restoring GPU objects...
Dec  4 20:02:30 localhost kernel: [72927.279570] usb 5-2: reset full-speed USB device number 3 using uhci_hcd
Dec  4 20:02:30 localhost kernel: [72927.286209] ata7: SATA link down (SStatus 0 SControl 300)
Dec  4 20:02:30 localhost kernel: [72927.286215] ata9: SATA link down (SStatus 0 SControl 300)
Dec  4 20:02:30 localhost kernel: [72927.286276] ata10: SATA link down (SStatus 0 SControl 300)
Dec  4 20:02:30 localhost kernel: [72927.292882] ata8: SATA link down (SStatus 0 SControl 300)
Dec  4 20:02:30 localhost kernel: [72927.345297] [drm] nouveau 0000:02:00.0: Reinitialising engines...
Dec  4 20:02:30 localhost kernel: [72927.345849] [drm] nouveau 0000:02:00.0: Restoring mode...
Dec  4 20:02:30 localhost kernel: [72927.516134] usb 5-1: reset full-speed USB device number 2 using uhci_hcd
Dec  4 20:02:30 localhost kernel: [72927.749334] usb 4-2: reset full-speed USB device number 2 using uhci_hcd
Dec  4 20:02:30 localhost kernel: [72927.947082] usb 5-2.3: reset full-speed USB device number 6 using uhci_hcd
Dec  4 20:02:30 localhost kernel: [72928.052039] btusb 5-2.3:1.0: no reset_resume for driver btusb?
Dec  4 20:02:30 localhost kernel: [72928.052041] btusb 5-2.3:1.1: no reset_resume for driver btusb?
Dec  4 20:02:30 localhost kernel: [72928.104062] usb 5-2.2: reset full-speed USB device number 5 using uhci_hcd
Dec  4 20:02:30 localhost kernel: [72928.263981] usb 5-2.1: reset full-speed USB device number 4 using uhci_hcd
Dec  4 20:02:30 localhost kernel: [72928.832445] r8169 0000:05:00.0: eth0: link up
Dec  4 20:02:30 localhost kernel: [72929.958633] 
Dec  4 20:02:30 localhost kernel: [72929.958634] floppy driver state
Dec  4 20:02:30 localhost kernel: [72929.958635] -------------------
Dec  4 20:02:30 localhost kernel: [72929.958651] now=4316763636 last interrupt=4294877446 diff=21886190 last called handler=reset_interrupt [floppy]
Dec  4 20:02:30 localhost kernel: [72929.958652] timeout_message=lock fdc
Dec  4 20:02:30 localhost kernel: [72929.958652] last output bytes:
Dec  4 20:02:30 localhost kernel: [72929.958654]  8 80 4294877441
Dec  4 20:02:30 localhost kernel: [72929.958655]  8 80 4294877441
Dec  4 20:02:30 localhost kernel: [72929.958655]  8 90 4294877441
Dec  4 20:02:30 localhost kernel: [72929.958656]  8 80 4294877445
Dec  4 20:02:30 localhost kernel: [72929.958657]  8 80 4294877445
Dec  4 20:02:30 localhost kernel: [72929.958658]  8 90 4294877445
Dec  4 20:02:30 localhost kernel: [72929.958658]  8 80 4294877445
Dec  4 20:02:30 localhost kernel: [72929.958659]  e 80 4294877445
Dec  4 20:02:30 localhost kernel: [72929.958660] 13 90 4294877445
Dec  4 20:02:30 localhost kernel: [72929.958661]  0 90 4294877445
Dec  4 20:02:30 localhost kernel: [72929.958661] 1a 90 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958662]  0 90 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958663] 12 90 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958664]  0 90 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958664] 14 90 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958665] 18 80 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958666]  8 80 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958667]  8 80 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958667]  8 80 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958668]  8 80 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958669] last result at 4294877446
Dec  4 20:02:30 localhost kernel: [72929.958669] last redo_fd_request at 4312009004
Dec  4 20:02:30 localhost kernel: [72929.958675] status=10
Dec  4 20:02:30 localhost kernel: [72929.958676] fdc_busy=1
Dec  4 20:02:30 localhost kernel: [72929.958679] do_floppy=reset_interrupt [floppy]
Dec  4 20:02:30 localhost kernel: [72929.958680] cont=ffffffffa0009680
Dec  4 20:02:30 localhost kernel: [72929.958681] current_req=          (null)
Dec  4 20:02:30 localhost kernel: [72929.958681] command_status=-1
Dec  4 20:02:30 localhost kernel: [72929.958682] 
Dec  4 20:02:30 localhost kernel: [72929.958687] floppy0: floppy timeout called
Dec  4 20:02:30 localhost kernel: [72936.949658] ata3: softreset failed (1st FIS failed)
Dec  4 20:02:30 localhost kernel: [72936.956336] ata4: softreset failed (1st FIS failed)
Dec  4 20:02:30 localhost kernel: [72936.976260] ata6: softreset failed (1st FIS failed)
Dec  4 20:02:30 localhost kernel: [72936.982972] ata5: softreset failed (1st FIS failed)
Dec  4 20:02:30 localhost kernel: [72937.436116] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec  4 20:02:30 localhost kernel: [72937.442775] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec  4 20:02:30 localhost kernel: [72937.462766] ata6: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec  4 20:02:30 localhost kernel: [72937.469376] ata5: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec  4 20:02:30 localhost kernel: [72937.506937] ata3.00: configured for UDMA/133
Dec  4 20:02:30 localhost kernel: [72937.513483] ata4.00: configured for UDMA/133
Dec  4 20:02:30 localhost kernel: [72937.529489] sd 2:0:0:0: [sda] Starting disk
Dec  4 20:02:30 localhost kernel: [72937.533446] ata6.00: configured for UDMA/133
Dec  4 20:02:30 localhost kernel: [72937.536213] sd 3:0:0:0: [sdb] Starting disk
Dec  4 20:02:30 localhost kernel: [72937.539988] ata5.00: configured for UDMA/133
Dec  4 20:02:30 localhost kernel: [72937.556316] sd 5:0:0:0: [sdd] Starting disk
Dec  4 20:02:30 localhost kernel: [72937.563001] sd 4:0:0:0: [sdc] Starting disk
Dec  4 20:02:30 localhost kernel: [72937.589659] PM: resume of devices complete after 10639.936 msecs
Dec  4 20:02:30 localhost kernel: [72937.590442] PM: Finishing wakeup.
Dec  4 20:02:30 localhost kernel: [72937.590443] Restarting tasks ... done.
Dec  4 20:02:31 localhost kernel: [72938.544272] EXT4-fs (sda3): re-mounted. Opts: commit=0
Dec  4 20:02:31 localhost kernel: [72938.708208] EXT4-fs (sdc2): re-mounted. Opts: commit=0
Dec  4 20:02:31 localhost kernel: [72938.727977] EXT4-fs (sda1): re-mounted. Opts: data=ordered,commit=0
Dec  4 20:02:31 localhost kernel: [72938.730982] EXT4-fs (sda2): re-mounted. Opts: data=ordered,commit=0
Dec  4 20:02:31 localhost kernel: [72938.742040] EXT4-fs (sdd5): re-mounted. Opts: data=ordered,commit=0
Dec  4 20:02:31 localhost kernel: [72938.757423] EXT4-fs (sdd2): re-mounted. Opts: data=ordered,commit=0
Dec  4 20:05:06 localhost kernel: [73094.109409] nepomukservices[951]: segfault at 0 ip           (null) sp 00007fff2516eb28 error 14 in nepomukservicestub[400000+7000]
Dec  4 20:05:06 localhost systemd-coredump[16807]: Process 951 (nepomukservices) dumped core.

I'm thinking systemd is powering down the usb based on this message on wake-up:
usb usb4: root hub lost power or was reset
Any help would be appreciated

Last edited by taclm55 (2012-12-06 11:27:32)

Offline

#3 2012-12-06 03:32:09

ewaller
Administrator
From: Pasadena, CA
Registered: 2009-07-13
Posts: 19,739

Re: [SOLVED] suspend to ram - wake up via usb fails

taclm55:  Welcome to Arch Linux.  Can I get you to please go back and edit your post and use bbcode code tags around your outputs.  Your readers will appreciate it.


Nothing is too wonderful to be true, if it be consistent with the laws of nature -- Michael Faraday
Sometimes it is the people no one can imagine anything of who do the things no one can imagine. -- Alan Turing
---
How to Ask Questions the Smart Way

Offline

#4 2012-12-06 11:35:07

taclm55
Member
Registered: 2012-12-04
Posts: 4

Re: [SOLVED] suspend to ram - wake up via usb fails

Hi ewaller,

Thank you for the welcome and the information!  I fixed the post.  I've been distro-hopping for over 7 years and finally found my home with Arch about a year ago.  The documentation here and the community is by far the best I've seen.  I have always been able to troubleshoot all my issues using this forum up to this point.  I've converted two machines full time and going to do two more.  The extra effort during install is definitely worth it!

Offline

#5 2012-12-15 13:35:36

JohnD
Member
Registered: 2007-02-12
Posts: 17

Re: [SOLVED] suspend to ram - wake up via usb fails

What you can try is to set /sys/bus/usb/devices/usb*/power/wakeup to enabled where * is the number of your usb device. You could start by enabling them all and see if you can wake up your pc. If this works you can make this permanent with a udev rule:

# enable wake from S3 from USB-Keyboard
SUBSYSTEM=="usb", ATTRS{idVendor}=="xxxx", ATTRS{idProduct}=="zzzz" RUN+="/bin/sh -c 'echo enabled > /sys$env{DEVPATH}/../power/wakeup'

xxxx and zzzz you can find with lsusb for your device.

Good luck!

JD

Offline

#6 2012-12-16 11:42:22

xinit
Member
Registered: 2011-11-12
Posts: 23

Re: [SOLVED] suspend to ram - wake up via usb fails

Thank you so much for this advice!

Following udev rule works:

# cat /etc/udev/rules.d/10-enable-wake-from-S3-via-keyboard.rules
SUBSYSTEM=="usb", ATTRS{idVendor}=="045e", ATTRS{idProduct}=="0768", RUN+="/bin/sh -c 'echo enabled > /sys$env{DEVPATH}/../../power/wakeup'"

taclm55, i hope this fixes your problem, too smile.

Last edited by xinit (2012-12-16 11:45:08)

Offline

#7 2012-12-17 14:47:39

taclm55
Member
Registered: 2012-12-04
Posts: 4

Re: [SOLVED] suspend to ram - wake up via usb fails

Thanks JD!  Will give it shot tonight and let you know if it works.  Funny thing is I did the same procedure on another laptop and it resumes fine with the mouse after systemd conversion.

Offline

#8 2012-12-18 21:10:03

taclm55
Member
Registered: 2012-12-04
Posts: 4

Re: [SOLVED] suspend to ram - wake up via usb fails

Finally had chance to test this.  It works!  Thanks JD big_smile

Offline

#9 2013-05-19 09:36:09

JohnDVD
Member
Registered: 2011-11-29
Posts: 39

Re: [SOLVED] suspend to ram - wake up via usb fails

Nice idea JohnD,
on my machine resuming from suspend-to-ram via usb-keyboard was not working. I'm using systemd. See what I have done:

rc.local (called by systemd servicefile):

[...]
# findout usb-port of keyboard
temp=$(dmesg | grep -i keyboard | grep "/devices/pci" | tail -1)
# parse string to get usb id
temp2=${temp#*as \/devices/}
temp3=${temp2%%\/usb*}
temp4=${temp3#*\/}
temp5=$(cat /proc/acpi/wakeup | grep $temp4 | cut -d " " -f1)
# enable if disabled
temp6=$(cat /proc/acpi/wakeup | grep $temp5 | grep disabled)
if [ ! "$temp6" = "" ]; then
    echo $temp5 > /proc/acpi/wakeup
fi

# string in lower case: ${temp5,,}
echo enabled > /sys/bus/usb/devices/$(echo ${temp5,,})/power/wakeup

unset temp temp2 temp3 temp4 temp5 temp6

That was obviously not enough.
I enabled all power/wakeup flags for usb[1-10] devices in /sys

for i in $(seq 1 10); do echo enabled > /sys/bus/usb/devices/usb$i/power/wakeup ; done

Then tried to suspend, but it resumed immediately.
I disabled successive each usb-device except the device I'd like to use and tested it.

i=1 ; echo disabled > /sys/bus/usb/devices/usb$i/power/wakeup ; pm-suspend
i=2 ; echo disabled > /sys/bus/usb/devices/usb$i/power/wakeup ; pm-suspend
[...]

So I found that (in my case) usb9 caused the immediate resume. Keeping this disabled was not enough.
Let's reverse it that way, enable all except usb9.

for i in $(seq 1 10); do echo enabled > /sys/bus/usb/devices/usb$i/power/wakeup ; done
i=9 ; echo disabled > /sys/bus/usb/devices/usb$i/power/wakeup

I checked the results with

for i in $(seq 1 10); do cat /sys/bus/usb/devices/usb$i/power/wakeup ; done

the output was

enabled
enabled
enabled
enabled
enabled
enabled
enabled
enabled
disabled
enabled

Ok, now edit the startup script e.g. rc.local, and everything should be fine. smile

But I don't know yet whether a reboot changes usb ids that way I'll have to do more recognition scripting.

# edit:

Of course a reboot changed the usb id of each device...
So I decided to dynamically findout the disturbing device.
As you have read above I found out, that (in my case) usb9 was the disturbing device.
Let's have a look what it actually is.

cat /sys/bus/usb/devices/usb9/serial

Compare this with

lspci

So now, we have got a name, which identifies the device perfectly, in my case it is "USB controller: Intel Corporation 82801JI (ICH10 Family) USB2 EHCI Controller #2".

Now put all together in a script like this and let systemd start the script on boot, (or put that in rc.local):

#!/bin/bash
# by the way you have to enable almost all usb devices for wakeup
for i in $(seq 1 $(ls -d1 /sys/bus/usb/devices/usb* | wc -l)); do
    echo enabled > /sys/bus/usb/devices/usb${i}/power/wakeup
done

#disable s2ram disturbing device
disturber_pciid="$(lspci | grep "USB controller: Intel Corporation 82801JI (ICH10 Family) USB2 EHCI Controller #2" | cut -d " " -f1)"
#findout usbid
disturber_path="$(grep "$disturber_pciid" /sys/bus/usb/devices/usb*/serial | cut -d ":" -f1 | sed 's/serial//')"
echo disabled > ${disturber_path}power/wakeup
unset disturber_pciid disturber_path

I hope my steps help other users too.

JD

Last edited by JohnDVD (2013-05-20 19:28:46)

Offline

Board footer

Powered by FluxBB