search

vrolife / modern_laptop

Redmi Book Pro 14/15 2022 锐龙版/Ryzen Linux 键盘驱动/keyboard driver
GNU Affero General Public License v3.0
242 stars 16 forks source link

Redmibook 15 Sleep doesn't work properly #47

Open Vliro opened 1 year ago

Vliro commented 1 year ago

Describe the bug After going into sleep, I cannot wake the computer at all. Have to force reboot it.

To Reproduce Let computer idle. After sleep cannot enter.

Expected behavior After pressing a key, or power button, I resume at lock screen.

Desktop (please complete the following information):

Log 

dec 04 09:51:15 albert-laptop systemd-logind[902]: The system will suspend and later hibernate now!
dec 04 09:51:15 albert-laptop NetworkManager[948]: <info>  [1670143875.4899] manager: sleep: sleep requested (sleeping: no  enabled: yes)
dec 04 09:51:15 albert-laptop NetworkManager[948]: <info>  [1670143875.4900] device (p2p-dev-wlan0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-sta>
dec 04 09:51:15 albert-laptop NetworkManager[948]: <info>  [1670143875.4903] manager: NetworkManager state is now ASLEEP
dec 04 09:51:15 albert-laptop NetworkManager[948]: <info>  [1670143875.4904] device (wlan0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'man>
dec 04 09:51:15 albert-laptop dbus-daemon[899]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service>
dec 04 09:51:15 albert-laptop systemd[1]: Starting Network Manager Script Dispatcher Service...
dec 04 09:51:15 albert-laptop kded5[1498]: org.kde.plasma.nm.kded: Unhandled active connection state change:  3
dec 04 09:51:15 albert-laptop dbus-daemon[899]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
dec 04 09:51:15 albert-laptop systemd[1]: Started Network Manager Script Dispatcher Service.
dec 04 09:51:15 albert-laptop audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/syst>
dec 04 09:51:15 albert-laptop kernel: audit: type=1130 audit(1670143875.591:339): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd>
dec 04 09:51:15 albert-laptop kernel: wlan0: deauthenticating from 3c:7c:3f:e5:fa:cc by local choice (Reason: 3=DEAUTH_LEAVING)
dec 04 09:51:15 albert-laptop wpa_supplicant[1144]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:7c:3f:e5:fa:cc reason=3 locally_generated=1
dec 04 09:51:15 albert-laptop wpa_supplicant[1144]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
dec 04 09:51:15 albert-laptop NetworkManager[948]: <info>  [1670143875.6760] device (wlan0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: '>
dec 04 09:51:15 albert-laptop avahi-daemon[896]: Withdrawing address record for fe80::4988:9e01:10f:4a1 on wlan0.
dec 04 09:51:15 albert-laptop avahi-daemon[896]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::4988:9e01:10f:4a1.
dec 04 09:51:15 albert-laptop avahi-daemon[896]: Interface wlan0.IPv6 no longer relevant for mDNS.
dec 04 09:51:15 albert-laptop audit[906]: NETFILTER_CFG table=firewalld:133 family=1 entries=6 op=nft_unregister_rule pid=906 comm="firewalld"
dec 04 09:51:15 albert-laptop kernel: audit: type=1325 audit(1670143875.690:340): table=firewalld:133 family=1 entries=6 op=nft_unregister_rule pid=906 comm="firewalld"
dec 04 09:51:15 albert-laptop kernel: audit: type=1300 audit(1670143875.690:340): arch=c000003e syscall=46 success=yes exit=492 a0=6 a1=7fff37cdb550 a2=0 a3=7fff37cca3fc item>
dec 04 09:51:15 albert-laptop kernel: audit: type=1327 audit(1670143875.690:340): proctitle=2F7573722F62696E2F707974686F6E002F7573722F62696E2F6669726577616C6C64002D2D6E6F666F>
dec 04 09:51:15 albert-laptop audit[906]: SYSCALL arch=c000003e syscall=46 success=yes exit=492 a0=6 a1=7fff37cdb550 a2=0 a3=7fff37cca3fc items=0 ppid=1 pid=906 auid=42949672>
dec 04 09:51:15 albert-laptop audit: PROCTITLE proctitle=2F7573722F62696E2F707974686F6E002F7573722F62696E2F6669726577616C6C64002D2D6E6F666F726B002D2D6E6F706964
dec 04 09:51:15 albert-laptop NetworkManager[948]: <info>  [1670143875.6930] dhcp4 (wlan0): canceled DHCP transaction
dec 04 09:51:15 albert-laptop NetworkManager[948]: <info>  [1670143875.6930] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
dec 04 09:51:15 albert-laptop NetworkManager[948]: <info>  [1670143875.6930] dhcp4 (wlan0): state changed no lease
dec 04 09:51:15 albert-laptop avahi-daemon[896]: Interface wlan0.IPv4 no longer relevant for mDNS.
dec 04 09:51:15 albert-laptop avahi-daemon[896]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.48.
dec 04 09:51:15 albert-laptop avahi-daemon[896]: Withdrawing address record for 192.168.50.48 on wlan0.
dec 04 09:51:15 albert-laptop NetworkManager[948]: <info>  [1670143875.7094] device (wlan0): set-hw-addr: set MAC address to 1E:4B:56:67:E8:BA (scanning)
dec 04 09:51:15 albert-laptop avahi-daemon[896]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.50.48.
dec 04 09:51:15 albert-laptop avahi-daemon[896]: New relevant interface wlan0.IPv4 for mDNS.
dec 04 09:51:15 albert-laptop avahi-daemon[896]: Registering new address record for 192.168.50.48 on wlan0.IPv4.
dec 04 09:51:15 albert-laptop avahi-daemon[896]: Withdrawing address record for 192.168.50.48 on wlan0
dec 04 09:51:15 albert-laptop systemd[1]: Reached target Sleep.
dec 04 09:51:15 albert-laptop systemd[1]: Starting Suspend; Hibernate if not used for a period of time...
dec 04 09:51:15 albert-laptop wpa_supplicant[1144]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all
dec 04 09:51:15 albert-laptop wpa_supplicant[1144]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all
dec 04 09:51:15 albert-laptop wpa_supplicant[1144]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0
dec 04 09:51:15 albert-laptop wpa_supplicant[1144]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
dec 04 09:51:15 albert-laptop systemd-sleep[18792]: Entering sleep state 'suspend'...
dec 04 09:51:15 albert-laptop kernel: PM: suspend entry (s2idle)
dec 04 09:51:15 albert-laptop wpa_supplicant[1144]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
dec 04 09:51:15 albert-laptop wpa_supplicant[1144]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
dec 04 09:51:15 albert-laptop kernel: Filesystems sync: 0.048 seconds
vrolife commented 1 year ago

Is the AC power connected when waking the laptop?

Vliro commented 1 year ago

Is the AC power connected when waking the laptop?

Sometimes, sometimes not. Both

vrolife commented 1 year ago

Please provide the output of this command cat /proc/cmdline

Vliro commented 1 year ago 
BOOT_IMAGE=/@/boot/vmlinuz-linux-zen root=UUID=... rw rootflags=subvol=@ acpi_osi=! acpi_osi=Linux resume=UUID=... loglevel=3 nowatchdog nvme_load=YES pstore.backend=efi efi_pstore.pstore_disable=0 initcall_blacklist=acpi_cpufreq_init
vrolife commented 1 year ago

You seem to have a third-party kernel installed. Have you tried the mainline kernel？

Vliro commented 1 year ago

Same issue on mainline

Vliro commented 1 year ago 
dec 04 15:57:41 albert-laptop kernel: INFO: task khugepaged:122 blocked for more than 122 seconds.
dec 04 15:57:41 albert-laptop kernel:       Tainted: G           OE      6.0.11-arch1-1 #1
dec 04 15:57:41 albert-laptop kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
dec 04 15:57:41 albert-laptop kernel: task:khugepaged      state:D stack:    0 pid:  122 ppid:     2 flags:0x00004000
dec 04 15:57:41 albert-laptop kernel: Call Trace:
dec 04 15:57:41 albert-laptop kernel:  <TASK>
dec 04 15:57:41 albert-laptop kernel:  __schedule+0x343/0x11c0
dec 04 15:57:41 albert-laptop kernel:  pagecache_get_page+0x17/0x90
dec 04 15:57:41 albert-laptop kernel:  collapse_file+0x4b6/0x13c0
dec 04 15:57:41 albert-laptop kernel:  khugepaged+0x20dd/0x2300
dec 04 15:57:41 albert-laptop kernel:  ? collapse_pte_mapped_thp+0x400/0x400
dec 04 15:57:41 albert-laptop kernel:  kthread+0xde/0x110
dec 04 15:57:41 albert-laptop kernel:  ? kthread_complete_and_exit+0x20/0x20
dec 04 15:57:41 albert-laptop kernel:  ret_from_fork+0x22/0x30
dec 04 15:57:41 albert-laptop kernel:  </TASK>
dec 04 15:59:44 albert-laptop kernel: INFO: task khugepaged:122 blocked for more than 245 seconds.
dec 04 15:59:44 albert-laptop kernel:       Tainted: G           OE      6.0.11-arch1-1 #1
dec 04 15:59:44 albert-laptop kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
dec 04 15:59:44 albert-laptop kernel: task:khugepaged      state:D stack:    0 pid:  122 ppid:     2 flags:0x00004000
dec 04 15:59:44 albert-laptop kernel: Call Trace:
dec 04 15:59:44 albert-laptop kernel:  <TASK>
dec 04 15:59:44 albert-laptop kernel:  __schedule+0x343/0x11c0
dec 04 15:59:44 albert-laptop kernel:  schedule+0x5e/0xd0
dec 04 15:59:44 albert-laptop kernel:  io_schedule+0x46/0x70
dec 04 15:59:44 albert-laptop kernel:  folio_wait_bit_common+0x13d/0x3a0
dec 04 15:59:44 albert-laptop kernel:  ? folio_unlock+0x20/0x20
dec 04 15:59:44 albert-laptop kernel:  __filemap_get_folio+0x34a/0x380
dec 04 15:59:44 albert-laptop kernel:  pagecache_get_page+0x17/0x90
dec 04 15:59:44 albert-laptop kernel:  collapse_file+0x4b6/0x13c0
dec 04 15:59:44 albert-laptop kernel:  khugepaged+0x20dd/0x2300
dec 04 15:59:44 albert-laptop kernel:  ? collapse_pte_mapped_thp+0x400/0x400
dec 04 15:59:44 albert-laptop kernel:  kthread+0xde/0x110
dec 04 15:59:44 albert-laptop kernel:  ? kthread_complete_and_exit+0x20/0x20
dec 04 15:59:44 albert-laptop kernel:  ret_from_fork+0x22/0x30
dec 04 15:59:44 albert-laptop kernel:  </TASK>
dec 04 16:01:47 albert-laptop kern

Saw this now

Vliro commented 1 year ago 
dec 04 18:59:59 albert-laptop kernel: ucsi_acpi USBC000:00: PM: dpm_run_callback(): acpi_subsys_resume+0x0/0x130 returns -110
dec 04 18:59:59 albert-laptop kernel: ucsi_acpi USBC000:00: PM: failed to resume: error -110

One sleep now worked. This came up though

vrolife commented 1 year ago

I use the mainline kernel as well, but everything is fine.

dec 04 15:59:44 albert-laptop kernel:  <TASK>
dec 04 15:59:44 albert-laptop kernel:  __schedule+0x343/0x11c0
dec 04 15:59:44 albert-laptop kernel:  schedule+0x5e/0xd0
dec 04 15:59:44 albert-laptop kernel:  io_schedule+0x46/0x70
dec 04 15:59:44 albert-laptop kernel:  folio_wait_bit_common+0x13d/0x3a0
dec 04 15:59:44 albert-laptop kernel:  ? folio_unlock+0x20/0x20
dec 04 15:59:44 albert-laptop kernel:  __filemap_get_folio+0x34a/0x380
dec 04 15:59:44 albert-laptop kernel:  pagecache_get_page+0x17/0x90
dec 04 15:59:44 albert-laptop kernel:  collapse_file+0x4b6/0x13c0
dec 04 15:59:44 albert-laptop kernel:  khugepaged+0x20dd/0x2300
dec 04 15:59:44 albert-laptop kernel:  ? collapse_pte_mapped_thp+0x400/0x400
dec 04 15:59:44 albert-laptop kernel:  kthread+0xde/0x110
dec 04 15:59:44 albert-laptop kernel:  ? kthread_complete_and_exit+0x20/0x20
dec 04 15:59:44 albert-laptop kernel:  ret_from_fork+0x22/0x30
dec 04 15:59:44 albert-laptop kernel:  </TASK>

Crash backtracking shows that the disk stops responding