You are not logged in.

#1 2017-01-29 15:09:55

arwidow
Member
Registered: 2017-01-29
Posts: 3

Kernel 4.9.6 & 4.9.8: system hangs after second suspend

After boot, I close the lid, open the lid, system wakes up and works (i3lock's lock screen is shown). When I again close the lid and open it a second time the system hangs (the screen is just black). It doesn't matter how much time was between first and second suspend. This only happens with 4.9.6, is reproducible and doesn't happen with 4.8.13 (so, I reverted back). Here my journalctl:

    ...
    Jan 29 13:46:39 x kernel: Bluetooth: hci0: Debug lock is disabled
    Jan 29 13:46:39 x kernel: Bluetooth: hci0: Minimum firmware build 1 week 10 2014
    Jan 29 13:46:39 x kernel: Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi
    Jan 29 13:46:39 x kernel: xhci_hcd 0000:00:14.0: Cannot set link state.
    Jan 29 13:46:39 x kernel: usb usb2-port2: cannot disable (err = -32)
    Jan 29 13:46:40 x kernel: xhci_hcd 0000:00:14.0: Cannot set link state.
    Jan 29 13:46:40 x kernel: usb usb2-port2: cannot disable (err = -32)
    Jan 29 13:46:40 x kernel: xhci_hcd 0000:00:14.0: Cannot set link state.
    Jan 29 13:46:40 x kernel: usb usb2-port2: cannot disable (err = -32)
    Jan 29 13:46:41 x kernel: [drm] RC6 on
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Waiting for firmware download to complete
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Firmware loaded in 1888007 usecs
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Waiting for device to boot
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Device booted in 10801 usecs
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Failed to send Intel_Write_DDC (-22)
    Jan 29 13:46:43 x kernel: wlp1s0: authenticate with a0:e4:cb:cf:78:49
    Jan 29 13:46:43 x kernel: wlp1s0: send auth to a0:e4:cb:cf:78:49 (try 1/3)
    Jan 29 13:46:43 x kernel: wlp1s0: authenticated
    Jan 29 13:46:43 x kernel: wlp1s0: associate with a0:e4:cb:cf:78:49 (try 1/3)
    Jan 29 13:46:43 x kernel: wlp1s0: RX AssocResp from a0:e4:cb:cf:78:49 (capab=0x11 status=0 aid=2)
    Jan 29 13:46:43 x kernel: wlp1s0: associated
    Jan 29 13:46:43 x wpa_actiond[899]: Interface 'wlp1s0' reestablished connection to network 'o2-WLAN64'
    Jan 29 13:46:43 x kernel: wlp1s0: Limiting TX power to 23 (23 - 0) dBm as advertised by a0:e4:cb:cf:78:49
    Jan 29 13:47:34 x sudo[1296]:        r : TTY=pts/0 ; PWD=/var/log ; USER=root ; COMMAND=/usr/bin/journalctl
    Jan 29 13:47:34 x sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0)
    Jan 29 13:48:55 x sudo[1296]: pam_unix(sudo:session): session closed for user root
    Jan 29 13:48:58 x systemd-logind[338]: Lid closed.
    Jan 29 13:48:58 x systemd-logind[338]: Suspending...
    Jan 29 13:48:58 x systemd[1]: Starting User resume actions...
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Failed to send Intel_Write_DDC (-22)
    Jan 29 13:46:43 x kernel: wlp1s0: authenticate with a0:e4:cb:cf:78:49
    Jan 29 13:46:43 x kernel: wlp1s0: send auth to a0:e4:cb:cf:78:49 (try 1/3)
    Jan 29 13:46:43 x kernel: wlp1s0: authenticated
    Jan 29 13:46:43 x kernel: wlp1s0: associate with a0:e4:cb:cf:78:49 (try 1/3)
    Jan 29 13:46:43 x kernel: wlp1s0: RX AssocResp from a0:e4:cb:cf:78:49 (capab=0x11 status=0 aid=2)
    Jan 29 13:46:43 x kernel: wlp1s0: associated
    Jan 29 13:46:43 x wpa_actiond[899]: Interface 'wlp1s0' reestablished connection to network 'o2-WLAN64'
    Jan 29 13:46:43 x kernel: wlp1s0: Limiting TX power to 23 (23 - 0) dBm as advertised by a0:e4:cb:cf:78:49
    Jan 29 13:47:34 x sudo[1296]:        r : TTY=pts/0 ; PWD=/var/log ; USER=root ; COMMAND=/usr/bin/journalctl
    Jan 29 13:47:34 x sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0)
    Jan 29 13:48:55 x sudo[1296]: pam_unix(sudo:session): session closed for user root
    Jan 29 13:48:58 x systemd-logind[338]: Lid closed.
    Jan 29 13:48:58 x systemd-logind[338]: Suspending...
    Jan 29 13:48:58 x systemd[1]: Starting User resume actions...
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.d
    Jan 29 13:46:41 x kernel: Bluetooth: hci0: Failed to send Intel_Write_DDC (-22)
    Jan 29 13:46:43 x kernel: wlp1s0: authenticate with a0:e4:cb:cf:78:49
    Jan 29 13:46:43 x kernel: wlp1s0: send auth to a0:e4:cb:cf:78:49 (try 1/3)
    Jan 29 13:46:43 x kernel: wlp1s0: authenticated
    Jan 29 13:46:43 x kernel: wlp1s0: associate with a0:e4:cb:cf:78:49 (try 1/3)
    Jan 29 13:46:43 x kernel: wlp1s0: RX AssocResp from a0:e4:cb:cf:78:49 (capab=0x11 statu
    Jan 29 13:46:43 x kernel: wlp1s0: associated
    Jan 29 13:46:43 x wpa_actiond[899]: Interface 'wlp1s0' reestablished connection to netw
    Jan 29 13:46:43 x kernel: wlp1s0: Limiting TX power to 23 (23 - 0) dBm as advertised by
    Jan 29 13:47:34 x sudo[1296]:        r : TTY=pts/0 ; PWD=/var/log ; USER=root ; COMMAND
    Jan 29 13:47:34 x sudo[1296]: pam_unix(sudo:session): session opened for user root by (

I closed the lid the second time ...

    Jan 29 13:48:55 x sudo[1296]: pam_unix(sudo:session): session closed for user root
    Jan 29 13:48:58 x systemd-logind[338]: Lid closed.
    Jan 29 13:48:58 x systemd-logind[338]: Suspending...
    Jan 29 13:48:58 x systemd[1]: Starting User resume actions...
    Jan 29 13:48:58 x systemd[1]: Reached target Sleep.
    Jan 29 13:48:58 x systemd[1]: Starting Suspend...

I opened the lid the second time, systems hangs, I restart the system by pressing the power button long and then again.

    -- Reboot --
    Jan 29 13:49:48 x systemd-journald[250]: Time spent on flushing to /var is 1.147ms for
    Jan 29 13:49:48 x kernel: Linux version 4.9.6-1-ARCH (builduser@tobias) (gcc version 6.
    Jan 29 13:49:48 x kernel: Command line: initrd=\initramfs-linux.img cryptdevice=UUID=6b
    Jan 29 13:49:48 x kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point

Last edited by arwidow (2017-02-14 08:19:42)

Offline

#2 2017-02-14 08:18:56

arwidow
Member
Registered: 2017-01-29
Posts: 3

Re: Kernel 4.9.6 & 4.9.8: system hangs after second suspend

I still have this problem with kernel 4.9.8, so I downgraded again the kernel to 4.8.13

Offline

#3 2017-02-15 19:37:59

alex.forencich
Member
Registered: 2011-05-29
Posts: 96

Re: Kernel 4.9.6 & 4.9.8: system hangs after second suspend

I am seeing a similar issue with a hang on suspend.  Definitely some sort of regression.  I have not investigated how to repeat the issue.

Offline

#4 2017-02-19 06:17:35

i026e
Member
Registered: 2017-02-19
Posts: 1

Re: Kernel 4.9.6 & 4.9.8: system hangs after second suspend

I also have similar problem with kernels 4.9.8 & 4.9.9
System hangs up after suspend, can only restart the system

10:28:30 pc kernel: PM: Preparing system for sleep (mem)
10:28:30 pc kernel: Freezing user space processes ... (elapsed 0.016 seconds) done.
10:28:30 pc kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
10:28:30 pc kernel: PM: Suspending system (mem)
10:28:30 pc kernel: Suspending console(s) (use no_console_suspend to debug)
10:28:30 pc kernel: sd 2:0:0:0: [sdb] Synchronizing SCSI cache
10:28:30 pc kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
10:28:30 pc kernel: sd 2:0:0:0: [sdb] Stopping disk
10:28:30 pc kernel: sd 0:0:0:0: [sda] Stopping disk
10:28:30 pc kernel: ACPI : EC: event blocked
10:28:30 pc kernel: PM: suspend of devices complete after 570.896 msecs
10:28:30 pc kernel: PM: late suspend of devices complete after 16.758 msecs
10:28:30 pc kernel: ACPI : EC: interrupt blocked
10:28:30 pc kernel: ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
10:28:30 pc kernel: xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
10:28:30 pc kernel: PM: noirq suspend of devices complete after 16.842 msecs
10:28:30 pc kernel: ACPI: Preparing to enter system sleep state S3
10:28:30 pc kernel: ACPI : EC: EC stopped
10:28:30 pc kernel: PM: Saving platform NVS memory
10:28:30 pc kernel: Disabling non-boot CPUs ...
10:28:30 pc kernel: smpboot: CPU 1 is now offline
10:28:30 pc kernel: smpboot: CPU 2 is now offline
10:28:30 pc kernel: smpboot: CPU 3 is now offline
10:28:30 pc kernel: ACPI: Low-level resume complete
10:28:30 pc kernel: ACPI : EC: EC started
10:28:30 pc kernel: PM: Restoring platform NVS memory
10:28:30 pc kernel: Suspended for 58487.000 seconds
10:28:30 pc kernel: Enabling non-boot CPUs ...
10:28:30 pc kernel: x86: Booting SMP configuration:
10:28:30 pc kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
10:28:30 pc kernel:  cache: parent cpu1 should not be sleeping
10:28:30 pc kernel: CPU1 is up
10:28:30 pc kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
10:28:30 pc kernel:  cache: parent cpu2 should not be sleeping
10:28:30 pc kernel: CPU2 is up
10:28:30 pc kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
10:28:30 pc kernel:  cache: parent cpu3 should not be sleeping
10:28:30 pc kernel: CPU3 is up
10:28:30 pc kernel: ACPI: Waking up from system sleep state S3
10:28:30 pc kernel: ACPI : EC: interrupt unblocked
10:28:30 pc kernel: xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
10:28:30 pc kernel: ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
10:28:30 pc kernel: PM: noirq resume of devices complete after 17.129 msecs
10:28:30 pc kernel: PM: early resume of devices complete after 0.379 msecs
10:28:30 pc kernel: ACPI : EC: event unblocked
10:28:30 pc kernel: iwlwifi 0000:02:00.0: RF_KILL bit toggled to disable radio.
10:28:30 pc kernel: sd 2:0:0:0: [sdb] Starting disk
10:28:30 pc kernel: sd 0:0:0:0: [sda] Starting disk
10:28:30 pc kernel: ACPI : button: The lid device is not compliant to SW_LID.
10:28:30 pc kernel: rtc_cmos 00:01: System wakeup disabled by ACPI
10:28:30 pc kernel: usb 1-1.3: reset high-speed USB device number 3 using ehci-pci
10:28:30 pc kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
10:28:30 pc kernel: ata1.00: supports DRM functions and may not be fully accessible
10:28:30 pc kernel: ata1.00: disabling queued TRIM support
10:28:30 pc kernel: ata1.00: supports DRM functions and may not be fully accessible
10:28:30 pc kernel: ata1.00: disabling queued TRIM support
10:28:30 pc kernel: ata1.00: configured for UDMA/133
10:28:30 pc kernel: usb 1-1.5: reset high-speed USB device number 4 using ehci-pci
10:28:30 pc kernel: usb 3-3.4: reset low-speed USB device number 3 using xhci_hcd
10:28:30 pc kernel: usb 3-3.1: reset high-speed USB device number 4 using xhci_hcd
10:28:30 pc kernel: PM: resume of devices complete after 880.349 msecs
10:28:30 pc kernel: PM: Finishing wakeup.
10:28:30 pc kernel: Restarting tasks ... done.
10:28:30 pc systemd[1]: Time has been changed
10:28:30 pc systemd[12191]: Time has been changed
10:28:30 pc systemd-udevd[656]: seq 3484 '/kernel/slab/:t-0001024/cgroup/kmalloc-1024(1461:systemd-suspend.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3460 '/kernel/slab/:tA-0000704/cgroup/files_cache(1449:tlp-sleep.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3483 '/kernel/slab/:t-0000256/cgroup/kmalloc-256(1461:systemd-suspend.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3482 '/kernel/slab/:tA-0000128/cgroup/pid(1461:systemd-suspend.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3464 '/kernel/slab/sighand_cache/cgroup/sighand_cache(1449:tlp-sleep.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3481 '/kernel/slab/:tA-0001088/cgroup/signal_cache(1461:systemd-suspend.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3480 '/kernel/slab/sighand_cache/cgroup/sighand_cache(1461:systemd-suspend.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3479 '/kernel/slab/:tA-0000704/cgroup/files_cache(1461:systemd-suspend.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3478 '/kernel/slab/:tA-0003648/cgroup/task_struct(1461:systemd-suspend.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3474 '/kernel/slab/anon_vma/cgroup/anon_vma(1461:systemd-suspend.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3469 '/kernel/slab/ext4_inode_cache/cgroup/ext4_inode_cache(1449:tlp-sleep.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3467 '/kernel/slab/:tA-0000192/cgroup/cred_jar(1461:systemd-suspend.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3478 '/kernel/slab/:tA-0003648/cgroup/task_struct(1461:systemd-suspend.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3461 '/kernel/slab/:t-0000064/cgroup/kmalloc-64(1449:tlp-sleep.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3460 '/kernel/slab/:tA-0000704/cgroup/files_cache(1449:tlp-sleep.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3484 '/kernel/slab/:t-0001024/cgroup/kmalloc-1024(1461:systemd-suspend.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3483 '/kernel/slab/:t-0000256/cgroup/kmalloc-256(1461:systemd-suspend.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3482 '/kernel/slab/:tA-0000128/cgroup/pid(1461:systemd-suspend.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3481 '/kernel/slab/:tA-0001088/cgroup/signal_cache(1461:systemd-suspend.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3480 '/kernel/slab/sighand_cache/cgroup/sighand_cache(1461:systemd-suspend.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3464 '/kernel/slab/sighand_cache/cgroup/sighand_cache(1449:tlp-sleep.service)' killed
10:28:30 pc systemd-udevd[656]: seq 3479 '/kernel/slab/:tA-0000704/cgroup/files_cache(1461:systemd-suspend.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3474 '/kernel/slab/anon_vma/cgroup/anon_vma(1461:systemd-suspend.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3469 '/kernel/slab/ext4_inode_cache/cgroup/ext4_inode_cache(1449:tlp-sleep.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3467 '/kernel/slab/:tA-0000192/cgroup/cred_jar(1461:systemd-suspend.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: seq 3461 '/kernel/slab/:t-0000064/cgroup/kmalloc-64(1449:tlp-sleep.service)' is taking a long time
10:28:30 pc systemd-udevd[656]: worker [30846] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30846] failed while handling '/kernel/slab/:tA-0001088/cgroup/signal_cache(1461:systemd-suspend.service)'
10:28:30 pc systemd-udevd[656]: worker [30848] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30848] failed while handling '/kernel/slab/:tA-0000704/cgroup/files_cache(1461:systemd-suspend.service)'
10:28:30 pc systemd-udevd[656]: worker [30850] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30850] failed while handling '/kernel/slab/:t-0001024/cgroup/kmalloc-1024(1461:systemd-suspend.service)'
10:28:30 pc systemd-udevd[656]: worker [30851] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30851] failed while handling '/kernel/slab/:tA-0000128/cgroup/pid(1461:systemd-suspend.service)'
10:28:30 pc systemd-udevd[656]: worker [30852] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30852] failed while handling '/kernel/slab/anon_vma/cgroup/anon_vma(1461:systemd-suspend.service)'
10:28:30 pc systemd-udevd[656]: worker [30853] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30853] failed while handling '/kernel/slab/:tA-0000704/cgroup/files_cache(1449:tlp-sleep.service)'
10:28:30 pc systemd-udevd[656]: worker [30856] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30856] failed while handling '/kernel/slab/:t-0000064/cgroup/kmalloc-64(1449:tlp-sleep.service)'
10:28:30 pc systemd-udevd[656]: worker [30860] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30860] failed while handling '/kernel/slab/sighand_cache/cgroup/sighand_cache(1461:systemd-suspend.service)'
10:28:30 pc systemd-udevd[656]: worker [30861] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30861] failed while handling '/kernel/slab/sighand_cache/cgroup/sighand_cache(1449:tlp-sleep.service)'
10:28:30 pc systemd-udevd[656]: worker [30862] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30862] failed while handling '/kernel/slab/:t-0000256/cgroup/kmalloc-256(1461:systemd-suspend.service)'
10:28:30 pc systemd-udevd[656]: worker [30863] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30863] failed while handling '/kernel/slab/:tA-0003648/cgroup/task_struct(1461:systemd-suspend.service)'
10:28:30 pc systemd-udevd[656]: worker [30865] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30865] failed while handling '/kernel/slab/:tA-0000192/cgroup/cred_jar(1461:systemd-suspend.service)'
10:28:30 pc systemd-udevd[656]: worker [30867] terminated by signal 9 (Killed)
10:28:30 pc systemd-udevd[656]: worker [30867] failed while handling '/kernel/slab/ext4_inode_cache/cgroup/ext4_inode_cache(1449:tlp-sleep.service)'
10:28:30 pc kernel: xhci_hcd 0000:00:14.0: Cannot set link state.
10:28:30 pc kernel: usb usb4-port3: cannot disable (err = -32)
10:28:30 pc kernel: xhci_hcd 0000:00:14.0: Cannot set link state.
10:28:30 pc kernel: usb usb4-port3: cannot disable (err = -32)
10:28:30 pc kernel: xhci_hcd 0000:00:14.0: Cannot set link state.
10:28:30 pc kernel: usb usb4-port3: cannot disable (err = -32)
10:28:30 pc kernel: xhci_hcd 0000:00:14.0: Cannot set link state.
10:28:30 pc kernel: usb usb4-port4: cannot disable (err = -32)
10:28:30 pc systemd-sleep[30854]: System resumed.
10:28:30 pc systemd[1]: Started Suspend.
10:28:30 pc systemd[1]: sleep.target: Unit not needed anymore. Stopping.
10:28:30 pc systemd[1]: Stopped target Sleep.
10:28:30 pc systemd[1]: tlp-sleep.service: Unit not needed anymore. Stopping.
10:28:30 pc systemd[1]: Stopping TLP suspend/resume...
10:28:30 pc systemd[1]: Reached target Suspend.
10:28:30 pc systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too.
10:28:30 pc systemd[1]: Stopped target Suspend.
10:28:30 pc systemd-logind[6692]: Operation 'sleep' finished.
10:28:30 pc NetworkManager[6686]: <info>  [1487402910.5495] manager: wake requested (sleeping: yes  enabled: yes)
10:28:30 pc NetworkManager[6686]: <info>  [1487402910.5495] manager: waking up...
10:28:30 pc NetworkManager[6686]: <info>  [1487402910.5496] device (enp1s0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
10:28:30 pc kernel: IPv6: ADDRCONF(NETDEV_UP): enp1s0: link is not ready
10:28:30 pc kernel: ------------[ cut here ]------------
10:28:30 pc kernel: kernel BUG at block/bfq.h:864!
10:28:30 pc kernel: invalid opcode: 0000 [#1] PREEMPT SMP
10:28:30 pc kernel: Modules linked in: sg st sr_mod cdrom ipt_MASQUERADE nf_nat_masquerade_ipv4 xfrm_user xfrm_algo iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 xt_addrtype iptable_filter xt_conntrack nf_nat nf_conntrack br_netfilter bridge stp llc aufs joydev mmc_block mousedev uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_core videodev media rtsx_usb_ms memstick tun zram fuse iTCO_wdt iTCO_vendor_support dell_wmi sparse_keymap dell_laptop dell_smbios dcdbas dell_smm_hwmon intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm nls_iso8859_1 irqbypass nls_cp437 crct10dif_pclmul crc32_pclmul vfat fat crc32c_intel ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd intel_cstate intel_rapl_perf evdev input_leds mac_hid psmouse
10:28:30 pc kernel:  pcspkr arc4 iwldvm mac80211 iwlwifi cfg80211 rfkill snd_hda_codec_hdmi snd_hda_codec_conexant snd_hda_codec_generic r8169 i2c_i801 mii i2c_smbus snd_hda_intel lpc_ich snd_hda_codec snd_hda_core snd_hwdep snd_pcm snd_timer snd shpchp soundcore wmi battery i915 drm_kms_helper drm intel_gtt ac syscopyarea sysfillrect sysimgblt fb_sys_fops video i2c_algo_bit mei_me mei fjes button tpm_tis tpm_tis_core tpm sch_fq_codel pci_stub vboxpci(O) vboxnetflt(O) vboxnetadp(O) vboxdrv(O) ip_tables x_tables ext4 crc16 jbd2 fscrypto mbcache hid_generic usbhid hid rtsx_usb_sdmmc led_class mmc_core rtsx_usb sd_mod serio_raw atkbd libps2 ahci libahci libata xhci_pci ehci_pci xhci_hcd ehci_hcd usbcore scsi_mod usb_common i8042 serio
10:28:30 pc kernel: CPU: 1 PID: 30843 Comm: kworker/1:59 Tainted: G           O    4.9.9-1-MANJARO #1
10:28:30 pc kernel: Hardware name: Dell Inc. Inspiron 5520/04G65K, BIOS A14 05/13/2013
10:28:30 pc kernel: Workqueue: cgroup_destroy css_killed_work_fn
10:28:30 pc kernel: task: ffff8801182b1c80 task.stack: ffffc900023f4000
10:28:30 pc kernel: RIP: 0010:[<ffffffff8130727c>]  [<ffffffff8130727c>] bfq_entity_service_tree+0x18c/0x1f0
10:28:30 pc kernel: RSP: 0018:ffffc900023f7ac8  EFLAGS: 00010046
10:28:30 pc kernel: RAX: 0000000000000001 RBX: ffff88001e833810 RCX: ffff88024f2578e0
10:28:30 pc kernel: RDX: ffff880234ca0408 RSI: 0000000000000000 RDI: ffff88001e833810
10:28:30 pc kernel: RBP: ffffc900023f7b78 R08: 0000000000000000 R09: ffff88024f2578e0
10:28:30 pc kernel: R10: 00000000000000ac R11: 0000000000000000 R12: 0000000000000000
10:28:30 pc kernel: R13: 0000000000000001 R14: 0000000000000000 R15: ffff88001e833980
10:28:30 pc kernel: FS:  0000000000000000(0000) GS:ffff88024f240000(0000) knlGS:0000000000000000
10:28:30 pc kernel: CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
10:28:30 pc kernel: CR2: 00007f367531c2f8 CR3: 00000002442fd000 CR4: 00000000001406e0
10:28:30 pc kernel: Stack:
10:28:30 pc kernel:  0000000000000246 dead000000000200 0000000000000000 000000010035b658
10:28:30 pc kernel:  ffffffff810f0640 ffff8801182b1c80 ffffffff13000001 0000000000000000
10:28:30 pc kernel:  0000000000000000 0000000000000000 000000002198cce9 000000000000012c
10:28:30 pc kernel: Call Trace:
10:28:30 pc kernel:  [<ffffffff810f0640>] ? del_timer_sync+0x50/0x50
10:28:30 pc kernel:  [<ffffffff81309c21>] __bfq_deactivate_entity+0x21/0x1c0
10:28:30 pc kernel:  [<ffffffff8130e044>] bfq_pd_offline+0xf4/0x1a0
10:28:30 pc kernel:  [<ffffffff812f59ce>] blkg_destroy+0x8e/0x340
10:28:30 pc kernel:  [<ffffffff8146cd45>] ? rpm_idle+0x25/0x2b0
10:28:30 pc kernel:  [<ffffffff8146caae>] ? rpm_suspend+0x50e/0x690
10:28:30 pc kernel:  [<ffffffff810cafa6>] ? cpuacct_charge+0x86/0xa0
10:28:30 pc kernel:  [<ffffffff810b5e05>] ? update_curr+0xe5/0x190
10:28:30 pc kernel:  [<ffffffff810f1c3d>] ? enqueue_hrtimer+0x3d/0xa0
10:28:30 pc kernel:  [<ffffffff810b6b0f>] ? dequeue_entity+0x24f/0xc60
10:28:30 pc kernel:  [<ffffffffa04586a9>] ? __gen6_gt_wait_for_thread_c0+0x39/0x70 [i915]
10:28:30 pc kernel:  [<ffffffff810b2e8c>] ? set_next_entity+0x4c/0xa10
10:28:30 pc kernel:  [<ffffffff810b80b3>] ? put_prev_entity+0x33/0x870
10:28:30 pc kernel:  [<ffffffff8102d752>] ? __switch_to+0x2d2/0x630
10:28:30 pc kernel:  [<ffffffff810be701>] ? pick_next_task_fair+0x451/0x4c0
10:28:30 pc kernel:  [<ffffffff812f6073>] blkcg_css_offline+0x53/0xa0
10:28:30 pc kernel:  [<ffffffff811164c5>] css_killed_work_fn+0x75/0x100
10:28:30 pc kernel:  [<ffffffff81098ae5>] process_one_work+0x1e5/0x470
10:28:30 pc kernel:  [<ffffffff81098db8>] worker_thread+0x48/0x4e0
10:28:30 pc kernel:  [<ffffffff81098d70>] ? process_one_work+0x470/0x470
10:28:30 pc kernel:  [<ffffffff81098d70>] ? process_one_work+0x470/0x470
10:28:30 pc kernel: xhci_hcd 0000:00:14.0: Cannot set link state.
10:28:30 pc kernel: usb usb4-port4: cannot disable (err = -32)
10:28:30 pc kernel: xhci_hcd 0000:00:14.0: Cannot set link state.
10:28:30 pc kernel: usb usb4-port4: cannot disable (err = -32)
10:28:30 pc kernel:  [<ffffffff8109e949>] kthread+0xd9/0xf0
10:28:30 pc kernel:  [<ffffffff8102d752>] ? __switch_to+0x2d2/0x630
10:28:30 pc kernel:  [<ffffffff8109e870>] ? kthread_park+0x60/0x60
10:28:30 pc kernel:  [<ffffffff81619b55>] ret_from_fork+0x25/0x30
10:28:30 pc kernel: Code: 70 44 94 81 48 c1 e0 04 49 8d 5c 06 10 48 89 d9 e8 2a 12 e4 ff 48 89 d8 e9 6b ff ff ff e8 7d f5 ff ff 0f 0b 31 c0 e9 16 ff ff ff <0f> 0b 0f 0b 44 89 e8 41 8b 94 24 44 01 00 00 4c 8d 85 58 ff ff 
10:28:30 pc kernel: RIP  [<ffffffff8130727c>] bfq_entity_service_tree+0x18c/0x1f0
10:28:30 pc kernel:  RSP <ffffc900023f7ac8>
10:28:30 pc kernel: ---[ end trace 091ba1986916b059 ]---
10:28:30 pc kernel: note: kworker/1:59[30843] exited with preempt_count 2
10:28:30 pc kernel: BUG: unable to handle kernel paging request at 000000002198cce9

Downgrading to 4.8.17 resolves the problem

Offline

#5 2017-02-21 16:06:29

rsk002
Member
Registered: 2017-02-03
Posts: 17

Re: Kernel 4.9.6 & 4.9.8: system hangs after second suspend

I used to have a similar issue though not necessarily consistent. Eventually, a suspend would cause the system to hang. After Googling, I found a work-around somewhere. It involved disabling asynchronous suspend by "echo 0 > /sys/power/pm_async". At the very least, it will allow you to determine what may be causing the machine to hang.

If it works, you can make the change permanent by adding a file to /etc/tmpfiles.d so that systemd will take care of toggling the flag for you. I have the following:

/etc/tmpfiles.d/no_pm_async.conf:

w /sys/power/pm_async - - - - 0

Offline

#6 2018-12-12 06:39:49

b3n10
Member
Registered: 2018-11-03
Posts: 14

Re: Kernel 4.9.6 & 4.9.8: system hangs after second suspend

rsk002 wrote:

I used to have a similar issue though not necessarily consistent. Eventually, a suspend would cause the system to hang. After Googling, I found a work-around somewhere. It involved disabling asynchronous suspend by "echo 0 > /sys/power/pm_async". At the very least, it will allow you to determine what may be causing the machine to hang.

has anyone found a fix? disabling asynchronous suspend as mentioned above didn't work for me.

I am using 4.19.8-arch1-1-ARCH on my thinkpad x220.

Offline

Board footer

Powered by FluxBB