You are not logged in.

#1 2019-01-01 16:05:04

snack
Member
From: Italy
Registered: 2009-01-13
Posts: 861

[SOLVED] Suspend issue with Dell XPS 9570

Hi, my laptop looks like it has some problem with suspend to ram. At first sight suspending it using the Suspend entry in the KDE menu works:  the screen goes blank, the keyboard backlight is shut down, the fans stop to spin etc. But I noticed some quite high power drain while at sleep (3-5% per hour) so I took a look at dmesg and here's what appears around suspend and resume:

[Tue Jan  1 16:49:20 2019] PM: suspend entry (deep)
[Tue Jan  1 16:49:20 2019] PM: Syncing filesystems ... done.
[Tue Jan  1 16:49:21 2019] Freezing user space processes ... (elapsed 0.001 seconds) done.
[Tue Jan  1 16:49:21 2019] OOM killer disabled.
[Tue Jan  1 16:49:21 2019] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[Tue Jan  1 16:49:21 2019] printk: Suspending console(s) (use no_console_suspend to debug)
[Tue Jan  1 16:49:21 2019] wlan0: deauthenticating from 00:24:b2:38:fc:18 by local choice (Reason: 3=DEAUTH_LEAVING)
[Tue Jan  1 16:49:21 2019] psmouse serio1: Failed to disable mouse on isa0060/serio1
[Tue Jan  1 16:49:22 2019] ACPI: EC: interrupt blocked
[Tue Jan  1 16:49:22 2019] ACPI: Preparing to enter system sleep state S3
[Tue Jan  1 16:49:22 2019] ACPI: EC: event blocked
[Tue Jan  1 16:49:22 2019] ACPI: EC: EC stopped
[Tue Jan  1 16:49:22 2019] PM: Saving platform NVS memory
[Tue Jan  1 16:49:22 2019] Disabling non-boot CPUs ...
[Tue Jan  1 16:49:22 2019] Removed affinity for 343 processes to cpu 1
[Tue Jan  1 16:49:22 2019] smpboot: CPU 1 is now offline
[Tue Jan  1 16:49:22 2019] Removed affinity for 343 processes to cpu 2
[Tue Jan  1 16:49:22 2019] smpboot: CPU 2 is now offline
[Tue Jan  1 16:49:23 2019] Removed affinity for 343 processes to cpu 3
[Tue Jan  1 16:49:23 2019] smpboot: CPU 3 is now offline
[Tue Jan  1 16:49:23 2019] Removed affinity for 342 processes to cpu 4
[Tue Jan  1 16:49:23 2019] smpboot: CPU 4 is now offline
[Tue Jan  1 16:49:23 2019] Removed affinity for 343 processes to cpu 5
[Tue Jan  1 16:49:23 2019] smpboot: CPU 5 is now offline
[Tue Jan  1 16:49:23 2019] Removed affinity for 342 processes to cpu 6
[Tue Jan  1 16:49:23 2019] smpboot: CPU 6 is now offline
[Tue Jan  1 16:49:23 2019] Removed affinity for 342 processes to cpu 7
[Tue Jan  1 16:49:23 2019] smpboot: CPU 7 is now offline
[Tue Jan  1 16:49:23 2019] Removed affinity for 342 processes to cpu 8
[Tue Jan  1 16:49:23 2019] smpboot: CPU 8 is now offline
[Tue Jan  1 16:49:23 2019] Removed affinity for 342 processes to cpu 9
[Tue Jan  1 16:49:23 2019] smpboot: CPU 9 is now offline
[Tue Jan  1 16:49:23 2019] Removed affinity for 343 processes to cpu 10
[Tue Jan  1 16:49:23 2019] smpboot: CPU 10 is now offline
[Tue Jan  1 16:49:23 2019] Removed affinity for 341 processes to cpu 11
[Tue Jan  1 16:49:23 2019] smpboot: CPU 11 is now offline
[Tue Jan  1 16:49:23 2019] ACPI: Low-level resume complete
[Tue Jan  1 16:49:23 2019] ACPI: EC: EC started
[Tue Jan  1 16:49:23 2019] PM: Restoring platform NVS memory
[Tue Jan  1 16:49:23 2019] Enabling non-boot CPUs ...
[Tue Jan  1 16:49:23 2019] x86: Booting SMP configuration:
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 1 APIC 0x2
[Tue Jan  1 16:49:23 2019]  cache: parent cpu1 should not be sleeping
[Tue Jan  1 16:49:23 2019] intel_pstate: Disabling energy efficiency optimization
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 1
[Tue Jan  1 16:49:23 2019] CPU1 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 2 APIC 0x4
[Tue Jan  1 16:49:23 2019]  cache: parent cpu2 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 2
[Tue Jan  1 16:49:23 2019] CPU2 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 3 APIC 0x6
[Tue Jan  1 16:49:23 2019]  cache: parent cpu3 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 3
[Tue Jan  1 16:49:23 2019] CPU3 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 4 APIC 0x8
[Tue Jan  1 16:49:23 2019]  cache: parent cpu4 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 4
[Tue Jan  1 16:49:23 2019] CPU4 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 5 APIC 0xa
[Tue Jan  1 16:49:23 2019]  cache: parent cpu5 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 5
[Tue Jan  1 16:49:23 2019] CPU5 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 6 APIC 0x1
[Tue Jan  1 16:49:23 2019]  cache: parent cpu6 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 6
[Tue Jan  1 16:49:23 2019] CPU6 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 7 APIC 0x3
[Tue Jan  1 16:49:23 2019]  cache: parent cpu7 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 7
[Tue Jan  1 16:49:23 2019] CPU7 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 8 APIC 0x5
[Tue Jan  1 16:49:23 2019]  cache: parent cpu8 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 8
[Tue Jan  1 16:49:23 2019] CPU8 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 9 APIC 0x7
[Tue Jan  1 16:49:23 2019]  cache: parent cpu9 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 9
[Tue Jan  1 16:49:23 2019] CPU9 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 10 APIC 0x9
[Tue Jan  1 16:49:23 2019]  cache: parent cpu10 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 10
[Tue Jan  1 16:49:23 2019] CPU10 is up
[Tue Jan  1 16:49:23 2019] smpboot: Booting Node 0 Processor 11 APIC 0xb
[Tue Jan  1 16:49:23 2019]  cache: parent cpu11 should not be sleeping
[Tue Jan  1 16:49:23 2019] Added affinity for 262 processes to cpu 11
[Tue Jan  1 16:49:23 2019] Released forced binding to cpu0 for 262 processes
[Tue Jan  1 16:49:23 2019] CPU11 is up
[Tue Jan  1 16:49:23 2019] ACPI: Waking up from system sleep state S3
[Tue Jan  1 16:49:24 2019] ACPI: EC: interrupt unblocked
[Tue Jan  1 16:49:24 2019] ACPI: EC: event unblocked
[Tue Jan  1 16:49:24 2019] ACPI: button: The lid device is not compliant to SW_LID.
[Tue Jan  1 16:49:24 2019] usb 1-4: reset full-speed USB device number 2 using xhci_hcd
[Tue Jan  1 16:49:24 2019] ath10k_pci 0000:3b:00.0: unsupported HTC service id: 1536
[Tue Jan  1 16:49:24 2019] ath10k_pci 0000:3b:00.0: Unknown eventid: 118809
[Tue Jan  1 16:49:24 2019] WARNING: CPU: 6 PID: 1261 at drivers/net/wireless/ath/ath10k/mac.c:5650 ath10k_bss_info_changed+0xfb6/0x1120 [ath10k_core]
[Tue Jan  1 16:49:24 2019] Modules linked in: ccm mei_wdt hid_multitouch hid_generic snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic iTCO_wdt iTCO_vendor_support joydev mousedev arc4 cdc_acm dell_wmi wmi_bmof intel_wmi_thunderbolt mxm_wmi dell_laptop dell_smbios dell_wmi_descriptor dcdbas intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel i915 ath10k_pci ath10k_core kvmgt vfio_mdev mdev ath vfio_iommu_type1 vfio mac80211 kvm btusb btrtl btbcm btintel irqbypass crct10dif_pclmul snd_hda_intel crc32_pclmul i2c_algo_bit ghash_clmulni_intel bluetooth snd_hda_codec drm_kms_helper aesni_intel aes_x86_64 snd_hda_core crypto_simd snd_hwdep ecdh_generic tpm_crb drm cfg80211 idma64 cryptd glue_helper snd_pcm intel_cstate intel_uncore psmouse intel_gtt snd_timer intel_rapl_perf agpgart snd mei_me syscopyarea input_leds pcspkr sysfillrect sysimgblt rfkill rtsx_pci_ms fb_sys_fops soundcore mei memstick i2c_i801 i2c_hid intel_lpss_pci ucsi_acpi processor_thermal_device typec_ucsi
[Tue Jan  1 16:49:24 2019]  intel_lpss intel_soc_dts_iosf intel_pch_thermal hid typec battery int3403_thermal tpm_tis int340x_thermal_zone tpm_tis_core wmi dell_smo8800 tpm intel_hid evdev rng_core pcc_cpufreq int3400_thermal mac_hid acpi_thermal_rel sparse_keymap ac crypto_user ip_tables x_tables ext4 crc32c_generic crc16 mbcache jbd2 fscrypto rtsx_pci_sdmmc serio_raw mmc_core atkbd ahci libps2 libahci libata xhci_pci xhci_hcd crc32c_intel scsi_mod rtsx_pci i8042 serio
[Tue Jan  1 16:49:24 2019] CPU: 6 PID: 1261 Comm: kworker/u24:31 Not tainted 4.20.0-1-ck-skylake #1
[Tue Jan  1 16:49:24 2019] Hardware name: Dell Inc. XPS 15 9570/0D0T05, BIOS 1.3.0 07/12/2018
[Tue Jan  1 16:49:24 2019] Workqueue: events_unbound async_run_entry_fn
[Tue Jan  1 16:49:24 2019] RIP: 0010:ath10k_bss_info_changed+0xfb6/0x1120 [ath10k_core]
[Tue Jan  1 16:49:24 2019] Code: 40 67 00 00 e9 a6 f1 ff ff 0f 0b 4c 89 f7 e8 a1 8e 6a f8 e9 fd f3 ff ff b8 40 00 00 00 e9 63 f5 ff ff 8b 95 70 01 00 00 eb c4 <0f> 0b e9 01 f3 ff ff 89 c2 48 c7 c6 40 83 da c0 4c 89 cf 4c 89 44
[Tue Jan  1 16:49:24 2019] RSP: 0000:ffff9f25013d7cd0 EFLAGS: 00010282
[Tue Jan  1 16:49:24 2019] RAX: 00000000fffffffe RBX: ffff916c61021580 RCX: 0000000000000000
[Tue Jan  1 16:49:24 2019] RDX: ffff916c610218f8 RSI: ffff9f25013d7cf0 RDI: ffff916c66db73a0
[Tue Jan  1 16:49:24 2019] RBP: ffff916c66db73a0 R08: 0000000000000001 R09: 0000000000000000
[Tue Jan  1 16:49:24 2019] R10: 0000000000000000 R11: ffff916c59316500 R12: 00000000020674ff
[Tue Jan  1 16:49:24 2019] R13: ffff916c66db73a8 R14: ffff916c61022558 R15: ffff916c61021580
[Tue Jan  1 16:49:24 2019] FS:  0000000000000000(0000) GS:ffff916c6c380000(0000) knlGS:0000000000000000
[Tue Jan  1 16:49:24 2019] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[Tue Jan  1 16:49:24 2019] CR2: 0000000000000000 CR3: 00000002a7e0a001 CR4: 00000000003606e0
[Tue Jan  1 16:49:24 2019] Call Trace:
[Tue Jan  1 16:49:24 2019]  ? ath10k_conf_tx+0x7a/0x4b0 [ath10k_core]
[Tue Jan  1 16:49:24 2019]  ieee80211_bss_info_change_notify+0xb5/0x1c0 [mac80211]
[Tue Jan  1 16:49:24 2019]  ieee80211_reconfig+0x6c1/0x14d0 [mac80211]
[Tue Jan  1 16:49:24 2019]  ? preempt_count_add+0x77/0xb0
[Tue Jan  1 16:49:24 2019]  wiphy_resume+0x7f/0x150 [cfg80211]
[Tue Jan  1 16:49:24 2019]  ? wiphy_namespace+0x10/0x10 [cfg80211]
[Tue Jan  1 16:49:24 2019]  dpm_run_callback+0x59/0x180
[Tue Jan  1 16:49:24 2019]  device_resume+0xac/0x1f0
[Tue Jan  1 16:49:24 2019]  async_resume+0x19/0x30
[Tue Jan  1 16:49:24 2019]  async_run_entry_fn+0x37/0x140
[Tue Jan  1 16:49:24 2019]  process_one_work+0x1ee/0x410
[Tue Jan  1 16:49:24 2019]  worker_thread+0x2d/0x3d0
[Tue Jan  1 16:49:24 2019]  kthread+0x10f/0x130
[Tue Jan  1 16:49:24 2019]  ? process_one_work+0x410/0x410
[Tue Jan  1 16:49:24 2019]  ? kthread_park+0x80/0x80
[Tue Jan  1 16:49:24 2019]  ret_from_fork+0x35/0x40
[Tue Jan  1 16:49:24 2019] ---[ end trace f8fd9a4dfa20066a ]---
[Tue Jan  1 16:49:24 2019] WARNING: CPU: 6 PID: 1261 at drivers/net/wireless/ath/ath10k/mac.c:5688 ath10k_bss_info_changed+0xf95/0x1120 [ath10k_core]
[Tue Jan  1 16:49:24 2019] Modules linked in: ccm mei_wdt hid_multitouch hid_generic snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic iTCO_wdt iTCO_vendor_support joydev mousedev arc4 cdc_acm dell_wmi wmi_bmof intel_wmi_thunderbolt mxm_wmi dell_laptop dell_smbios dell_wmi_descriptor dcdbas intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel i915 ath10k_pci ath10k_core kvmgt vfio_mdev mdev ath vfio_iommu_type1 vfio mac80211 kvm btusb btrtl btbcm btintel irqbypass crct10dif_pclmul snd_hda_intel crc32_pclmul i2c_algo_bit ghash_clmulni_intel bluetooth snd_hda_codec drm_kms_helper aesni_intel aes_x86_64 snd_hda_core crypto_simd snd_hwdep ecdh_generic tpm_crb drm cfg80211 idma64 cryptd glue_helper snd_pcm intel_cstate intel_uncore psmouse intel_gtt snd_timer intel_rapl_perf agpgart snd mei_me syscopyarea input_leds pcspkr sysfillrect sysimgblt rfkill rtsx_pci_ms fb_sys_fops soundcore mei memstick i2c_i801 i2c_hid intel_lpss_pci ucsi_acpi processor_thermal_device typec_ucsi
[Tue Jan  1 16:49:24 2019]  intel_lpss intel_soc_dts_iosf intel_pch_thermal hid typec battery int3403_thermal tpm_tis int340x_thermal_zone tpm_tis_core wmi dell_smo8800 tpm intel_hid evdev rng_core pcc_cpufreq int3400_thermal mac_hid acpi_thermal_rel sparse_keymap ac crypto_user ip_tables x_tables ext4 crc32c_generic crc16 mbcache jbd2 fscrypto rtsx_pci_sdmmc serio_raw mmc_core atkbd ahci libps2 libahci libata xhci_pci xhci_hcd crc32c_intel scsi_mod rtsx_pci i8042 serio
[Tue Jan  1 16:49:24 2019] CPU: 6 PID: 1261 Comm: kworker/u24:31 Tainted: G        W         4.20.0-1-ck-skylake #1
[Tue Jan  1 16:49:24 2019] Hardware name: Dell Inc. XPS 15 9570/0D0T05, BIOS 1.3.0 07/12/2018
[Tue Jan  1 16:49:24 2019] Workqueue: events_unbound async_run_entry_fn
[Tue Jan  1 16:49:24 2019] RIP: 0010:ath10k_bss_info_changed+0xf95/0x1120 [ath10k_core]
[Tue Jan  1 16:49:24 2019] Code: 24 8b 95 70 01 00 00 85 c0 0f 85 a7 00 00 00 89 d1 be 10 00 00 00 48 c7 c2 7c 00 db c0 4c 89 c7 e8 40 67 00 00 e9 a6 f1 ff ff <0f> 0b 4c 89 f7 e8 a1 8e 6a f8 e9 fd f3 ff ff b8 40 00 00 00 e9 63
[Tue Jan  1 16:49:24 2019] RSP: 0000:ffff9f25013d7cd0 EFLAGS: 00010282
[Tue Jan  1 16:49:24 2019] RAX: 00000000fffffffe RBX: ffff916c61021580 RCX: 0000000000000000
[Tue Jan  1 16:49:24 2019] RDX: ffff916c610218f8 RSI: ffff9f25013d7cf0 RDI: ffff916c66db73a0
[Tue Jan  1 16:49:24 2019] RBP: ffff916c66db73a0 R08: 0000000000000001 R09: 0000000000000000
[Tue Jan  1 16:49:24 2019] R10: 0000000000000000 R11: ffff916c59316500 R12: 0000000000000020
[Tue Jan  1 16:49:24 2019] R13: ffff916c66db73a8 R14: ffff916c61022558 R15: ffff916c61021580
[Tue Jan  1 16:49:24 2019] FS:  0000000000000000(0000) GS:ffff916c6c380000(0000) knlGS:0000000000000000
[Tue Jan  1 16:49:24 2019] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[Tue Jan  1 16:49:24 2019] CR2: 0000000000000000 CR3: 00000002a7e0a001 CR4: 00000000003606e0
[Tue Jan  1 16:49:24 2019] Call Trace:
[Tue Jan  1 16:49:24 2019]  ? ath10k_conf_tx+0x7a/0x4b0 [ath10k_core]
[Tue Jan  1 16:49:24 2019]  ieee80211_bss_info_change_notify+0xb5/0x1c0 [mac80211]
[Tue Jan  1 16:49:24 2019]  ieee80211_reconfig+0x6c1/0x14d0 [mac80211]
[Tue Jan  1 16:49:24 2019]  ? preempt_count_add+0x77/0xb0
[Tue Jan  1 16:49:24 2019]  wiphy_resume+0x7f/0x150 [cfg80211]
[Tue Jan  1 16:49:24 2019]  ? wiphy_namespace+0x10/0x10 [cfg80211]
[Tue Jan  1 16:49:24 2019]  dpm_run_callback+0x59/0x180
[Tue Jan  1 16:49:24 2019]  device_resume+0xac/0x1f0
[Tue Jan  1 16:49:24 2019]  async_resume+0x19/0x30
[Tue Jan  1 16:49:24 2019]  async_run_entry_fn+0x37/0x140
[Tue Jan  1 16:49:24 2019]  process_one_work+0x1ee/0x410
[Tue Jan  1 16:49:24 2019]  worker_thread+0x2d/0x3d0
[Tue Jan  1 16:49:24 2019]  kthread+0x10f/0x130
[Tue Jan  1 16:49:24 2019]  ? process_one_work+0x410/0x410
[Tue Jan  1 16:49:24 2019]  ? kthread_park+0x80/0x80
[Tue Jan  1 16:49:24 2019]  ret_from_fork+0x35/0x40
[Tue Jan  1 16:49:24 2019] ---[ end trace f8fd9a4dfa20066b ]---
[Tue Jan  1 16:49:24 2019] ata3: SATA link down (SStatus 4 SControl 300)
[Tue Jan  1 16:49:24 2019] acpi LNXPOWER:07: Turning OFF
[Tue Jan  1 16:49:24 2019] acpi LNXPOWER:02: Turning OFF
[Tue Jan  1 16:49:24 2019] acpi LNXPOWER:01: Turning OFF
[Tue Jan  1 16:49:24 2019] OOM killer enabled.
[Tue Jan  1 16:49:24 2019] Restarting tasks ... 
[Tue Jan  1 16:49:24 2019] Bluetooth: hci0: using rampatch file: qca/rampatch_usb_00000302.bin
[Tue Jan  1 16:49:24 2019] Bluetooth: hci0: QCA: patch rome 0x302 build 0x3e8, firmware rome 0x302 build 0x111
[Tue Jan  1 16:49:24 2019] done.
[Tue Jan  1 16:49:24 2019] audit: type=1130 audit(1546357840.969:43): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Tue Jan  1 16:49:24 2019] Bluetooth: hci0: using NVM file: qca/nvm_usb_00000302.bin
[Tue Jan  1 16:49:24 2019] PM: suspend exit
[Tue Jan  1 16:49:24 2019] audit: type=1130 audit(1546357841.029:44): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Tue Jan  1 16:49:24 2019] audit: type=1131 audit(1546357841.029:45): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'

