Kernel 4.12: Crashes when resuming from suspending

kde
kernel
laptop

#12

@torvic I successfully suspended and resumed several times using your kernel, but at some point I was faced with a black screen… Is there anything else I can try?


#13

It just seemed wrong to :heart: your post, but I do want to thank you for giving that a go!


The upgrade 2017-08-10, it broke suspend Manjaro 17.0.2 Gnome
#14

I can confirm this is a Manjaro regression, following a comment by @jonathon I have installed:

Linux steve-pc 4.12.3-1-ARCH #1 SMP PREEMPT Sat Jul 22 15:32:02 UTC 2017 x86_64 GNU/Linux

From http://mirrors.kernel.org/archlinux/core/os/x86_64/
And it appears to work (suspend and resumed), Manjaro kernel does not do this.

@philm can I provide any further info?


#15

journalctl -b -2       
-- Logs begin at Sun 2017-01-29 10:56:39 GMT, end at Sun 2017-07-30 00:47:11 BST. --
Jul 29 23:36:37 steve-pc kernel: Linux version 4.12.4-1-MANJARO (builduser@manjaro) (gcc version 7.1.1 20170630 (GCC) ) #1 SMP PREEMPT Fri Jul 28 0
Jul 29 23:36:37 steve-pc kernel: Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.12-x86_64 root=UUID=0042e29a-cf37-442b-8b87-fc5e11a9254f rw quiet 
Jul 29 23:36:37 steve-pc kernel: ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
Jul 29 23:36:37 steve-pc kernel: ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
Jul 29 23:36:37 steve-pc kernel: PCCT header not found.
Jul 29 23:36:37 steve-pc kernel: pmd_set_huge: Cannot satisfy [mem 0xf8000000-0xf8200000] with a huge-page mapping due to MTRR override.
Jul 29 23:36:37 steve-pc kernel: ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored
Jul 29 23:36:37 steve-pc kernel: VFS: Disk quotas dquot_6.6.0
Jul 29 23:36:37 steve-pc kernel: Initialise system trusted keyrings
Jul 29 23:36:37 steve-pc kernel: Key type blacklist registered
Jul 29 23:36:37 steve-pc kernel: Key type asymmetric registered
Jul 29 23:36:37 steve-pc kernel: Loading compiled-in X.509 certificates
Jul 29 23:36:37 steve-pc kernel: SCSI subsystem initialized
Jul 29 23:36:37 steve-pc kernel: scsi 0:0:0:0: CD-ROM            TSSTcorp CDDVDW SN-208FB  SB00 PQ: 0 ANSI: 5
Jul 29 23:36:37 steve-pc kernel: scsi 5:0:0:0: Direct-Access     ATA      ST2000LM003 HN-M 0001 PQ: 0 ANSI: 5
Jul 29 23:36:37 steve-pc kernel: sd 5:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
Jul 29 23:36:37 steve-pc kernel: sd 5:0:0:0: [sda] 4096-byte physical blocks
Jul 29 23:36:37 steve-pc kernel: sd 5:0:0:0: [sda] Write Protect is off
Jul 29 23:36:37 steve-pc kernel: sd 5:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Jul 29 23:36:37 steve-pc kernel: sd 5:0:0:0: [sda] Attached SCSI disk
Jul 29 23:36:37 steve-pc kernel: random: fast init done
Jul 29 23:36:37 steve-pc systemd-journald[188]: Journal started
Jul 29 23:36:37 steve-pc systemd-journald[188]: Runtime journal (/run/log/journal/e1d2ad259a0c4b3494932a11ddd42221) is 6.2M, max 50.0M, 43.7M free.
Jul 29 23:36:38 steve-pc kernel: sr 0:0:0:0: Attached scsi generic sg0 type 5
Jul 29 23:36:38 steve-pc kernel: sd 5:0:0:0: Attached scsi generic sg1 type 0
Jul 29 23:36:38 steve-pc kernel: vboxguest: loading out-of-tree module taints kernel.
Jul 29 23:36:38 steve-pc kernel: vboxguest: PCI device not found, probably running on physical hardware.
Jul 29 23:36:38 steve-pc systemd-modules-load[184]: Failed to insert 'vboxguest': No such device
Jul 29 23:36:38 steve-pc kernel: vboxguest: PCI device not found, probably running on physical hardware.
Jul 29 23:36:38 steve-pc systemd-journald[188]: Time spent on flushing to /var is 394.955ms for 29 entries.
Jul 29 23:36:38 steve-pc systemd-journald[188]: System journal (/var/log/journal/e1d2ad259a0c4b3494932a11ddd42221) is 62.5M, max 50.0M, 0B free.
Jul 29 23:36:41 steve-pc kernel: vboxguest: PCI device not found, probably running on physical hardware.
Jul 29 23:36:41 steve-pc kernel: VBoxPciLinuxInit
Jul 29 23:36:41 steve-pc kernel: VBoxNetAdp: Successfully started.
Jul 29 23:36:41 steve-pc kernel: VBoxNetFlt: Successfully started.
Jul 29 23:36:38 steve-pc systemd-modules-load[184]: Failed to insert 'vboxsf': No such device
Jul 29 23:36:39 steve-pc systemd-modules-load[184]: Failed to insert 'vboxvideo': No such device
Jul 29 23:36:39 steve-pc systemd[1]: systemd-modules-load.service: Main process exited, code=exited, status=1/FAILURE
Jul 29 23:36:39 steve-pc systemd[1]: Failed to start Load Kernel Modules.
Jul 29 23:36:39 steve-pc systemd[1]: systemd-modules-load.service: Unit entered failed state.
Jul 29 23:36:39 steve-pc systemd[1]: systemd-modules-load.service: Failed with result 'exit-code'.
Jul 29 23:36:42 steve-pc kernel: (NULL device *): hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_wit
Jul 29 23:36:42 steve-pc kernel: r8168 0000:05:00.2: can't disable ASPM; OS doesn't have ASPM control
Jul 29 23:36:42 steve-pc kernel: ACPI Warning: SystemIO range 0x0000000000001828-0x000000000000182F conflicts with OpRegion 0x0000000000001800-0x00
Jul 29 23:36:42 steve-pc kernel: ACPI Warning: SystemIO range 0x0000000000001C40-0x0000000000001C4F conflicts with OpRegion 0x0000000000001C00-0x00
Jul 29 23:36:42 steve-pc kernel: ACPI Warning: SystemIO range 0x0000000000001C40-0x0000000000001C4F conflicts with OpRegion 0x0000000000001C00-0x00
Jul 29 23:36:42 steve-pc kernel: ACPI Warning: SystemIO range 0x0000000000001C30-0x0000000000001C3F conflicts with OpRegion 0x0000000000001C00-0x00
Jul 29 23:36:42 steve-pc kernel: ACPI Warning: SystemIO range 0x0000000000001C30-0x0000000000001C3F conflicts with OpRegion 0x0000000000001C00-0x00
Jul 29 23:36:42 steve-pc kernel: ACPI Warning: SystemIO range 0x0000000000001C30-0x0000000000001C3F conflicts with OpRegion 0x0000000000001C00-0x00
Jul 29 23:36:42 steve-pc kernel: ACPI Warning: SystemIO range 0x0000000000001C00-0x0000000000001C2F conflicts with OpRegion 0x0000000000001C00-0x00
Jul 29 23:36:42 steve-pc kernel: ACPI Warning: SystemIO range 0x0000000000001C00-0x0000000000001C2F conflicts with OpRegion 0x0000000000001C00-0x00
Jul 29 23:36:42 steve-pc kernel: ACPI Warning: SystemIO range 0x0000000000001C00-0x0000000000001C2F conflicts with OpRegion 0x0000000000001C00-0x00
Jul 29 23:36:42 steve-pc kernel: lpc_ich: Resource conflict(s) found affecting gpio_ich
Jul 29 23:36:42 steve-pc kernel: r8168  Copyright (C) 2017  Realtek NIC software team <nicfae@realtek.com> 
                                  This program comes with ABSOLUTELY NO WARRANTY; for details, please see <http://www.gnu.org/licenses/>. 
                                  This is free software, and you are welcome to redistribute it under certain conditions; see <http://www.gnu.org/l
