[SOLVED] System freezes after resume from standby

KreativeKrise · 2014-03-11 20:40:31

Hi!

I don't know if this the correct forum to ask this questions, because I don't know if it's a software or hardware problem.

Here is my problem: If I close the lid of my laptop, my laptop goes into standby. If I open the lid it shows the user login form. I log in and everything works for around 5 seconds without any problem. But then the system starts to hang. The mouse isn't moving normally, it jumps over the desktop. It gets slower and slower until it completly freezes. Then I wait for around 30 seconds and everything works fine again. It's not a bug problem, but it's really annoying.

Anybody has an idea where I can start to search for the reason?

Linux Kernel: 3.10.32
DE: Cinnamon 2.0.14
Laptop: Lenovo Thinkpad T500 with integrated Intel GPU

Do you need any other information?

Thank you!

Last edited by KreativeKrise (2014-03-19 21:09:43)

frank604 · 2014-03-11 23:37:19

What does dmesg say? What does journalctl say? Cat them into a file and upload the logs. Has suspend/resume worked flawlessly before? If yes, when did the problem start happening?

Is there a reason you haven't updated your full system? 3.10.32 is kinda old, OR are you are running a LTS or other patched kernel? Then can you try with latest arch kernel?

progandy · 2014-03-12 02:11:45

If the current kernel does not help, try to use pm-utils to suspend. If it works, add the necessary quirks to systemd. According to the pm-quirks it is something like this:
http://cgit.freedesktop.org/pm-utils/tr … deo-quirks
executable script in /usr/lib/systemd/system-sleep/lenovo.sh
run "chvt 1 && vbetool vbestate save >/tmp/vbestate" on suspend
run "vbetool post" on resume
run "vbetool vbestate restore </tmp/vbestate && chvt 7" on resume

Last edited by progandy (2014-03-12 02:13:18)

KreativeKrise · 2014-03-13 20:51:21

Thank you for your answers!

I think suspend/resume didn't work correctly from the very beginning. From time to time it works without any problem, but that's the exception.
I'm using the 3.10 kernel, because it's the LTS version. And I think, that the T500 is such a old laptop, that it doesn't need a newer kernel. Or am I wrong with my opinion?

But of course, I could try another kernel.

The dmesg output:

