Hibernation fails on t430s

philipW · 2018-12-08 19:29:33

Hello,
When i try to hibernate my laptop it instantly wakes up again.
I have a 4gb swap partition and my swap and root is on a luks partition.

cat /etc/default/grub 
# GRUB boot loader configuration

GRUB_DEFAULT="0"
GRUB_TIMEOUT="5"
GRUB_DISTRIBUTOR="Arch"
GRUB_CMDLINE_LINUX_DEFAULT="resume=/dev/ssd/swap acpi_sleep=nonvs"
GRUB_CMDLINE_LINUX="quiet cryptdevice=UUID=9395d17c-9467-44a8-aa35-9d62c223ce35:cryptlvm root=/dev/ssd/root"

# Preload both GPT and MBR modules so that they are not missed
GRUB_PRELOAD_MODULES="lvm part_gpt part_msdos"

# Uncomment to enable booting from LUKS encrypted devices
#GRUB_ENABLE_CRYPTODISK="y"

# Uncomment to enable Hidden Menu, and optionally hide the timeout count
#GRUB_HIDDEN_TIMEOUT="5"
#GRUB_HIDDEN_TIMEOUT_QUIET="true"

# Uncomment to use basic console
GRUB_TERMINAL_INPUT="console"

# Uncomment to disable graphical terminal
#GRUB_TERMINAL_OUTPUT="console"

# The resolution used on graphical terminal
# note that you can use only modes which your graphic card supports via VBE
# you can see them in real GRUB with the command `vbeinfo'
#GRUB_GFXMODE="auto"

# Uncomment to allow the kernel use the same resolution used by grub
GRUB_GFXPAYLOAD_LINUX="keep"

# Uncomment if you want GRUB to pass to the Linux kernel the old parameter
# format "root=/dev/xxx" instead of "root=/dev/disk/by-uuid/xxx"
#GRUB_DISABLE_LINUX_UUID="true"

# Uncomment to disable generation of recovery mode menu entries
GRUB_DISABLE_RECOVERY="true"

# Uncomment and set to the desired menu colors.  Used by normal and wallpaper
# modes only.  Entries specified as foreground/background.
export GRUB_COLOR_NORMAL="black/black"
export GRUB_COLOR_HIGHLIGHT="white/black"

# Uncomment one of them for the gfx desired, a image background or a gfxtheme
GRUB_BACKGROUND="/boot/gnu.jpg"
#GRUB_THEME="/path/to/gfxtheme"

# Uncomment to get a beep at GRUB start
#GRUB_INIT_TUNE="480 440 1"

# Uncomment to make GRUB remember the last selection. This requires to
# set 'GRUB_DEFAULT=saved' above.
#GRUB_SAVEDEFAULT="true"

GRUB_DISABLE_OS_PROBER="false"

sda              8:0    0 223.6G  0 disk  
├─sda1           8:1    0   100M  0 part  
├─sda2           8:2    0   400M  0 part  /boot
└─sda3           8:3    0 223.1G  0 part  
  └─cryptlvm   254:0    0 223.1G  0 crypt 
    ├─ssd-swap 254:1    0     4G  0 lvm   [SWAP]
    └─ssd-root 254:2    0 219.1G  0 lvm   /

This is what journalctl tells me:

