Wake up from deep sleep fails or takes a very long time

Issues & Assistance Unsupported hardware | Dual boot
1

Hello there

So, I as the forum section suggests: I am new to garuda. I've installed dual boot installed it on my macbookair 6,2 and so far everything runs really smooth, beside one thing thats nagging me: when i send the system do deep sleep, it takes a really long time to get the system to a running state. normally when i hit the power button when its in suspended state, nothing happens. first few times this happend i thought it will not recover at all, but one time i noticed if i just keep smashing and pressing buttons, after a while, suddenly the loginscreen appears. But there seems not to be any pattern. Sometimes the state revovers after "some" tries of pressing power, or any other button, sometimes it takes like 2-3 minutes, and sometimes it seems that it freezes completely.

if tried to take down my wifi interface before entering sleep to determine if maybe the wifi driver might cause the problem. I searched the forums and the web but could'nt find anything usefull. since I am new to this its also difficult to read the logs and identifying the culprits.

so for starters here i post some outputs:

garuda-inxi:

System:
Kernel: 6.0.8-zen1-1-zen arch: x86_64 bits: 64 compiler: gcc v: 12.2.0
parameters: BOOT_IMAGE=/@/boot/vmlinuz-linux-zen
root=UUID=08b943a7-3701-4f11-ae28-e50da4889882 rw rootflags=subvol=@
quiet quiet splash rd.udev.log_priority=3 vt.global_cursor_default=0
loglevel=3
Desktop: KDE Plasma v: 5.26.3 tk: Qt v: 5.15.7 info: latte-dock
wm: kwin_x11 vt: 1 dm: SDDM Distro: Garuda Linux base: Arch Linux
Machine:
Type: Laptop System: Apple product: MacBookAir6,2 v: 1.0
serial: <superuser required> Chassis: type: 10 v: Mac-7DF21CB3ED6977E5
serial: <superuser required>
Mobo: Apple model: Mac-7DF21CB3ED6977E5 v: MacBookAir6,2
serial: <superuser required> UEFI: Apple v: 474.0.0.0.0 date: 08/23/2022
Battery:
ID-1: BAT0 charge: 38.2 Wh (100.0%) condition: 38.2/54.3 Wh (70.3%)
volts: 8.4 min: 7.6 model: SMP bq20z451 type: Li-ion serial: N/A
status: full cycles: 400
ID-2: hidpp_battery_0 charge: 54% condition: N/A volts: 3.8 min: N/A
model: Logitech G903 LIGHTSPEED Wireless Gaming Mouse w/ HERO type: N/A
serial: <filter> status: discharging
CPU:
Info: model: Intel Core i7-4650U bits: 64 type: MT MCP arch: Haswell
gen: core 4 level: v3 note: check built: 2013-15 process: Intel 22nm
family: 6 model-id: 0x45 (69) stepping: 1 microcode: 0x26
Topology: cpus: 1x cores: 2 tpc: 2 threads: 4 smt: enabled cache:
L1: 128 KiB desc: d-2x32 KiB; i-2x32 KiB L2: 512 KiB desc: 2x256 KiB
L3: 4 MiB desc: 1x4 MiB
Speed (MHz): avg: 858 high: 1034 min/max: 800/3300 scaling:
driver: intel_cpufreq governor: schedutil cores: 1: 1034 2: 800 3: 800
4: 800 bogomips: 18400
Flags: avx avx2 ht lm nx pae sse sse2 sse3 sse4_1 sse4_2 ssse3 vmx
Vulnerabilities:
Type: itlb_multihit status: KVM: VMX disabled
Type: l1tf mitigation: PTE Inversion; VMX: conditional cache flushes, SMT
vulnerable
Type: mds mitigation: Clear CPU buffers; SMT vulnerable
Type: meltdown mitigation: PTI
Type: mmio_stale_data status: Unknown: No mitigations
Type: retbleed status: Not affected
Type: spec_store_bypass mitigation: Speculative Store Bypass disabled via
prctl
Type: spectre_v1 mitigation: usercopy/swapgs barriers and __user pointer
sanitization
Type: spectre_v2 mitigation: Retpolines, IBPB: conditional, IBRS_FW,
STIBP: conditional, RSB filling, PBRSB-eIBRS: Not affected
Type: srbds mitigation: Microcode
Type: tsx_async_abort status: Not affected
Graphics:
Device-1: Intel Haswell-ULT Integrated Graphics vendor: Apple driver: i915
v: kernel arch: Gen-7.5 process: Intel 22nm built: 2013 ports: active: eDP-1
empty: DP-1, DP-2, HDMI-A-1, HDMI-A-2 bus-ID: 00:02.0 chip-ID: 8086:0a26
class-ID: 0300
Display: x11 server: X.Org v: 21.1.4 with: Xwayland v: 22.1.5
compositor: kwin_x11 driver: X: loaded: modesetting
alternate: fbdev,intel,vesa dri: crocus gpu: i915 display-ID: :0
screens: 1
Screen-1: 0 s-res: 1440x900 s-dpi: 96 s-size: 381x238mm (15.00x9.37")
s-diag: 449mm (17.69")
Monitor-1: eDP-1 model: Apple Color LCD built: 2012 res: 1440x900 hz: 60
dpi: 126 gamma: 1.2 size: 290x180mm (11.42x7.09") diag: 341mm (13.4")
ratio: 16:10, 15:9 modes: 1440x900
API: OpenGL v: 4.6 Mesa 22.2.3 renderer: Mesa Intel HD Graphics 5000 (HSW
GT3) direct render: Yes
Audio:
Device-1: Intel Haswell-ULT HD Audio vendor: Apple driver: snd_hda_intel
v: kernel bus-ID: 00:03.0 chip-ID: 8086:0a0c class-ID: 0403
Device-2: Intel 8 Series HD Audio driver: snd_hda_intel v: kernel
bus-ID: 00:1b.0 chip-ID: 8086:9c20 class-ID: 0403
Device-3: Broadcom 720p FaceTime HD Camera driver: N/A pcie: gen: 2
speed: 5 GT/s lanes: 1 bus-ID: 02:00.0 chip-ID: 14e4:1570 class-ID: 0480
Sound API: ALSA v: k6.0.8-zen1-1-zen running: yes
Sound Server-1: PulseAudio v: 16.1 running: no
Sound Server-2: PipeWire v: 0.3.59 running: yes
Network:
Device-1: Broadcom BCM4360 802.11ac Wireless Network Adapter vendor: Apple
driver: wl v: kernel modules: bcma pcie: gen: 1 speed: 2.5 GT/s lanes: 1
bus-ID: 03:00.0 chip-ID: 14e4:43a0 class-ID: 0280
IF: wlp3s0 state: up mac: <filter>
Bluetooth:
Device-1: Apple Bluetooth USB Host Controller type: USB driver: btusb v: 0.8
bus-ID: 1-3.3:7 chip-ID: 05ac:828f class-ID: fe01
Report: bt-adapter ID: hci0 rfk-id: 1 state: up address: <filter>
Drives:
Local Storage: total: 233.76 GiB used: 10.86 GiB (4.6%)
SMART Message: Unable to run smartctl. Root privileges required.
ID-1: /dev/sda maj-min: 8:0 vendor: Apple model: SSD SD0256F
size: 233.76 GiB block-size: physical: 4096 B logical: 512 B speed: 6.0 Gb/s
type: SSD serial: <filter> rev: 821 scheme: GPT
Partition:
ID-1: / raw-size: 65.48 GiB size: 65.48 GiB (100.00%)
used: 10.83 GiB (16.5%) fs: btrfs dev: /dev/sda3 maj-min: 8:3
ID-2: /boot/efi raw-size: 200 MiB size: 196.9 MiB (98.45%)
used: 24.4 MiB (12.4%) fs: vfat dev: /dev/sda1 maj-min: 8:1
ID-3: /home raw-size: 65.48 GiB size: 65.48 GiB (100.00%)
used: 10.83 GiB (16.5%) fs: btrfs dev: /dev/sda3 maj-min: 8:3
ID-4: /var/log raw-size: 65.48 GiB size: 65.48 GiB (100.00%)
used: 10.83 GiB (16.5%) fs: btrfs dev: /dev/sda3 maj-min: 8:3
ID-5: /var/tmp raw-size: 65.48 GiB size: 65.48 GiB (100.00%)
used: 10.83 GiB (16.5%) fs: btrfs dev: /dev/sda3 maj-min: 8:3
Swap:
Kernel: swappiness: 133 (default 60) cache-pressure: 100 (default)
ID-1: swap-1 type: zram size: 7.7 GiB used: 0 KiB (0.0%) priority: 100
dev: /dev/zram0
Sensors:
System Temperatures: cpu: 71.0 C mobo: N/A
Fan Speeds (RPM): N/A
Info:
Processes: 216 Uptime: 1h 50m wakeups: 26 Memory: 7.7 GiB
used: 2.99 GiB (38.8%) Init: systemd v: 252 default: graphical
tool: systemctl Compilers: gcc: 12.2.0 Packages: pm: pacman pkgs: 1252
libs: 342 tools: octopi,paru Shell: fish v: 3.5.1 default: Bash v: 5.1.16
running-in: konsole inxi: 3.3.23
Garuda (2.6.9-1):
System install date:     2022-11-11
Last full system update: 2022-11-14
Is partially upgraded:   No
Relevant software:       NetworkManager
Windows dual boot:       No/Undetected
Snapshots:               Snapper
Failed units:

so now, as i read in some other posts, there are some logs that could help, i hope the formatting will be acceptable.

to help understand what i was doing: i sent the system to deep sleep at almost exactly 22:08 and on 22:12 i tried to wake the system up but screen was finally up between 22:13 and 22:14.

journalctl:

Nov 14 22:14:05 Sephora kernel: audit: type=1100 audit(1668460445.474:161): pid=4736 uid=1000 auid=1000 ses=3 msg='op=PAM:unix_chkpwd acct="leno" exe="/usr/bin/unix_chkpwd" hostname=? addr=? terminal=? res=success'
Nov 14 22:14:05 Sephora audit[4736]: USER_AUTH pid=4736 uid=1000 auid=1000 ses=3 msg='op=PAM:unix_chkpwd acct="leno" exe="/usr/bin/unix_chkpwd" hostname=? addr=? terminal=? res=success'
Nov 14 22:13:51 Sephora kernel: audit: type=1131 audit(1668460431.632:160): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 14 22:13:51 Sephora 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'
Nov 14 22:13:51 Sephora systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.6169] manager: NetworkManager state is now CONNECTED_GLOBAL
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.4959] device (wlp3s0): Activation: successful, device activated.
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.4955] manager: NetworkManager state is now CONNECTED_SITE
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.4952] device (wlp3s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.4950] device (wlp3s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.4919] device (wlp3s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Nov 14 22:13:41 Sephora avahi-daemon[437]: Registering new address record for 192.168.0.102 on wlp3s0.IPv4.
Nov 14 22:13:41 Sephora avahi-daemon[437]: New relevant interface wlp3s0.IPv4 for mDNS.
Nov 14 22:13:41 Sephora avahi-daemon[437]: Joining mDNS multicast group on interface wlp3s0.IPv4 with address 192.168.0.102.
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.4798] policy: set 'Nostromo' (wlp3s0) as default for IPv4 routing and DNS
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.4793] dhcp4 (wlp3s0): state changed new lease, address=192.168.0.102
Nov 14 22:13:41 Sephora avahi-daemon[437]: Registering new address record for fe80::d615:cb50:d17d:537e on wlp3s0.*.
Nov 14 22:13:41 Sephora avahi-daemon[437]: New relevant interface wlp3s0.IPv6 for mDNS.
Nov 14 22:13:41 Sephora avahi-daemon[437]: Joining mDNS multicast group on interface wlp3s0.IPv6 with address fe80::d615:cb50:d17d:537e.
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.0297] dhcp4 (wlp3s0): activation: beginning transaction (timeout in 45 seconds)
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.0286] device (wlp3s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.0285] device (wlp3s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "Nostromo"
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.0284] device (wlp3s0): supplicant interface state: 4way_handshake -> completed
Nov 14 22:13:41 Sephora wpa_supplicant[522]: bgscan simple: Failed to enable signal strength monitoring
Nov 14 22:13:41 Sephora kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
Nov 14 22:13:41 Sephora wpa_supplicant[522]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to 1c:b7:2c:c0:e6:18 completed [id=0 id_str=]
Nov 14 22:13:41 Sephora wpa_supplicant[522]: wlp3s0: WPA: Key negotiation completed with 1c:b7:2c:c0:e6:18 [PTK=CCMP GTK=CCMP]
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.0188] device (wlp3s0): supplicant interface state: associated -> 4way_handshake
Nov 14 22:13:41 Sephora NetworkManager[463]: <info>  [1668460421.0128] device (wlp3s0): supplicant interface state: associating -> associated
Nov 14 22:13:41 Sephora wpa_supplicant[522]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Nov 14 22:13:41 Sephora wpa_supplicant[522]: wlp3s0: Associated with 1c:b7:2c:c0:e6:18
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9216] device (wlp3s0): supplicant interface state: inactive -> associating
Nov 14 22:13:40 Sephora wpa_supplicant[522]: wlp3s0: Trying to associate with 1c:b7:2c:c0:e6:18 (SSID='Nostromo' freq=2422 MHz)
Nov 14 22:13:40 Sephora kdeconnectd[1440]: "Object does not exist at path “/org/freedesktop/NetworkManager/ActiveConnection/1”"
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9074] Config: added 'psk' value '<hidden>'
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9074] Config: added 'auth_alg' value 'OPEN'
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9074] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9073] Config: added 'bgscan' value 'simple:30:-70:86400'
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9073] Config: added 'scan_ssid' value '1'
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9073] Config: added 'ssid' value 'Nostromo'
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9073] device (wlp3s0): Activation: (wifi) connection 'Nostromo' has security, and secrets exist.  No new secrets needed.
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9069] device (wlp3s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Nov 14 22:13:40 Sephora garuda-system-maintenance[1434]: "Object does not exist at path “/org/freedesktop/NetworkManager/ActiveConnection/1”"
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.9065] device (wlp3s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.8990] device (wlp3s0): supplicant interface state: disconnected -> inactive
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.8807] device (wlp3s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.8807] device (wlp3s0): Activation: (wifi) access point 'Nostromo' has security, but secrets are required.
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.8803] device (wlp3s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.8799] device (wlp3s0): set-hw-addr: reset MAC address to D0:E1:40:9D:9B:88 (preserve)
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.8787] manager: NetworkManager state is now CONNECTING
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.8783] device (wlp3s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.8781] device (wlp3s0): Activation: starting connection 'Nostromo' (a46efc85-5891-4e22-90bb-fc8ed2fed621)
Nov 14 22:13:40 Sephora NetworkManager[463]: <info>  [1668460420.8774] policy: auto-activating connection 'Nostromo' (a46efc85-5891-4e22-90bb-fc8ed2fed621)
Nov 14 22:13:40 Sephora wpa_supplicant[522]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Nov 14 22:13:40 Sephora wpa_supplicant[522]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Nov 14 22:13:39 Sephora ModemManager[480]: <info>  [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:1c.2/0000:03:00.0': not supported by any plugin
Nov 14 22:13:36 Sephora latte-dock[1836]: [1836:4019:1114/221336.912361:ERROR:get_updates_processor.cc(260)] PostClientToServerMessage() failed during GetUpdates with error 2
Nov 14 22:13:36 Sephora NetworkManager[463]: <info>  [1668460416.8794] device (wlp3s0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Nov 14 22:13:36 Sephora NetworkManager[463]: <info>  [1668460416.8794] device (wlp3s0): supplicant interface state: internal-starting -> disconnected
Nov 14 22:13:36 Sephora wpa_supplicant[522]: dbus: fill_dict_with_properties dbus_interface=fi.w1.wpa_supplicant1.Interface.P2PDevice dbus_property=P2PDeviceConfig getter failed
Nov 14 22:13:36 Sephora NetworkManager[463]: <info>  [1668460416.8487] manager: NetworkManager state is now DISCONNECTED
Nov 14 22:13:36 Sephora avahi-daemon[437]: Interface wlp3s0.IPv6 no longer relevant for mDNS.
Nov 14 22:13:36 Sephora NetworkManager[463]: <info>  [1668460416.8474] device (wlp3s0): set-hw-addr: set MAC address to EA:C2:4B:DF:E0:30 (scanning)
Nov 14 22:13:36 Sephora avahi-daemon[437]: Leaving mDNS multicast group on interface wlp3s0.IPv6 with address fe80::d2e1:40ff:fe9d:9b88.
Nov 14 22:13:36 Sephora NetworkManager[463]: <info>  [1668460416.8427] device (wlp3s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Nov 14 22:13:36 Sephora avahi-daemon[437]: Withdrawing address record for fe80::d2e1:40ff:fe9d:9b88 on wlp3s0.
Nov 14 22:13:36 Sephora NetworkManager[463]: <info>  [1668460416.8426] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Nov 14 22:13:36 Sephora ModemManager[480]: <info>  [sleep-monitor-systemd] system is resuming
Nov 14 22:13:36 Sephora systemd-logind[452]: Operation 'sleep' finished.
Nov 14 22:13:36 Sephora systemd[1]: Stopped target Suspend.
Nov 14 22:13:36 Sephora systemd[1]: Reached target Suspend.
Nov 14 22:13:36 Sephora kernel: audit: type=1131 audit(1668460416.838:159): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 14 22:13:36 Sephora systemd[1]: Stopped target Sleep.
Nov 14 22:13:36 Sephora systemd[1]: Finished System Suspend.
Nov 14 22:13:36 Sephora systemd[1]: systemd-suspend.service: Deactivated successfully.
Nov 14 22:13:36 Sephora 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'
Nov 14 22:13:36 Sephora 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'
Nov 14 22:13:36 Sephora kernel: audit: type=1130 audit(1668460416.838:158): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 14 22:13:36 Sephora systemd-sleep[4621]: System returned from sleep state.
Nov 14 22:13:36 Sephora bluetoothd[465]: Controller resume with wake event 0x0
Nov 14 22:13:36 Sephora kernel: PM: suspend exit
Nov 14 22:13:36 Sephora kernel: video LNXVIDEO:00: Restoring backlight state
Nov 14 22:13:36 Sephora kernel: audit: type=1130 audit(1668460416.814:157): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 14 22:13:36 Sephora systemd[1]: Started Network Manager Script Dispatcher Service.
Nov 14 22:13:36 Sephora dbus-daemon[438]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Nov 14 22:13:36 Sephora 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'
Nov 14 22:13:36 Sephora kscreenlocker_greet[4566]: pam_systemd_home(kde:auth): systemd-homed is not available: Unit dbus-org.freedesktop.home1.service not found.
Nov 14 22:13:36 Sephora dbus-daemon[438]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Nov 14 22:13:36 Sephora dbus-daemon[438]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.96' (uid=1000 pid=4566 comm="/usr/lib/kscreenlocker_greet --immediateLock --gra")
Nov 14 22:13:36 Sephora systemd[1]: Starting Network Manager Script Dispatcher Service...
Nov 14 22:13:36 Sephora systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Nov 14 22:13:36 Sephora 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'
Nov 14 22:13:36 Sephora kernel: audit: type=1131 audit(1668460416.789:156): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 14 22:13:36 Sephora kernel: random: crng reseeded on system resumption
Nov 14 22:13:36 Sephora kernel: Restarting tasks ... done.
Nov 14 22:13:36 Sephora dbus-daemon[438]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.9' (uid=0 pid=463 comm="/usr/bin/NetworkManager --no-daemon")
Nov 14 22:13:36 Sephora avahi-daemon[437]: Registering new address record for fe80::d2e1:40ff:fe9d:9b88 on wlp3s0.*.
Nov 14 22:13:36 Sephora avahi-daemon[437]: New relevant interface wlp3s0.IPv6 for mDNS.
Nov 14 22:13:36 Sephora avahi-daemon[437]: Joining mDNS multicast group on interface wlp3s0.IPv6 with address fe80::d2e1:40ff:fe9d:9b88.
Nov 14 22:13:36 Sephora rtkit-daemon[1240]: Demoted 0 threads.
Nov 14 22:13:36 Sephora rtkit-daemon[1240]: Demoting known real-time threads.
Nov 14 22:13:36 Sephora libinput-gestures[1511]: libinput error: WARNING: log rate limit exceeded (5 msgs per 3600000ms). Discarding future messages.
Nov 14 22:13:36 Sephora libinput-gestures[1511]: libinput error: client bug: timer event5 hold: scheduled expiry is in the past (-286ms), your system is too slow
Nov 14 22:13:36 Sephora libinput-gestures[1511]: libinput error: client bug: timer event5 hold: scheduled expiry is in the past (-328ms), your system is too slow
Nov 14 22:13:36 Sephora rtkit-daemon[1240]: The canary thread is apparently starving. Taking action.
Nov 14 22:13:36 Sephora kernel: OOM killer enabled.
Nov 14 22:13:36 Sephora kernel: ata1.00: configured for UDMA/133
Nov 14 22:13:36 Sephora kernel: ata1.00: unexpected _GTF length (8)
Nov 14 22:13:36 Sephora kernel: ata1.00: unexpected _GTF length (8)
Nov 14 22:13:36 Sephora kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Nov 14 22:13:36 Sephora kernel: sd 0:0:0:0: [sda] Starting disk
Nov 14 22:13:36 Sephora kernel: ACPI: EC: event unblocked
Nov 14 22:13:36 Sephora kernel: ACPI: EC: interrupt unblocked
Nov 14 22:13:36 Sephora kernel: ACPI: PM: Waking up from system sleep state S3
Nov 14 22:13:36 Sephora kernel: CPU3 is up
Nov 14 22:13:36 Sephora kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Nov 14 22:13:36 Sephora kernel: CPU2 is up
Nov 14 22:13:36 Sephora kernel: smpboot: Booting Node 0 Processor 2 APIC 0x1
Nov 14 22:13:36 Sephora kernel: CPU1 is up
Nov 14 22:13:36 Sephora kernel: smpboot: Booting Node 0 Processor 1 APIC 0x2
Nov 14 22:13:36 Sephora kernel: x86: Booting SMP configuration:
Nov 14 22:13:36 Sephora kernel: Enabling non-boot CPUs ...
Nov 14 22:13:36 Sephora kernel: ACPI: PM: Restoring platform NVS memory
Nov 14 22:13:36 Sephora kernel: ACPI: EC: EC started
Nov 14 22:13:36 Sephora kernel: ACPI: PM: Low-level resume complete
Nov 14 22:13:36 Sephora kernel: smpboot: CPU 3 is now offline
Nov 14 22:13:36 Sephora kernel: smpboot: CPU 2 is now offline
Nov 14 22:13:36 Sephora kernel: smpboot: CPU 1 is now offline
Nov 14 22:13:36 Sephora kernel: Disabling non-boot CPUs ...
Nov 14 22:13:36 Sephora kernel: ACPI: PM: Saving platform NVS memory
Nov 14 22:13:36 Sephora kernel: ACPI: EC: EC stopped
Nov 14 22:13:36 Sephora kernel: ACPI: EC: event blocked
Nov 14 22:13:36 Sephora kernel: ACPI: PM: Preparing to enter system sleep state S3
Nov 14 22:13:36 Sephora kernel: pcieport 0000:05:00.0: quirk_apple_poweroff_thunderbolt+0x0/0x100 took 296002 usecs
Nov 14 22:13:36 Sephora kernel: pcieport 0000:05:00.0: quirk: cutting power to Thunderbolt controller...
Nov 14 22:13:36 Sephora kernel: ACPI: EC: interrupt blocked
Nov 14 22:13:36 Sephora kernel: sd 0:0:0:0: [sda] Stopping disk
Nov 14 22:13:36 Sephora kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Nov 14 22:13:36 Sephora kernel: wlp3s0 Scan_results error (-22)
Nov 14 22:13:36 Sephora kernel: ERROR @wl_notify_scan_status : 
Nov 14 22:13:36 Sephora kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Nov 14 22:13:36 Sephora kernel: Freezing remaining freezable tasks ... (elapsed 0.000 seconds) done.
Nov 14 22:13:36 Sephora kernel: OOM killer disabled.
Nov 14 22:13:36 Sephora kernel: Freezing user space processes ... (elapsed 0.002 seconds) done.
Nov 14 22:13:36 Sephora kernel: Filesystems sync: 0.049 seconds
Nov 14 22:08:01 Sephora kernel: PM: suspend entry (deep)
Nov 14 22:08:01 Sephora systemd-sleep[4621]: Entering sleep state 'suspend'...
Nov 14 22:08:01 Sephora systemd[1]: Starting System Suspend...
Nov 14 22:08:01 Sephora systemd[1]: Reached target Sleep.
Nov 14 22:08:01 Sephora kscreenlocker_greet[4566]: file:///usr/share/plasma/look-and-feel/org.kde.breeze.desktop/contents/components/VirtualKeyboard.qml:8:1: module "QtQuick.VirtualKeyboard" is not installed
Nov 14 22:08:01 Sephora kscreenlocker_greet[4566]: kf.kirigami: Failed to find a Kirigami platform plugin
Nov 14 22:08:00 Sephora kscreenlocker_greet[4566]: kscreenlocker_greet: Lockscreen QML outdated, falling back to default
Nov 14 22:08:00 Sephora kscreenlocker_greet[4566]: Qt: Session management error: networkIdsList argument is NULL
Nov 14 22:08:00 Sephora sudo[4582]:     leno : a password is required ; TTY=pts/2 ; PWD=/home/leno ; USER=root ; COMMAND=/usr/bin/true
Nov 14 22:08:00 Sephora kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
Nov 14 22:08:00 Sephora wpa_supplicant[522]: nl80211: deinit ifname=wlp3s0 disabled_11b_rates=0
Nov 14 22:08:00 Sephora wpa_supplicant[522]: wlp3s0: CTRL-EVENT-DSCP-POLICY clear_all
Nov 14 22:08:00 Sephora wpa_supplicant[522]: wlp3s0: CTRL-EVENT-DSCP-POLICY clear_all
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.6296] device (wlp3s0): set-hw-addr: reset MAC address to D0:E1:40:9D:9B:88 (unmanage)
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.6289] device (wlp3s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Nov 14 22:08:00 Sephora avahi-daemon[437]: Interface wlp3s0.IPv4 no longer relevant for mDNS.
Nov 14 22:08:00 Sephora avahi-daemon[437]: Leaving mDNS multicast group on interface wlp3s0.IPv4 with address 192.168.0.102.
Nov 14 22:08:00 Sephora avahi-daemon[437]: Withdrawing address record for 192.168.0.102 on wlp3s0.
Nov 14 22:08:00 Sephora avahi-daemon[437]: Registering new address record for 192.168.0.102 on wlp3s0.IPv4.
Nov 14 22:08:00 Sephora avahi-daemon[437]: New relevant interface wlp3s0.IPv4 for mDNS.
Nov 14 22:08:00 Sephora avahi-daemon[437]: Joining mDNS multicast group on interface wlp3s0.IPv4 with address 192.168.0.102.
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.6071] device (wlp3s0): set-hw-addr: set MAC address to 22:9D:54:98:42:30 (scanning)
Nov 14 22:08:00 Sephora avahi-daemon[437]: Withdrawing address record for 192.168.0.102 on wlp3s0.
Nov 14 22:08:00 Sephora avahi-daemon[437]: Leaving mDNS multicast group on interface wlp3s0.IPv4 with address 192.168.0.102.
Nov 14 22:08:00 Sephora avahi-daemon[437]: Interface wlp3s0.IPv4 no longer relevant for mDNS.
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.6061] dhcp4 (wlp3s0): state changed no lease
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.6061] dhcp4 (wlp3s0): activation: beginning transaction (timeout in 45 seconds)
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.6061] dhcp4 (wlp3s0): canceled DHCP transaction
Nov 14 22:08:00 Sephora avahi-daemon[437]: Interface wlp3s0.IPv6 no longer relevant for mDNS.
Nov 14 22:08:00 Sephora avahi-daemon[437]: Leaving mDNS multicast group on interface wlp3s0.IPv6 with address fe80::d615:cb50:d17d:537e.
Nov 14 22:08:00 Sephora avahi-daemon[437]: Withdrawing address record for fe80::d615:cb50:d17d:537e on wlp3s0.
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.5988] device (wlp3s0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.5986] device (wlp3s0): supplicant interface state: completed -> disconnected
Nov 14 22:08:00 Sephora wpa_supplicant[522]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Nov 14 22:08:00 Sephora wpa_supplicant[522]: nl80211: Was expecting local disconnect but got another disconnect event first
Nov 14 22:08:00 Sephora wpa_supplicant[522]: wlp3s0: CTRL-EVENT-DSCP-POLICY clear_all
Nov 14 22:08:00 Sephora wpa_supplicant[522]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=1c:b7:2c:c0:e6:18 reason=3 locally_generated=1
Nov 14 22:08:00 Sephora kernel: audit: type=1130 audit(1668460080.589:155): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 14 22:08:00 Sephora 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'
Nov 14 22:08:00 Sephora systemd[1]: Started Network Manager Script Dispatcher Service.
Nov 14 22:08:00 Sephora dbus-daemon[438]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Nov 14 22:08:00 Sephora systemd[1]: Starting Network Manager Script Dispatcher Service...
Nov 14 22:08:00 Sephora polkitd[447]: Unregistered Authentication Agent for unix-process:4554:383159 (system bus name :1.92, object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8) (disconnected from bus)
Nov 14 22:08:00 Sephora dbus-daemon[438]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.9' (uid=0 pid=463 comm="/usr/bin/NetworkManager --no-daemon")
Nov 14 22:08:00 Sephora kded5[1249]: org.kde.plasma.nm.kded: Unhandled active connection state change:  3
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.5722] device (wlp3s0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.5721] manager: NetworkManager state is now ASLEEP
Nov 14 22:08:00 Sephora ModemManager[480]: <info>  [sleep-monitor-systemd] system is about to suspend
Nov 14 22:08:00 Sephora NetworkManager[463]: <info>  [1668460080.5720] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Nov 14 22:08:00 Sephora systemd-logind[452]: The system will suspend now!