[35137.516785] wlp3s0: deauthenticating from 00:1f:3f:1d:db:cf by local choice (reason=3)
[35137.532549] cfg80211: Calling CRDA to update world regulatory domain
[35137.940744] PM: Syncing filesystems ... done.
[35140.373656] PM: Preparing system for mem sleep
[35140.374841] Freezing user space processes ... (elapsed 0.01 seconds) done.
[35140.390323] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[35140.410222] PM: Entering mem sleep
[35140.410356] Suspending console(s) (use no_console_suspend to debug)
[35140.568419] hdaps: setting ec_rate=0, filter_order=1
[35140.568869] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
[35140.568949] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[35140.569770] sd 0:0:0:0: [sda] Stopping disk
[35140.586342] sd 1:0:0:0: [sdb] Stopping disk
[35140.618704] mei_me 0000:00:03.0: suspend
[35140.630134] i915 0000:00:02.0: power state changed by ACPI to D3hot
[35141.620183] PM: suspend of devices complete after 1208.849 msecs
[35141.620378] PM: late suspend of devices complete after 0.192 msecs
[35141.660157] ehci-pci 0000:00:1d.7: System wakeup enabled by ACPI
[35141.680037] ehci-pci 0000:00:1d.7: power state changed by ACPI to D3hot
[35141.680241] uhci_hcd 0000:00:1d.0: System wakeup enabled by ACPI
[35141.680249] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D2
[35141.680583] ehci-pci 0000:00:1a.7: System wakeup enabled by ACPI
[35141.700047] ehci-pci 0000:00:1a.7: power state changed by ACPI to D3hot
[35141.700159] uhci_hcd 0000:00:1a.2: System wakeup enabled by ACPI
[35141.700167] uhci_hcd 0000:00:1a.2: power state changed by ACPI to D2
[35141.700307] uhci_hcd 0000:00:1a.0: System wakeup enabled by ACPI
[35141.700314] uhci_hcd 0000:00:1a.0: power state changed by ACPI to D2
[35141.740198] PM: noirq suspend of devices complete after 119.814 msecs
[35141.741231] ACPI: Preparing to enter system sleep state S3
[35141.747428] PM: Saving platform NVS memory
[35141.750663] Disabling non-boot CPUs ...
[35141.860035] smpboot: CPU 1 is now offline
[35141.860555] ACPI: Low-level resume complete
[35141.860555] PM: Restoring platform NVS memory
[35141.860555] Enabling non-boot CPUs ...
[35141.860555] smpboot: Booting Node 0 Processor 1 APIC 0x1
[35141.752234] Disabled fast string operations
[35141.951290] CPU1 is up
[35141.953462] ACPI: Waking up from system sleep state S3
[35142.289905] i915 0000:00:02.0: power state changed by ACPI to D0
[35142.320123] thinkpad_acpi: fan watchdog: enabling fan
[35142.360207] uhci_hcd 0000:00:1a.0: power state changed by ACPI to D0
[35142.360304] uhci_hcd 0000:00:1a.0: System wakeup disabled by ACPI
[35142.360362] uhci_hcd 0000:00:1a.2: power state changed by ACPI to D0
[35142.360447] uhci_hcd 0000:00:1a.2: System wakeup disabled by ACPI
[35142.360457] ehci-pci 0000:00:1a.7: power state changed by ACPI to D0
[35142.380210] ehci-pci 0000:00:1a.7: System wakeup disabled by ACPI
[35142.400611] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[35142.400702] uhci_hcd 0000:00:1d.0: System wakeup disabled by ACPI
[35142.400805] ehci-pci 0000:00:1d.7: power state changed by ACPI to D0
[35142.420206] ehci-pci 0000:00:1d.7: System wakeup disabled by ACPI
[35142.520806] PM: noirq resume of devices complete after 230.994 msecs
[35142.521277] PM: early resume of devices complete after 0.411 msecs
[35142.521351] i915 0000:00:02.0: setting latency timer to 64
[35142.521613] mei_me 0000:00:03.0: irq 45 for MSI/MSI-X
[35142.521895] e1000e 0000:00:19.0: setting latency timer to 64
[35142.521937] e1000e 0000:00:19.0: irq 46 for MSI/MSI-X
[35142.524166] uhci_hcd 0000:00:1a.0: setting latency timer to 64
[35142.524203] usb usb1: root hub lost power or was reset
[35142.524369] uhci_hcd 0000:00:1a.1: setting latency timer to 64
[35142.524403] usb usb2: root hub lost power or was reset
[35142.524554] uhci_hcd 0000:00:1a.2: setting latency timer to 64
[35142.524588] usb usb3: root hub lost power or was reset
[35142.524741] ehci-pci 0000:00:1a.7: setting latency timer to 64
[35142.524951] snd_hda_intel 0000:00:1b.0: irq 48 for MSI/MSI-X
[35142.525225] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[35142.525259] usb usb4: root hub lost power or was reset
[35142.525410] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[35142.525444] usb usb5: root hub lost power or was reset
[35142.525592] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[35142.525625] usb usb6: root hub lost power or was reset
[35142.525777] ehci-pci 0000:00:1d.7: setting latency timer to 64
[35142.525816] pci 0000:00:1e.0: setting latency timer to 64
[35142.525839] ahci 0000:00:1f.2: setting latency timer to 64
[35142.525996] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it.
[35142.527027] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it.
[35142.870131] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[35142.870204] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[35142.880789] ata2.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[35142.880794] ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[35142.880799] ata2.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[35142.901124] ata2.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[35142.901129] ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[35142.901133] ata2.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[35142.910944] ata2.00: configured for UDMA/133
[35142.929071] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[35142.929073] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[35142.929075] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[35142.930086] sd 1:0:0:0: [sdb] Starting disk
[35143.039222] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[35143.039224] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[35143.039227] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[35143.088995] ata1.00: configured for UDMA/133
[35143.130103] sd 0:0:0:0: [sda] Starting disk
[35143.170157] firewire_core 0000:15:00.1: rediscovered device fw0
[35143.290233] hdaps: initial mode latch is 0x05
[35143.290374] hdaps: setting ec_rate=250, filter_order=2
[35143.290686] PM: resume of devices complete after 769.402 msecs
[35143.291636] PM: Finishing wakeup.
[35143.291640] Restarting tasks ... done.
[35143.311936] video LNXVIDEO:00: Restoring backlight state
[35143.440244] e1000e 0000:00:19.0: irq 46 for MSI/MSI-X
[35143.550122] e1000e 0000:00:19.0: irq 46 for MSI/MSI-X
[35143.550251] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
[35143.551203] iwlwifi 0000:03:00.0: L1 Disabled; Enabling L0S
[35143.554240] iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
[35143.673989] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
[35150.005309] wlp3s0: authenticate with 00:1f:3f:1d:db:cf
[35150.007121] wlp3s0: send auth to 00:1f:3f:1d:db:cf (try 1/3)
[35150.012246] wlp3s0: authenticated
[35150.020152] wlp3s0: associate with 00:1f:3f:1d:db:cf (try 1/3)
[35150.023958] wlp3s0: RX AssocResp from 00:1f:3f:1d:db:cf (capab=0x431 status=0 aid=3)
[35150.026345] wlp3s0: associated
[35150.026378] IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
[35150.041526] wlp3s0: deauthenticating from 00:1f:3f:1d:db:cf by local choice (reason=2)
[35150.061685] cfg80211: Calling CRDA to update world regulatory domain
[35150.062202] wlp3s0: authenticate with 00:1f:3f:1d:db:cf
[35150.064003] wlp3s0: send auth to 00:1f:3f:1d:db:cf (try 1/3)
[35150.066212] wlp3s0: authenticated
[35150.070534] wlp3s0: associate with 00:1f:3f:1d:db:cf (try 1/3)
[35150.074690] wlp3s0: RX AssocResp from 00:1f:3f:1d:db:cf (capab=0x431 status=0 aid=3)
[35150.081921] wlp3s0: associated
[35191.310197] [drm] HPD interrupt storm detected on connector DP-3: switching from hotplug detection to polling