I am no expert in reading dmesg but what it seems strange to me (apart from a crash of the wireless system) is that according to the reported times the whole suspend and resume procedure happens in about 4 seconds. Since I kept the laptop at "sleep" at least for one minute before waking it up I suspect that the sleep was just apparent and that the laptop actually woke up immediately after going to sleep, even if the screen was still turned off.
Am I right or am I completely missing the point? Thanks.

Last edited by snack (2019-01-05 10:41:35)

Offline

#2 2019-01-04 14:57:05

gtklocker
Member
Registered: 2009-09-01
Posts: 462

Re: [SOLVED] Suspend issue with Dell XPS 9570

I have the same error. Looks like it's already been reported. There could possibly be a better way to report directly to the kernel but I'm not aware.

I don't think I have the same battery drain although I haven't been monitoring it, I do use GNOME instead though as I found it handles power management edge cases better (like, if you quickly open and close the lid from sleep).

Offline

#3 2019-01-04 17:21:38

loqs
Member
Registered: 2014-03-06
Posts: 17,194

Re: [SOLVED] Suspend issue with Dell XPS 9570

See https://lkml.org/lkml/2018/11/4/16 attributed to https://github.com/torvalds/linux/commi … e59328b461
Can you please test the parent of cd93b83ad927b2c7979e0add0343ace59328b461 1e3c43a7f111943fb6bb7cafa9d1e9834d6b2b0e to see if that is not affected.
If it is not then check cd93b83ad927b2c7979e0add0343ace59328b461 if it is then please email the linux-wireless mailing list with a CC to the committer listed.

