You are not logged in.
Pages: 1
Hello Arch Linux Forums,
For the quite sometime, I'm experiencing a problem in laptop
suspension. In an problematic suspend, my laptop doesn't accept any
user input via keyboard and mouse, thus making it impossible to get
back to lock screen or an TTY. Logs of the aforementioned event:
systemd-logind[415]: Lid closed.
systemd-logind[415]: Suspending...
NetworkManager[427]: <info> [1654401202.1720] manager: sleep: sleep requested (sleeping: no enabled: yes)
NetworkManager[427]: <info> [1654401202.1721] device (enp1s0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
NetworkManager[427]: <info> [1654401202.1796] device (p2p-dev-wlp2s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
NetworkManager[427]: <info> [1654401202.1799] manager: NetworkManager state is now ASLEEP
NetworkManager[427]: <info> [1654401202.1801] device (wlp2s0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
dbus-daemon[414]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=427 comm="/usr/bin/NetworkManager --no-daemon")
systemd[1]: Starting Network Manager Script Dispatcher Service...
dbus-daemon[414]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
systemd[1]: Started Network Manager Script Dispatcher Service.
audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
kernel: audit: type=1130 audit(1654401202.185:85): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
kernel: wlp2s0: deauthenticating from 30:49:50:2c:41:e7 by local choice (Reason: 3=DEAUTH_LEAVING)
systemd[1]: systemd-hostnamed.service: Deactivated successfully.
audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
kernel: audit: type=1131 audit(1654401202.572:86): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
wpa_supplicant[465]: wlp2s0: CTRL-EVENT-DISCONNECTED bssid=30:49:50:2c:41:e7 reason=3 locally_generated=1
wpa_supplicant[465]: wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
NetworkManager[427]: <info> [1654401202.6175] device (wlp2s0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
wpa_supplicant[465]: wlp2s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
NetworkManager[427]: <info> [1654401202.6526] dhcp4 (wlp2s0): canceled DHCP transaction
NetworkManager[427]: <info> [1654401202.6527] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
NetworkManager[427]: <info> [1654401202.6527] dhcp4 (wlp2s0): state changed no lease
NetworkManager[427]: <info> [1654401202.6532] dhcp6 (wlp2s0): canceled DHCP transaction
NetworkManager[427]: <info> [1654401202.6532] dhcp6 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
NetworkManager[427]: <info> [1654401202.6533] dhcp6 (wlp2s0): state changed no lease
NetworkManager[427]: <info> [1654401202.6560] device (wlp2s0): set-hw-addr: set MAC address to 5E:42:B3:91:74:54 (scanning)
audit: BPF prog-id=0 op=UNLOAD
audit: BPF prog-id=0 op=UNLOAD
audit: BPF prog-id=0 op=UNLOAD
kernel: audit: type=1334 audit(1654401202.688:87): prog-id=0 op=UNLOAD
kernel: audit: type=1334 audit(1654401202.688:88): prog-id=0 op=UNLOAD
kernel: audit: type=1334 audit(1654401202.688:89): prog-id=0 op=UNLOAD
systemd[1]: systemd-localed.service: Deactivated successfully.
audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-localed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
kernel: audit: type=1131 audit(1654401202.732:90): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-localed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
NetworkManager[427]: <info> [1654401202.8168] device (wlp2s0): supplicant interface state: completed -> disconnected
NetworkManager[427]: <info> [1654401202.8171] device (wlp2s0): supplicant interface state: disconnected -> interface_disabled
NetworkManager[427]: <info> [1654401202.8172] device (wlp2s0): supplicant interface state: interface_disabled -> disconnected
NetworkManager[427]: <info> [1654401202.8187] device (wlp2s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
NetworkManager[427]: <info> [1654401202.8215] device (wlp2s0): set-hw-addr: reset MAC address to F8:A2:D6:F9:00:43 (unmanage)
systemd[781]: Starting Mailbox synchronization service...
audit: BPF prog-id=0 op=UNLOAD
audit: BPF prog-id=0 op=UNLOAD
audit: BPF prog-id=0 op=UNLOAD
kernel: audit: type=1334 audit(1654401202.842:91): prog-id=0 op=UNLOAD
kernel: audit: type=1334 audit(1654401202.842:92): prog-id=0 op=UNLOAD
kernel: audit: type=1334 audit(1654401202.842:93): prog-id=0 op=UNLOAD
wpa_supplicant[465]: p2p-dev-wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
gnome-shell[845]: An active wireless connection, in infrastructure mode, involves no access point?
wpa_supplicant[465]: p2p-dev-wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
wpa_supplicant[465]: nl80211: deinit ifname=p2p-dev-wlp2s0 disabled_11b_rates=0
wpa_supplicant[465]: wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
wpa_supplicant[465]: wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
wpa_supplicant[465]: nl80211: deinit ifname=wlp2s0 disabled_11b_rates=0
notmuch[1291]: Error: Cannot resolve server 'imap.gmail.com': Temporary failure in name resolution
notmuch[1289]: Error: pre-new hook failed with status 1
systemd[781]: notmuch.service: Main process exited, code=exited, status=1/FAILURE
systemd[781]: notmuch.service: Failed with result 'exit-code'.
systemd[781]: Failed to start Mailbox synchronization service.
systemd[1]: Reached target Sleep.
systemd[1]: Starting System Suspend...
systemd-sleep[1292]: Entering sleep state 'suspend'...
kernel: PM: suspend entry (deep)
-- Boot 899101473c3a435db9c2ece59ddd7558 --Compared to logs for an 'normal' suspend:
systemd-logind[423]: Lid closed.
systemd-logind[423]: Suspending...
NetworkManager[435]: <info> [1654401026.6712] manager: sleep: sleep requested (sleeping: no enabled: yes)
NetworkManager[435]: <info> [1654401026.6713] device (enp1s0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
NetworkManager[435]: <info> [1654401026.6803] device (p2p-dev-wlp2s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
NetworkManager[435]: <info> [1654401026.6806] manager: NetworkManager state is now ASLEEP
NetworkManager[435]: <info> [1654401026.6807] device (wlp2s0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
dbus-daemon[422]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=435 comm="/usr/bin/NetworkManager --no-daemon")
systemd[1]: Starting Network Manager Script Dispatcher Service...
dbus-daemon[422]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
systemd[1]: Started Network Manager Script Dispatcher Service.
audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
kernel: audit: type=1130 audit(1654401026.686:125): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
kernel: wlp2s0: deauthenticating from 30:49:50:2c:41:e7 by local choice (Reason: 3=DEAUTH_LEAVING)
wpa_supplicant[473]: wlp2s0: CTRL-EVENT-DISCONNECTED bssid=30:49:50:2c:41:e7 reason=3 locally_generated=1
wpa_supplicant[473]: wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
NetworkManager[435]: <info> [1654401026.9253] device (wlp2s0): supplicant interface state: completed -> disconnected
wpa_supplicant[473]: wlp2s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
NetworkManager[435]: <info> [1654401026.9258] device (wlp2s0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
NetworkManager[435]: <info> [1654401026.9465] dhcp4 (wlp2s0): canceled DHCP transaction
NetworkManager[435]: <info> [1654401026.9466] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
NetworkManager[435]: <info> [1654401026.9467] dhcp4 (wlp2s0): state changed no lease
NetworkManager[435]: <info> [1654401026.9471] dhcp6 (wlp2s0): canceled DHCP transaction
NetworkManager[435]: <info> [1654401026.9472] dhcp6 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
NetworkManager[435]: <info> [1654401026.9472] dhcp6 (wlp2s0): state changed no lease
NetworkManager[435]: <info> [1654401026.9498] device (wlp2s0): set-hw-addr: set MAC address to D2:24:F4:52:D4:06 (scanning)
gnome-shell[846]: An active wireless connection, in infrastructure mode, involves no access point?
NetworkManager[435]: <info> [1654401027.1329] device (wlp2s0): supplicant interface state: disconnected -> interface_disabled
NetworkManager[435]: <info> [1654401027.1331] device (wlp2s0): supplicant interface state: interface_disabled -> disconnected
NetworkManager[435]: <info> [1654401027.1349] device (wlp2s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
NetworkManager[435]: <info> [1654401027.1387] device (wlp2s0): set-hw-addr: reset MAC address to F8:A2:D6:F9:00:43 (unmanage)
wpa_supplicant[473]: p2p-dev-wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
wpa_supplicant[473]: p2p-dev-wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
wpa_supplicant[473]: nl80211: deinit ifname=p2p-dev-wlp2s0 disabled_11b_rates=0
wpa_supplicant[473]: wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
wpa_supplicant[473]: wlp2s0: CTRL-EVENT-DSCP-POLICY clear_all
wpa_supplicant[473]: nl80211: deinit ifname=wlp2s0 disabled_11b_rates=0
systemd[1]: Reached target Sleep.
systemd[1]: Starting System Suspend...
systemd-sleep[2421]: Entering sleep state 'suspend'...
kernel: PM: suspend entry (deep)
kernel: Filesystems sync: 0.242 seconds
kernel: Freezing user space processes ... (elapsed 0.003 seconds) done.
kernel: OOM killer disabled.
kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
kernel: printk: Suspending console(s) (use no_console_suspend to debug)
kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
kernel: sd 0:0:0:0: [sda] Stopping disk
kernel: ACPI: EC: interrupt blocked
kernel: ACPI: PM: Preparing to enter system sleep state S3
kernel: ACPI: EC: event blocked
kernel: ACPI: EC: EC stopped
kernel: ACPI: PM: Saving platform NVS memory
kernel: Disabling non-boot CPUs ...
kernel: smpboot: CPU 1 is now offline
kernel: smpboot: CPU 2 is now offline
kernel: smpboot: CPU 3 is now offline
kernel: smpboot: CPU 4 is now offline
kernel: smpboot: CPU 5 is now offline
kernel: smpboot: CPU 6 is now offline
kernel: smpboot: CPU 7 is now offline
kernel: ACPI: PM: Low-level resume complete
kernel: ACPI: EC: EC started
kernel: ACPI: PM: Restoring platform NVS memory
kernel: Enabling non-boot CPUs ...
kernel: x86: Booting SMP configuration:
kernel: smpboot: Booting Node 0 Processor 1 APIC 0x2
kernel: CPU1 is up
kernel: smpboot: Booting Node 0 Processor 2 APIC 0x4
kernel: CPU2 is up
kernel: smpboot: Booting Node 0 Processor 3 APIC 0x6
kernel: CPU3 is up
kernel: smpboot: Booting Node 0 Processor 4 APIC 0x1
kernel: CPU4 is up
kernel: smpboot: Booting Node 0 Processor 5 APIC 0x3
kernel: CPU5 is up
kernel: smpboot: Booting Node 0 Processor 6 APIC 0x5
kernel: CPU6 is up
kernel: smpboot: Booting Node 0 Processor 7 APIC 0x7
kernel: CPU7 is up
kernel: ACPI: PM: Waking up from system sleep state S3
kernel: ACPI: EC: interrupt unblocked
kernel: ACPI: EC: event unblocked
kernel: sd 0:0:0:0: [sda] Starting disk
kernel: i915 0000:00:02.0: [drm] [ENCODER:94:DDI A/PHY A] is disabled/in DSI mode with an ungated DDI clock, gate it
kernel: i915 0000:00:02.0: [drm] [ENCODER:102:DDI B/PHY B] is disabled/in DSI mode with an ungated DDI clock, gate it
kernel: i915 0000:00:02.0: [drm] [ENCODER:111:DDI C/PHY C] is disabled/in DSI mode with an ungated DDI clock, gate it
kernel: usb 1-7: reset full-speed USB device number 2 using xhci_hcd
kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
kernel: ata2.00: configured for UDMA/133
kernel: usb 1-8: reset high-speed USB device number 3 using xhci_hcd
kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
kernel: ata1.00: configured for UDMA/133
kernel: OOM killer enabled.
kernel: Restarting tasks ...
kernel: mei_hdcp 0000:00:16.0-b638ab7e-94e2-4ea2-a552-d1c54b627f04: bound 0000:00:02.0 (ops i915_hdcp_component_ops [i915])
kernel: done.
systemd-logind[423]: Lid opened.
Jun 05 09:14:54 ideapad330 systemd-logind[423]: Lid opened.
Jun 05 09:14:54 ideapad330 systemd[1]: Starting Load/Save RF Kill Switch Status...
Jun 05 09:14:54 ideapad330 systemd[1]: Stopped target Bluetooth Support.
Jun 05 09:14:54 ideapad330 systemd[1]: Started Load/Save RF Kill Switch Status.
Jun 05 09:14:54 ideapad330 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:14:54 ideapad330 kernel: audit: type=1130 audit(1654400694.200:100): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:14:54 ideapad330 systemd[1]: Reached target Bluetooth Support.
Jun 05 09:14:54 ideapad330 systemd[782]: Reached target Bluetooth.
Jun 05 09:14:54 ideapad330 systemd-sleep[1601]: System returned from sleep state.
Jun 05 09:14:54 ideapad330 kernel: PM: suspend exit
Jun 05 09:14:54 ideapad330 kernel: Bluetooth: hci0: using rampatch file: qca/rampatch_usb_00000302.bin
Jun 05 09:14:54 ideapad330 kernel: Bluetooth: hci0: QCA: patch rome 0x302 build 0x3e8, firmware rome 0x302 build 0x111
Jun 05 09:14:54 ideapad330 systemd[1]: systemd-suspend.service: Deactivated successfully.
Jun 05 09:14:54 ideapad330 systemd[1]: Finished System Suspend.
Jun 05 09:14:54 ideapad330 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:14:54 ideapad330 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:14:54 ideapad330 systemd[1]: Stopped target Sleep.
Jun 05 09:14:54 ideapad330 systemd[1]: Reached target Suspend.
Jun 05 09:14:54 ideapad330 kernel: audit: type=1130 audit(1654400694.280:101): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:14:54 ideapad330 kernel: audit: type=1131 audit(1654400694.280:102): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:14:54 ideapad330 systemd[1]: Stopped target Suspend.
Jun 05 09:14:54 ideapad330 systemd-logind[423]: Operation 'sleep' finished.
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.2905] manager: sleep: wake requested (sleeping: yes enabled: yes)
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.2908] device (enp1s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Jun 05 09:14:54 ideapad330 kernel: Generic FE-GE Realtek PHY r8169-0-100:00: attached PHY driver (mii_bus:phy_addr=r8169-0-100:00, irq=MAC)
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.4978] device (wlp2s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Jun 05 09:14:54 ideapad330 kernel: r8169 0000:01:00.0 enp1s0: Link is Down
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.4996] device (p2p-dev-wlp2s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.5004] manager: NetworkManager state is now DISCONNECTED
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.5700] device (wlp2s0): supplicant interface state: internal-starting -> disconnected
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.5701] device (p2p-dev-wlp2s0): state change: unavailable -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.5716] Wi-Fi P2P device controlled by interface wlp2s0 created
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.5723] manager: (p2p-dev-wlp2s0): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/5)
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.5731] device (p2p-dev-wlp2s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.5742] device (wlp2s0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Jun 05 09:14:54 ideapad330 NetworkManager[435]: <info> [1654400694.5756] device (p2p-dev-wlp2s0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Jun 05 09:14:54 ideapad330 gnome-shell[846]: Removing a network device that was not added
Jun 05 09:14:54 ideapad330 kernel: Bluetooth: hci0: using NVM file: qca/nvm_usb_00000302.bin
Jun 05 09:14:55 ideapad330 wpa_supplicant[473]: wlp2s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Jun 05 09:14:58 ideapad330 wpa_supplicant[473]: wlp2s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Jun 05 09:14:59 ideapad330 systemd[1]: systemd-rfkill.service: Deactivated successfully.
Jun 05 09:14:59 ideapad330 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:14:59 ideapad330 kernel: audit: type=1131 audit(1654400699.210:103): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4486] policy: auto-activating connection 'Z9Y8X7' (077ca99e-649e-4275-a20e-45c1662bf96b)
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4491] device (wlp2s0): Activation: starting connection 'Z9Y8X7' (077ca99e-649e-4275-a20e-45c1662bf96b)
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4492] device (wlp2s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4494] manager: NetworkManager state is now CONNECTING
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4496] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4499] device (wlp2s0): Activation: (wifi) access point 'Z9Y8X7' has security, but secrets are required.
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4499] device (wlp2s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4501] sup-iface[d8072fab47be0b6f,1,wlp2s0]: wps: type pbc start...
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4511] device (wlp2s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4514] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4516] device (wlp2s0): Activation: (wifi) connection 'Z9Y8X7' has security, and secrets exist. No new secrets needed.
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4516] Config: added 'ssid' value 'Z9Y8X7'
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4517] Config: added 'scan_ssid' value '1'
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4517] Config: added 'bgscan' value 'simple:30:-70:86400'
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4517] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4517] Config: added 'auth_alg' value 'OPEN'
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4517] Config: added 'psk' value '<hidden>'
Jun 05 09:14:59 ideapad330 wpa_supplicant[473]: wlp2s0: WPS-CANCEL
Jun 05 09:14:59 ideapad330 wpa_supplicant[473]: wlp2s0: SME: Trying to authenticate with 30:49:50:2c:41:e7 (SSID='Z9Y8X7' freq=2417 MHz)
Jun 05 09:14:59 ideapad330 kernel: wlp2s0: authenticate with 30:49:50:2c:41:e7
Jun 05 09:14:59 ideapad330 kernel: wlp2s0: bad VHT capabilities, disabling VHT
Jun 05 09:14:59 ideapad330 kernel: wlp2s0: 80 MHz not supported, disabling VHT
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4975] device (wlp2s0): supplicant interface state: disconnected -> authenticating
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4976] device (p2p-dev-wlp2s0): supplicant management interface state: disconnected -> authenticating
Jun 05 09:14:59 ideapad330 wpa_supplicant[473]: wlp2s0: Trying to associate with 30:49:50:2c:41:e7 (SSID='Z9Y8X7' freq=2417 MHz)
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4993] device (wlp2s0): supplicant interface state: authenticating -> associating
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.4993] device (p2p-dev-wlp2s0): supplicant management interface state: authenticating -> associating
Jun 05 09:14:59 ideapad330 kernel: wlp2s0: send auth to 30:49:50:2c:41:e7 (try 1/3)
Jun 05 09:14:59 ideapad330 kernel: wlp2s0: authenticated
Jun 05 09:14:59 ideapad330 kernel: wlp2s0: associate with 30:49:50:2c:41:e7 (try 1/3)
Jun 05 09:14:59 ideapad330 kernel: wlp2s0: RX AssocResp from 30:49:50:2c:41:e7 (capab=0x1411 status=0 aid=3)
Jun 05 09:14:59 ideapad330 wpa_supplicant[473]: wlp2s0: Associated with 30:49:50:2c:41:e7
Jun 05 09:14:59 ideapad330 wpa_supplicant[473]: wlp2s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jun 05 09:14:59 ideapad330 kernel: wlp2s0: associated
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.5153] device (wlp2s0): supplicant interface state: associating -> associated
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.5154] device (p2p-dev-wlp2s0): supplicant management interface state: associating -> associated
Jun 05 09:14:59 ideapad330 kernel: wlp2s0: Limiting TX power to 20 (20 - 0) dBm as advertised by 30:49:50:2c:41:e7
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.8483] device (wlp2s0): supplicant interface state: associated -> 4way_handshake
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.8484] device (p2p-dev-wlp2s0): supplicant management interface state: associated -> 4way_handshake
Jun 05 09:14:59 ideapad330 wpa_supplicant[473]: wlp2s0: WPA: Key negotiation completed with 30:49:50:2c:41:e7 [PTK=CCMP GTK=CCMP]
Jun 05 09:14:59 ideapad330 wpa_supplicant[473]: wlp2s0: CTRL-EVENT-CONNECTED - Connection to 30:49:50:2c:41:e7 completed [id=0 id_str=]
Jun 05 09:14:59 ideapad330 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.8872] device (wlp2s0): supplicant interface state: 4way_handshake -> completed
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.8873] device (wlp2s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "Z9Y8X7"
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.8873] device (p2p-dev-wlp2s0): supplicant management interface state: 4way_handshake -> completed
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.8878] device (wlp2s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jun 05 09:14:59 ideapad330 NetworkManager[435]: <info> [1654400699.8898] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
Jun 05 09:14:59 ideapad330 wpa_supplicant[473]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-50 noise=-113 txrate=1000
Jun 05 09:15:00 ideapad330 NetworkManager[435]: <info> [1654400700.1494] dhcp4 (wlp2s0): state changed new lease, address=192.168.29.78
Jun 05 09:15:00 ideapad330 NetworkManager[435]: <info> [1654400700.1548] device (wlp2s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Jun 05 09:15:00 ideapad330 NetworkManager[435]: <info> [1654400700.1572] device (wlp2s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Jun 05 09:15:00 ideapad330 NetworkManager[435]: <info> [1654400700.1574] device (wlp2s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Jun 05 09:15:00 ideapad330 NetworkManager[435]: <info> [1654400700.1577] manager: NetworkManager state is now CONNECTED_LOCAL
Jun 05 09:15:00 ideapad330 NetworkManager[435]: <info> [1654400700.1583] manager: NetworkManager state is now CONNECTED_SITE
Jun 05 09:15:00 ideapad330 NetworkManager[435]: <info> [1654400700.1584] policy: set 'Z9Y8X7' (wlp2s0) as default for IPv4 routing and DNS
Jun 05 09:15:00 ideapad330 NetworkManager[435]: <info> [1654400700.4151] device (wlp2s0): Activation: successful, device activated.
Jun 05 09:15:00 ideapad330 systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Jun 05 09:15:00 ideapad330 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:15:00 ideapad330 kernel: audit: type=1131 audit(1654400700.586:104): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:15:00 ideapad330 dbus-daemon[422]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=435 comm="/usr/bin/NetworkManager --no-daemon")
Jun 05 09:15:00 ideapad330 systemd[1]: Starting Network Manager Script Dispatcher Service...
Jun 05 09:15:00 ideapad330 dbus-daemon[422]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jun 05 09:15:00 ideapad330 systemd[1]: Started Network Manager Script Dispatcher Service.
Jun 05 09:15:00 ideapad330 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:15:00 ideapad330 kernel: audit: type=1130 audit(1654400700.680:105): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 09:15:01 ideapad330 NetworkManager[435]: <info> [1654400701.0965] manager: NetworkManager state is now CONNECTED_GLOBAL
Jun 05 09:15:02 ideapad330 NetworkManager[435]: <info> [1654400702.1194] dhcp6 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
Jun 05 09:15:02 ideapad330 NetworkManager[435]: <info> [1654400702.1210] policy: set 'Z9Y8X7' (wlp2s0) as default for IPv6 routing and DNS
Jun 05 09:15:02 ideapad330 NetworkManager[435]: <info> [1654400702.4248] dhcp6 (wlp2s0): state changed new leaseAs we can see, the later includes some ACPI and smpboot event from the
kernel, which are missing from the former. Are these the source of
error?
Thank you.
Offline
I had similar issues on my ThinkPad X230 with coreboot BIOS some time ago. Every once in a while (1 to 10 times) my system failed to wake up from suspend.
You can try the following, one or both of these options helped me and I never encountered the problem ever since.
Option #1: Edit /sys/power/pm_async. Change 1 to 0 and save changes.
This will allow your system to suspend and wakeup synchronously during transitions.
What: /sys/power/pm_async
Date: January 2009
Contact: Rafael J. Wysocki <rjw@sisk.pl>
Description:
The /sys/power/pm_async file controls the switch allowing the
user space to enable or disable asynchronous suspend and resume
of devices. If enabled, this feature will cause some device
drivers' suspend and resume callbacks to be executed in parallel
with each other and with the main suspend thread. It is enabled
if this file contains "1", which is the default. It may be
disabled by writing "0" to this file, in which case all devices
will be suspended and resumed synchronously.If the change to this file don't persist after shutdown, you can create a systemd unit file script that will do it for you. Read more about it on Arch Wiki here: ArchWiki: systemd - Writing Unit Files.
Option #2: Create the file and/or add the following to the /etc/modprobe.d/blacklist.conf:
blacklist mei
blacklist mei_meThis will prevent those kernel modules from loading. I'm not sure about the impact, depending on your setup your system can even fail to boot, but maybe it will help you.
Let me know whether these worked out for you.
Last edited by mard (2022-06-20 23:04:15)
Offline
Hi @mard,
I have only tried the Option #1, as another requires to disable modules
required by Intel Management Engine, which is unfortunately mandatory
in my case. To achieve the task, I used the following Systemd service:
[Unit]
Description=Disable asynchronous suspend and resume
[Service]
Type=oneshot
ExecStart=/bin/sh -c 'echo 0 > /sys/power/pm_async'
[Install]
WantedBy=multi-user.targetBut again, unfortunately, this doesn't work. Additionally, with this,
my systems freezes after suspend on every occasion as
opposed to occasional freezes sans this tweak.
Last edited by utkarsh_singh (2022-07-09 15:21:24)
Offline
JoeyCorleone wrote:Try to suspend using
systemctl suspendand grab a journal of the affected boot + share it here. Consider
rebooting your system gracefully if nothing else works anymore.Please check the aforementioned thread for logs and unfortunately
Magic SysRq keys doesn't work. Is this a case of kernel panic?
Have you enabled the SysRq shortcut? You can test if the shortcut works in a normal session via ALT + SYSRQ + SPACE.
It did work if
sudo dmesgdisplays a help info in one of the last lines.
- Would you mind sharing a complete journal log from a boot with a failed suspend + pressed REISUB sequence?
- If I understood correctly, suspending does fail every now and then, not all the time. Right?
- Disable Bluetooth before suspending. Does it work now?
For the quite sometime, I'm experiencing a problem in laptop
suspension.
So it used to work fine and something broke suspend. Do you remember the time it broke?
- Update your system firmware (BIOS) to the latest available version. Does it work now?
- What kind of graphics driver do you use? Depending on your setup, temporarily switching the driver could be used to rule out the graphics stack.
- Have you installed the linux-firmware package?
- Have you installed the microcode package for your processor?
- If it used to work fine, you could test older kernel versions to rule out a kernel regression. See the AUR LTS kernels or the archive.
For further investigation:
- Install the uswsusp-git package.
- Use the s2ram tool to debug STR.
Basically, you start with testing "freezer" a couple of times and work your way through the list of modes to determine the faulty layer:
echo freezer | sudo tee /sys/power/pm_test
sudo s2ramAnd after a couple of suspends:
sudo cat /sys/kernel/debug/suspend_statsOf course, if a suspend action fails completely, you will notice earlier.
Last edited by JoeyCorleone (2022-07-10 17:27:42)
Offline
NOTE: This reply in WIP, I will test all suggestions incrementally.
Have you
enabled
the SysRq shortcut? You can test if the shortcut works in a normal
session via ALT + SYSRQ + SPACE.It did work if
sudo dmesgdisplays a help info in one of
the last lines.
Yes, I have enabled the SysRq shortcut and here is the output from ALT + SYSRQ + SPACE:
[23816.463853] sysrq: HELP : loglevel(0-9) reboot(b) crash(c) terminate-all-tasks(e) memory-full-oom-kill(f) kill-all-tasks(i) thaw-filesystems(j) sak(k) show-backtrace-all-active-cpus(l) show-memory-usage(m) nice-all-RT-tasks(n) poweroff(o) show-registers(p) show-all-timers(q) unraw(r) sync(s) show-task-states(t) unmount(u) force-fb(v) show-blocked-tasks(w) dump-ftrace-buffer(z)- Would you mind sharing a complete journal log from a boot with a failed suspend + pressed REISUB sequence?
Here are the logs, although please note that REISUB was not
working due to failed suspend and thus I retreated to forced shutdown.
- If I understood correctly, suspending does fail every now and then,
not all the time. Right?
Yes, although there is a rule of thumb: suspend always fail on the 2nd
boot from the failed session. That is, I always have a error-free
session on an reboot from the failed one.
- Disable Bluetooth before suspending. Does it work now?
No, I don't use Bluetooth.
- Update your system firmware (BIOS) to the latest available version. Does it work now?
Unfortunately, my vendor (Lenovo) doesn't provide firmware updates
using only GNU/Linux and I don't have Windows installed.
- What kind of graphics driver do you use? Depending on your setup,
temporarily switching the driver could be used to rule out the
graphics stack.
Here you go:
$ lspci -k | grep -EA3 'VGA|3D|Display'
00:02.0 VGA compatible controller: Intel Corporation UHD Graphics 620 (rev 07)
Subsystem: Lenovo Device 3965
Kernel driver in use: i915
Kernel modules: i915- Have you installed the linux-firmware package?
Yes.
- Have you installed the microcode package for your processor?
Yes, its intel-ucode.
Offline
Pages: 1