if i should provide any more information just let me know.

and thanks for your help guys in advance. :slight_smile:

2

since the message body was to long here also dmesg output.

dmesg

[Mo Nov 14 22:12:01 2022] audit: type=1130 audit(1668460080.589:155): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mo Nov 14 22:12:01 2022] IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
[Mo Nov 14 22:12:02 2022] PM: suspend entry (deep)
[Mo Nov 14 22:12:02 2022] Filesystems sync: 0.049 seconds
[Mo Nov 14 22:12:02 2022] Freezing user space processes ... (elapsed 0.002 seconds) done.
[Mo Nov 14 22:12:02 2022] OOM killer disabled.
[Mo Nov 14 22:12:02 2022] Freezing remaining freezable tasks ... (elapsed 0.000 seconds) done.
[Mo Nov 14 22:12:02 2022] printk: Suspending console(s) (use no_console_suspend to debug)
[Mo Nov 14 22:12:02 2022] ERROR @wl_notify_scan_status :
[Mo Nov 14 22:12:02 2022] wlp3s0 Scan_results error (-22)
[Mo Nov 14 22:12:02 2022] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[Mo Nov 14 22:12:02 2022] sd 0:0:0:0: [sda] Stopping disk
[Mo Nov 14 22:12:02 2022] ACPI: EC: interrupt blocked
[Mo Nov 14 22:12:02 2022] pcieport 0000:05:00.0: quirk: cutting power to Thunderbolt controller...
[Mo Nov 14 22:12:03 2022] pcieport 0000:05:00.0: quirk_apple_poweroff_thunderbolt+0x0/0x100 took 296002 usecs
[Mo Nov 14 22:12:03 2022] ACPI: PM: Preparing to enter system sleep state S3
[Mo Nov 14 22:12:03 2022] ACPI: EC: event blocked
[Mo Nov 14 22:12:03 2022] ACPI: EC: EC stopped
[Mo Nov 14 22:12:03 2022] ACPI: PM: Saving platform NVS memory
[Mo Nov 14 22:12:03 2022] Disabling non-boot CPUs ...
[Mo Nov 14 22:12:03 2022] smpboot: CPU 1 is now offline
[Mo Nov 14 22:12:03 2022] smpboot: CPU 2 is now offline
[Mo Nov 14 22:12:03 2022] smpboot: CPU 3 is now offline
[Mo Nov 14 22:12:03 2022] ACPI: PM: Low-level resume complete
[Mo Nov 14 22:12:03 2022] ACPI: EC: EC started
[Mo Nov 14 22:12:03 2022] ACPI: PM: Restoring platform NVS memory
[Mo Nov 14 22:12:03 2022] Enabling non-boot CPUs ...
[Mo Nov 14 22:12:03 2022] x86: Booting SMP configuration:
[Mo Nov 14 22:12:03 2022] smpboot: Booting Node 0 Processor 1 APIC 0x2
[Mo Nov 14 22:13:28 2022] CPU1 is up
[Mo Nov 14 22:13:28 2022] smpboot: Booting Node 0 Processor 2 APIC 0x1
[Mo Nov 14 22:13:28 2022] CPU2 is up
[Mo Nov 14 22:13:28 2022] smpboot: Booting Node 0 Processor 3 APIC 0x3
[Mo Nov 14 22:13:35 2022] CPU3 is up
[Mo Nov 14 22:13:35 2022] ACPI: PM: Waking up from system sleep state S3
[Mo Nov 14 22:13:35 2022] ACPI: EC: interrupt unblocked
[Mo Nov 14 22:13:35 2022] ACPI: EC: event unblocked
[Mo Nov 14 22:13:35 2022] sd 0:0:0:0: [sda] Starting disk
[Mo Nov 14 22:13:35 2022] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[Mo Nov 14 22:13:35 2022] ata1.00: unexpected _GTF length (8)
[Mo Nov 14 22:13:35 2022] ata1.00: unexpected _GTF length (8)
[Mo Nov 14 22:13:35 2022] ata1.00: configured for UDMA/133
[Mo Nov 14 22:13:35 2022] OOM killer enabled.
[Mo Nov 14 22:13:35 2022] Restarting tasks ... done.
[Mo Nov 14 22:13:36 2022] random: crng reseeded on system resumption
[Mo Nov 14 22:13:36 2022] audit: type=1131 audit(1668460416.789:156): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mo Nov 14 22:13:36 2022] audit: type=1130 audit(1668460416.814:157): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mo Nov 14 22:13:36 2022] video LNXVIDEO:00: Restoring backlight state
[Mo Nov 14 22:13:36 2022] PM: suspend exit
[Mo Nov 14 22:13:36 2022] audit: type=1130 audit(1668460416.838:158): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mo Nov 14 22:13:36 2022] audit: type=1131 audit(1668460416.838:159): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mo Nov 14 22:13:40 2022] IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
[Mo Nov 14 22:13:50 2022] audit: type=1131 audit(1668460431.632:160): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[Mo Nov 14 22:14:04 2022] audit: type=1100 audit(1668460445.474:161): pid=4736 uid=1000 auid=1000 ses=3 msg='op=PAM:unix_chkpwd acct="leno" exe="/usr/bin/unix_chkpwd" hostname=? addr=? terminal=? res=success'
3