Offline

#4 2019-01-04 17:40:14

snack
Member
From: Italy
Registered: 2009-01-13
Posts: 861

Re: [SOLVED] Suspend issue with Dell XPS 9570

Thanks gtklocker. Actually I don't know if the wireless crash is related to the issue about suspend I am reporting, but I think it's not since it happens after the system re-enables the CPUs and thus after exiting from suspend (if I correctly understand). Anyway for the atheros crash I read here that there is a new ath10k firmware in linux-firmware 20181219, but maybe it's already included in Arch since the commit hash in the package name for F28 package coincides with that for Arch.

Offline

#5 2019-01-04 17:44:29

snack
Member
From: Italy
Registered: 2009-01-13
Posts: 861

Re: [SOLVED] Suspend issue with Dell XPS 9570

loqs wrote:

See https://lkml.org/lkml/2018/11/4/16 attributed to https://github.com/torvalds/linux/commi … e59328b461
Can you please test the parent of cd93b83ad927b2c7979e0add0343ace59328b461 1e3c43a7f111943fb6bb7cafa9d1e9834d6b2b0e to see if that is not affected.
If it is not then check cd93b83ad927b2c7979e0add0343ace59328b461 if it is then please email the linux-wireless mailing list with a CC to the committer listed.

Thanks for the hint. Actually I have not the opportunity to compile and test the kernel commit you suggested, at least not in this moment. I will try when I will have some time to devote to it.
Anyway, As I said in my previous comment, the ath10k crash seems to happen AFTER the system is resumed, just a couple of seconds after it goes to sleep, so if it is correct then the ath10k might be unrelated to my suspend issue. Any thought about this?

