You are not logged in.

#1 2022-06-05 05:33:42

utkarsh_singh
Member
Registered: 2022-06-05
Posts: 1
Website

Ideapad 330: Freeze after Suspend

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 lease

As 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

#2 2022-06-20 23:02:05

mard
Member
Registered: 2016-04-03
Posts: 3

Re: Ideapad 330: Freeze after Suspend

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_me

This 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

Board footer

Powered by FluxBB