You are not logged in.

#1 2016-08-26 16:20:29

Elopdat
Member
Registered: 2016-08-26
Posts: 5

Power management issues with KDE - indeterminate behaviour

Hi, this is my first post and apologies if I break any rules. I've been using Arch Linux for a while and have found it very enjoyable to use, with the wiki and google providing me help for most of my issues.

However, one issue has been plaguing me for a while and I can't seem to find a satisfactory solution. This seems to be a common issue and I understand it may be hardware dependent. I'm not sure exactly what details to post but here is the outline:

I'm using KDE and suspend/hibernate from system settings freeze my laptop (I have a swapfile for hibernate). Running systemctl suspend yields the same results. I have looked into using pm-utils as per the wiki page on power management. Running pm-utils suspend seems to yield different results but the most frustrating part is that the outcome seems to be indeterminate. The initial resume seems to work but suspending a second time will result in wifi not being restored correctly (I am using wicd) and will sometimes freeze the system, and I'm unable to move the mouse or enter any commands.

I have resorted to not using suspend at all, but even this is causing problems. Just setting KDE to lock the screen still causes indeterminate behaviour. The first resume (which I assume is from a "freeze" state written to /sys/power/state) seems to work, but similar to the pm-utils suspend issue, wifi will not be restored correctly the second time and I am also unable to reboot. The system doesn't seem to have been restored properly, so I'm forced to hold the power button which also corrupts my .zsh_history for some reason when the system finally restarts.

I've scoured the web and the wiki and tried countless solutions with no luck. The wiki suggests that the problem could be a kernel bug, but I have no idea if this is the case for me. Any assistance would be greatly appreciated. I'll provide any log files if needed but again I'm not sure what is most relevant in this case.

Last edited by Elopdat (2016-08-26 16:22:40)

Offline

#2 2016-08-27 04:57:12

Elopdat
Member
Registered: 2016-08-26
Posts: 5

Re: Power management issues with KDE - indeterminate behaviour

This is still a problem for me. I also tried posting this in Kernel & Hardware but it was deleted. Is anyone able to provide any advice? I'd be extremely grateful.

Offline

#3 2016-08-27 06:49:35

Elopdat
Member
Registered: 2016-08-26
Posts: 5

Re: Power management issues with KDE - indeterminate behaviour