Dec 08 20:10:47 memepad sudo[17424]:   philip : TTY=pts/0 ; PWD=/home/philip ; USER=root ; COMMAND=/usr/lib/systemd/systemd-sleep hibernate
Dec 08 20:10:47 memepad sudo[17424]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 08 20:10:47 memepad kernel: PM: Image not found (code -22)
Dec 08 20:10:47 memepad kernel: PM: hibernation entry
Dec 08 20:10:47 memepad kernel: PM: Syncing filesystems ... 
Dec 08 20:10:52 memepad kernel: PM: done.
Dec 08 20:10:52 memepad kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Dec 08 20:10:52 memepad kernel: OOM killer disabled.
Dec 08 20:10:52 memepad kernel: PM: Marking nosave pages: [mem 0x00000000-0x00000fff]
Dec 08 20:10:52 memepad kernel: PM: Marking nosave pages: [mem 0x0009d000-0x000fffff]
Dec 08 20:10:52 memepad kernel: PM: Marking nosave pages: [mem 0x20000000-0x201fffff]
Dec 08 20:10:52 memepad kernel: PM: Marking nosave pages: [mem 0x40004000-0x40004fff]
Dec 08 20:10:52 memepad kernel: PM: Marking nosave pages: [mem 0xced30000-0xffffffff]
Dec 08 20:10:52 memepad kernel: PM: Basic memory bitmaps created
Dec 08 20:10:52 memepad kernel: PM: Preallocating image memory... done (allocated 750268 pages)
Dec 08 20:10:52 memepad kernel: PM: Allocated 3001072 kbytes in 0.47 seconds (6385.25 MB/s)
Dec 08 20:10:52 memepad kernel: Freezing remaining freezable tasks ... (elapsed 0.003 seconds) done.
Dec 08 20:10:52 memepad kernel: Suspending console(s) (use no_console_suspend to debug)
Dec 08 20:10:52 memepad kernel: wlp3s0: deauthenticating from ac:22:05:f9:0e:6b by local choice (Reason: 3=DEAUTH_LEAVING)
Dec 08 20:10:52 memepad kernel: ACPI: EC: interrupt blocked
Dec 08 20:10:52 memepad kernel: ACPI: Preparing to enter system sleep state S4
Dec 08 20:10:52 memepad kernel: ACPI: EC: event blocked
Dec 08 20:10:52 memepad kernel: ACPI: EC: EC stopped
Dec 08 20:10:52 memepad kernel: PM: Saving platform NVS memory
Dec 08 20:10:52 memepad kernel: Disabling non-boot CPUs ...
Dec 08 20:10:52 memepad kernel: smpboot: CPU 1 is now offline
Dec 08 20:10:52 memepad kernel: smpboot: CPU 2 is now offline
Dec 08 20:10:52 memepad kernel: smpboot: CPU 3 is now offline
Dec 08 20:10:52 memepad kernel: PM: Creating hibernation image:
Dec 08 20:10:52 memepad kernel: PM: Need to copy 749041 pages
Dec 08 20:10:52 memepad kernel: PM: Normal pages needed: 749041 + 1024, available pages: 1270345
Dec 08 20:10:52 memepad kernel: PM: Hibernation image created (749041 pages copied)
Dec 08 20:10:52 memepad kernel: PM: Restoring platform NVS memory
Dec 08 20:10:52 memepad kernel: ACPI: EC: EC started
Dec 08 20:10:52 memepad kernel: Enabling non-boot CPUs ...
Dec 08 20:10:52 memepad kernel: x86: Booting SMP configuration:
Dec 08 20:10:52 memepad kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Dec 08 20:10:52 memepad kernel:  cache: parent cpu1 should not be sleeping
Dec 08 20:10:52 memepad kernel: CPU1 is up
Dec 08 20:10:52 memepad kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Dec 08 20:10:52 memepad kernel:  cache: parent cpu2 should not be sleeping
Dec 08 20:10:52 memepad kernel: CPU2 is up
Dec 08 20:10:52 memepad kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Dec 08 20:10:52 memepad kernel:  cache: parent cpu3 should not be sleeping
Dec 08 20:10:52 memepad kernel: CPU3 is up
Dec 08 20:10:52 memepad kernel: ACPI: Waking up from system sleep state S4
Dec 08 20:10:52 memepad kernel: ACPI: EC: interrupt unblocked
Dec 08 20:10:52 memepad kernel: ACPI: EC: event unblocked
Dec 08 20:10:52 memepad kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
Dec 08 20:10:52 memepad kernel: tpm tpm0: TPM is disabled/deactivated (0x6)
Dec 08 20:10:52 memepad kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
Dec 08 20:10:52 memepad kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Dec 08 20:10:52 memepad kernel: ata5: SATA link down (SStatus 0 SControl 300)
Dec 08 20:10:52 memepad kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Dec 08 20:10:52 memepad kernel: ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec 08 20:10:52 memepad kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:10:52 memepad kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:10:52 memepad kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:10:52 memepad kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:10:52 memepad kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:10:52 memepad kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:10:52 memepad kernel: ata1.00: configured for UDMA/133
Dec 08 20:10:52 memepad kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:10:52 memepad kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:10:52 memepad kernel: ata3.00: configured for UDMA/133
Dec 08 20:10:52 memepad kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Dec 08 20:10:52 memepad kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Dec 08 20:10:52 memepad kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Dec 08 20:10:52 memepad kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Dec 08 20:10:52 memepad kernel: ata2.00: configured for UDMA/133
Dec 08 20:10:52 memepad kernel: PM: Using 3 thread(s) for compression
Dec 08 20:10:52 memepad kernel: PM: Compressing and saving image data (750504 pages)...
Dec 08 20:10:52 memepad kernel: PM: Image saving progress:   0%
Dec 08 20:10:52 memepad kernel: /dev/vmmon[17425]: HostIFReadUptimeWork: detected settimeofday: fixed uptimeBase old 18445199780667080225 new 18445199780665810030 attempts 1
Dec 08 20:10:52 memepad kernel: PM: Image saving progress:  10%
Dec 08 20:10:52 memepad kernel: PM: Image saving progress:  20%
Dec 08 20:10:52 memepad kernel: PM: Image saving progress:  30%
Dec 08 20:10:52 memepad kernel: PM: Wrote 3002016 kbytes in 1.88 seconds (1596.81 MB/s)
Dec 08 20:10:52 memepad kernel: PM: Basic memory bitmaps freed
Dec 08 20:10:52 memepad kernel: OOM killer enabled.
Dec 08 20:10:52 memepad kernel: Restarting tasks ... done.
Dec 08 20:10:52 memepad vmnetBridge[665]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00001003
Dec 08 20:10:52 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=ac:22:05:f9:0e:6b reason=3 locally_generated=1
Dec 08 20:10:52 memepad NetworkManager[580]: <warn>  [1544296252.5815] sup-iface[0x562a509e4230,wlp3s0]: connection disconnected (reason -3)
Dec 08 20:10:52 memepad kernel: PM: hibernation exit
Dec 08 20:10:52 memepad vmnetBridge[665]: Removing interface wlp3s0 index:2
Dec 08 20:10:52 memepad NetworkManager[580]: <info>  [1544296252.5880] device (wlp3s0): supplicant interface state: completed -> disconnected
Dec 08 20:10:52 memepad vmnet-natd[1613]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00001003
Dec 08 20:10:52 memepad vmnetBridge[665]: Stopped bridge wlp3s0 to virtual network 0.
Dec 08 20:10:52 memepad sudo[17424]: pam_unix(sudo:session): session closed for user root
Dec 08 20:10:52 memepad systemd[1]: Reloading Laptop Mode Tools.
Dec 08 20:10:52 memepad NetworkManager[580]: <info>  [1544296252.6856] device (wlp3s0): supplicant interface state: disconnected -> scanning
Dec 08 20:10:52 memepad wpa_supplicant[1080]: wlp3s0: SME: Trying to authenticate with ac:22:05:f9:0e:1c (SSID='WuTangLAN' freq=2472 MHz)
Dec 08 20:10:52 memepad kernel: wlp3s0: authenticate with ac:22:05:f9:0e:1c
Dec 08 20:10:52 memepad kernel: wlp3s0: capabilities/regulatory prevented using AP HT/VHT configuration, downgraded
Dec 08 20:10:52 memepad kernel: wlp3s0: send auth to ac:22:05:f9:0e:1c (try 1/3)
Dec 08 20:10:52 memepad laptop_mode[17458]: Laptop mode
Dec 08 20:10:52 memepad laptop_mode[17458]: enabled, active [unchanged]
Dec 08 20:10:52 memepad NetworkManager[580]: <info>  [1544296252.7392] device (wlp3s0): supplicant interface state: scanning -> authenticating
Dec 08 20:10:52 memepad wpa_supplicant[1080]: wlp3s0: Trying to associate with ac:22:05:f9:0e:1c (SSID='WuTangLAN' freq=2472 MHz)
Dec 08 20:10:52 memepad kernel: wlp3s0: authenticated
Dec 08 20:10:52 memepad kernel: wlp3s0: associating with AP with corrupt probe response
Dec 08 20:10:52 memepad NetworkManager[580]: <info>  [1544296252.7477] device (wlp3s0): supplicant interface state: authenticating -> associating
Dec 08 20:10:52 memepad kernel: wlp3s0: associate with ac:22:05:f9:0e:1c (try 1/3)
Dec 08 20:10:52 memepad kernel: userif-3: sent link down event.
Dec 08 20:10:52 memepad kernel: userif-3: sent link up event.
Dec 08 20:10:52 memepad kernel: wlp3s0: RX AssocResp from ac:22:05:f9:0e:1c (capab=0x411 status=0 aid=3)
Dec 08 20:10:52 memepad wpa_supplicant[1080]: wlp3s0: Associated with ac:22:05:f9:0e:1c
Dec 08 20:10:52 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Dec 08 20:10:52 memepad vmnetBridge[665]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011003
Dec 08 20:10:52 memepad kernel: wlp3s0: associated
Dec 08 20:10:52 memepad NetworkManager[580]: <info>  [1544296252.8218] device (wlp3s0): supplicant interface state: associating -> associated
Dec 08 20:10:52 memepad vmnet-natd[1613]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011003
Dec 08 20:10:53 memepad NetworkManager[580]: <info>  [1544296253.2063] device (wlp3s0): supplicant interface state: associated -> 4-way handshake
Dec 08 20:10:53 memepad wpa_supplicant[1080]: wlp3s0: WPA: Key negotiation completed with ac:22:05:f9:0e:1c [PTK=CCMP GTK=TKIP]
Dec 08 20:10:53 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to ac:22:05:f9:0e:1c completed [id=0 id_str=]
Dec 08 20:10:53 memepad vmnet-natd[1613]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011043
Dec 08 20:10:53 memepad vmnetBridge[665]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011043
Dec 08 20:10:53 memepad vmnetBridge[665]: Adding interface wlp3s0 index:2
Dec 08 20:10:53 memepad vmnetBridge[665]: Started bridge wlp3s0 to virtual network 0.
Dec 08 20:10:53 memepad NetworkManager[580]: <info>  [1544296253.2417] device (wlp3s0): supplicant interface state: 4-way handshake -> completed
Dec 08 20:10:53 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-41 noise=9999 txrate=1000
Dec 08 20:10:53 memepad kernel: userif-3: sent link down event.
Dec 08 20:10:53 memepad kernel: userif-3: sent link up event.
Dec 08 20:10:59 memepad sudo[17537]:   philip : TTY=pts/0 ; PWD=/home/philip ; USER=root ; COMMAND=/usr/lib/systemd/systemd-sleep hibernate
Dec 08 20:10:59 memepad sudo[17537]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 08 20:10:59 memepad kernel: PM: Image not found (code -22)
Dec 08 20:10:59 memepad kernel: PM: hibernation entry
Dec 08 20:10:59 memepad kernel: PM: Syncing filesystems ... 
Dec 08 20:11:04 memepad kernel: PM: done.
Dec 08 20:11:04 memepad kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Dec 08 20:11:04 memepad kernel: OOM killer disabled.
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0x00000000-0x00000fff]
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0x0009d000-0x000fffff]
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0x20000000-0x201fffff]
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0x40004000-0x40004fff]
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0xced30000-0xffffffff]
Dec 08 20:11:04 memepad kernel: PM: Basic memory bitmaps created
Dec 08 20:11:04 memepad kernel: PM: Preallocating image memory... done (allocated 629518 pages)
Dec 08 20:11:04 memepad kernel: PM: Allocated 2518072 kbytes in 0.21 seconds (11990.81 MB/s)
Dec 08 20:11:04 memepad kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Dec 08 20:11:04 memepad kernel: Suspending console(s) (use no_console_suspend to debug)
Dec 08 20:11:04 memepad kernel: wlp3s0: deauthenticating from ac:22:05:f9:0e:1c by local choice (Reason: 3=DEAUTH_LEAVING)
Dec 08 20:11:04 memepad kernel: ACPI: EC: interrupt blocked
Dec 08 20:11:04 memepad kernel: ACPI: Preparing to enter system sleep state S4
Dec 08 20:11:04 memepad kernel: ACPI: EC: event blocked
Dec 08 20:11:04 memepad kernel: ACPI: EC: EC stopped
Dec 08 20:11:04 memepad kernel: PM: Saving platform NVS memory
Dec 08 20:11:04 memepad kernel: Disabling non-boot CPUs ...
Dec 08 20:11:04 memepad kernel: smpboot: CPU 1 is now offline
Dec 08 20:11:04 memepad kernel: smpboot: CPU 2 is now offline
Dec 08 20:11:04 memepad kernel: smpboot: CPU 3 is now offline
Dec 08 20:11:04 memepad kernel: PM: Creating hibernation image:
Dec 08 20:11:04 memepad kernel: PM: Need to copy 627583 pages
Dec 08 20:11:04 memepad kernel: PM: Normal pages needed: 627583 + 1024, available pages: 1391798
Dec 08 20:11:04 memepad kernel: PM: Hibernation image created (627583 pages copied)
Dec 08 20:11:04 memepad kernel: PM: Restoring platform NVS memory
Dec 08 20:11:04 memepad kernel: ACPI: EC: EC started
Dec 08 20:11:04 memepad kernel: Enabling non-boot CPUs ...
Dec 08 20:11:04 memepad kernel: x86: Booting SMP configuration:
Dec 08 20:11:04 memepad kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Dec 08 20:11:04 memepad kernel:  cache: parent cpu1 should not be sleeping
Dec 08 20:11:04 memepad kernel: CPU1 is up
Dec 08 20:11:04 memepad kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Dec 08 20:11:04 memepad kernel:  cache: parent cpu2 should not be sleeping
Dec 08 20:11:04 memepad kernel: CPU2 is up
Dec 08 20:11:04 memepad kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Dec 08 20:11:04 memepad kernel:  cache: parent cpu3 should not be sleeping
Dec 08 20:11:04 memepad kernel: CPU3 is up
Dec 08 20:11:04 memepad kernel: ACPI: Waking up from system sleep state S4
Dec 08 20:11:04 memepad kernel: ACPI: EC: interrupt unblocked
Dec 08 20:11:04 memepad kernel: ACPI: EC: event unblocked
Dec 08 20:11:04 memepad kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
Dec 08 20:11:04 memepad kernel: tpm tpm0: TPM is disabled/deactivated (0x6)
Dec 08 20:11:04 memepad kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
Dec 08 20:11:04 memepad kernel: ata5: SATA link down (SStatus 0 SControl 300)
Dec 08 20:11:04 memepad kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Dec 08 20:11:04 memepad kernel: ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec 08 20:11:04 memepad kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Dec 08 20:11:04 memepad kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:11:04 memepad kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:11:04 memepad kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:11:04 memepad kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:11:04 memepad kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:11:04 memepad kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:11:04 memepad kernel: ata1.00: configured for UDMA/133
Dec 08 20:11:04 memepad kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:11:04 memepad kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:11:04 memepad kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Dec 08 20:11:04 memepad kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Dec 08 20:10:52 memepad kernel: wlp3s0: associated
Dec 08 20:10:52 memepad NetworkManager[580]: <info>  [1544296252.8218] device (wlp3s0): supplicant interface state: associating -> associated
Dec 08 20:10:52 memepad vmnet-natd[1613]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011003
Dec 08 20:10:53 memepad NetworkManager[580]: <info>  [1544296253.2063] device (wlp3s0): supplicant interface state: associated -> 4-way handshake
Dec 08 20:10:53 memepad wpa_supplicant[1080]: wlp3s0: WPA: Key negotiation completed with ac:22:05:f9:0e:1c [PTK=CCMP GTK=TKIP]
Dec 08 20:10:53 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to ac:22:05:f9:0e:1c completed [id=0 id_str=]
Dec 08 20:10:53 memepad vmnet-natd[1613]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011043
Dec 08 20:10:53 memepad vmnetBridge[665]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011043
Dec 08 20:10:53 memepad vmnetBridge[665]: Adding interface wlp3s0 index:2
Dec 08 20:10:53 memepad vmnetBridge[665]: Started bridge wlp3s0 to virtual network 0.
Dec 08 20:10:53 memepad NetworkManager[580]: <info>  [1544296253.2417] device (wlp3s0): supplicant interface state: 4-way handshake -> completed
Dec 08 20:10:53 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-41 noise=9999 txrate=1000
Dec 08 20:10:53 memepad kernel: userif-3: sent link down event.
Dec 08 20:10:53 memepad kernel: userif-3: sent link up event.
Dec 08 20:10:59 memepad sudo[17537]:   philip : TTY=pts/0 ; PWD=/home/philip ; USER=root ; COMMAND=/usr/lib/systemd/systemd-sleep hibernate
Dec 08 20:10:59 memepad sudo[17537]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 08 20:10:59 memepad kernel: PM: Image not found (code -22)
Dec 08 20:10:59 memepad kernel: PM: hibernation entry
Dec 08 20:10:59 memepad kernel: PM: Syncing filesystems ... 
Dec 08 20:11:04 memepad kernel: PM: done.
Dec 08 20:11:04 memepad kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Dec 08 20:11:04 memepad kernel: OOM killer disabled.
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0x00000000-0x00000fff]
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0x0009d000-0x000fffff]
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0x20000000-0x201fffff]
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0x40004000-0x40004fff]
Dec 08 20:11:04 memepad kernel: PM: Marking nosave pages: [mem 0xced30000-0xffffffff]
Dec 08 20:11:04 memepad kernel: PM: Basic memory bitmaps created
Dec 08 20:11:04 memepad kernel: PM: Preallocating image memory... done (allocated 629518 pages)
Dec 08 20:11:04 memepad kernel: PM: Allocated 2518072 kbytes in 0.21 seconds (11990.81 MB/s)
Dec 08 20:11:04 memepad kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Dec 08 20:11:04 memepad kernel: Suspending console(s) (use no_console_suspend to debug)
Dec 08 20:11:04 memepad kernel: wlp3s0: deauthenticating from ac:22:05:f9:0e:1c by local choice (Reason: 3=DEAUTH_LEAVING)
Dec 08 20:11:04 memepad kernel: ACPI: EC: interrupt blocked
Dec 08 20:11:04 memepad kernel: ACPI: Preparing to enter system sleep state S4
Dec 08 20:11:04 memepad kernel: ACPI: EC: event blocked
Dec 08 20:11:04 memepad kernel: ACPI: EC: EC stopped
Dec 08 20:11:04 memepad kernel: PM: Saving platform NVS memory
Dec 08 20:11:04 memepad kernel: Disabling non-boot CPUs ...
Dec 08 20:11:04 memepad kernel: smpboot: CPU 1 is now offline
Dec 08 20:11:04 memepad kernel: smpboot: CPU 2 is now offline
Dec 08 20:11:04 memepad kernel: smpboot: CPU 3 is now offline
Dec 08 20:11:04 memepad kernel: PM: Creating hibernation image:
Dec 08 20:11:04 memepad kernel: PM: Need to copy 627583 pages
Dec 08 20:11:04 memepad kernel: PM: Normal pages needed: 627583 + 1024, available pages: 1391798
Dec 08 20:11:04 memepad kernel: PM: Hibernation image created (627583 pages copied)
Dec 08 20:11:04 memepad kernel: PM: Restoring platform NVS memory
Dec 08 20:11:04 memepad kernel: ACPI: EC: EC started
Dec 08 20:11:04 memepad kernel: Enabling non-boot CPUs ...
Dec 08 20:11:04 memepad kernel: x86: Booting SMP configuration:
Dec 08 20:11:04 memepad kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Dec 08 20:11:04 memepad kernel:  cache: parent cpu1 should not be sleeping
Dec 08 20:11:04 memepad kernel: CPU1 is up
Dec 08 20:11:04 memepad kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Dec 08 20:11:04 memepad kernel:  cache: parent cpu2 should not be sleeping
Dec 08 20:11:04 memepad kernel: CPU2 is up
Dec 08 20:11:04 memepad kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Dec 08 20:11:04 memepad kernel:  cache: parent cpu3 should not be sleeping
Dec 08 20:11:04 memepad kernel: CPU3 is up
Dec 08 20:11:04 memepad kernel: ACPI: Waking up from system sleep state S4
Dec 08 20:11:04 memepad kernel: ACPI: EC: interrupt unblocked
Dec 08 20:11:04 memepad kernel: ACPI: EC: event unblocked
Dec 08 20:11:04 memepad kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
Dec 08 20:11:04 memepad kernel: tpm tpm0: TPM is disabled/deactivated (0x6)
Dec 08 20:11:04 memepad kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
Dec 08 20:11:04 memepad kernel: ata5: SATA link down (SStatus 0 SControl 300)
Dec 08 20:11:04 memepad kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Dec 08 20:11:04 memepad kernel: ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Dec 08 20:11:04 memepad kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Dec 08 20:11:04 memepad kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:11:04 memepad kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:11:04 memepad kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:11:04 memepad kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:11:04 memepad kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:11:04 memepad kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:11:04 memepad kernel: ata1.00: configured for UDMA/133
Dec 08 20:11:04 memepad kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Dec 08 20:11:04 memepad kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Dec 08 20:11:04 memepad kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Dec 08 20:11:04 memepad kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Dec 08 20:11:04 memepad kernel: ata3.00: configured for UDMA/133
Dec 08 20:11:04 memepad kernel: ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded
Dec 08 20:11:04 memepad kernel: ata2.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded
Dec 08 20:11:04 memepad kernel: ata2.00: configured for UDMA/133
Dec 08 20:11:04 memepad kernel: PM: Using 3 thread(s) for compression
Dec 08 20:11:04 memepad kernel: PM: Compressing and saving image data (628809 pages)...
Dec 08 20:11:04 memepad kernel: PM: Image saving progress:   0%
Dec 08 20:11:04 memepad kernel: /dev/vmmon[17544]: HostIFReadUptimeWork: detected settimeofday: fixed uptimeBase old 18445199780665810030 new 18445199780663993075 attempts 1
Dec 08 20:11:04 memepad kernel: PM: Image saving progress:  10%
Dec 08 20:11:04 memepad kernel: PM: Image saving progress:  20%
Dec 08 20:11:04 memepad kernel: PM: Image saving progress:  30%
Dec 08 20:11:04 memepad kernel: PM: Image saving progress:  40%
Dec 08 20:11:04 memepad kernel: PM: Wrote 2515236 kbytes in 1.82 seconds (1381.99 MB/s)
Dec 08 20:11:04 memepad kernel: PM: Basic memory bitmaps freed
Dec 08 20:11:04 memepad kernel: OOM killer enabled.
Dec 08 20:11:04 memepad kernel: Restarting tasks ... done.
Dec 08 20:11:04 memepad kernel: PM: hibernation exit
Dec 08 20:11:04 memepad rtkit-daemon[9218]: The canary thread is apparently starving. Taking action.
Dec 08 20:11:04 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=ac:22:05:f9:0e:1c reason=3 locally_generated=1
Dec 08 20:11:04 memepad NetworkManager[580]: <warn>  [1544296264.6587] sup-iface[0x562a509e4230,wlp3s0]: connection disconnected (reason -3)
Dec 08 20:11:04 memepad rtkit-daemon[9218]: Demoting known real-time threads.
Dec 08 20:11:04 memepad NetworkManager[580]: <info>  [1544296264.6627] device (wlp3s0): supplicant interface state: completed -> disconnected
Dec 08 20:11:04 memepad vmnetBridge[665]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00001003
Dec 08 20:11:04 memepad vmnetBridge[665]: Removing interface wlp3s0 index:2
Dec 08 20:11:04 memepad rtkit-daemon[9218]: Successfully demoted thread 9458 of process 9200 (/usr/bin/pulseaudio).
Dec 08 20:11:04 memepad rtkit-daemon[9218]: Successfully demoted thread 9452 of process 9200 (/usr/bin/pulseaudio).
Dec 08 20:11:04 memepad rtkit-daemon[9218]: Successfully demoted thread 9200 of process 9200 (/usr/bin/pulseaudio).
Dec 08 20:11:04 memepad rtkit-daemon[9218]: Demoted 3 threads.
Dec 08 20:11:04 memepad vmnetBridge[665]: Stopped bridge wlp3s0 to virtual network 0.
Dec 08 20:11:04 memepad vmnet-natd[1613]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00001003
Dec 08 20:11:04 memepad sudo[17537]: pam_unix(sudo:session): session closed for user root
Dec 08 20:11:04 memepad systemd[1]: Reloading Laptop Mode Tools.
Dec 08 20:11:04 memepad NetworkManager[580]: <info>  [1544296264.7608] device (wlp3s0): supplicant interface state: disconnected -> scanning
Dec 08 20:11:04 memepad laptop_mode[17571]: Laptop mode
Dec 08 20:11:04 memepad laptop_mode[17571]: enabled, active [unchanged]
Dec 08 20:11:04 memepad kernel: userif-3: sent link down event.
Dec 08 20:11:04 memepad kernel: userif-3: sent link up event.
Dec 08 20:11:04 memepad wpa_supplicant[1080]: wlp3s0: SME: Trying to authenticate with ac:22:05:f9:0e:6b (SSID='WuTangLAN' freq=5320 MHz)
Dec 08 20:11:04 memepad kernel: wlp3s0: authenticate with ac:22:05:f9:0e:6b
Dec 08 20:11:04 memepad kernel: wlp3s0: send auth to ac:22:05:f9:0e:6b (try 1/3)
Dec 08 20:11:04 memepad NetworkManager[580]: <info>  [1544296264.8886] device (wlp3s0): supplicant interface state: scanning -> authenticating
Dec 08 20:11:04 memepad wpa_supplicant[1080]: wlp3s0: Trying to associate with ac:22:05:f9:0e:6b (SSID='WuTangLAN' freq=5320 MHz)
Dec 08 20:11:04 memepad kernel: wlp3s0: authenticated
Dec 08 20:11:04 memepad kernel: wlp3s0: associate with ac:22:05:f9:0e:6b (try 1/3)
Dec 08 20:11:04 memepad NetworkManager[580]: <info>  [1544296264.9807] device (wlp3s0): supplicant interface state: authenticating -> associating
Dec 08 20:11:05 memepad kernel: wlp3s0: RX AssocResp from ac:22:05:f9:0e:6b (capab=0x511 status=0 aid=3)
Dec 08 20:11:05 memepad kernel: wlp3s0: associated
Dec 08 20:11:05 memepad vmnetBridge[665]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011003
Dec 08 20:11:05 memepad wpa_supplicant[1080]: wlp3s0: Associated with ac:22:05:f9:0e:6b
Dec 08 20:11:05 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Dec 08 20:11:05 memepad vmnet-natd[1613]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011003
Dec 08 20:11:05 memepad NetworkManager[580]: <info>  [1544296265.0136] device (wlp3s0): supplicant interface state: associating -> associated
Dec 08 20:11:05 memepad NetworkManager[580]: <info>  [1544296265.0370] device (wlp3s0): supplicant interface state: associated -> 4-way handshake
Dec 08 20:11:06 memepad wpa_supplicant[1080]: wlp3s0: WPA: Key negotiation completed with ac:22:05:f9:0e:6b [PTK=CCMP GTK=TKIP]
Dec 08 20:11:06 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to ac:22:05:f9:0e:6b completed [id=0 id_str=]
Dec 08 20:11:06 memepad vmnet-natd[1613]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011043
Dec 08 20:11:06 memepad vmnetBridge[665]: RTM_NEWLINK: name:wlp3s0 index:2 flags:0x00011043
Dec 08 20:11:06 memepad vmnetBridge[665]: Adding interface wlp3s0 index:2
Dec 08 20:11:06 memepad vmnetBridge[665]: Started bridge wlp3s0 to virtual network 0.
Dec 08 20:11:06 memepad NetworkManager[580]: <info>  [1544296266.0731] device (wlp3s0): supplicant interface state: 4-way handshake -> completed
Dec 08 20:11:06 memepad wpa_supplicant[1080]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-50 noise=9999 txrate=6000
Dec 08 20:11:06 memepad sudo[17632]:   philip : TTY=pts/0 ; PWD=/home/philip ; USER=root ; COMMAND=/usr/bin/journalctl --since 1 minute ago

