Suspend works only ~2 of 3 Times else stuck with black screen

[Hetti]

EDIT: I will try the hint with the network stuff from #6 (comment) and comment if this worked the next days!

Hey,
I have a problem with suspending my X220 Tablet.
It's working like 2 of 3 times without problem. The other time it get stuck with a black screen and a white underline sign _ in the upper left corner. I can't really say when this is triggered when suspending.

In both cases the log comes to the same point:
systemd-journald[xxxx]: Journal stopped

Anyone Ideas how to debug further? Probably the debug flag of luks-suspend helpful?
Sorry for the long log :(

Here are my logs of journalctl
Failed Suspend:

Jun 11 00:22:05 sternenregen systemd-logind[765]: Lid closed.
Jun 11 00:22:05 sternenregen systemd-logind[765]: Suspending...
Jun 11 00:22:05 sternenregen root[22919]: LID closed
Jun 11 00:22:05 sternenregen systemd[1]: Starting TLP suspend/resume...
Jun 11 00:22:05 sternenregen systemd[1]: Starting netctl sleep hook...
Jun 11 00:22:06 sternenregen systemd[1]: Started TLP suspend/resume.
Jun 11 00:22:06 sternenregen systemd[1]: Stopping Networking for netctl profile wlp3s0-<wifiname>...
Jun 11 00:22:06 sternenregen systemd[1]: Stopping Networking for netctl profile xxxxx...
Jun 11 00:22:06 sternenregen network[23113]: Stopping network profile 'wlp3s0-<wifiname>'...
Jun 11 00:22:06 sternenregen network[23114]: Stopping network profile 'tuvpn'...
Jun 11 00:22:06 sternenregen network[29272]: BYE-Paket senden: Aborted by caller
Jun 11 00:22:06 sternenregen dhcpcd[23124]: sending signal TERM to pid 22349
Jun 11 00:22:06 sternenregen dhcpcd[23124]: waiting for pid 22349 to exit
Jun 11 00:22:06 sternenregen dhcpcd[22349]: received SIGTERM, stopping
Jun 11 00:22:06 sternenregen dhcpcd[22349]: wlp3s0: removing interface
Jun 11 00:22:06 sternenregen dhcpcd[22349]: dhcpcd exited
Jun 11 00:22:06 sternenregen systemd-udevd[23209]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jun 11 00:22:06 sternenregen systemd-udevd[23209]: link_config: could not get ethtool features for vpn
Jun 11 00:22:06 sternenregen systemd-udevd[23209]: Could not set offload features of vpn: No such device
Jun 11 00:22:06 sternenregen kernel: wlp3s0: deauthenticating from xxxxxxxxxx by local choice (Reason: 3=DEAUTH_LEAVING)
Jun 11 00:22:06 sternenregen network[23114]: Stopped network profile 'xxxx'
Jun 11 00:22:06 sternenregen systemd[1]: Stopped Networking for netctl profile tuvpn.
Jun 11 00:22:06 sternenregen network[23113]: Stopped network profile 'wlp3s0-<wifiname>'
Jun 11 00:22:06 sternenregen systemd[1]: Stopped Networking for netctl profile wlp3s0-<wifiname>.
Jun 11 00:22:06 sternenregen systemd[1]: Started netctl sleep hook.
Jun 11 00:22:06 sternenregen systemd[1]: Reached target Sleep.
Jun 11 00:22:06 sternenregen systemd[1]: Starting Suspend...
Jun 11 00:22:07 sternenregen systemd-journald[22029]: Journal stopped
-- Reboot --
Jun 11 01:06:26 sternenregen kernel: microcode: microcode updated early to revision 0x2d, date = 2018-02-07

Correct Suspend:

Jun 11 01:56:02 <hostname> systemd-logind[835]: Lid closed.
Jun 11 01:56:02 <hostname> systemd-logind[835]: Suspending...
Jun 11 01:56:02 <hostname> systemd[1]: Starting netctl sleep hook...
Jun 11 01:56:02 <hostname> systemd[1]: Starting TLP suspend/resume...
Jun 11 01:56:02 <hostname> root[10386]: LID closed
Jun 11 01:56:02 <hostname> systemd[1]: Stopping Networking for netctl profile wlp3s0-<wifiname>...
Jun 11 01:56:02 <hostname> network[10554]: Stopping network profile 'wlp3s0-<wifiname>'...
Jun 11 01:56:02 <hostname> systemd[1]: Started TLP suspend/resume.
Jun 11 01:56:02 <hostname> dhcpcd[10578]: sending signal TERM to pid 7461
Jun 11 01:56:02 <hostname> dhcpcd[10578]: waiting for pid 7461 to exit
Jun 11 01:56:02 <hostname> dhcpcd[7461]: received SIGTERM, stopping
Jun 11 01:56:02 <hostname> dhcpcd[7461]: wlp3s0: removing interface
Jun 11 01:56:03 <hostname> dhcpcd[7461]: dhcpcd exited
Jun 11 01:56:03 <hostname> kernel: wlp3s0: deauthenticating from xxxxxxx by local choice (Reason: 3=DEAUTH_LEAVING)
Jun 11 01:56:03 <hostname> network[10554]: Stopped network profile 'wlp3s0-<wifiname>'
Jun 11 01:56:03 <hostname> systemd[1]: Stopped Networking for netctl profile wlp3s0-<wifiname>.
Jun 11 01:56:03 <hostname> systemd[1]: Started netctl sleep hook.
Jun 11 01:56:03 <hostname> systemd[1]: Reached target Sleep.
Jun 11 01:56:03 <hostname> systemd[1]: Starting Suspend...
Jun 11 01:56:03 <hostname> systemd-journald[7147]: Journal stopped
========= HERE SUSPENDED STATE ===========
Jun 11 01:56:17 <hostname> systemd-journald[7147]: Received SIGTERM from PID 1 (systemd).
Jun 11 01:56:17 <hostname> systemd[1]: Stopping Journal Service...
Jun 11 01:56:17 <hostname> systemd[1]: Closed udev Control Socket.
Jun 11 01:56:17 <hostname> systemd[1]: Closed udev Kernel Socket.
Jun 11 01:56:17 <hostname> systemd[1]: Stopping udev Kernel Device Manager...
Jun 11 01:56:17 <hostname> systemd[1]: Stopped Journal Service.
Jun 11 01:56:17 <hostname> systemd[1]: Closed Journal Socket (/dev/log).
Jun 11 01:56:17 <hostname> systemd[1]: Stopped udev Kernel Device Manager.
Jun 11 01:56:17 <hostname> systemd[1]: Closed Journal Socket.
Jun 11 01:56:17 <hostname> kernel: EXT4-fs (dm-1): re-mounted. Opts: nobarrier
Jun 11 01:56:17 <hostname> kernel: EXT4-fs (dm-2): re-mounted. Opts: nobarrier
Jun 11 01:56:17 <hostname> kernel: PM: suspend entry (deep)
Jun 11 01:56:17 <hostname> kernel: PM: Syncing filesystems ... done.
Jun 11 01:56:17 <hostname> kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Jun 11 01:56:17 <hostname> kernel: OOM killer disabled.
Jun 11 01:56:17 <hostname> kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Jun 11 01:56:17 <hostname> kernel: Suspending console(s) (use no_console_suspend to debug)
Jun 11 01:56:17 <hostname> kernel: sd 2:0:0:0: [sdb] Synchronizing SCSI cache
Jun 11 01:56:17 <hostname> kernel: sd 2:0:0:0: [sdb] Stopping disk
Jun 11 01:56:17 <hostname> kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Jun 11 01:56:17 <hostname> kernel: e1000e: EEE TX LPI TIMER: 00000011
Jun 11 01:56:17 <hostname> kernel: sd 0:0:0:0: [sda] Stopping disk
Jun 11 01:56:17 <hostname> kernel: ACPI: EC: interrupt blocked
Jun 11 01:56:17 <hostname> kernel: ACPI: Preparing to enter system sleep state S3
Jun 11 01:56:17 <hostname> kernel: ACPI: EC: event blocked
Jun 11 01:56:17 <hostname> kernel: ACPI: EC: EC stopped
Jun 11 01:56:17 <hostname> kernel: PM: Saving platform NVS memory
Jun 11 01:56:17 <hostname> kernel: Disabling non-boot CPUs ...
Jun 11 01:56:17 <hostname> kernel: smpboot: CPU 1 is now offline
Jun 11 01:56:17 <hostname> kernel: smpboot: CPU 2 is now offline
Jun 11 01:56:17 <hostname> kernel: smpboot: CPU 3 is now offline
Jun 11 01:56:17 <hostname> kernel: ACPI: Low-level resume complete
Jun 11 01:56:17 <hostname> kernel: ACPI: EC: EC started
Jun 11 01:56:17 <hostname> kernel: PM: Restoring platform NVS memory
Jun 11 01:56:17 <hostname> kernel: Enabling non-boot CPUs ...
Jun 11 01:56:17 <hostname> kernel: x86: Booting SMP configuration:
Jun 11 01:56:17 <hostname> kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Jun 11 01:56:17 <hostname> kernel: Disabled fast string operations
Jun 11 01:56:17 <hostname> kernel:  cache: parent cpu1 should not be sleeping
Jun 11 01:56:17 <hostname> kernel: CPU1 is up
Jun 11 01:56:17 <hostname> kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Jun 11 01:56:17 <hostname> kernel: Disabled fast string operations
Jun 11 01:56:17 <hostname> kernel:  cache: parent cpu2 should not be sleeping
Jun 11 01:56:17 <hostname> kernel: CPU2 is up
Jun 11 01:56:17 <hostname> kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Jun 11 01:56:17 <hostname> kernel: Disabled fast string operations
Jun 11 01:56:17 <hostname> kernel:  cache: parent cpu3 should not be sleeping
Jun 11 01:56:17 <hostname> kernel: CPU3 is up
Jun 11 01:56:17 <hostname> kernel: ACPI: Waking up from system sleep state S3
Jun 11 01:56:17 <hostname> kernel: ACPI: EC: interrupt unblocked
Jun 11 01:56:17 <hostname> kernel: sdhci-pci 0000:0d:00.0: MMC controller base frequency changed to 50Mhz.
Jun 11 01:56:17 <hostname> kernel: ACPI: EC: event unblocked
Jun 11 01:56:17 <hostname> kernel: sd 0:0:0:0: [sda] Starting disk
Jun 11 01:56:17 <hostname> kernel: sd 2:0:0:0: [sdb] Starting disk
Jun 11 01:56:17 <hostname> kernel: usb 1-1.3: reset full-speed USB device number 3 using ehci-pci
Jun 11 01:56:17 <hostname> kernel: usb 2-1.5: reset full-speed USB device number 3 using ehci-pci
Jun 11 01:56:17 <hostname> kernel: ata2: SATA link down (SStatus 0 SControl 300)
Jun 11 01:56:17 <hostname> kernel: ata5: SATA link down (SStatus 0 SControl 300)
Jun 11 01:56:17 <hostname> kernel: ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Jun 11 01:56:17 <hostname> kernel: ata3.00: supports DRM functions and may not be fully accessible
Jun 11 01:56:17 <hostname> kernel: ata3.00: disabling queued TRIM support
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Jun 11 01:56:17 <hostname> kernel: ata3.00: supports DRM functions and may not be fully accessible
Jun 11 01:56:17 <hostname> kernel: ata3.00: disabling queued TRIM support
Jun 11 01:56:17 <hostname> kernel: ata3.00: configured for UDMA/133
Jun 11 01:56:17 <hostname> kernel: usb 1-1.6: reset high-speed USB device number 4 using ehci-pci
Jun 11 01:56:17 <hostname> kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Jun 11 01:56:17 <hostname> kernel: Disabled fast string operations
Jun 11 01:56:17 <hostname> kernel:  cache: parent cpu2 should not be sleeping
Jun 11 01:56:17 <hostname> kernel: CPU2 is up
Jun 11 01:56:17 <hostname> kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Jun 11 01:56:17 <hostname> kernel: Disabled fast string operations
Jun 11 01:56:17 <hostname> kernel:  cache: parent cpu3 should not be sleeping
Jun 11 01:56:17 <hostname> kernel: CPU3 is up
Jun 11 01:56:17 <hostname> kernel: ACPI: Waking up from system sleep state S3
Jun 11 01:56:17 <hostname> kernel: ACPI: EC: interrupt unblocked
Jun 11 01:56:17 <hostname> kernel: sdhci-pci 0000:0d:00.0: MMC controller base frequency changed to 50Mhz.
Jun 11 01:56:17 <hostname> kernel: ACPI: EC: event unblocked
Jun 11 01:56:17 <hostname> kernel: sd 0:0:0:0: [sda] Starting disk
Jun 11 01:56:17 <hostname> kernel: sd 2:0:0:0: [sdb] Starting disk
Jun 11 01:56:17 <hostname> kernel: usb 1-1.3: reset full-speed USB device number 3 using ehci-pci
Jun 11 01:56:17 <hostname> kernel: usb 2-1.5: reset full-speed USB device number 3 using ehci-pci
Jun 11 01:56:17 <hostname> kernel: ata2: SATA link down (SStatus 0 SControl 300)
Jun 11 01:56:17 <hostname> kernel: ata5: SATA link down (SStatus 0 SControl 300)
Jun 11 01:56:17 <hostname> kernel: ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Jun 11 01:56:17 <hostname> kernel: ata3.00: supports DRM functions and may not be fully accessible
Jun 11 01:56:17 <hostname> kernel: ata3.00: disabling queued TRIM support
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Jun 11 01:56:17 <hostname> kernel: ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Jun 11 01:56:17 <hostname> kernel: ata3.00: supports DRM functions and may not be fully accessible
Jun 11 01:56:17 <hostname> kernel: ata3.00: disabling queued TRIM support
Jun 11 01:56:17 <hostname> kernel: ata3.00: configured for UDMA/133
Jun 11 01:56:17 <hostname> kernel: usb 1-1.6: reset high-speed USB device number 4 using ehci-pci
Jun 11 01:56:17 <hostname> kernel: psmouse serio1: synaptics: queried max coordinates: x [..5472], y [..4448]
Jun 11 01:56:17 <hostname> kernel: OOM killer enabled.
Jun 11 01:56:17 <hostname> kernel: Restarting tasks ... 
Jun 11 01:56:17 <hostname> systemd-logind[835]: Lid opened.
Jun 11 01:56:17 <hostname> kernel: done.
Jun 11 01:56:17 <hostname> kernel: video LNXVIDEO:00: Restoring backlight state
Jun 11 01:56:17 <hostname> kernel: PM: suspend exit
Jun 11 01:56:17 <hostname> kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Jun 11 01:56:17 <hostname> kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Jun 11 01:56:17 <hostname> kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Jun 11 01:56:17 <hostname> kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Jun 11 01:56:17 <hostname> kernel: ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Jun 11 01:56:17 <hostname> kernel: ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Jun 11 01:56:17 <hostname> kernel: ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
Jun 11 01:56:17 <hostname> kernel: ata1.00: configured for UDMA/133
Jun 11 01:56:17 <hostname> systemd[1]: Listening on Journal Socket.
Jun 11 01:56:17 <hostname> systemd[1]: Listening on Journal Socket (/dev/log).
Jun 11 01:56:17 <hostname> systemd[1]: Starting Journal Service...
Jun 11 01:56:17 <hostname> systemd[1]: Listening on udev Control Socket.
Jun 11 01:56:17 <hostname> systemd[1]: Listening on udev Kernel Socket.
Jun 11 01:56:17 <hostname> systemd[1]: Starting udev Kernel Device Manager...
Jun 11 01:56:17 <hostname> systemd-journald[10711]: Journal started
Jun 11 01:56:17 <hostname> systemd-journald[10711]: System journal (/var/log/journal/591fad164bd44292b7a315f01fe98be1) is 512.1M, max 512.1M, 0B free.
Jun 11 01:56:17 <hostname> systemd[1]: Started Journal Service.
Jun 11 01:56:17 <hostname> systemd[1]: Started udev Kernel Device Manager.
Jun 11 01:56:17 <hostname> kernel: EXT4-fs (dm-1): re-mounted. Opts: barrier
Jun 11 01:56:17 <hostname> kernel: EXT4-fs (dm-2): re-mounted. Opts: barrier
Jun 11 01:56:17 <hostname> systemd[1]: Started Suspend.
Jun 11 01:56:17 <hostname> systemd[1]: sleep.target: Unit not needed anymore. Stopping.
Jun 11 01:56:17 <hostname> systemd[1]: Stopped target Sleep.
Jun 11 01:56:17 <hostname> systemd[1]: tlp-sleep.service: Unit not needed anymore. Stopping.
Jun 11 01:56:17 <hostname> systemd[1]: netctl-sleep.service: Unit not needed anymore. Stopping.
Jun 11 01:56:17 <hostname> systemd[1]: Stopping netctl sleep hook...
Jun 11 01:56:17 <hostname> systemd[1]: Stopping TLP suspend/resume...
Jun 11 01:56:17 <hostname> systemd[1]: Reached target Suspend.
Jun 11 01:56:17 <hostname> systemd[1]: suspend.target: Unit not needed anymore. Stopping.
Jun 11 01:56:17 <hostname> systemd[1]: Stopped target Suspend.
Jun 11 01:56:17 <hostname> systemd[1]: Starting Networking for netctl profile wlp3s0-<wifiname>...
Jun 11 01:56:17 <hostname> network[10724]: Starting network profile 'wlp3s0-<wifiname>'...
Jun 11 01:56:17 <hostname> kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
Jun 11 01:56:17 <hostname> systemd-logind[835]: Operation 'sleep' finished.
Jun 11 01:56:17 <hostname> kernel: iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
Jun 11 01:56:17 <hostname> kernel: IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
Jun 11 01:56:17 <hostname> systemd[1]: Stopped TLP suspend/resume.

[guns]

Anyone Ideas how to debug further? Probably the debug flag of luks-suspend helpful?

Yes, you can run the following from a root shell and share the output here.

# /usr/lib/go-luks-suspend/go-luks-suspend -debug

[Hetti]

This is the output of the command.
I will keep you updated!
I will remove for testing purposes your suggested script from #6 (comment) and run
# /usr/lib/go-luks-suspend/go-luks-suspend -debug instead normal suspending.

worked two times without problems:

# /usr/lib/go-luks-suspend/go-luks-suspend -debug
2018/06/12 07:46:56 disabling ISIG in TTY
2018/06/12 07:46:56 checking suspend program in initramfs
2018/06/12 07:46:56 gathering cryptdevices
2018/06/12 07:46:56 Name:"<name0>" IsRootDevice:true
2018/06/12 07:46:56 Name:"<name1>" IsRootDevice:false
2018/06/12 07:46:56 running pre-suspend scripts
2018/06/12 07:46:56 exec: /usr/lib/systemd/system-sleep/stop-network-interfaces.sleep pre suspend
2018/06/12 07:46:57 gathering filesystems with write barriers
2018/06/12 07:46:57 main.filesystem{mountpoint:"/", devno:0xfe01}
2018/06/12 07:46:57 main.filesystem{mountpoint:"/home", devno:0xfe02}
2018/06/12 07:46:57 main.filesystem{mountpoint:"/mnt/xxx", devno:0xfe03}
2018/06/12 07:46:57 preparing initramfs chroot
2018/06/12 07:46:57 stopping selected system services
2018/06/12 07:46:57 exec: /usr/bin/systemctl --quiet is-active syslog.socket
2018/06/12 07:46:57 exec: /usr/bin/systemctl --quiet is-active systemd-journald.socket
2018/06/12 07:46:57 exec: /usr/bin/systemctl --quiet is-active systemd-journald-dev-log.socket
2018/06/12 07:46:57 exec: /usr/bin/systemctl --quiet is-active systemd-journald-audit.socket
2018/06/12 07:46:57 exec: /usr/bin/systemctl --quiet is-active systemd-journald.service
2018/06/12 07:46:57 exec: /usr/bin/systemctl --quiet is-active systemd-udevd-control.socket
2018/06/12 07:46:57 exec: /usr/bin/systemctl --quiet is-active systemd-udevd-kernel.socket
2018/06/12 07:46:57 exec: /usr/bin/systemctl --quiet is-active systemd-udevd.service
2018/06/12 07:46:57 exec: /usr/bin/systemctl stop systemd-journald.socket systemd-journald-dev-log.socket systemd-journald.service systemd-udevd-control.socket systemd-udevd-kernel.socket systemd-udevd.service
2018/06/12 07:46:57 flushing pending writes
2018/06/12 07:46:57 disabling write barriers on filesystems to avoid IO hangs
2018/06/12 07:46:57 calling suspend in initramfs chroot
2018/06/12 05:46:57 loading cryptdevices
2018/06/12 05:46:57 suspending cryptdevices
2018/06/12 05:46:57 exec: /usr/bin/cryptsetup luksSuspend <name1>
2018/06/12 05:46:57 exec: /usr/bin/cryptsetup luksSuspend <name0>
2018/06/12 05:46:57 debug: skipping suspend to RAM
2018/06/12 05:46:57 resuming root cryptdevice

Press Escape to suspend to RAM, or Ctrl-T to start a debug shell.

Enter passphrase for <name0>: 2018/06/12 05:46:57 exec: /usr/bin/cryptsetup --tries=1 luksResume <name0>

2018/06/12 07:47:07 starting previously stopped system services
2018/06/12 07:47:07 exec: /usr/bin/systemctl start systemd-journald.socket systemd-journald-dev-log.socket systemd-journald.service systemd-udevd-control.socket systemd-udevd-kernel.socket systemd-udevd.service
2018/06/12 07:47:08 gathering keyfiles from /etc/crypttab
2018/06/12 07:47:08 goLuksSuspend.Keyfile{Device:"", FSType:"", Header:"", Path:"/root/keyfile_data", Offset:0, Size:0, KeySlot:0x0}
2018/06/12 07:47:08 resuming non-root cryptdevices with keyfiles
2018/06/12 07:47:08 Resuming <name1>
2018/06/12 07:47:08 exec: /usr/bin/cryptsetup --key-file /root/xxx luksResume <name1>
2018/06/12 07:47:11 <name1> resumed
2018/06/12 07:47:11 re-enabling write barriers on filesystems
2018/06/12 07:47:11 unmounting initramfs bind mounts
2018/06/12 07:47:12 running post-suspend scripts
2018/06/12 07:47:12 exec: /usr/lib/systemd/system-sleep/stop-network-interfaces.sleep post suspend
2018/06/12 07:47:16 restoring TTY to original state

[Hetti]

Status:
So running here and there # /usr/lib/go-luks-suspend/go-luks-suspend -debug don't helps to identify the problem. It runs through every time without problems.
Yesterday I run it, succeded without problems. Directly afterwards I closed my laptop lid and the same thing happend again that it stuck on black screen.

Is there som other possibility to log it somehow better, beside running a dry run with # /usr/lib/go-luks-suspend/go-luks-suspend -debug ?

[manukall]

I'm running into the same problem. Is there any way I can help with debugging?

[Hetti]

The problem could be some program which blocks something.
For me firefox is quite suspicious, most of the time when I close it before it works. But I still not know whats the problem.
There is somewhere in the arch wiki a guide how to start debugging suspend problems.
But I had no time to follow the guide.

[manukall]

I've tried the linux-lts kernel and adding the intel graphis driver to the modules list in mkinitpcio.conf. Both did not help. This shows how to debug things like driver or graphics related suspend issues, but I'm not sure it's relevant to our case, as (at least for me) "unsafe" suspend without this package works.

[manukall]

Another piece of information: after trying to suspend and running into the described issue, i did not turn the computer off but let it run over night. In the morning the following messages were printed on the screen. It looks as if there really are programs blocking, but I have no idea what to do against that.

[Maryse47]

This is generally known issue. Also reported in #6 (comment) .

Folks from Debian tried to tackle it and failed:
https://blog.freesources.org/posts/2018/06/debian_cryptsetup_sprint_report/
https://www.saout.de/pipermail/dm-crypt/2018-June/005896.html

This is problem with some race conditions in linux kernel.