Aug 27 18:43:40 dan systemd[1]: Started Cleanup of Temporary Directories.
Aug 27 18:43:40 dan systemd[1]: Starting Cleanup of Temporary Directories...
Aug 27 18:42:58 dan dhcpcd[2996]: forked to background, child pid 3067
Aug 27 18:42:58 dan dhcpcd[2996]: wlp2s0: adding default route via 192.168.1.254
Aug 27 18:42:58 dan dhcpcd[2996]: wlp2s0: adding route to 192.168.1.0/24
Aug 27 18:42:58 dan dhcpcd[2996]: wlp2s0: leased 192.168.1.67 for 86400 seconds
Aug 27 18:42:55 dan dhcpcd[2996]: wlp2s0: requesting DHCPv6 information
Aug 27 18:42:55 dan dhcpcd[2996]: wlp2s0: adding route to fd0c:d6bd:193b:6f00::/64
Aug 27 18:42:55 dan dhcpcd[2996]: wlp2s0: adding address fd0c:d6bd:193b:6f00:f6d:32e5:8ecb:e18a/64
Aug 27 18:42:55 dan dhcpcd[2996]: wlp2s0: Router Advertisement from fe80::1
Aug 27 18:42:54 dan dhcpcd[2996]: wlp2s0: probing address 192.168.1.67/24
Aug 27 18:42:54 dan dhcpcd[2996]: wlp2s0: offered 192.168.1.67 from 192.168.1.254
Aug 27 18:42:54 dan dhcpcd[2996]: wlp2s0: soliciting a DHCP lease
Aug 27 18:42:54 dan dhcpcd[2996]: wlp2s0: soliciting an IPv6 router
Aug 27 18:42:54 dan dhcpcd[2996]: wlp2s0: IAID 9c:00:72:1f
Aug 27 18:42:54 dan dhcpcd[2996]: DUID 00:01:00:01:1e:ea:d6:56:e4:f8:9c:00:72:1f
Aug 27 18:42:54 dan dhcpcd[2996]: wlp2s0: adding address fe80::d332:2570:98f6:b3d3
Aug 27 18:42:53 dan kernel: wlp2s0: Limiting TX power to 30 (30 - 0) dBm as advertised by 0c:d6:bd:19:3b:7c
Aug 27 18:42:53 dan kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready
Aug 27 18:42:53 dan kernel: wlp2s0: associated
Aug 27 18:42:53 dan kernel: wlp2s0: RX AssocResp from 0c:d6:bd:19:3b:7c (capab=0x11 status=0 aid=1)
Aug 27 18:42:53 dan kernel: wlp2s0: associate with 0c:d6:bd:19:3b:7c (try 1/3)
Aug 27 18:42:53 dan kernel: wlp2s0: authenticated
Aug 27 18:42:53 dan kernel: wlp2s0: send auth to 0c:d6:bd:19:3b:7c (try 2/3)
Aug 27 18:42:53 dan kernel: wlp2s0: send auth to 0c:d6:bd:19:3b:7c (try 1/3)
Aug 27 18:42:53 dan kernel: wlp2s0: authenticate with 0c:d6:bd:19:3b:7c
Aug 27 18:42:50 dan wicd[608]:     SET failed on device %s ; No such device.
Aug 27 18:42:50 dan wicd[608]: Error for wireless request "Set Bit Rate" (8B20) :
Aug 27 18:42:50 dan wicd[608]: Failed to connect to non-global ctrl_ifname: wlp2s0  error: No such file or directory
Aug 27 18:42:50 dan kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
Aug 27 18:42:50 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:50 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:50 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:50 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:50 dan dhcpcd[2974]: dhcpcd not running
Aug 27 18:42:50 dan wicd[608]: dhcpcd not running
Aug 27 18:42:50 dan wicd[608]: Failed to connect to non-global ctrl_ifname: wlp2s0  error: No such file or directory
Aug 27 18:42:50 dan kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
Aug 27 18:42:50 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:50 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:50 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:50 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:50 dan dhcpcd[2962]: dhcpcd not running
Aug 27 18:42:50 dan wicd[608]: dhcpcd not running
Aug 27 18:42:47 dan kdeinit5[1058]: bluedevil: About to resume
Aug 27 18:42:47 dan systemd[1]: Stopped target Suspend.
Aug 27 18:42:47 dan systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too.
Aug 27 18:42:47 dan systemd-logind[586]: Operation 'sleep' finished.
Aug 27 18:42:47 dan systemd[1]: Reached target Suspend.
Aug 27 18:42:47 dan systemd[1]: Stopped target Sleep.
Aug 27 18:42:47 dan systemd[1]: sleep.target: Unit not needed anymore. Stopping.
Aug 27 18:42:47 dan systemd[1]: Started Suspend.
Aug 27 18:42:47 dan kernel: EXT4-fs (sda8): re-mounted. Opts: data=ordered,commit=0
Aug 27 18:42:47 dan kernel: EXT4-fs (sda8): re-mounted. Opts: data=ordered,commit=0
Aug 27 18:42:45 dan kernel: hub 1-1:1.0: 8 ports detected
Aug 27 18:42:45 dan kernel: hub 1-1:1.0: USB hub found
Aug 27 18:42:45 dan kernel: usb 1-1: new high-speed USB device number 2 using ehci-pci
Aug 27 18:42:44 dan kernel: nouveau: probe of 0000:03:00.0 failed with error -12
Aug 27 18:42:44 dan kernel: nouveau 0000:03:00.0: unknown chipset (ffffffff)
Aug 27 18:42:44 dan kernel: VGA switcheroo: detected Optimus DSM method \_SB_.PCI0.RP05.PEGP handle
Aug 27 18:42:44 dan kernel: pci 0000:03:00.0: optimus capabilities: enabled, status dynamic power, hda bios codec supported
Aug 27 18:42:44 dan kernel: ACPI Warning: \_SB.PCI0.RP05.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160422/nsarguments-
Aug 27 18:42:44 dan kernel: ACPI Warning: \_SB.PCI0.RP05.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160422/nsarguments-
Aug 27 18:42:44 dan kernel: i915 0000:00:02.0: optimus capabilities: enabled, status dynamic power, hda bios codec supported
Aug 27 18:42:44 dan kernel: ACPI Warning: \_SB.PCI0.GFX0._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160422/nsarguments-95)
Aug 27 18:42:44 dan kernel: ACPI Warning: \_SB.PCI0.GFX0._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160422/nsarguments-95)
Aug 27 18:42:44 dan kernel: hub 1-0:1.0: 2 ports detected
Aug 27 18:42:44 dan kernel: hub 1-0:1.0: USB hub found
Aug 27 18:42:44 dan kernel: ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00
Aug 27 18:42:44 dan kernel: ehci-pci 0000:00:1d.0: irq 23, io mem 0xc361c000
Aug 27 18:42:44 dan kernel: ehci-pci 0000:00:1d.0: cache line size of 64 is not supported
Aug 27 18:42:44 dan kernel: ehci-pci 0000:00:1d.0: debug port 2
Aug 27 18:42:44 dan kernel: ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 1
Aug 27 18:42:44 dan kernel: ehci-pci 0000:00:1d.0: EHCI Host Controller
Aug 27 18:42:44 dan kernel: ehci-pci: EHCI PCI platform driver
Aug 27 18:42:44 dan kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Aug 27 18:42:44 dan systemd[1]: Started Load/Save RF Kill Switch Status.
Aug 27 18:42:44 dan kernel: ata1.00: configured for UDMA/100
Aug 27 18:42:44 dan kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Aug 27 18:42:43 dan kernel: Bluetooth: hci0: Intel Bluetooth firmware patch completed and activated
Aug 27 18:42:43 dan kernel: Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.7.10-fw-1.0.2.3.d.bseq
Aug 27 18:42:43 dan kernel: Bluetooth: hci0: read Intel version: 370710010002030d00
Aug 27 18:42:43 dan systemd[1]: Reached target Bluetooth.
Aug 27 18:42:43 dan systemd[1]: Stopped target Bluetooth.
Aug 27 18:42:43 dan systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Aug 27 18:42:43 dan systemd[1]: Starting Load/Save RF Kill Switch Status...
Aug 27 18:42:43 dan plasmashell[1317]: Time engine Clock skew signaled
Aug 27 18:42:43 dan systemd[813]: Time has been changed
Aug 27 18:42:43 dan systemd[1]: Time has been changed
Aug 27 18:42:43 dan kernel: done.
Aug 27 18:42:43 dan kernel: pci_bus 0000:03: Allocating resources
Aug 27 18:42:43 dan kernel: pci_bus 0000:02: Allocating resources
Aug 27 18:42:43 dan kernel: pci_bus 0000:01: Allocating resources
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.3: BAR 13: assigned [io  0x2000-0x2fff]
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.3: BAR 15: assigned [mem 0xafd00000-0xafefffff 64bit pref]
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.0: BAR 15: assigned [mem 0xafb00000-0xafcfffff 64bit pref]
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.3: res[13]=[io  0x1000-0x1fff] res_to_dev_res add_size 1000 min_align 1000
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.3: res[13]=[io  0x1000-0x0fff] res_to_dev_res add_size 1000 min_align 1000
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.3: res[15]=[mem 0x00100000-0x002fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.3: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.0: res[15]=[mem 0x00100000-0x002fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
Aug 27 18:42:43 dan kernel: pci_bus 0000:03: Allocating resources
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.3: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 02] add_size 200000 add_align 100000
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.3: bridge window [io  0x1000-0x0fff] to [bus 02] add_size 1000
Aug 27 18:42:43 dan kernel: pci_bus 0000:02: Allocating resources
Aug 27 18:42:43 dan kernel: pcieport 0000:00:1c.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 01] add_size 200000 add_align 100000
Aug 27 18:42:43 dan kernel: pci_bus 0000:01: Allocating resources
Aug 27 18:42:43 dan kernel: Restarting tasks ... 
Aug 27 18:42:43 dan kernel: PM: Finishing wakeup.
Aug 27 18:42:43 dan kernel: usb 2-7:1.1: rebind failed: -517
Aug 27 18:42:43 dan kernel: usb 2-7:1.0: rebind failed: -517
Aug 27 18:42:43 dan kernel: PM: resume of devices complete after 1085.181 msecs
Aug 27 18:42:43 dan kernel: usb 2-6: reset high-speed USB device number 4 using xhci_hcd
Aug 27 18:42:43 dan kernel: psmouse serio1: synaptics: queried min coordinates: x [1314..], y [1098..]
Aug 27 18:42:43 dan kernel: psmouse serio1: synaptics: queried max coordinates: x [..5670], y [..4794]
Aug 27 18:42:43 dan kernel: usb 2-4: reset high-speed USB device number 3 using xhci_hcd
Aug 27 18:42:43 dan kernel: ata2.00: configured for UDMA/100
Aug 27 18:42:43 dan kernel: ata4: SATA link down (SStatus 0 SControl 300)
Aug 27 18:42:43 dan kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Aug 27 18:42:43 dan kernel: ata3: SATA link down (SStatus 0 SControl 300)
Aug 27 18:42:43 dan kernel: usb 2-7: reset full-speed USB device number 5 using xhci_hcd
Aug 27 18:42:43 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:43 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:43 dan kernel: xhci_hcd 0000:00:14.0: port 3 resume PLC timeout
Aug 27 18:42:43 dan kernel: xhci_hcd 0000:00:14.0: port 6 resume PLC timeout
Aug 27 18:42:43 dan kernel: pci 0000:03:00.0: Refused to change power state, currently in D3
Aug 27 18:42:43 dan kernel: sd 0:0:0:0: [sda] Starting disk
Aug 27 18:42:43 dan kernel: rtc_cmos 00:01: System wakeup disabled by ACPI
Aug 27 18:42:43 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:43 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:43 dan kernel: PM: early resume of devices complete after 6.288 msecs
Aug 27 18:42:43 dan kernel: PM: noirq resume of devices complete after 63.112 msecs
Aug 27 18:42:43 dan kernel: xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
Aug 27 18:42:43 dan kernel: pci 0000:03:00.0: Refused to change power state, currently in D3
Aug 27 18:42:43 dan kernel: acpi LNXPOWER:00: Turning OFF
Aug 27 18:42:43 dan kernel: acpi LNXPOWER:01: Turning OFF
Aug 27 18:42:43 dan kernel: ACPI: Waking up from system sleep state S3
Aug 27 18:42:43 dan kernel: CPU3 is up
Aug 27 18:42:43 dan kernel:  cache: parent cpu3 should not be sleeping
Aug 27 18:42:43 dan kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Aug 27 18:42:43 dan kernel: CPU2 is up
Aug 27 18:42:43 dan kernel:  cache: parent cpu2 should not be sleeping
Aug 27 18:42:43 dan kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Aug 27 18:42:43 dan kernel: CPU1 is up
Aug 27 18:42:43 dan kernel:  cache: parent cpu1 should not be sleeping
Aug 27 18:42:43 dan kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Aug 27 18:42:43 dan kernel: x86: Booting SMP configuration:
Aug 27 18:42:43 dan kernel: Enabling non-boot CPUs ...
Aug 27 18:42:43 dan kernel: mce: [Hardware Error]: Machine check events logged
Aug 27 18:42:43 dan kernel: PM: Restoring platform NVS memory
Aug 27 18:42:43 dan kernel: ACPI : EC: EC started
Aug 27 18:42:43 dan kernel: ACPI: Low-level resume complete
Aug 27 18:42:43 dan kernel: smpboot: CPU 3 is now offline
Aug 27 18:42:43 dan kernel: smpboot: CPU 2 is now offline
Aug 27 18:42:43 dan kernel: smpboot: CPU 1 is now offline
Aug 27 18:42:43 dan kernel: Disabling non-boot CPUs ...
Aug 27 18:42:43 dan kernel: PM: Saving platform NVS memory
Aug 27 18:42:43 dan kernel: ACPI : EC: EC stopped
Aug 27 18:42:43 dan kernel: ACPI: Preparing to enter system sleep state S3
Aug 27 18:42:43 dan kernel: PM: noirq suspend of devices complete after 13.725 msecs
Aug 27 18:42:43 dan kernel: xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
Aug 27 18:42:43 dan kernel: PM: late suspend of devices complete after 22.230 msecs
Aug 27 18:42:43 dan kernel: PM: suspend of devices complete after 490.102 msecs
Aug 27 18:42:43 dan kernel: sd 0:0:0:0: [sda] Stopping disk
Aug 27 18:42:43 dan kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Aug 27 18:42:43 dan kernel: Suspending console(s) (use no_console_suspend to debug)
Aug 27 18:42:43 dan kernel: PM: Suspending system (mem)
Aug 27 18:42:43 dan kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Aug 27 18:42:43 dan kernel: Double checking all user space processes after OOM killer disable... (elapsed 0.000 seconds) 
Aug 27 18:42:43 dan kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Aug 27 18:42:43 dan kernel: PM: Preparing system for sleep (mem)
Aug 27 18:42:43 dan kernel: PM: Syncing filesystems ... done.
Aug 27 18:42:33 dan kernel: ehci-pci 0000:00:1d.0: USB bus 1 deregistered
Aug 27 18:42:33 dan kernel: usb 1-1: USB disconnect, device number 2
Aug 27 18:42:33 dan kernel: usb usb1: USB disconnect, device number 1
Aug 27 18:42:33 dan kernel: ehci-pci 0000:00:1d.0: remove, state 4
Aug 27 18:42:33 dan kernel: [drm] Module unloaded
Aug 27 18:42:33 dan kernel: vga_switcheroo: disabled
Aug 27 18:42:33 dan kernel: [TTM] Zone   dma32: Used memory at exit: 0 kiB
Aug 27 18:42:33 dan kernel: [TTM] Zone  kernel: Used memory at exit: 0 kiB
Aug 27 18:42:33 dan kernel: [TTM] Finalizing DMA pool allocator
Aug 27 18:42:33 dan kernel: [TTM] Finalizing pool allocator
Aug 27 18:42:33 dan kernel: nouveau 0000:03:00.0: DRM: resuming client object trees...
Aug 27 18:42:33 dan kernel: nouveau 0000:03:00.0: DRM: resuming kernel object tree...
Aug 27 18:42:33 dan wicd[608]: Failed to connect to non-global ctrl_ifname: wlp2s0  error: No such file or directory
Aug 27 18:42:33 dan kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
Aug 27 18:42:33 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:33 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:33 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:33 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:33 dan dhcpcd[2702]: dhcpcd not running
Aug 27 18:42:33 dan wicd[608]: dhcpcd not running
Aug 27 18:42:33 dan kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
Aug 27 18:42:33 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:33 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:33 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:33 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:42:33 dan kernel: wlp2s0: deauthenticating from 0c:d6:bd:19:3b:7c by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 27 18:42:33 dan dhcpcd[1378]: dhcpcd exited
Aug 27 18:42:33 dan dhcpcd[1378]: wlp2s0: deleting default route via 192.168.1.254
Aug 27 18:42:33 dan dhcpcd[1378]: wlp2s0: deleting route to 192.168.1.0/24
Aug 27 18:42:33 dan dhcpcd[1378]: wlp2s0: releasing lease of 192.168.1.67
Aug 27 18:42:33 dan dhcpcd[1378]: wlp2s0: deleting route to fd0c:d6bd:193b:6f00::/64
Aug 27 18:42:33 dan dhcpcd[1378]: wlp2s0: deleting address fd0c:d6bd:193b:6f00:f6d:32e5:8ecb:e18a/64
Aug 27 18:42:33 dan dhcpcd[1378]: wlp2s0: removing interface
Aug 27 18:42:33 dan dhcpcd[1378]: received SIGALRM, releasing
Aug 27 18:42:33 dan dhcpcd[2647]: waiting for pid 1378 to exit
Aug 27 18:42:33 dan dhcpcd[2647]: sending signal ARLM to pid 1378
Aug 27 18:42:32 dan kernel: EXT4-fs (sda8): re-mounted. Opts: data=ordered,commit=0
Aug 27 18:42:32 dan kernel: EXT4-fs (sda8): re-mounted. Opts: data=ordered,commit=0
Aug 27 18:42:32 dan systemd[1]: Starting Suspend...
Aug 27 18:42:32 dan sudo[2548]: pam_unix(sudo:session): session closed for user root
Aug 27 18:42:32 dan systemd[1]: Reached target Sleep.
Aug 27 18:42:32 dan kdeinit5[1058]: bluedevil: About to suspend
Aug 27 18:42:32 dan sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0)

