Unable to exit from sleep

Questions about Grub, UEFI,the liveCD and the installer
Forum rules
Before you post please read how to get help
Post Reply
User avatar
matm
Level 1
Level 1
Posts: 31
Joined: Sun Apr 22, 2012 5:12 pm

Unable to exit from sleep

Post by matm »

Hello

My PC sometimes cannot wake up from sleep.
It is a boxed PC (see signature for the config).

Code: Select all

root@imperator:/var/log# inxi -Fxz
System:    Host: imperator Kernel: 4.15.0-70-generic x86_64 bits: 64 compiler: gcc v: 7.4.0 Desktop: Cinnamon 4.2.4 
           Distro: Linux Mint 19.2 Tina base: Ubuntu 18.04 bionic 
Machine:   Type: Desktop Mobo: ASUSTeK model: P7P55D-E LX v: Rev 1.xx serial: <filter> BIOS: American Megatrends 
           v: 1701 date: 09/27/2012 
Battery:   Device-1: hidpp_battery_0 model: Logitech MX Ergo Multi-Device Trackball charge: 55% status: Discharging 
CPU:       Topology: Quad Core model: Intel Core i5 750 bits: 64 type: MCP arch: Nehalem rev: 5 L2 cache: 8192 KiB 
           flags: lm nx pae sse sse2 sse3 sse4_1 sse4_2 ssse3 vmx bogomips: 21399 
           Speed: 1286 MHz min/max: 1200/2668 MHz Core speeds (MHz): 1: 1341 2: 1230 3: 1240 4: 1265 
Graphics:  Device-1: Advanced Micro Devices [AMD/ATI] Cypress PRO [Radeon HD 5850] vendor: PC Partner Limited 
           driver: radeon v: kernel bus ID: 01:00.0 
           Display: server: X.Org 1.19.6 driver: ati,radeon unloaded: fbdev,modesetting,vesa 
           resolution: 1920x1200~60Hz 
           OpenGL: renderer: AMD CYPRESS (DRM 2.50.0 / 4.15.0-70-generic LLVM 8.0.0) v: 4.3 Mesa 19.0.8 
           direct render: Yes 
Audio:     Device-1: Intel 5 Series/3400 Series High Definition Audio vendor: ASUSTeK driver: snd_hda_intel 
           v: kernel bus ID: 00:1b.0 
           Device-2: AMD Cypress HDMI Audio [Radeon HD 5830/5850/5870 / 6850/6870 Rebrand] 
           vendor: PC Partner Limited driver: snd_hda_intel v: kernel bus ID: 01:00.1 
           Sound Server: ALSA v: k4.15.0-70-generic 
Network:   Device-1: Realtek RTL8111/8168/8411 PCI Express Gigabit Ethernet vendor: ASUSTeK M4A785/P7P55 
           driver: r8169 v: 2.3LK-NAPI port: d800 bus ID: 03:00.0 
           IF: enp3s0 state: up speed: 1000 Mbps duplex: full mac: <filter> 
           IF-ID-1: br-a0f1456f4289 state: down mac: <filter> 
           IF-ID-2: br-ab0132ce259d state: down mac: <filter> 
           IF-ID-3: docker0 state: down mac: <filter> 
Drives:    Local Storage: total: 817.89 GiB used: 287.54 GiB (35.2%) 
           ID-1: /dev/sda vendor: Seagate model: ST500LM000-SSHD-8GB size: 465.76 GiB temp: 30 C 
           ID-2: /dev/sdb vendor: Samsung model: SSD 840 EVO 250GB size: 232.89 GiB 
           ID-3: /dev/sdc vendor: Samsung model: SSD 830 Series size: 119.24 GiB 
Partition: ID-1: / size: 90.96 GiB used: 36.34 GiB (39.9%) fs: ext4 dev: /dev/sdb2 
           ID-2: /home size: 137.64 GiB used: 111.32 GiB (80.9%) fs: ext4 dev: /dev/sdb1 
Sensors:   System Temperatures: cpu: 34.0 C mobo: N/A gpu: radeon temp: 49 C 
           Fan Speeds (RPM): N/A 