Jul 29 23:36:43 steve-pc systemd-udevd[209]: Process '/usr/bin/set-wireless-regdom' failed with exit code 1.
Jul 29 23:36:44 steve-pc systemd-udevd[223]: Process '/usr/bin/tlp auto' failed with exit code 4.
Jul 29 23:36:45 steve-pc systemd-udevd[219]: Process '/usr/bin/alsactl restore 0' failed with exit code 99.
Jul 29 23:36:45 steve-pc systemd-udevd[209]: Process '/usr/bin/alsactl restore 1' failed with exit code 99.
Jul 29 23:36:45 steve-pc systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
Jul 29 23:36:45 steve-pc systemd-tmpfiles[566]: [/usr/lib/tmpfiles.d/var.conf:17] Duplicate line for path "/var/log/lastlog", ignoring.
Jul 29 23:36:45 steve-pc kernel: vboxguest: PCI device not found, probably running on physical hardware.
Jul 29 23:36:45 steve-pc systemd-modules-load[568]: Failed to insert 'vboxguest': No such device
Jul 29 23:36:45 steve-pc kernel: vboxguest: PCI device not found, probably running on physical hardware.
Jul 29 23:36:45 steve-pc kernel: uvcvideo 3-8:1.0: Entity type for entity Extension 4 was not initialized!
Jul 29 23:36:45 steve-pc kernel: uvcvideo 3-8:1.0: Entity type for entity Processing 2 was not initialized!
Jul 29 23:36:45 steve-pc kernel: uvcvideo 3-8:1.0: Entity type for entity Camera 1 was not initialized!
Jul 29 23:36:45 steve-pc systemd-modules-load[568]: Failed to insert 'vboxsf': No such device
Jul 29 23:36:45 steve-pc kernel: vboxguest: PCI device not found, probably running on physical hardware.
Jul 29 23:36:45 steve-pc systemd-modules-load[568]: Failed to insert 'vboxvideo': No such device
Jul 29 23:36:45 steve-pc systemd[1]: systemd-modules-load.service: Main process exited, code=exited, status=1/FAILURE
Jul 29 23:36:45 steve-pc systemd[1]: Failed to start Load Kernel Modules.
Jul 29 23:36:45 steve-pc systemd[1]: systemd-modules-load.service: Unit entered failed state.
Jul 29 23:36:45 steve-pc systemd[1]: systemd-modules-load.service: Failed with result 'exit-code'.
Jul 29 23:36:47 steve-pc watchdog[584]: starting daemon (5.15):
Jul 29 23:36:48 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.systemd1'
Jul 29 23:36:49 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.
Jul 29 23:36:49 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jul 29 23:36:52 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dis
Jul 29 23:36:52 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jul 29 23:37:02 steve-pc nmbd[923]: [2017/07/29 23:37:02.098026,  0] ../lib/util/become_daemon.c:135(daemon_status)
Jul 29 23:37:02 steve-pc nmbd[923]:   STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...NOTE: NetBIO
Jul 29 23:37:03 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
Jul 29 23:37:04 steve-pc dbus[582]: [system] Activating via systemd: service name='fi.w1.wpa_supplicant1' unit='wpa_supplicant.service'
Jul 29 23:37:04 steve-pc kernel: random: crng init done
Jul 29 23:37:04 steve-pc dbus[582]: [system] Successfully activated service 'fi.w1.wpa_supplicant1'
Jul 29 23:37:07 steve-pc polkitd[995]: Loading rules from directory /etc/polkit-1/rules.d
Jul 29 23:37:07 steve-pc polkitd[995]: Loading rules from directory /usr/share/polkit-1/rules.d
Jul 29 23:37:08 steve-pc polkitd[995]: Finished loading, compiling and executing 6 rules
Jul 29 23:37:08 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Jul 29 23:37:08 steve-pc polkitd[995]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
Jul 29 23:37:10 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dis
Jul 29 23:37:10 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jul 29 23:37:10 steve-pc ntpd[1693]: ntpd 4.2.8p10@1.3728-o Mon Apr 24 18:57:17 UTC 2017 (1): Starting
Jul 29 23:37:10 steve-pc NetworkManager[586]: <warn>  [1501367830.8952] dhcp6 (wlp4s0): hostname is not a FQDN, it will be ignored
Jul 29 23:37:10 steve-pc ntpd[1820]: bind(21) AF_INET6 2a02:c7f:5e49:1000:b823:c63f:f544:372a#123 flags 0x11 failed: Cannot assign requested addres
Jul 29 23:37:10 steve-pc ntpd[1820]: unable to create socket on wlp4s0 (5) for 2a02:c7f:5e49:1000:b823:c63f:f544:372a#123
Jul 29 23:37:10 steve-pc ntpd[1820]: bind(21) AF_INET6 fd69:7bd4:9230:0:1537:370c:823b:3d4#123 flags 0x11 failed: Cannot assign requested address
Jul 29 23:37:10 steve-pc ntpd[1820]: unable to create socket on wlp4s0 (6) for fd69:7bd4:9230:0:1537:370c:823b:3d4#123
Jul 29 23:37:12 steve-pc nmbd[923]: [2017/07/29 23:37:12.198330,  0] ../lib/util/become_daemon.c:124(daemon_ready)
Jul 29 23:37:12 steve-pc nmbd[923]:   STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Jul 29 23:37:12 steve-pc smbd[1514]: [2017/07/29 23:37:12.062920,  0] ../lib/util/become_daemon.c:124(daemon_ready)
Jul 29 23:37:12 steve-pc smbd[1514]:   STATUS=daemon 'smbd' finished starting up and ready to serve connections
Jul 29 23:37:14 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.ColorManager' unit='colord.service'
Jul 29 23:37:15 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.ColorManager'
Jul 29 23:37:22 steve-pc colord[2521]: failed to get session [pid 2122]: No data available
Jul 29 23:37:35 steve-pc nmbd[923]: [2017/07/29 23:37:35.224813,  0] ../source3/nmbd/nmbd_become_lmb.c:397(become_local_master_stage2)
Jul 29 23:37:35 steve-pc nmbd[923]:   *****
Jul 29 23:37:35 steve-pc nmbd[923]:   
Jul 29 23:37:35 steve-pc nmbd[923]:   Samba name server STEVE-PC is now a local master browser for workgroup MANJARO on subnet 192.168.0.2
Jul 29 23:37:35 steve-pc nmbd[923]:   
Jul 29 23:37:35 steve-pc nmbd[923]:   *****
Jul 29 23:37:59 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.UDisks2' unit='udisks2.service'
Jul 29 23:38:00 steve-pc udisksd[3454]: udisks daemon version 2.7.1 starting
Jul 29 23:38:03 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.UDisks2'
Jul 29 23:38:03 steve-pc udisksd[3454]: Acquired the name org.freedesktop.UDisks2 on the system message bus
Jul 29 23:38:03 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service'
Jul 29 23:38:05 steve-pc upowerd[3463]: energy_full (49.716900) is greater than energy_full_design (48.840000)
Jul 29 23:38:05 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.UPower'
Jul 29 23:38:55 steve-pc kaccess[3661]: kf5.kcoreaddons.kaboutdata: Could not initialize the equivalent properties of Q*Application: no instance (y
Jul 29 23:39:02 steve-pc polkit-kde-authentication-agent-1[3702]: kf5.kcoreaddons.kaboutdata: Could not initialize the equivalent properties of Q*A
Jul 29 23:39:02 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service'
Jul 29 23:39:02 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Jul 29 23:39:05 steve-pc polkitd[995]: Registered Authentication Agent for unix-session:c2 (system bus name :1.32 [/usr/lib/polkit-kde/polkit-kde-a
Jul 29 23:39:07 steve-pc dbus[582]: [system] Activating service name='org.kde.powerdevil.discretegpuhelper' (using servicehelper)
Jul 29 23:39:07 steve-pc dbus[582]: [system] Successfully activated service 'org.kde.powerdevil.discretegpuhelper'
Jul 29 23:39:07 steve-pc dbus[582]: [system] Activating service name='org.kde.powerdevil.backlighthelper' (using servicehelper)
Jul 29 23:39:07 steve-pc dbus[582]: [system] Successfully activated service 'org.kde.powerdevil.backlighthelper'
Jul 29 23:40:36 steve-pc org.gtk.vfs.Daemon[3764]: fusermount: failed to access mountpoint /run/user/1000/gvfs: Permission denied
Jul 29 23:40:40 steve-pc xfce4-notifyd[3556]: Failed to set text '<markup><html>Application successfully started.<br></br>Press <b>F12</b> to use i
Jul 29 23:41:02 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.ModemManager1' unit='dbus-org.freedesktop.ModemM
Jul 29 23:41:02 steve-pc dbus[582]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.ModemManager1.service': Unit dbus-org.fre
Jul 29 23:41:02 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.ModemManager1' unit='dbus-org.freedesktop.ModemM
Jul 29 23:41:02 steve-pc dbus[582]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.ModemManager1.service': Unit dbus-org.fre
Jul 29 23:46:42 steve-pc dbus[582]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dis
Jul 29 23:46:42 steve-pc NetworkManager[586]: <warn>  [1501368402.4504] sup-iface[0xd00820,wlp4s0]: connection disconnected (reason -3)
Jul 29 23:46:42 steve-pc dbus[582]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jul 29 23:46:42 steve-pc ntpd[1820]: ntpd exiting on signal 15 (Terminated)

Here is the journalctl for the 412 boot.

In case anyone wants to try:

md archk
cd archk
wget http://mirrors.kernel.org/archlinux/core/os/x86_64//linux-4.12.3-1-x86_64.pkg.tar.xz
wget http://mirrors.kernel.org/archlinux/core/os/x86_64//linux-4.12.3-1-x86_64.pkg.tar.xz.sig
wget http://mirrors.kernel.org/archlinux/core/os/x86_64/linux-headers-4.12.3-1-x86_64.pkg.tar.xz  
wget http://mirrors.kernel.org/archlinux/core/os/x86_64/linux-headers-4.12.3-1-x86_64.pkg.tar.xz.sig
sudo pacman -U linux-4.12.3-1-x86_64.pkg.tar.xz
sudo pacman -U linux-headers-4.12.3-1-x86_64.pkg.tar.xz
reboot
uname -a

md = mkdir


#16

This problem persists on 4.13 rc2 as well.


#17

I fixed it by disabling mq.

Here is my /etc/default/grub

GRUB_CMDLINE_LINUX_DEFAULT=“quiet acpi_osi= acpi_backlight=intel modprobe.blacklist=iTCO_wdt scsi_mod.use_blk_mq=n elevator=bfq-sq”

after that update-grub and reboot


#18
GRUB_CMDLINE_LINUX_DEFAULT="quiet splash resume=UUID=8882e411-219b-4243-b87a-86d667967b81"

is all I have in mine. Do I need to add?:

scsi_mod.use_blk_mq=n

#19

Yes… in your case… :

GRUB_CMDLINE_LINUX_DEFAULT="quiet splash resume=UUID=8882e411-219b-4243-b87a-86d667967b81 scsi_mod.use_blk_mq=n"

Then run this :

sudo grub-mkconfig -o /boot/grub/grub.cfg

[Unstable Update] 2017-07-29 - Kernels, Virtualbox, spl/zfs, Cinnamon, Pamac
#20

After I disabled blk-mq, the suspend works nicely now. Thanks @mac! You’re the best!!!


Now that I disabled blk-mq, this is what I get when I tried checking its scheduler … :

~ >>> cat /sys/block/sd*/queue/scheduler                                                                                                                             
noop deadline cfq [bfq-sq]

It looks like disabling blk-mq (multi queue) will get you a single queue ???


#21

Perhaps the Manjaro devs could use this patch for now…

http://git.kernel.dk/cgit/linux-block/commit/?h=for-linus&id=765e40b675a9566459ddcb8358ad16f3b8344bbe


#22

The problem is discussed here (pf kernel)

and here


#23

same issue here. running Dell Latitude E6440. kernel 4.12 is no good on this hardware. 4.11 no issue…


#24

A few questions, if I may:

  1. Is the nature of the problem now understood?

  2. If so, is it a kernel problem or something in Manjaro not keeping up with the new kernel?

  3. How long do these kind of problems usually take to officially fix?

Thanks.


#25

Perhaps @philm could give us some input on this issue.


#26

I can confirm this issue is Manjaro specific. I have tried Archlinux with 4.12.3 kernel and Voidlinux with 4.12.4 (both use cfq scheduler) on the same hardware as Manjaro 4.12.3. Only Manjaro hangs after resume from suspend to RAM.

I also can confirm that adding this kernel parameter solved the issue for me:


#27

It looks like the problem has been fixed by pf-kernel’d patch released today. I hope this will find its way to manjaro’s next kernel update!

In the meantime, I have installed the fix mac suggests, but it seems like a workaround rather than a real solution since (as I understand it, I’m still pretty newbish) the fix sacrificies performance for suspend-resume. (I am basing my understanding on this explanation, in a different thread, here.


[Unstable Update] 2017-07-29 - Kernels, Virtualbox, spl/zfs, Cinnamon, Pamac
#28

Thanks for the update.


#30

Start on my hands the 4.12 building with the key mentioned overrided :wink:


#31

I think the forum software is feeling unwell :smiley: Taken 10 minutes to get here.

Will try to reply when it (the software) is feeling better - it is getting on my nerves.


#32

Can someone let us know whether this patch will be included in the Manjaro kernels?