Above is the output of journalctl -r after the initial suspend with systemctl suspend. This suspend seems to work, but any subsequent suspends fail in various ways, either freezing after wakeup, or wifi not being restored correctly.

Suspending a second time results in the following output (the suspend turns off the system as normal, and this is the output once I restart it):

Aug 27 18:50:43 dan kernel: nouveau 0000:03:00.0: DRM: Pointer to flat panel table invalid
Aug 27 18:50:43 dan kernel: nouveau 0000:03:00.0: DRM: DCB version 4.0
Aug 27 18:50:43 dan kernel: nouveau 0000:03:00.0: DRM: Pointer to TMDS table invalid
Aug 27 18:50:43 dan kernel: nouveau 0000:03:00.0: DRM: GART: 1048576 MiB
Aug 27 18:50:43 dan kernel: nouveau 0000:03:00.0: DRM: VRAM: -1159778304 MiB
Aug 27 18:50:43 dan kernel: [TTM] Initializing DMA pool allocator
Aug 27 18:50:43 dan kernel: [TTM] Initializing pool allocator
Aug 27 18:50:43 dan kernel: [TTM] Zone   dma32: Available graphics memory: 2097152 kiB
Aug 27 18:50:43 dan kernel: [TTM] Zone  kernel: Available graphics memory: 4034754 kiB
Aug 27 18:50:43 dan kernel: vga_switcheroo: enabled
Aug 27 18:50:43 dan kernel: nouveau 0000:03:00.0: fb: -1159778304 MiB of unknown memory type
Aug 27 18:50:43 dan kernel: nouveau 0000:03:00.0: bios: M0203E type 0f
Aug 27 18:50:39 dan kernel: hub 1-1:1.0: 8 ports detected
Aug 27 18:50:39 dan kernel: hub 1-1:1.0: USB hub found
Aug 27 18:50:38 dan kernel: usb 1-1: new high-speed USB device number 2 using ehci-pci
Aug 27 18:50:38 dan kernel: nouveau 0000:03:00.0: bios: version 75.17.79.00.0f
Aug 27 18:50:38 dan kernel: nouveau 0000:03:00.0: NVIDIA GF117 (0d7000a2)
Aug 27 18:50:38 dan kernel: VGA switcheroo: detected Optimus DSM method \_SB_.PCI0.RP05.PEGP handle
Aug 27 18:50:38 dan kernel: pci 0000:03:00.0: optimus capabilities: enabled, status dynamic power, hda bios codec supported
Aug 27 18:50:38 dan kernel: ACPI Warning: \_SB.PCI0.RP05.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160422/nsarguments-
Aug 27 18:50:38 dan kernel: ACPI Warning: \_SB.PCI0.RP05.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160422/nsarguments-
Aug 27 18:50:38 dan kernel: i915 0000:00:02.0: optimus capabilities: enabled, status dynamic power, hda bios codec supported
Aug 27 18:50:38 dan kernel: ACPI Warning: \_SB.PCI0.GFX0._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160422/nsarguments-95)
Aug 27 18:50:38 dan kernel: ACPI Warning: \_SB.PCI0.GFX0._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160422/nsarguments-95)
Aug 27 18:50:38 dan kernel: Bluetooth: hci0: Intel Bluetooth firmware patch completed and activated
Aug 27 18:50:38 dan kernel: hub 1-0:1.0: 2 ports detected
Aug 27 18:50:38 dan kernel: hub 1-0:1.0: USB hub found
Aug 27 18:50:38 dan kernel: ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00
Aug 27 18:50:38 dan kernel: ehci-pci 0000:00:1d.0: irq 23, io mem 0xc361c000
Aug 27 18:50:38 dan kernel: ehci-pci 0000:00:1d.0: cache line size of 64 is not supported
Aug 27 18:50:38 dan kernel: ehci-pci 0000:00:1d.0: debug port 2
Aug 27 18:50:38 dan kernel: ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 1
Aug 27 18:50:38 dan kernel: ehci-pci 0000:00:1d.0: EHCI Host Controller
Aug 27 18:50:38 dan kernel: ehci-pci: EHCI PCI platform driver
Aug 27 18:50:38 dan kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Aug 27 18:50:38 dan kernel: Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.7.10-fw-1.0.2.3.d.bseq
Aug 27 18:50:38 dan kernel: Bluetooth: hci0: read Intel version: 370710010002030d00
Aug 27 18:50:38 dan systemd[1]: Started Load/Save RF Kill Switch Status.
Aug 27 18:50:38 dan systemd[1]: Reached target Bluetooth.
Aug 27 18:50:38 dan rtkit-daemon[1396]: Demoted 3 threads.
Aug 27 18:50:38 dan systemd[1]: Stopped target Bluetooth.
Aug 27 18:50:38 dan rtkit-daemon[1396]: Successfully demoted thread 1385 of process 1385 (/usr/bin/pulseaudio).
Aug 27 18:50:38 dan systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Aug 27 18:50:38 dan rtkit-daemon[1396]: Successfully demoted thread 1415 of process 1385 (/usr/bin/pulseaudio).
Aug 27 18:50:38 dan systemd[1]: Starting Load/Save RF Kill Switch Status...
Aug 27 18:50:38 dan rtkit-daemon[1396]: Successfully demoted thread 1416 of process 1385 (/usr/bin/pulseaudio).
Aug 27 18:50:38 dan systemd[813]: Time has been changed
Aug 27 18:50:38 dan rtkit-daemon[1396]: Demoting known real-time threads.
Aug 27 18:50:38 dan plasmashell[1317]: Time engine Clock skew signaled
Aug 27 18:50:38 dan rtkit-daemon[1396]: The canary thread is apparently starving. Taking action.
Aug 27 18:50:38 dan systemd[1]: Time has been changed
Aug 27 18:50:38 dan kernel: done.
Aug 27 18:50:38 dan kernel: pci_bus 0000:03: Allocating resources
Aug 27 18:50:38 dan kernel: pci_bus 0000:02: Allocating resources
Aug 27 18:50:38 dan kernel: pci_bus 0000:01: Allocating resources
Aug 27 18:50:38 dan kernel: pci_bus 0000:03: Allocating resources
Aug 27 18:50:38 dan kernel: pci_bus 0000:02: Allocating resources
Aug 27 18:50:38 dan kernel: pci_bus 0000:01: Allocating resources
Aug 27 18:50:38 dan kernel: Restarting tasks ... 
Aug 27 18:50:38 dan kernel: PM: Finishing wakeup.
Aug 27 18:50:38 dan kernel: usb 2-7:1.1: rebind failed: -517
Aug 27 18:50:38 dan kernel: usb 2-7:1.0: rebind failed: -517
Aug 27 18:50:38 dan kernel: PM: resume of devices complete after 1229.598 msecs
Aug 27 18:50:38 dan kernel: usb 2-6: reset high-speed USB device number 4 using xhci_hcd
Aug 27 18:50:38 dan kernel: psmouse serio1: synaptics: queried min coordinates: x [1314..], y [1098..]
Aug 27 18:50:38 dan kernel: psmouse serio1: synaptics: queried max coordinates: x [..5670], y [..4794]
Aug 27 18:50:38 dan kernel: usb 2-4: reset high-speed USB device number 3 using xhci_hcd
Aug 27 18:50:38 dan kernel: ata1.00: configured for UDMA/100
Aug 27 18:50:38 dan kernel: usb 2-7: reset full-speed USB device number 5 using xhci_hcd
Aug 27 18:50:38 dan kernel: ata2.00: configured for UDMA/100
Aug 27 18:50:38 dan kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Aug 27 18:50:38 dan kernel: ata3: SATA link down (SStatus 0 SControl 300)
Aug 27 18:50:38 dan kernel: ata4: SATA link down (SStatus 0 SControl 300)
Aug 27 18:50:38 dan kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Aug 27 18:50:38 dan kernel: xhci_hcd 0000:00:14.0: port 3 resume PLC timeout
Aug 27 18:50:38 dan kernel: xhci_hcd 0000:00:14.0: port 6 resume PLC timeout
Aug 27 18:50:38 dan kernel: rtc_cmos 00:01: System wakeup disabled by ACPI
Aug 27 18:50:38 dan kernel: sd 0:0:0:0: [sda] Starting disk
Aug 27 18:50:38 dan kernel: PM: early resume of devices complete after 6.239 msecs
Aug 27 18:50:38 dan kernel: PM: noirq resume of devices complete after 4065.008 msecs
Aug 27 18:50:38 dan kernel: xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
Aug 27 18:50:38 dan kernel: acpi LNXPOWER:01: Turning OFF
Aug 27 18:50:38 dan kernel: ACPI: Waking up from system sleep state S3
Aug 27 18:50:38 dan kernel: CPU3 is up
Aug 27 18:50:38 dan kernel:  cache: parent cpu3 should not be sleeping
Aug 27 18:50:38 dan kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Aug 27 18:50:38 dan kernel: CPU2 is up
Aug 27 18:50:38 dan kernel:  cache: parent cpu2 should not be sleeping
Aug 27 18:50:38 dan kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Aug 27 18:50:38 dan kernel: CPU1 is up
Aug 27 18:50:38 dan kernel:  cache: parent cpu1 should not be sleeping
Aug 27 18:50:38 dan kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Aug 27 18:50:38 dan kernel: x86: Booting SMP configuration:
Aug 27 18:50:38 dan kernel: Enabling non-boot CPUs ...
Aug 27 18:50:38 dan kernel: mce: [Hardware Error]: Machine check events logged
Aug 27 18:50:38 dan kernel: PM: Restoring platform NVS memory
Aug 27 18:50:38 dan kernel: ACPI : EC: EC started
Aug 27 18:50:38 dan kernel: ACPI: Low-level resume complete
Aug 27 18:50:38 dan kernel: smpboot: CPU 3 is now offline
Aug 27 18:50:38 dan kernel: smpboot: CPU 2 is now offline
Aug 27 18:50:38 dan kernel: smpboot: CPU 1 is now offline
Aug 27 18:50:38 dan kernel: Disabling non-boot CPUs ...
Aug 27 18:50:38 dan kernel: PM: Saving platform NVS memory
Aug 27 18:50:38 dan kernel: ACPI : EC: EC stopped
Aug 27 18:50:38 dan kernel: ACPI: Preparing to enter system sleep state S3
Aug 27 18:50:38 dan kernel: PM: noirq suspend of devices complete after 13.869 msecs
Aug 27 18:50:38 dan kernel: xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
Aug 27 18:50:38 dan kernel: PM: late suspend of devices complete after 21.609 msecs
Aug 27 18:50:38 dan kernel: PM: suspend of devices complete after 488.400 msecs
Aug 27 18:50:38 dan kernel: sd 0:0:0:0: [sda] Stopping disk
Aug 27 18:50:38 dan kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Aug 27 18:50:38 dan kernel: Suspending console(s) (use no_console_suspend to debug)
Aug 27 18:50:38 dan kernel: PM: Suspending system (mem)
Aug 27 18:50:38 dan kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Aug 27 18:50:38 dan kernel: Double checking all user space processes after OOM killer disable... (elapsed 0.000 seconds) 
Aug 27 18:50:38 dan kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Aug 27 18:50:38 dan kernel: PM: Preparing system for sleep (mem)
Aug 27 18:50:38 dan kernel: PM: Syncing filesystems ... done.
Aug 27 18:50:18 dan kernel: ehci-pci 0000:00:1d.0: USB bus 1 deregistered
Aug 27 18:50:18 dan kernel: usb 1-1: USB disconnect, device number 2
Aug 27 18:50:18 dan kernel: usb usb1: USB disconnect, device number 1
Aug 27 18:50:18 dan kernel: ehci-pci 0000:00:1d.0: remove, state 4
Aug 27 18:50:17 dan kernel: [drm] Module unloaded
Aug 27 18:50:07 dan kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
Aug 27 18:50:07 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:50:07 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:50:07 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:50:07 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:50:07 dan dhcpcd[3491]: dhcpcd not running
Aug 27 18:50:07 dan wicd[608]: dhcpcd not running
Aug 27 18:50:07 dan kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
Aug 27 18:50:07 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:50:07 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:50:07 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:50:07 dan kernel: iwlwifi 0000:02:00.0: L1 Enabled - LTR Disabled
Aug 27 18:50:07 dan kernel: wlp2s0: deauthenticating from 0c:d6:bd:19:3b:7c by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 27 18:50:07 dan dhcpcd[3067]: dhcpcd exited
Aug 27 18:50:07 dan dhcpcd[3067]: wlp2s0: deleting default route via 192.168.1.254
Aug 27 18:50:07 dan dhcpcd[3067]: wlp2s0: deleting route to 192.168.1.0/24
Aug 27 18:50:07 dan dhcpcd[3067]: wlp2s0: releasing lease of 192.168.1.67
Aug 27 18:50:07 dan dhcpcd[3067]: wlp2s0: deleting route to fd0c:d6bd:193b:6f00::/64
Aug 27 18:50:07 dan dhcpcd[3067]: wlp2s0: deleting address fd0c:d6bd:193b:6f00:f6d:32e5:8ecb:e18a/64
Aug 27 18:50:07 dan dhcpcd[3067]: wlp2s0: removing interface
Aug 27 18:50:07 dan dhcpcd[3067]: received SIGALRM, releasing
Aug 27 18:50:07 dan dhcpcd[3436]: waiting for pid 3067 to exit
Aug 27 18:50:07 dan dhcpcd[3436]: sending signal ARLM to pid 3067
Aug 27 18:50:07 dan kernel: EXT4-fs (sda8): re-mounted. Opts: data=ordered,commit=0
Aug 27 18:50:07 dan kernel: EXT4-fs (sda8): re-mounted. Opts: data=ordered,commit=0
Aug 27 18:50:07 dan systemd[1]: Starting Suspend...
Aug 27 18:50:07 dan systemd[1]: Reached target Sleep.
Aug 27 18:50:07 dan sudo[3344]: pam_unix(sudo:session): session closed for user root
Aug 27 18:50:07 dan kdeinit5[1058]: bluedevil: About to suspend
Aug 27 18:50:07 dan sudo[3344]: pam_unix(sudo:session): session opened for user root by (uid=0)

Wifi is not restored and trying to

reboot

gives:

 Failed to reboot system via logind: There's already a shutdown or sleep operation in progress 

At this point, I'm forced to shut down with the power button. Trying to suspend (or even logout through KDE) a third time seems to freeze the system.

Last edited by Elopdat (2016-08-27 07:08:26)

Offline

#4 2016-08-28 01:48:05

Elopdat
Member
Registered: 2016-08-26
Posts: 5

Re: Power management issues with KDE - indeterminate behaviour

Bump

Offline

Board footer

Powered by FluxBB