Info:      Processes: 284 Uptime: 19m Memory: 7.79 GiB used: 5.20 GiB (66.8%) Init: systemd runlevel: 5 Compilers: 
           gcc: 7.4.0 Shell: bash v: 4.4.20 inxi: 3.0.32 

Syslog tells the following.

A first sleep was reached at 12:05

Code: Select all

Nov 30 12:04:45 imperator anacron[9771]: Anacron 2.3 started on 2019-11-30
Nov 30 12:04:45 imperator anacron[9771]: Normal exit (0 jobs run)
Nov 30 12:05:50 imperator NetworkManager[1009]: <info>  [1575111950.9681] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Nov 30 12:05:50 imperator NetworkManager[1009]: <info>  [1575111950.9683] manager: NetworkManager state is now ASLEEP
Nov 30 12:05:51 imperator systemd[1]: Reached target Sleep.
Nov 30 12:05:51 imperator systemd[1]: Starting Suspend...
Nov 30 12:05:51 imperator systemd-sleep[9797]: Suspending system...
Nov 30 12:05:51 imperator kernel: [ 5295.846701] PM: suspend entry (deep)
Nov 30 12:05:51 imperator kernel: [ 5295.846705] PM: Syncing filesystems ... done.
Nov 30 12:05:54 imperator kernel: [ 5296.078707] Freezing user space processes ... (elapsed 0.002 seconds) done.
Nov 30 12:05:54 imperator kernel: [ 5296.080797] OOM killer disabled.
Nov 30 12:05:54 imperator kernel: [ 5296.080798] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Nov 30 12:05:54 imperator kernel: [ 5296.082108] Suspending console(s) (use no_console_suspend to debug)
Nov 30 12:05:54 imperator kernel: [ 5296.085979] serial 00:05: disabled
Nov 30 12:05:54 imperator kernel: [ 5296.103534] sd 9:0:0:0: [sdc] Synchronizing SCSI cache
Nov 30 12:05:54 imperator kernel: [ 5296.103679] sd 8:0:0:0: [sdb] Synchronizing SCSI cache
Nov 30 12:05:54 imperator kernel: [ 5296.103683] sd 6:0:0:0: [sda] Synchronizing SCSI cache
Nov 30 12:05:54 imperator kernel: [ 5296.103895] sd 6:0:0:0: [sda] Stopping disk
Nov 30 12:05:54 imperator kernel: [ 5296.103945] sd 8:0:0:0: [sdb] Stopping disk
Nov 30 12:05:54 imperator kernel: [ 5296.105279] sd 9:0:0:0: [sdc] Stopping disk
Nov 30 12:05:54 imperator kernel: [ 5296.123420] xhci_hcd 0000:05:00.0: WARN: xHC CMD_RUN timeout
Nov 30 12:05:54 imperator kernel: [ 5296.123452] suspend_common(): xhci_pci_suspend+0x0/0xd0 returns -110
Nov 30 12:05:54 imperator kernel: [ 5296.123457] pci_pm_suspend(): hcd_pci_suspend+0x0/0x30 returns -110
Nov 30 12:05:54 imperator kernel: [ 5296.123461] dpm_run_callback(): pci_pm_suspend+0x0/0x150 returns -110
Nov 30 12:05:54 imperator kernel: [ 5296.123465] PM: Device 0000:05:00.0 failed to suspend async: error -110
Nov 30 12:05:54 imperator kernel: [ 5297.931718] PM: Some devices failed to suspend, or early wake event detected
Nov 30 12:05:54 imperator kernel: [ 5297.937104] sd 6:0:0:0: [sda] Starting disk
Nov 30 12:05:54 imperator kernel: [ 5297.937587] sd 8:0:0:0: [sdb] Starting disk
Nov 30 12:05:54 imperator kernel: [ 5297.937600] sd 9:0:0:0: [sdc] Starting disk
Nov 30 12:05:54 imperator kernel: [ 5297.960967] serial 00:05: activated
Nov 30 12:05:54 imperator kernel: [ 5298.007197] [drm] PCIE gen 2 link speeds already enabled
Nov 30 12:05:54 imperator kernel: [ 5298.016369] [drm] PCIE GART of 1024M enabled (table at 0x000000000014C000).
Nov 30 12:05:54 imperator kernel: [ 5298.016490] radeon 0000:01:00.0: WB enabled
Nov 30 12:05:54 imperator kernel: [ 5298.016494] radeon 0000:01:00.0: fence driver on ring 0 use gpu addr 0x0000000040000c00 and cpu addr 0x00000000a268886a
Nov 30 12:05:54 imperator kernel: [ 5298.016496] radeon 0000:01:00.0: fence driver on ring 3 use gpu addr 0x0000000040000c0c and cpu addr 0x00000000ac2bb20b
Nov 30 12:05:54 imperator kernel: [ 5298.016916] radeon 0000:01:00.0: fence driver on ring 5 use gpu addr 0x000000000005c418 and cpu addr 0x000000006ae43207
Nov 30 12:05:54 imperator kernel: [ 5298.033446] [drm] ring test on 0 succeeded in 1 usecs
Nov 30 12:05:54 imperator kernel: [ 5298.033453] [drm] ring test on 3 succeeded in 2 usecs
Nov 30 12:05:54 imperator kernel: [ 5298.033754] r8169 0000:03:00.0 enp3s0: link down
Nov 30 12:05:54 imperator kernel: [ 5298.209238] [drm] ring test on 5 succeeded in 1 usecs
Nov 30 12:05:54 imperator kernel: [ 5298.209243] [drm] UVD initialized successfully.
Nov 30 12:05:54 imperator kernel: [ 5298.209359] [drm] ib test on ring 0 succeeded in 0 usecs
Nov 30 12:05:54 imperator kernel: [ 5298.209440] [drm] ib test on ring 3 succeeded in 0 usecs
Nov 30 12:05:54 imperator kernel: [ 5298.254706] ata11: SATA link down (SStatus 0 SControl 300)
Nov 30 12:05:54 imperator kernel: [ 5298.295686] ata4: SATA link down (SStatus 0 SControl 300)
Nov 30 12:05:54 imperator kernel: [ 5298.295705] ata3: SATA link down (SStatus 0 SControl 300)
Nov 30 12:05:54 imperator kernel: [ 5298.295738] ata8: SATA link down (SStatus 0 SControl 300)
Nov 30 12:05:54 imperator kernel: [ 5298.295757] ata6: SATA link down (SStatus 0 SControl 300)
Nov 30 12:05:54 imperator kernel: [ 5298.339531] usb 2-1.4: reset low-speed USB device number 3 using ehci-pci
Nov 30 12:05:54 imperator kernel: [ 5298.455510] ata5: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Nov 30 12:05:54 imperator kernel: [ 5298.462318] ata5.00: configured for UDMA/100
Nov 30 12:05:54 imperator kernel: [ 5298.863654] [drm] ib test on ring 5 succeeded
Nov 30 12:05:54 imperator kernel: [ 5299.015223] OOM killer enabled.
Nov 30 12:05:54 imperator kernel: [ 5299.015224] Restarting tasks ... done.
Nov 30 12:05:54 imperator kernel: [ 5299.037928] PM: suspend exit
Nov 30 12:05:54 imperator kernel: [ 5299.037971] PM: suspend entry (shallow)
Nov 30 12:05:54 imperator rtkit-daemon[1401]: The canary thread is apparently starving. Taking action.
Nov 30 12:05:54 imperator rtkit-daemon[1401]: Demoting known real-time threads.
Nov 30 12:05:54 imperator rtkit-daemon[1401]: Successfully demoted thread 1529 of process 1399 (n/a).
Nov 30 12:05:54 imperator rtkit-daemon[1401]: Successfully demoted thread 1528 of process 1399 (n/a).
Nov 30 12:05:54 imperator rtkit-daemon[1401]: Successfully demoted thread 1399 of process 1399 (n/a).
Nov 30 12:05:54 imperator rtkit-daemon[1401]: Demoted 3 threads.
Nov 30 12:05:54 imperator kernel: [ 5299.037972] PM: Syncing filesystems ... done.
Nov 30 15:40:00 imperator kernel: [ 5299.356943] Freezing user space processes ... (elapsed 0.002 seconds) done.
Nov 30 15:40:00 imperator kernel: [ 5299.359086] OOM killer disabled.
Nov 30 15:40:00 imperator kernel: [ 5299.359087] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Nov 30 15:40:00 imperator kernel: [ 5299.360470] Suspending console(s) (use no_console_suspend to debug)
Nov 30 15:40:00 imperator kernel: [ 5299.361423] serial 00:05: disabled
Nov 30 15:40:00 imperator kernel: [ 5299.379676] sd 8:0:0:0: [sdb] Synchronizing SCSI cache
Nov 30 15:40:00 imperator kernel: [ 5299.379728] sd 9:0:0:0: [sdc] Synchronizing SCSI cache
Nov 30 15:40:00 imperator kernel: [ 5299.379745] sd 6:0:0:0: [sda] Synchronizing SCSI cache
Nov 30 15:40:00 imperator kernel: [ 5299.380082] sd 8:0:0:0: [sdb] Stopping disk
Nov 30 15:40:00 imperator kernel: [ 5299.380932] sd 9:0:0:0: [sdc] Stopping disk
Nov 30 15:40:00 imperator kernel: [ 5299.388873] sd 6:0:0:0: [sda] Stopping disk
Nov 30 15:40:00 imperator kernel: [ 5299.907915] ACPI: Preparing to enter system sleep state S1
Nov 30 15:40:00 imperator kernel: [ 5299.908494] PM: Saving platform NVS memory
Nov 30 15:40:00 imperator kernel: [ 5299.908499] Disabling non-boot CPUs ...
Nov 30 15:40:00 imperator kernel: [ 5299.923806] IRQ 23: no longer affine to CPU1
Nov 30 15:40:00 imperator kernel: [ 5299.923809] IRQ 25: no longer affine to CPU1
Nov 30 15:40:00 imperator kernel: [ 5299.923869] IRQ 37: no longer affine to CPU1
Nov 30 15:40:00 imperator kernel: [ 5299.924884] smpboot: CPU 1 is now offline
Nov 30 15:40:00 imperator kernel: [ 5299.947806] IRQ 26: no longer affine to CPU2
Nov 30 15:40:00 imperator kernel: [ 5299.947866] IRQ 36: no longer affine to CPU2
Nov 30 15:40:00 imperator kernel: [ 5299.948880] smpboot: CPU 2 is now offline
Nov 30 15:40:00 imperator kernel: [ 5299.971801] IRQ 27: no longer affine to CPU3
Nov 30 15:40:00 imperator kernel: [ 5299.971857] IRQ 30: no longer affine to CPU3
Nov 30 15:40:00 imperator kernel: [ 5299.971914] IRQ 35: no longer affine to CPU3
Nov 30 15:40:00 imperator kernel: [ 5299.972922] smpboot: CPU 3 is now offline
Nov 30 15:40:00 imperator kernel: [18145.129293] PM: Restoring platform NVS memory
Nov 30 15:40:00 imperator kernel: [18145.129794] Enabling non-boot CPUs ...
Nov 30 15:40:00 imperator kernel: [18145.129875] x86: Booting SMP configuration:
Nov 30 15:40:00 imperator kernel: [18145.129876] smpboot: Booting Node 0 Processor 1 APIC 0x2
Nov 30 15:40:00 imperator kernel: [18145.132528]  cache: parent cpu1 should not be sleeping
Nov 30 15:40:00 imperator kernel: [18145.132844] hpet: hpet3 irq 25 for MSI
Nov 30 15:40:00 imperator kernel: [18145.132970] CPU1 is up
Nov 30 15:40:00 imperator kernel: [18145.133003] smpboot: Booting Node 0 Processor 2 APIC 0x4
Nov 30 15:40:00 imperator kernel: [18145.135531]  cache: parent cpu2 should not be sleeping
Nov 30 15:40:00 imperator kernel: [18145.135856] hpet: hpet4 irq 26 for MSI
Nov 30 15:40:00 imperator kernel: [18145.136044] CPU2 is up
Nov 30 15:40:00 imperator kernel: [18145.136079] smpboot: Booting Node 0 Processor 3 APIC 0x6
Nov 30 15:40:00 imperator kernel: [18145.138635]  cache: parent cpu3 should not be sleeping
Nov 30 15:40:00 imperator kernel: [18145.138966] hpet: hpet5 irq 27 for MSI
Nov 30 15:40:00 imperator kernel: [18145.139120] CPU3 is up
Nov 30 15:40:00 imperator kernel: [18145.141873] ACPI: Waking up from system sleep state S1