The journal output:

Mär 13 21:38:28 Deus-Atratus kernel: PM: Syncing filesystems ... done.
Mär 13 21:38:28 Deus-Atratus systemd[1]: Time has been changed
Mär 13 21:38:28 Deus-Atratus systemd[3277]: Time has been changed
Mär 13 21:38:28 Deus-Atratus systemd-sleep[10683]: System resumed.
Mär 13 21:38:28 Deus-Atratus systemd[1]: Started Suspend.
Mär 13 21:38:28 Deus-Atratus systemd[1]: Requested transaction contradicts existing jobs: File exists
Mär 13 21:38:28 Deus-Atratus systemd[1]: Service sleep.target is not needed anymore. Stopping.
Mär 13 21:38:28 Deus-Atratus systemd[1]: Stopping Sleep.
Mär 13 21:38:28 Deus-Atratus systemd[1]: Stopped target Sleep.
Mär 13 21:38:28 Deus-Atratus systemd[1]: Service tlp-sleep.service is not needed anymore. Stopping.
Mär 13 21:38:28 Deus-Atratus systemd[1]: Stopping TLP suspend/resume...
Mär 13 21:38:28 Deus-Atratus systemd[1]: Reached target Suspend.
Mär 13 21:38:28 Deus-Atratus kernel: PM: Preparing system for mem sleep
Mär 13 21:38:28 Deus-Atratus kernel: Freezing user space processes ... (elapsed 0.01 seconds) done.
Mär 13 21:38:28 Deus-Atratus kernel: Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Mär 13 21:38:28 Deus-Atratus kernel: PM: Entering mem sleep
Mär 13 21:38:28 Deus-Atratus kernel: Suspending console(s) (use no_console_suspend to debug)
Mär 13 21:38:28 Deus-Atratus kernel: hdaps: setting ec_rate=0, filter_order=1
Mär 13 21:38:28 Deus-Atratus kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache
Mär 13 21:38:28 Deus-Atratus kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mär 13 21:38:28 Deus-Atratus kernel: sd 0:0:0:0: [sda] Stopping disk
Mär 13 21:38:28 Deus-Atratus systemd-logind[351]: Operation finished.
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> wake requested (sleeping: yes  enabled: yes)
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> waking up and re-enabling...
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (enp0s25): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (enp0s25): bringing up device.
Mär 13 21:38:28 Deus-Atratus kernel: sd 1:0:0:0: [sdb] Stopping disk
Mär 13 21:38:28 Deus-Atratus kernel: mei_me 0000:00:03.0: suspend
Mär 13 21:38:28 Deus-Atratus kernel: i915 0000:00:02.0: power state changed by ACPI to D3hot
Mär 13 21:38:28 Deus-Atratus kernel: PM: suspend of devices complete after 1208.849 msecs
Mär 13 21:38:28 Deus-Atratus kernel: PM: late suspend of devices complete after 0.192 msecs
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1d.7: System wakeup enabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1d.7: power state changed by ACPI to D3hot
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1d.0: System wakeup enabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D2
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1a.7: System wakeup enabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1a.7: power state changed by ACPI to D3hot
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.2: System wakeup enabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.2: power state changed by ACPI to D2
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.0: System wakeup enabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.0: power state changed by ACPI to D2
Mär 13 21:38:28 Deus-Atratus kernel: PM: noirq suspend of devices complete after 119.814 msecs
Mär 13 21:38:28 Deus-Atratus kernel: ACPI: Preparing to enter system sleep state S3
Mär 13 21:38:28 Deus-Atratus kernel: PM: Saving platform NVS memory
Mär 13 21:38:28 Deus-Atratus kernel: Disabling non-boot CPUs ...
Mär 13 21:38:28 Deus-Atratus kernel: smpboot: CPU 1 is now offline
Mär 13 21:38:28 Deus-Atratus kernel: ACPI: Low-level resume complete
Mär 13 21:38:28 Deus-Atratus kernel: PM: Restoring platform NVS memory
Mär 13 21:38:28 Deus-Atratus kernel: Enabling non-boot CPUs ...
Mär 13 21:38:28 Deus-Atratus kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Mär 13 21:38:28 Deus-Atratus kernel: Disabled fast string operations
Mär 13 21:38:28 Deus-Atratus kernel: CPU1 is up
Mär 13 21:38:28 Deus-Atratus kernel: ACPI: Waking up from system sleep state S3
Mär 13 21:38:28 Deus-Atratus kernel: i915 0000:00:02.0: power state changed by ACPI to D0
Mär 13 21:38:28 Deus-Atratus kernel: thinkpad_acpi: fan watchdog: enabling fan
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.0: System wakeup disabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.2: power state changed by ACPI to D0
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.2: System wakeup disabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1a.7: power state changed by ACPI to D0
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1a.7: System wakeup disabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1d.0: System wakeup disabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1d.7: power state changed by ACPI to D0
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1d.7: System wakeup disabled by ACPI
Mär 13 21:38:28 Deus-Atratus kernel: PM: noirq resume of devices complete after 230.994 msecs
Mär 13 21:38:28 Deus-Atratus kernel: PM: early resume of devices complete after 0.411 msecs
Mär 13 21:38:28 Deus-Atratus kernel: i915 0000:00:02.0: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: mei_me 0000:00:03.0: irq 45 for MSI/MSI-X
Mär 13 21:38:28 Deus-Atratus kernel: e1000e 0000:00:19.0: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: e1000e 0000:00:19.0: irq 46 for MSI/MSI-X
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.0: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: usb usb1: root hub lost power or was reset
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.1: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: usb usb2: root hub lost power or was reset
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1a.2: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: usb usb3: root hub lost power or was reset
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1a.7: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: snd_hda_intel 0000:00:1b.0: irq 48 for MSI/MSI-X
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1d.0: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: usb usb4: root hub lost power or was reset
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1d.1: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: usb usb5: root hub lost power or was reset
Mär 13 21:38:28 Deus-Atratus kernel: uhci_hcd 0000:00:1d.2: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: usb usb6: root hub lost power or was reset
Mär 13 21:38:28 Deus-Atratus kernel: ehci-pci 0000:00:1d.7: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: pci 0000:00:1e.0: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: ahci 0000:00:1f.2: setting latency timer to 64
Mär 13 21:38:28 Deus-Atratus kernel: sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it.
Mär 13 21:38:28 Deus-Atratus kernel: sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it.
Mär 13 21:38:28 Deus-Atratus kernel: ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mär 13 21:38:28 Deus-Atratus kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mär 13 21:38:28 Deus-Atratus kernel: ata2.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mär 13 21:38:28 Deus-Atratus kernel: ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mär 13 21:38:28 Deus-Atratus kernel: ata2.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mär 13 21:38:28 Deus-Atratus kernel: ata2.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mär 13 21:38:28 Deus-Atratus kernel: ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mär 13 21:38:28 Deus-Atratus kernel: ata2.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mär 13 21:38:28 Deus-Atratus kernel: ata2.00: configured for UDMA/133
Mär 13 21:38:28 Deus-Atratus kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mär 13 21:38:28 Deus-Atratus kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mär 13 21:38:28 Deus-Atratus kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mär 13 21:38:28 Deus-Atratus kernel: sd 1:0:0:0: [sdb] Starting disk
Mär 13 21:38:28 Deus-Atratus kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Mär 13 21:38:28 Deus-Atratus kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Mär 13 21:38:28 Deus-Atratus kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Mär 13 21:38:28 Deus-Atratus kernel: ata1.00: configured for UDMA/133
Mär 13 21:38:28 Deus-Atratus kernel: sd 0:0:0:0: [sda] Starting disk
Mär 13 21:38:28 Deus-Atratus kernel: firewire_core 0000:15:00.1: rediscovered device fw0
Mär 13 21:38:28 Deus-Atratus kernel: hdaps: initial mode latch is 0x05
Mär 13 21:38:28 Deus-Atratus kernel: hdaps: setting ec_rate=250, filter_order=2
Mär 13 21:38:28 Deus-Atratus kernel: PM: resume of devices complete after 769.402 msecs
Mär 13 21:38:28 Deus-Atratus kernel: PM: Finishing wakeup.
Mär 13 21:38:28 Deus-Atratus kernel: Restarting tasks ... done.
Mär 13 21:38:28 Deus-Atratus kernel: video LNXVIDEO:00: Restoring backlight state
Mär 13 21:38:28 Deus-Atratus kernel: e1000e 0000:00:19.0: irq 46 for MSI/MSI-X
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (enp0s25): preparing device.
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (enp0s25): deactivating device (reason 'managed') [2]
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> NetworkManager state is now DISCONNECTED
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): bringing up device.
Mär 13 21:38:28 Deus-Atratus kernel: e1000e 0000:00:19.0: irq 46 for MSI/MSI-X
Mär 13 21:38:28 Deus-Atratus kernel: IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
Mär 13 21:38:28 Deus-Atratus kernel: iwlwifi 0000:03:00.0: L1 Disabled; Enabling L0S
Mär 13 21:38:28 Deus-Atratus kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): preparing device.
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): deactivating device (reason 'managed') [2]
Mär 13 21:38:28 Deus-Atratus kernel: IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (wlp3s0) supports 5 scan SSIDs
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: starting -> ready
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <warn> Trying to remove a non-existant call id.
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: ready -> disconnected
Mär 13 21:38:28 Deus-Atratus NetworkManager[352]: <info> (wlp3s0) supports 5 scan SSIDs
Mär 13 21:38:30 Deus-Atratus systemd[1]: Stopped TLP suspend/resume.
Mär 13 21:38:30 Deus-Atratus cinnamon-screensaver-dialog[10820]: pam_tally(cinnamon-screensaver:auth): Couldn't create /var/log/faillog
Mär 13 21:38:30 Deus-Atratus cinnamon-screensaver-dialog[10820]: pam_tally(cinnamon-screensaver:auth): Couldn't stat /var/log/faillog
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Auto-activating connection 'Automatisch MoesTavern'.
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) starting connection 'Automatisch MoesTavern'
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> NetworkManager state is now CONNECTING
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 1 of 5 (Device Prepare) scheduled...
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 1 of 5 (Device Prepare) started...
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 2 of 5 (Device Configure) scheduled...
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 1 of 5 (Device Prepare) complete.
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 2 of 5 (Device Configure) starting...
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: prepare -> config (reason 'none') [40 50 0]
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0/wireless): access point 'Automatisch MoesTavern' has security, but secrets are required.
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: config -> need-auth (reason 'none') [50 60 0]
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 2 of 5 (Device Configure) complete.
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: disconnected -> inactive
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 1 of 5 (Device Prepare) scheduled...
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 1 of 5 (Device Prepare) started...
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 2 of 5 (Device Configure) scheduled...
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 1 of 5 (Device Prepare) complete.
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 2 of 5 (Device Configure) starting...
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: prepare -> config (reason 'none') [40 50 0]
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0/wireless): connection 'Automatisch MoesTavern' has security, and secrets exist.  No new secrets needed.
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Config: added 'ssid' value 'MoesTavern'
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Config: added 'scan_ssid' value '1'
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Config: added 'auth_alg' value 'OPEN'
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Config: added 'psk' value '<omitted>'
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 2 of 5 (Device Configure) complete.
Mär 13 21:38:32 Deus-Atratus NetworkManager[352]: <info> Config: set interface ap_scan to 1
Mär 13 21:38:32 Deus-Atratus cinnamon-screensaver-dialog[10820]: gkr-pam: unlocked login keyring
Mär 13 21:38:32 Deus-Atratus cinnamon-screensaver-dialog[10820]: pam_tally(cinnamon-screensaver:setcred): Couldn't create /var/log/faillog
Mär 13 21:38:32 Deus-Atratus cinnamon-screensaver-dialog[10820]: pam_tally(cinnamon-screensaver:setcred): Couldn't create /var/log/faillog
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: authenticate with 00:1f:3f:1d:db:cf
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: send auth to 00:1f:3f:1d:db:cf (try 1/3)
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: inactive -> authenticating
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: authenticating -> associating
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: authenticated
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: associate with 00:1f:3f:1d:db:cf (try 1/3)
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: RX AssocResp from 00:1f:3f:1d:db:cf (capab=0x431 status=0 aid=3)
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: associated
Mär 13 21:38:35 Deus-Atratus kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: associating -> associated
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: associated -> 4-way handshake
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: deauthenticating from 00:1f:3f:1d:db:cf by local choice (reason=2)
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: 4-way handshake -> authenticating
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <warn> Connection disconnected (reason -2)
Mär 13 21:38:35 Deus-Atratus kernel: cfg80211: Calling CRDA to update world regulatory domain
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: authenticate with 00:1f:3f:1d:db:cf
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: send auth to 00:1f:3f:1d:db:cf (try 1/3)
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: authenticated
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: authenticating -> associating
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: associate with 00:1f:3f:1d:db:cf (try 1/3)
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: RX AssocResp from 00:1f:3f:1d:db:cf (capab=0x431 status=0 aid=3)
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: associating -> 4-way handshake
Mär 13 21:38:35 Deus-Atratus kernel: wlp3s0: associated
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): supplicant interface state: 4-way handshake -> completed
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'MoesTavern'.
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 3 of 5 (IP Configure Start) scheduled.
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 3 of 5 (IP Configure Start) started...
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: config -> ip-config (reason 'none') [50 70 0]
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> dhclient started with pid 10852
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Beginning IP6 addrconf.
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 3 of 5 (IP Configure Start) complete.
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: Internet Systems Consortium DHCP Client 4.2.5-P1
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: Copyright 2004-2013 Internet Systems Consortium.
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: All rights reserved.
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: For info, please visit https://www.isc.org/software/dhcp/
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): DHCPv4 state changed nbi -> preinit
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: Listening on LPF/wlp3s0/00:21:5d:4e:c8:2c
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: Sending on   LPF/wlp3s0/00:21:5d:4e:c8:2c
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: Sending on   Socket/fallback
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: DHCPREQUEST on wlp3s0 to 255.255.255.255 port 67
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: DHCPACK from 192.168.178.254
Mär 13 21:38:35 Deus-Atratus dhclient[10852]: bound to 192.168.178.7 -- renewal in 342274 seconds.
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): DHCPv4 state changed preinit -> reboot
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info>   address 192.168.178.7
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info>   prefix 24 (255.255.255.0)
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info>   gateway 192.168.178.254
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info>   nameserver '192.168.178.254'
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info>   domain name 'fritz.box'
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Mär 13 21:38:35 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 5 of 5 (IPv4 Commit) started...
Mär 13 21:38:36 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
Mär 13 21:38:36 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 5 of 5 (IPv4 Commit) complete.
Mär 13 21:38:36 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): device state change: secondaries -> activated (reason 'none') [90 100 0]
Mär 13 21:38:36 Deus-Atratus NetworkManager[352]: <info> NetworkManager state is now CONNECTED_GLOBAL
Mär 13 21:38:36 Deus-Atratus NetworkManager[352]: <info> Policy set 'Automatisch MoesTavern' (wlp3s0) as default for IPv4 routing and DNS.
Mär 13 21:38:36 Deus-Atratus NetworkManager[352]: <info> Writing DNS information to /usr/bin/resolvconf
Mär 13 21:38:36 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) successful, device activated.
Mär 13 21:38:36 Deus-Atratus dbus[353]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Mär 13 21:38:36 Deus-Atratus systemd[1]: Cannot add dependency job for unit cups.socket, ignoring: Unit cups.socket failed to load: No such file or directory.
Mär 13 21:38:36 Deus-Atratus systemd[1]: Starting Network Manager Script Dispatcher Service...
Mär 13 21:38:36 Deus-Atratus dbus[353]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mär 13 21:38:36 Deus-Atratus systemd[1]: Started Network Manager Script Dispatcher Service.
Mär 13 21:38:36 Deus-Atratus systemd[1]: Cannot add dependency job for unit cups.socket, ignoring: Unit cups.socket failed to load: No such file or directory.
Mär 13 21:38:36 Deus-Atratus systemd[1]: Starting Network Time Service...
Mär 13 21:38:36 Deus-Atratus ntpd[10890]: ntpd 4.2.6p5@1.2349-o Sat Jan 11 16:46:32 UTC 2014 (1)
Mär 13 21:38:36 Deus-Atratus systemd[1]: PID file /run/ntpd.pid not readable (yet?) after start.
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: proto: precision = 0.768 usec
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: ntp_io: estimated max descriptors: 1024, initial socket boundary: 16
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: Listen and drop on 1 v6wildcard :: UDP 123
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: Listen normally on 2 lo 127.0.0.1 UDP 123
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: Listen normally on 3 wlp3s0 192.168.178.7 UDP 123
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: Listen normally on 4 lo ::1 UDP 123
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: Listen normally on 5 wlp3s0 fe80::221:5dff:fe4e:c82c UDP 123
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: peers refreshed
Mär 13 21:38:36 Deus-Atratus ntpd[10891]: Listening on routing socket on fd #22 for interface updates
Mär 13 21:38:36 Deus-Atratus systemd[1]: Started Network Time Service.
Mär 13 21:38:55 Deus-Atratus NetworkManager[352]: <info> (wlp3s0): IP6 addrconf timed out or failed.
Mär 13 21:38:55 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Mär 13 21:38:55 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 4 of 5 (IPv6 Configure Timeout) started...
Mär 13 21:38:55 Deus-Atratus NetworkManager[352]: <info> Activation (wlp3s0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Mär 13 21:39:16 Deus-Atratus kernel: [drm] HPD interrupt storm detected on connector DP-3: switching from hotplug detection to polling

@progandy
Sorry, I don't get what that quirks stuff do. I'm not that Linux professional, but I want to learn as much as I can. Maybe you can describe me with a few words what it does?

frank604 · 2014-03-13 21:42:54

I am not a knowledgable guy but

Mär 13 21:39:16 Deus-Atratus kernel: [drm] HPD interrupt storm detected on connector DP-3: switching from hotplug detection to polling

indicates there is a storming interrupt happening so things slow down from this. Please do try the arch kernel as there may be a fix included since 3.10.

Maybe someone with better kernel knowledge can expand about 'interrupt storms'.

Similar to Progandy's advice, try suspending through systemd. I noticed you have TLP controlling suspend. Just another trial and error test. PS. I really do enjoy the ck patched kernel with BFS (brain fuck scheduler). Not because of just the name. Graysky maintains the repo-ck where it is already compiled. Just need to enable the repo and pacman install. Play around a bit and report your findings. Good luck!

Last edited by frank604 (2014-03-13 21:45:17)

progandy · 2014-03-13 23:19:22

KreativeKrise wrote:

@progandy
Sorry, I don't get what that quirks stuff do. I'm not that Linux professional, but I want to learn as much as I can. Maybe you can describe me with a few words what it does?

The quirks are workarounds for strange behaviour of certain hardware. pm-utils comes with a database of such quirks and detects automatically whether they have to be used. It shoudn't be necessary anymore with systemd, but there might be problems with old hardware. So just try to suspend with pure systemd (without TLP). If that doesn't work, install pm-utils and suspend with "pm-suspend". Only if that works you should worry about the quirks.

KreativeKrise · 2014-03-18 19:55:33

Thank you for your help and descriptions! I learned a little bit more and everything works now.

I did a little more research about the "HDP interrupt storm" and it seems that there is a bug with the intel gpu driver in kernel 3.10, which causes a freeze if it's detected.
So I did an update to version 3.12 and now everything is fine!

frank604 · 2014-03-19 02:25:40

Hey, glad to hear you find a fix!! Please remember to edit your first post and add [SOLVED] to the title.

KreativeKrise · 2014-03-19 21:10:31

frank604 wrote:

Hey, glad to hear you find a fix!! Please remember to edit your first post and add [SOLVED] to the title.

Ah okay. I didn't know that I have to do it. Thanks for the hint!

Arch Linux

#1 2014-03-11 20:40:31

[SOLVED] System freezes after resume from standby

#2 2014-03-11 23:37:19

Re: [SOLVED] System freezes after resume from standby

#3 2014-03-12 02:11:45

Re: [SOLVED] System freezes after resume from standby

#4 2014-03-13 20:51:21

Re: [SOLVED] System freezes after resume from standby

#5 2014-03-13 21:42:54

Re: [SOLVED] System freezes after resume from standby

#6 2014-03-13 23:19:22

Re: [SOLVED] System freezes after resume from standby

#7 2014-03-18 19:55:33

Re: [SOLVED] System freezes after resume from standby

#8 2014-03-19 02:25:40

Re: [SOLVED] System freezes after resume from standby

#9 2014-03-19 21:10:31

Re: [SOLVED] System freezes after resume from standby

Board footer