Offline

#6 2019-01-04 18:37:01

loqs
Member
Registered: 2014-03-06
Posts: 17,194

Re: [SOLVED] Suspend issue with Dell XPS 9570

[Tue Jan  1 16:49:23 2019] smpboot: CPU 11 is now offline
[Tue Jan  1 16:49:23 2019] ACPI: Low-level resume complete

Yes it is resuming as soon as it has attempted suspend.  Unfortunately that is not my area of expertise.  The only thing that looks wrong to me is

[Tue Jan  1 16:49:21 2019] psmouse serio1: Failed to disable mouse on isa0060/serio1

I would suggest trying https://archive.archlinux.org/packages/ … pkg.tar.xz which is the last 4.14 release of linux-lts and does not contain the commit alleged to cause the ath10k issue and see if that makes any difference.

Offline

#7 2019-01-04 22:30:51

snack
Member
From: Italy
Registered: 2009-01-13
Posts: 861

Re: [SOLVED] Suspend issue with Dell XPS 9570

@loqs: Thanks for the suggestion, I tried with 4.14.90 and here's the dmesg:

[Fri Jan  4 23:20:35 2019] ACPI: button: The lid device is not compliant to SW_LID.
[Fri Jan  4 23:20:35 2019] PM: suspend entry (deep)
[Fri Jan  4 23:20:35 2019] PM: Syncing filesystems ... done.
[Fri Jan  4 23:20:35 2019] Freezing user space processes ... (elapsed 0.001 seconds) done.
[Fri Jan  4 23:20:35 2019] OOM killer disabled.
[Fri Jan  4 23:20:35 2019] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[Fri Jan  4 23:20:35 2019] Suspending console(s) (use no_console_suspend to debug)
[Fri Jan  4 23:20:35 2019] wlan0: deauthenticating from a0:4f:d4:02:ba:b3 by local choice (Reason: 3=DEAUTH_LEAVING)
[Fri Jan  4 23:20:36 2019] ACPI: EC: interrupt blocked
[Fri Jan  4 23:20:36 2019] ACPI: Preparing to enter system sleep state S3
[Fri Jan  4 23:20:36 2019] ACPI: EC: event blocked
[Fri Jan  4 23:20:36 2019] ACPI: EC: EC stopped
[Fri Jan  4 23:20:36 2019] PM: Saving platform NVS memory
[Fri Jan  4 23:20:36 2019] Disabling non-boot CPUs ...
[Fri Jan  4 23:20:36 2019] smpboot: CPU 1 is now offline
[Fri Jan  4 23:20:36 2019] smpboot: CPU 2 is now offline
[Fri Jan  4 23:20:36 2019] smpboot: CPU 3 is now offline
[Fri Jan  4 23:20:36 2019] smpboot: CPU 4 is now offline
[Fri Jan  4 23:20:36 2019] smpboot: CPU 5 is now offline
[Fri Jan  4 23:20:36 2019] smpboot: CPU 6 is now offline
[Fri Jan  4 23:20:36 2019] smpboot: CPU 7 is now offline
[Fri Jan  4 23:20:36 2019] smpboot: CPU 8 is now offline
[Fri Jan  4 23:20:37 2019] smpboot: CPU 9 is now offline
[Fri Jan  4 23:20:37 2019] smpboot: CPU 10 is now offline
[Fri Jan  4 23:20:37 2019] smpboot: CPU 11 is now offline
[Fri Jan  4 23:20:37 2019] ACPI: Low-level resume complete
[Fri Jan  4 23:20:37 2019] ACPI: EC: EC started
[Fri Jan  4 23:20:37 2019] PM: Restoring platform NVS memory
[Fri Jan  4 23:20:37 2019] Enabling non-boot CPUs ...
[Fri Jan  4 23:20:37 2019] x86: Booting SMP configuration:
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 1 APIC 0x2
[Fri Jan  4 23:20:37 2019]  cache: parent cpu1 should not be sleeping
[Fri Jan  4 23:20:37 2019] intel_pstate: Disabling energy efficiency optimization
[Fri Jan  4 23:20:37 2019] CPU1 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 2 APIC 0x4
[Fri Jan  4 23:20:37 2019]  cache: parent cpu2 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU2 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 3 APIC 0x6
[Fri Jan  4 23:20:37 2019]  cache: parent cpu3 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU3 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 4 APIC 0x8
[Fri Jan  4 23:20:37 2019]  cache: parent cpu4 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU4 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 5 APIC 0xa
[Fri Jan  4 23:20:37 2019]  cache: parent cpu5 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU5 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 6 APIC 0x1
[Fri Jan  4 23:20:37 2019]  cache: parent cpu6 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU6 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 7 APIC 0x3
[Fri Jan  4 23:20:37 2019]  cache: parent cpu7 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU7 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 8 APIC 0x5
[Fri Jan  4 23:20:37 2019]  cache: parent cpu8 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU8 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 9 APIC 0x7
[Fri Jan  4 23:20:37 2019]  cache: parent cpu9 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU9 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 10 APIC 0x9
[Fri Jan  4 23:20:37 2019]  cache: parent cpu10 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU10 is up
[Fri Jan  4 23:20:37 2019] smpboot: Booting Node 0 Processor 11 APIC 0xb
[Fri Jan  4 23:20:37 2019]  cache: parent cpu11 should not be sleeping
[Fri Jan  4 23:20:37 2019] CPU11 is up
[Fri Jan  4 23:20:37 2019] ACPI: Waking up from system sleep state S3
[Fri Jan  4 23:20:37 2019] ACPI: EC: interrupt unblocked
[Fri Jan  4 23:20:37 2019] ACPI: EC: event unblocked
[Fri Jan  4 23:20:37 2019] rtc_cmos 00:01: Alarms can be up to one month in the future
[Fri Jan  4 23:20:37 2019] usb 1-4: reset full-speed USB device number 2 using xhci_hcd
[Fri Jan  4 23:20:37 2019] ata3: SATA link down (SStatus 4 SControl 300)
[Fri Jan  4 23:20:38 2019] usb 1-12: reset high-speed USB device number 4 using xhci_hcd
[Fri Jan  4 23:20:38 2019] ath10k_pci 0000:3b:00.0: Unknown eventid: 3
[Fri Jan  4 23:20:38 2019] ath10k_pci 0000:3b:00.0: Unknown eventid: 118809
[Fri Jan  4 23:20:38 2019] usb 1-4:1.0: rebind failed: -517
[Fri Jan  4 23:20:38 2019] usb 1-4:1.1: rebind failed: -517
[Fri Jan  4 23:20:38 2019] acpi LNXPOWER:07: Turning OFF
[Fri Jan  4 23:20:38 2019] acpi LNXPOWER:06: Turning OFF
[Fri Jan  4 23:20:38 2019] acpi LNXPOWER:05: Turning OFF
[Fri Jan  4 23:20:38 2019] acpi LNXPOWER:04: Turning OFF
[Fri Jan  4 23:20:38 2019] acpi LNXPOWER:02: Turning OFF
[Fri Jan  4 23:20:38 2019] acpi LNXPOWER:01: Turning OFF
[Fri Jan  4 23:20:38 2019] OOM killer enabled.
[Fri Jan  4 23:20:38 2019] Restarting tasks ... done.
[Fri Jan  4 23:20:38 2019] [drm] RC6 on
[Fri Jan  4 23:20:38 2019] PM: suspend exit
[Fri Jan  4 23:20:38 2019] Bluetooth: hci0: using rampatch file: qca/rampatch_usb_00000302.bin
[Fri Jan  4 23:20:38 2019] Bluetooth: hci0: QCA: patch rome 0x302 build 0x3e8, firmware rome 0x302 build 0x111
[Fri Jan  4 23:20:38 2019] Bluetooth: hci0: using NVM file: qca/nvm_usb_00000302.bin
[Fri Jan  4 23:20:43 2019] wlan0: authenticate with a0:4f:d4:02:ba:b3
[Fri Jan  4 23:20:43 2019] wlan0: send auth to a0:4f:d4:02:ba:b3 (try 1/3)
[Fri Jan  4 23:20:43 2019] wlan0: authenticated
[Fri Jan  4 23:20:43 2019] wlan0: associate with a0:4f:d4:02:ba:b3 (try 1/3)
[Fri Jan  4 23:20:43 2019] wlan0: RX AssocResp from a0:4f:d4:02:ba:b3 (capab=0x411 status=0 aid=1)
[Fri Jan  4 23:20:43 2019] wlan0: associated