It woke up successfully at 15:40.
Got back to Sleep at 16:05.
17:56 is when I reset the computer.
I have some errors about kernel modules, I don't know if it can be related.

Code: Select all

Nov 30 16:04:45 imperator anacron[10462]: Anacron 2.3 started on 2019-11-30
Nov 30 16:04:45 imperator anacron[10462]: Normal exit (0 jobs run)
Nov 30 16:16:22 imperator NetworkManager[1009]: <info>  [1575126982.0047] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Nov 30 16:16:22 imperator NetworkManager[1009]: <info>  [1575126982.0047] manager: NetworkManager state is now ASLEEP
Nov 30 16:16:22 imperator systemd[1]: Reached target Sleep.
Nov 30 16:16:22 imperator systemd[1]: Starting Suspend...
Nov 30 16:16:22 imperator systemd-sleep[10619]: Suspending system...
Nov 30 16:16:22 imperator kernel: [20327.258436] PM: suspend entry (deep)
Nov 30 17:56:59 imperator systemd-modules-load[372]: Failed to find module 'ashmem_linux'
Nov 30 17:56:59 imperator systemd-modules-load[372]: Failed to find module 'binder_linux'
Nov 30 17:56:59 imperator systemd-modules-load[372]: Inserted module 'lp'
Nov 30 17:56:59 imperator systemd-modules-load[372]: Inserted module 'ppdev'
Nov 30 17:56:59 imperator systemd-modules-load[372]: Inserted module 'parport_pc'
Nov 30 17:56:59 imperator systemd-modules-load[372]: Inserted module 'arp_tables'
Nov 30 17:56:59 imperator systemd-modules-load[372]: Inserted module 'br_netfilter'
Nov 30 17:56:59 imperator systemd[1]: Started udev Coldplug all Devices.
Nov 30 17:56:59 imperator systemd[1]: Starting Flush Journal to Persistent Storage...
Nov 30 17:56:59 imperator systemd[1]: Mounted Mount unit for core, revision 7917.
Nov 30 17:56:59 imperator systemd-modules-load[372]: Inserted module 'ip6table_filter'
Nov 30 17:56:59 imperator systemd[1]: Activated swap /swapfile.
Nov 30 17:56:59 imperator systemd[1]: Reached target Swap.
Nov 30 17:56:59 imperator systemd[1]: Mounted Mount unit for mailspring, revision 402.
Nov 30 17:56:59 imperator systemd[1]: Mounted Mount unit for docker, revision 418.
Nov 30 17:56:59 imperator systemd[1]: Started udev Kernel Device Manager.
Nov 30 17:56:59 imperator systemd[1]: Starting Show Plymouth Boot Screen...
Nov 30 17:56:59 imperator systemd[1]: Mounted Mount unit for gtk-common-themes, revision 1353.
Nov 30 17:56:59 imperator systemd[1]: Mounted Mount unit for core18, revision 1223.
Nov 30 17:56:59 imperator systemd[1]: Mounted Mount unit for core, revision 8039.
Nov 30 17:56:59 imperator systemd[1]: Mounted Mount unit for docker, revision 423.
Nov 30 17:56:59 imperator systemd-modules-load[372]: Inserted module 'iptable_filter'
Nov 30 17:56:59 imperator systemd[1]: systemd-modules-load.service: Main process exited, code=exited, status=1/FAILURE
Nov 30 17:56:59 imperator systemd[1]: systemd-modules-load.service: Failed with result 'exit-code'.
Nov 30 17:56:59 imperator systemd[1]: Failed to start Load Kernel Modules.
Nov 30 17:56:59 imperator systemd[1]: Starting Apply Kernel Variables...

I don't know how to troubleshoot this, since the freeze is not automatic. I am asking myself if it occurs only after a first successful sleep/wake sequence. Will follow-up on this.
Cooler Master Sileo 500 ¤ Corsair TX - 650 ¤ Asus P7P55 ¤ Core i5-750 ¤ 2x4GB G-Skill RipJaws-X ¤ Sapphire VaporX ATI HD5850 ¤ SSD Samsung 830 128GB + Evo840 256GB
Post Reply

Return to “Installation & Boot”