Welcome :slight_smile:

You are format the log very well, like the garuda-inxi, good start :slight_smile:

For long terminal outputs we prefer

you find the link on the forum pages upper right too. Just for next time

I can't help, first thing I remove, is on every DE, these sleep functions :slight_smile:

1 Like
4

Have you tried adding the kernel parameter "mem_sleep_default=s2idle"? I know you specifically asked about "deep" but I just want to make sure you are aware of this. I've got access to MacBookAir7,2 and this option allows the sleep, I think (keyboard backlight still stays on) but I can't ping it nor does a process continue processing but maybe it's not fully asleep still, I'm not sure. That and installing "bcwc-pcie-git" and "facetimehd-firmware" makes these great machines!

4 Likes
5

I agree, this is a good solution. Your device will resume from suspend much more rapidly and will be less likely to hang.

Bear in mind, this switches you from suspend-to-disk to suspend-to-RAM. A drawback of this is RAM must be constantly powered, so your device will continue to draw from the battery while suspended. If you tend to leave the computer for long stretches you may need to get in the habit of either plugging it in or shutting it down.

5 Likes
6

Another caution if you go the above route is that if you are on battery and you run out of juice, you may lose any unsaved work.

5 Likes
8

:slight_smile: Heya! Thanks for the warm welcome and thanks for the PrivateBin Info!

1 Like
9

So, this does not solve my original deep sleep problem, but, s2idle works very fine for me! Added the kernel parameter as you suggested and it works. I can close my notebook for a quick break, reopen it and resuming without waiting times. as some of you guys mentioned, for longer breaks its always a good habit of saving all data and shutting down the system or plug in the power cord.

guys and gals, thanks all for your help and suggestions :sunglasses:

2 Likes
10

You are right, it is more of a workaround. If you want to dig deeper (regarding getting the deep sleep working), the first things that come to mind would be the BIOS version and the kernel in use.

Since you have a MacBook, I don’t think you have any control over the BIOS/firmware. Judging by your inxi, it looks like you have a pretty recent version anyway.

You can test other kernels though, and you may find one that handles resuming from deep suspend better. I would try the vanilla Linux kernel and the LTS kernel for starters, if you are interested in falling down the rabbit hole.

4 Likes
11

This topic was automatically closed 2 days after the last reply. New replies are no longer allowed.