I think this part is a bit weird because first it seems like the sleep state gets blocked. But then it goes on creating a hibernation image. And them it brings the cpu back on?

Dec 08 20:11:04 memepad kernel: ACPI: Preparing to enter system sleep state S4
Dec 08 20:11:04 memepad kernel: ACPI: EC: event blocked
Dec 08 20:11:04 memepad kernel: ACPI: EC: EC stopped
Dec 08 20:11:04 memepad kernel: PM: Saving platform NVS memory
Dec 08 20:11:04 memepad kernel: Disabling non-boot CPUs ...
Dec 08 20:11:04 memepad kernel: smpboot: CPU 1 is now offline
Dec 08 20:11:04 memepad kernel: smpboot: CPU 2 is now offline
Dec 08 20:11:04 memepad kernel: smpboot: CPU 3 is now offline
Dec 08 20:11:04 memepad kernel: PM: Creating hibernation image:
Dec 08 20:11:04 memepad kernel: PM: Need to copy 627583 pages
Dec 08 20:11:04 memepad kernel: PM: Normal pages needed: 627583 + 1024, available pages: 1391798
Dec 08 20:11:04 memepad kernel: PM: Hibernation image created (627583 pages copied)
Dec 08 20:11:04 memepad kernel: PM: Restoring platform NVS memory
Dec 08 20:11:04 memepad kernel: ACPI: EC: EC started
Dec 08 20:11:04 memepad kernel: Enabling non-boot CPUs ...
Dec 08 20:11:04 memepad kernel: x86: Booting SMP configuration:
Dec 08 20:11:04 memepad kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Dec 08 20:11:04 memepad kernel:  cache: parent cpu1 should not be sleeping
Dec 08 20:11:04 memepad kernel: CPU1 is up
Dec 08 20:11:04 memepad kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Dec 08 20:11:04 memepad kernel:  cache: parent cpu2 should not be sleeping
Dec 08 20:11:04 memepad kernel: CPU2 is up
Dec 08 20:11:04 memepad kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Dec 08 20:11:04 memepad kernel:  cache: parent cpu3 should not be sleeping
Dec 08 20:11:04 memepad kernel: CPU3 is up
Dec 08 20:11:04 memepad kernel: ACPI: Waking up from system sleep state S4

When i googled this i saw some people with the same problem and some bug reports but no selutions Anyone here who knows what to do?

Arch Linux

#1 2018-12-08 19:29:33

Hibernation fails on t430s

Board footer