The ath10k crash is gone (although the messages about unknown events are still there) as well as the psmouse fail, but the system is resumed immediately after suspend as with 4.19 and 4.20. I'm beginning to think that it might be related to some nasty bios bug (I'm currently on 1.3.0) since I read about various suspend issues with Windows related to the bios, although I am absolutely not sure how and if these apply to Linux.

Offline

#8 2019-01-04 22:59:34

snack
Member
From: Italy
Registered: 2009-01-13
Posts: 861

Re: [SOLVED] Suspend issue with Dell XPS 9570

I found some instructions on the wiki. I disabled all the wakeup triggers:

# cat /proc/acpi/wakeup | grep enabled
#

and checked also that no usb device can wake the system up as described here:

# cat /sys/bus/usb/devices/*/power/wakeup
disabled
disabled
disabled
disabled
#

to no avail. The system still wakes up immediately. I'm out of clues.

Offline

#9 2019-01-05 09:05:42

gtklocker
Member
Registered: 2009-09-01
Posts: 462

Re: [SOLVED] Suspend issue with Dell XPS 9570

Looks like I also have the "ACPI: Low-level resume complete" message right after all CPUs become offline.

[master][~]$ dmesg|grep -C5 "CPU 11"
[ 2279.668845] smpboot: CPU 6 is now offline
[ 2279.721032] smpboot: CPU 7 is now offline
[ 2279.774340] smpboot: CPU 8 is now offline
[ 2279.827656] smpboot: CPU 9 is now offline
[ 2279.880982] smpboot: CPU 10 is now offline
[ 2279.934292] smpboot: CPU 11 is now offline
[ 2279.940654] ACPI: Low-level resume complete
[ 2279.940756] ACPI: EC: EC started
[ 2279.940756] PM: Restoring platform NVS memory
[ 2279.941704] Enabling non-boot CPUs ...
[ 2279.941739] x86: Booting SMP configuration:
--
[18848.174534] smpboot: CPU 6 is now offline
[18848.213382] smpboot: CPU 7 is now offline
[18848.266694] smpboot: CPU 8 is now offline
[18848.293349] smpboot: CPU 9 is now offline
[18848.333320] smpboot: CPU 10 is now offline
[18848.386613] smpboot: CPU 11 is now offline
[18848.393306] ACPI: Low-level resume complete
[18848.393417] ACPI: EC: EC started
[18848.393417] PM: Restoring platform NVS memory
[18848.394422] Enabling non-boot CPUs ...
[18848.394457] x86: Booting SMP configuration:

I measured my battery drain overnight and it was about 5% so nothing crazy.

I have the latest BIOS though.

[master][~]$ fwupdmgr update
No upgrades for XPS 15 9570 System Firmware, current is 0.1.6.0: 0.1.6.0=same, 0.1.5.0=older, 0.1.4.1=older, 0.1.3.1=older, 0.1.3.0=older, 0.1.2.2=older

Offline

#10 2019-01-05 10:37:00

snack
Member
From: Italy
Registered: 2009-01-13
Posts: 861

Re: [SOLVED] Suspend issue with Dell XPS 9570

I'm beginning to think that things actually work fine, and that our understanding of dmesg is broken. Since my system actually seems to go down (screen and keyboard off, completely non reactive until I press the power button) and also gtklocker sees a behavior which is compatible with a suspended state I guess that the problem is in the dmesg times. Maybe, for whatever reason, the reported times do not take the time gap due to the suspension into account, and report as consecutive two events that in reality are far apart in time:

[18848.386613] smpboot: CPU 11 is now offline
[18848.393306] ACPI: Low-level resume complete

I do not have another system with working suspend at hand right now so I cannot check. Would anybody try to suspend and post the dmesg here, to see if also in a working system the dmesg times are consecutive? Thanks.

Edit: it seems that I guessed right. From man dmesg:

-T, --ctime
              Print human-readable timestamps.

              Be aware that the timestamp could be inaccurate!  The time source used for the logs is not updated after system SUSPEND/RESUME.

Thanks for the help and sorry for the noise!

Last edited by snack (2019-01-05 10:41:13)

Offline

Board footer

Powered by FluxBB