Slow wake from sleep

loganb · October 7, 2022, 4:57am

Hi!

I’ve been trying out f37 beta and I’ve been generally loving it on my MacBookPro11,1. However, it seems that I’ve either ran in to a post install configuration issue or a bug with regards to suspend resume on this device.

It appears that my system can go to sleep without any issue but cannot wake from sleep. I’ve tried all methods of waking it, touch pad, keyboard, opening and closing the lid, pressing the power button, and even trying to reconnect the power in hopes that would cause it to wake but no luck.

I think this issue is related to wayland window manager. If I switch to xorg on login this issue goes away completely.

This MacBook has just Intel graphics and is a fresh install.

I’ve been looking around for some existing threads but I’ve yet to see any issue like this that is not either very old or is involving some nvidia gpu.

Any advice would be greatly appreciated!

ilikelinux · October 7, 2022, 11:35am

Welcome to ask.edora @loganb

Could you please give us more info’s as :

inxi -Fzx in terminal and post the output as </> Preformatted text here.
If you not had time yet, please also read the section #start-here

loganb · October 7, 2022, 4:27pm

@ilikelinux, sure thing! I was gonna post hwinfo but it looked a bit too verbose.

Here is the output of inxi -Fzx

$ sudo inxi -Fzx

System:
  Kernel: 5.19.13-300.fc37.x86_64 arch: x86_64 bits: 64 compiler: gcc
    v: 2.38-24.fc37 Desktop: GNOME v: 43.0 Distro: Fedora release 37 (Thirty
    Seven)
Machine:
  Type: Laptop System: Apple product: MacBookPro11,1 v: 1.0 serial: <filter>
  Mobo: Apple model: Mac-189A3D4F975D5FFC v: MacBookPro11,1
    serial: <filter> UEFI: Apple v: MBP111.88Z.0146.B00.1804111138
    date: 04/11/2018
Battery:
  ID-1: BAT0 charge: 58.6 Wh (98.7%) condition: 59.4/71.5 Wh (83.0%)
    volts: 12.7 min: 11.3 model: DP bq20z451 status: full
CPU:
  Info: dual core model: Intel Core i5-4288U bits: 64 type: MT MCP
    arch: Haswell rev: 1 cache: L1: 128 KiB L2: 512 KiB L3: 3 MiB
  Speed (MHz): avg: 800 min/max: 800/3100 cores: 1: 800 2: 800 3: 800
    4: 800 bogomips: 20799
  Flags: avx avx2 ht lm nx pae sse sse2 sse3 sse4_1 sse4_2 ssse3 vmx
Graphics:
  Device-1: Intel Haswell-ULT Integrated Graphics vendor: Apple driver: i915
    v: kernel arch: Gen-7.5 bus-ID: 00:02.0
  Display: server: X.Org v: 1.20.14 with: Xwayland v: 22.1.3 driver: X:
    loaded: modesetting unloaded: fbdev,vesa gpu: i915
    resolution: 2560x1600~60Hz
  OpenGL: renderer: Mesa Intel Iris Graphics 5100 (HSW GT3) v: 4.6 Mesa
    22.2.0 direct render: Yes
Audio:
  Device-1: Intel Haswell-ULT HD Audio vendor: Apple driver: snd_hda_intel
    v: kernel bus-ID: 00:03.0
  Device-2: Intel 8 Series HD Audio driver: snd_hda_intel v: kernel
    bus-ID: 00:1b.0
  Device-3: Broadcom 720p FaceTime HD Camera driver: N/A bus-ID: 02:00.0
  Sound Server-1: ALSA v: k5.19.13-300.fc37.x86_64 running: yes
  Sound Server-2: PulseAudio v: 16.1 running: no
  Sound Server-3: PipeWire v: 0.3.59 running: yes
Network:
  Device-1: Broadcom BCM4360 802.11ac Wireless Network Adapter vendor: Apple
    driver: wl v: kernel bus-ID: 03:00.0
  IF: wlp3s0 state: up mac: <filter>
Bluetooth:
  Device-1: Apple Bluetooth Host Controller type: USB driver: btusb v: 0.8
    bus-ID: 1-3.3:6
  Report: rfkill ID: hci0 rfk-id: 0 state: up address: see --recommends
Drives:
  Local Storage: total: 465.92 GiB used: 56.47 GiB (12.1%)
  ID-1: /dev/sda vendor: Apple model: SSD SM0512F size: 465.92 GiB
    temp: 34 C
Partition:
  ID-1: / size: 464.32 GiB used: 56.23 GiB (12.1%) fs: btrfs dev: /dev/dm-0
    mapped: luks-ae6f06c8-9cb7-40e8-967b-b14670776667
  ID-2: /boot size: 973.4 MiB used: 220.5 MiB (22.7%) fs: ext4
    dev: /dev/sda2
  ID-3: /boot/efi size: 600 MiB used: 30.6 MiB (5.1%) fs: hfsplus
    dev: /dev/sda1
  ID-4: /home size: 464.32 GiB used: 56.23 GiB (12.1%) fs: btrfs
    dev: /dev/dm-0 mapped: luks-ae6f06c8-9cb7-40e8-967b-b14670776667
Swap:
  ID-1: swap-1 type: zram size: 7.67 GiB used: 0 KiB (0.0%) dev: /dev/zram0
Sensors:
  System Temperatures: cpu: 55.0 C mobo: N/A
  Fan Speeds (RPM): N/A
Info:
  Processes: 304 Uptime: 5m Memory: 7.67 GiB used: 3.59 GiB (46.8%)
  Init: systemd target: graphical (5) Compilers: gcc: 12.2.1 Packages: 20
  note: see --rpm Shell: Bash v: 5.1.16 inxi: 3.3.21

One more thing to note is that I did attempt a fix to prevent unwanted wake events as I had that issue on the old linux mint install on this laptop but this was not needed. This service has now been disabled.

sudo cat /etc/systemd/system/fix-spurious-wakeups.service 

[Service]
Type=oneshot
ExecStart=sh -c "echo XHC1 > /proc/acpi/wakeup"
RemainAfterExit=true
[Install]
WantedBy=multi-user.target

jpbn · October 7, 2022, 6:24pm

Some laptops do not wake up after a sleep. Mine only wakes up when running with a loader attached. Not when on Battery.
The hardware cannot follow the opening of the lid. when on Battery.

computersavvy · October 7, 2022, 8:48pm

Maybe, and maybe not depending upon the hardware and bios. It also may depend upon using wayland or xorg and how the software interacts.

loganb · October 7, 2022, 9:42pm

Ok my apologies for sending us down the wrong rabbit hole here. After doing some more testing it looks like this is not directly related to the window manager and the system will wake from sleep it just take a looonnnggg time. I can press the power button and the system will wake in about 3-4 minutes and this behavior does not change from xorg to wayland. I’m new to this site, should I update the title and tags for this?

computersavvy · October 8, 2022, 1:00am

I think the best approach would be to look at the logs. dmesg and journalctl -b for the times involved in waking up. There may be some clues there related to the long delay in wake-up.

You may post that output here and we can look to see if anything stands out as a cause. It certainly seems odd that it would take 3-4 minutes to wake from sleep.

F37 though is currently beta so we may not find a cause that is not still in flux with development.

loganb · October 8, 2022, 4:41pm

Ok, it took me a while to get a log but at long last here is is the output of journalctl --boot with a boot and then a sleep. I’ve skimmed the log and spotted some warns and errors related to ACPI which may be causing this…

Note that I’ve ommited several hundred lines related to tracker-miner-f service because it was spitting out a lot of sensitive filenames and paths. I think this is related to my nextcloud sync client making a bunch of invalid symlinks.

Example:

Oct 08 01:12:20 akiha tracker-miner-f[3883]: Could not add mark for path '/home/loganb/.local/share/applications': Invalid cross-device link

I will also update the title and tags of this thread to reflect this issue.

Thanks!

computersavvy · October 8, 2022, 7:09pm

I went through that, and this seems to be the part related to the wake-up

Oct 07 20:27:36 akiha ModemManager[1304]: <info>  [sleep-monitor] system is resuming
Oct 07 20:27:36 akiha systemd[1]: Stopped target sleep.target - Sleep.
Oct 07 20:27:36 akiha systemd[1]: Reached target suspend.target - Suspend.
Oct 07 20:27:36 akiha systemd[1]: Stopped target suspend.target - Suspend.
Oct 07 20:27:36 akiha systemd-logind[1115]: Operation 'sleep' finished.
Oct 07 20:27:36 akiha NetworkManager[1338]: <info>  [1665199656.9437] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Oct 07 20:27:36 akiha NetworkManager[1338]: <info>  [1665199656.9441] device (wlp3s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Oct 07 20:27:36 akiha audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=bolt comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:27:36 akiha audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=bolt comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:27:36 akiha NetworkManager[1338]: <info>  [1665199656.9455] manager: NetworkManager state is now DISCONNECTED
Oct 07 20:27:36 akiha systemd[1]: bolt.service: Scheduled restart job, restart counter is at 1.
Oct 07 20:27:36 akiha systemd[1]: Stopped bolt.service - Thunderbolt system service.
Oct 07 20:27:36 akiha systemd[1]: Starting bolt.service - Thunderbolt system service...
Oct 07 20:27:36 akiha wpa_supplicant[1367]: dbus: fill_dict_with_properties dbus_interface=fi.w1.wpa_supplicant1.Interface.P2PDevice dbus_property=P2PDeviceConfig getter failed
Oct 07 20:27:36 akiha NetworkManager[1338]: <info>  [1665199656.9776] device (wlp3s0): supplicant interface state: internal-starting -> disconnected
Oct 07 20:27:36 akiha NetworkManager[1338]: <info>  [1665199656.9777] device (wlp3s0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Oct 07 20:27:36 akiha boltd[3141]: bolt 0.9.2 starting up.
Oct 07 20:27:36 akiha boltd[3141]: manager: initializing store
Oct 07 20:27:36 akiha boltd[3141]: store: located at: /var/lib/boltd
Oct 07 20:27:37 akiha boltd[3141]: config: loading user config
Oct 07 20:27:37 akiha boltd[3141]: bouncer: initializing polkit
Oct 07 20:27:37 akiha boltd[3141]: watchdog: enabled [pulse: 90s]
Oct 07 20:27:37 akiha boltd[3141]: udev: initializing udev
Oct 07 20:27:37 akiha boltd[3141]: store: loading domains
Oct 07 20:27:37 akiha boltd[3141]: store: loading devices
Oct 07 20:27:37 akiha boltd[3141]: power: state located at: /run/boltd/power
Oct 07 20:27:37 akiha boltd[3141]: power: force power support: no
Oct 07 20:27:37 akiha boltd[3141]: udev: enumerating devices
Oct 07 20:27:37 akiha boltd[3141]: probing: adding /sys/devices/pci0000:00/0000:00:1c.4/0000:05:00.0 to roots
Oct 07 20:27:37 akiha boltd[3141]: [00000000-0000-domain0                    ] newly connected [user] (/sys/devices/pci0000:00/0000:00:1c.4/0000:05:00.0/0000:06:00.0/0000:07:00.0/domain0/0-0)
Oct 07 20:27:37 akiha boltd[3141]: security level set to 'user'
Oct 07 20:27:37 akiha boltd[3141]: [00000000-0000-domain0                    ] domain: registered (bootacl: 0/0)
Oct 07 20:27:37 akiha boltd[3141]: [00000000-0000-domain0                    ] bootacl: bootacl not supported, no sync
Oct 07 20:27:37 akiha boltd[3141]: [00000000-0000-domain0                    ] udev: failed to determine if uid is stable: unknown NHI PCI id '0x156c'
Oct 07 20:27:37 akiha boltd[3141]: [00000000-0000-domain0                    ] udev: uuid is stable: no (for NHI: 0x156c)
Oct 07 20:27:37 akiha boltd[3141]: global 'generation' set to '2'
Oct 07 20:27:37 akiha boltd[3141]: [00000000-0000-Macintosh                  ] device added, status: authorized, at /sys/devices/pci0000:00/0000:00:1c.4/0000:05:00.0/0000:06:00.0/0000:07:00.0/domain0/0-0
Oct 07 20:27:37 akiha boltd[3141]: [00000000-0000-Macintosh                  ] labeling device: Apple Inc. Macintosh
Oct 07 20:27:37 akiha boltd[3141]: [00000000-0000-domain0                    ] dbus: exported domain at /org/freedesktop/bolt/domains/00000000_0000_0008_80f6_21666bd0104e
Oct 07 20:27:37 akiha boltd[3141]: [00000000-0000-Macintosh                  ] dbus: exported device at /org/freedesktop/bolt/devices/00000000_0000...
Oct 07 20:27:37 akiha systemd[1]: Started bolt.service - Thunderbolt system service.
Oct 07 20:27:37 akiha audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=bolt comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:27:39 akiha chronyd[1233]: Forward time jump detected!
Oct 07 20:27:39 akiha ModemManager[1304]: <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
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4642] policy: auto-activating connection 'Lafayette' (0fa6c2fd-4ca6-4aad-90d9-464148964bc4)
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4662] device (wlp3s0): Activation: starting connection 'Lafayette' (0fa6c2fd-4ca6-4aad-90d9-464148964bc4)
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4665] device (wlp3s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4676] manager: NetworkManager state is now CONNECTING
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4686] device (wlp3s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4695] device (wlp3s0): Activation: (wifi) access point 'Lafayette' has security, but secrets are required.
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4696] device (wlp3s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4730] device (wlp3s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4739] device (wlp3s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4749] device (wlp3s0): Activation: (wifi) connection 'Lafayette' has security, and secrets exist.  No new secrets needed.
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4749] Config: added 'ssid' value 'Lafayette'
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4750] Config: added 'scan_ssid' value '1'
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4751] Config: added 'bgscan' value 'simple:30:-70:86400'
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4751] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4752] Config: added 'auth_alg' value 'OPEN'
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.4752] Config: added 'psk' value '<hidden>'
Oct 07 20:27:40 akiha wpa_supplicant[1367]: wlp3s0: Trying to associate with f4:92:bf:dc:75:23 (SSID='Lafayette' freq=5300 MHz)
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.5011] device (wlp3s0): supplicant interface state: disconnected -> associating
Oct 07 20:27:40 akiha wpa_supplicant[1367]: wlp3s0: Associated with f4:92:bf:dc:75:23
Oct 07 20:27:40 akiha wpa_supplicant[1367]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 07 20:27:40 akiha wpa_supplicant[1367]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.6443] device (wlp3s0): supplicant interface state: associating -> associated
Oct 07 20:27:40 akiha wpa_supplicant[1367]: wlp3s0: WPA: Key negotiation completed with f4:92:bf:dc:75:23 [PTK=CCMP GTK=CCMP]
Oct 07 20:27:40 akiha kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
Oct 07 20:27:40 akiha wpa_supplicant[1367]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to f4:92:bf:dc:75:23 completed [id=0 id_str=]
Oct 07 20:27:40 akiha wpa_supplicant[1367]: bgscan simple: Failed to enable signal strength monitoring
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.6526] device (wlp3s0): supplicant interface state: associated -> completed
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.6527] device (wlp3s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "Lafayette"
Oct 07 20:27:40 akiha audit[1311]: NETFILTER_CFG table=firewalld:7 family=1 entries=6 op=nft_register_rule pid=1311 subj=system_u:system_r:firewalld_t:s0 comm="firewalld"
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.6650] device (wlp3s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.6666] dhcp4 (wlp3s0): activation: beginning transaction (timeout in 45 seconds)
Oct 07 20:27:40 akiha avahi-daemon[1067]: Joining mDNS multicast group on interface wlp3s0.IPv6 with address fe80::9106:fc12:dc25:962e.
Oct 07 20:27:40 akiha avahi-daemon[1067]: New relevant interface wlp3s0.IPv6 for mDNS.
Oct 07 20:27:40 akiha avahi-daemon[1067]: Registering new address record for fe80::9106:fc12:dc25:962e on wlp3s0.*.
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.6734] dhcp4 (wlp3s0): state changed new lease, address=10.0.3.106
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.6737] policy: set 'Lafayette' (wlp3s0) as default for IPv4 routing and DNS
Oct 07 20:27:40 akiha avahi-daemon[1067]: Joining mDNS multicast group on interface wlp3s0.IPv4 with address 10.0.3.106.
Oct 07 20:27:40 akiha avahi-daemon[1067]: New relevant interface wlp3s0.IPv4 for mDNS.
Oct 07 20:27:40 akiha avahi-daemon[1067]: Registering new address record for 10.0.3.106 on wlp3s0.IPv4.
Oct 07 20:27:40 akiha mullvad-daemon[1447]: [mullvad_api::availability][DEBUG] Resuming API requests due to coming online
Oct 07 20:27:40 akiha systemd-resolved[1028]: wlp3s0: Bus client set search domain list to: minton.work
Oct 07 20:27:40 akiha systemd-resolved[1028]: wlp3s0: Bus client set default route setting: yes
Oct 07 20:27:40 akiha systemd-resolved[1028]: wlp3s0: Bus client set DNS server list to: 10.0.1.1
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.6954] device (wlp3s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Oct 07 20:27:40 akiha systemd[1]: Starting NetworkManager-dispatcher.service - Network Manager Script Dispatcher Service...
Oct 07 20:27:40 akiha systemd[1]: Started NetworkManager-dispatcher.service - Network Manager Script Dispatcher Service.
Oct 07 20:27:40 akiha audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.7101] device (wlp3s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.7104] device (wlp3s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.7107] manager: NetworkManager state is now CONNECTED_SITE
Oct 07 20:27:40 akiha NetworkManager[1338]: <info>  [1665199660.7127] device (wlp3s0): Activation: successful, device activated.
Oct 07 20:27:40 akiha systemd[1]: iscsi.service: Unit cannot be reloaded because it is inactive.
Oct 07 20:27:40 akiha chronyd[1233]: Source 134.215.114.62 online
Oct 07 20:27:40 akiha chronyd[1233]: Source 74.6.168.72 online
Oct 07 20:27:40 akiha chronyd[1233]: Source 54.185.46.30 online
Oct 07 20:27:40 akiha chronyd[1233]: Source 38.229.52.9 online
Oct 07 20:27:41 akiha NetworkManager[1338]: <info>  [1665199661.0026] manager: NetworkManager state is now CONNECTED_GLOBAL
Oct 07 20:27:41 akiha wpa_supplicant[1367]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22
Oct 07 20:27:41 akiha geoclue[1591]: Failed to query location: No WiFi networks found
Oct 07 20:27:41 akiha geoclue[1591]: WiFi scan failed
Oct 07 20:27:42 akiha NetworkManager[1338]: <info>  [1665199662.6120] dhcp6 (wlp3s0): activation: beginning transaction (timeout in 45 seconds)
Oct 07 20:27:42 akiha NetworkManager[1338]: <info>  [1665199662.6139] policy: set 'Lafayette' (wlp3s0) as default for IPv6 routing and DNS
Oct 07 20:27:44 akiha bluetoothd[1068]: profiles/audio/avdtp.c:avdtp_connect_cb() connect to 1A:2B:22:32:8C:27: Host is down (112)
Oct 07 20:27:46 akiha chronyd[1233]: Selected source 38.229.52.9 (2.fedora.pool.ntp.org)
Oct 07 20:27:47 akiha chronyd[1233]: Selected source 134.215.114.62 (2.fedora.pool.ntp.org)
Oct 07 20:27:47 akiha chronyd[1233]: Source 74.6.168.72 replaced with 200.25.3.17 (2.fedora.pool.ntp.org)
Oct 07 20:27:51 akiha systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Oct 07 20:27:51 akiha audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:27:52 akiha bluetoothd[1068]: profiles/audio/avdtp.c:avdtp_connect_cb() connect to 1A:2B:22:32:8C:27: Host is down (112)
Oct 07 20:28:02 akiha bluetoothd[1068]: profiles/audio/avdtp.c:avdtp_connect_cb() connect to 1A:2B:22:32:8C:27: Host is down (112)
Oct 07 20:28:06 akiha systemd[1]: systemd-timedated.service: Deactivated successfully.
Oct 07 20:28:06 akiha audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-timedated comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:28:06 akiha systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Oct 07 20:28:06 akiha audit: BPF prog-id=0 op=UNLOAD
Oct 07 20:28:06 akiha audit: BPF prog-id=0 op=UNLOAD
Oct 07 20:28:06 akiha audit: BPF prog-id=0 op=UNLOAD
Oct 07 20:28:06 akiha audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:28:06 akiha systemd[1]: systemd-localed.service: Deactivated successfully.
Oct 07 20:28:06 akiha audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-localed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:28:06 akiha audit: BPF prog-id=0 op=UNLOAD
Oct 07 20:28:06 akiha audit: BPF prog-id=0 op=UNLOAD
Oct 07 20:28:06 akiha audit: BPF prog-id=0 op=UNLOAD
Oct 07 20:28:06 akiha audit: BPF prog-id=0 op=UNLOAD
Oct 07 20:28:06 akiha audit: BPF prog-id=0 op=UNLOAD
Oct 07 20:28:06 akiha audit: BPF prog-id=0 op=UNLOAD
Oct 07 20:28:16 akiha bluetoothd[1068]: profiles/audio/avdtp.c:avdtp_connect_cb() connect to 1A:2B:22:32:8C:27: Host is down (112)
Oct 07 20:28:38 akiha bluetoothd[1068]: profiles/audio/avdtp.c:avdtp_connect_cb() connect to 1A:2B:22:32:8C:27: Host is down (112)
Oct 07 20:28:55 akiha systemd[1973]: Created slice background.slice - User Background Tasks Slice.
Oct 07 20:28:55 akiha systemd[1973]: Starting systemd-tmpfiles-clean.service - Cleanup of User's Temporary Files and Directories...
Oct 07 20:28:55 akiha systemd[1973]: Finished systemd-tmpfiles-clean.service - Cleanup of User's Temporary Files and Directories.
Oct 07 20:29:16 akiha bluetoothd[1068]: profiles/audio/avdtp.c:avdtp_connect_cb() connect to 1A:2B:22:32:8C:27: Host is down (112)
Oct 07 20:30:26 akiha bluetoothd[1068]: profiles/audio/avdtp.c:avdtp_connect_cb() connect to 1A:2B:22:32:8C:27: Host is down (112)
Oct 07 20:32:07 akiha chronyd[1233]: Selected source 54.185.46.30 (2.fedora.pool.ntp.org)
Oct 07 20:33:37 akiha systemd[1]: flatpak-system-helper.service: Deactivated successfully.
Oct 07 20:33:37 akiha audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=flatpak-system-helper comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:37:55 akiha systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories...
Oct 07 20:37:55 akiha systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully.
Oct 07 20:37:55 akiha systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories.
Oct 07 20:37:55 akiha audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 20:37:55 akiha audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 21:04:55 akiha systemd[1]: Starting dnf-makecache.service - dnf makecache...
Oct 07 21:04:56 akiha dnf[3294]: Copr repo for PyCharm owned by phracek          3.8 kB/s | 3.6 kB     00:00
Oct 07 21:04:57 akiha dnf[3294]: Fedora 37 - x86_64                               21 kB/s |  19 kB     00:00
Oct 07 21:04:58 akiha dnf[3294]: Fedora 37 openh264 (From Cisco) - x86_64        1.6 kB/s | 989  B     00:00
Oct 07 21:04:59 akiha dnf[3294]: Fedora Modular 37 - x86_64                       25 kB/s |  19 kB     00:00
Oct 07 21:05:00 akiha dnf[3294]: Fedora 37 - x86_64 - Updates                     35 kB/s |  22 kB     00:00
Oct 07 21:05:00 akiha dnf[3294]: Fedora Modular 37 - x86_64 - Updates             33 kB/s |  21 kB     00:00
Oct 07 21:05:01 akiha dnf[3294]: Fedora 37 - x86_64 - Test Updates                15 kB/s | 9.6 kB     00:00
Oct 07 21:05:02 akiha dnf[3294]: Fedora Modular 37 - x86_64 - Test Updates        27 kB/s |  15 kB     00:00
Oct 07 21:05:02 akiha dnf[3294]: google-chrome                                   2.9 kB/s | 1.3 kB     00:00
Oct 07 21:05:03 akiha dnf[3294]: RPM Fusion for Fedora 37 - Free                 3.6 kB/s | 4.0 kB     00:01
Oct 07 21:05:04 akiha dnf[3294]: RPM Fusion for Fedora 37 - Free - Test Updates  8.2 kB/s | 3.9 kB     00:00
Oct 07 21:05:04 akiha dnf[3294]: RPM Fusion for Fedora 37 - Nonfree              6.7 kB/s | 4.6 kB     00:00
Oct 07 21:05:05 akiha dnf[3294]: RPM Fusion for Fedora 37 - Nonfree - NVIDIA Dri 6.6 kB/s | 4.4 kB     00:00
Oct 07 21:05:06 akiha dnf[3294]: RPM Fusion for Fedora 37 - Nonfree - Steam      4.5 kB/s | 3.7 kB     00:00
Oct 07 21:05:07 akiha dnf[3294]: RPM Fusion for Fedora 37 - Nonfree - Test Updat 6.5 kB/s | 4.4 kB     00:00
Oct 07 21:05:07 akiha dnf[3294]: Metadata cache created.
Oct 07 21:05:07 akiha systemd[1]: dnf-makecache.service: Deactivated successfully.
Oct 07 21:05:07 akiha systemd[1]: Finished dnf-makecache.service - dnf makecache.
Oct 07 21:05:07 akiha audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 21:05:07 akiha audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 21:05:07 akiha systemd[1]: dnf-makecache.service: Consumed 2.934s CPU time.
Oct 07 21:21:49 akiha cupsd[1345]: REQUEST localhost - - "POST / HTTP/1.1" 200 185 Renew-Subscription successful-ok
Oct 07 21:25:22 akiha NetworkManager[1338]: <info>  [1665203122.6881] dhcp4 (wlp3s0): state changed new lease, address=10.0.3.106
Oct 07 22:20:09 akiha cupsd[1345]: REQUEST localhost - - "POST / HTTP/1.1" 200 185 Renew-Subscription successful-ok
Oct 07 22:25:22 akiha NetworkManager[1338]: <info>  [1665206722.6787] dhcp4 (wlp3s0): state changed new lease, address=10.0.3.106
Oct 07 22:45:37 akiha systemd[1]: Starting dnf-makecache.service - dnf makecache...
Oct 07 22:45:37 akiha dnf[3462]: Metadata cache refreshed recently.
Oct 07 22:45:37 akiha systemd[1]: dnf-makecache.service: Deactivated successfully.
Oct 07 22:45:37 akiha systemd[1]: Finished dnf-makecache.service - dnf makecache.
Oct 07 22:45:37 akiha audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 22:45:37 akiha audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dnf-makecache comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 07 23:18:29 akiha cupsd[1345]: REQUEST localhost - - "POST / HTTP/1.1" 200 185 Renew-Subscription successful-ok

It seems the wakeup began at ~20:27:36 and ended somewhere near 20:28:55 but there is nothing in that time period to indicate any errors or excessive delays. The only thing odd (to me) is boltd.

You are on a Mac, using F37, and unless someone else sees an issue I missed I am afraid I cannot help.

anticringeclub · December 15, 2022, 10:23pm

I’ll just chime in here to say I’ve been having the same issue. It’s a 2 week old install on the same MacBook Pro as OP (MacBookPro11,1).

The only modification/driver I’ve installed is broadcom-wl.

Here is the journal output from when I closed the lid to opening the lid a few seconds later:

Dec 15 22:01:33 fedora systemd-logind[729]: Lid closed.
Dec 15 22:01:33 fedora NetworkManager[990]: <info>  [1671141693.4881] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Dec 15 22:01:33 fedora NetworkManager[990]: <info>  [1671141693.4893] device (wlp3s0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Dec 15 22:01:33 fedora NetworkManager[990]: <info>  [1671141693.4902] manager: NetworkManager state is now ASLEEP
Dec 15 22:01:33 fedora ModemManager[973]: <info>  [sleep-monitor] system is about to suspend
Dec 15 22:01:34 fedora systemd[1]: Reached target sleep.target - Sleep.
Dec 15 22:01:34 fedora systemd[1]: Starting systemd-suspend.service - System Suspend...
Dec 15 22:01:34 fedora systemd-sleep[25443]: Entering sleep state 'suspend'...
Dec 15 22:01:34 fedora kernel: PM: suspend entry (deep)
Dec 15 22:01:34 fedora kernel: Filesystems sync: 0.044 seconds
Dec 15 22:04:59 fedora kernel: Freezing user space processes ... (elapsed 0.002 seconds) done.
Dec 15 22:04:59 fedora kernel: OOM killer disabled.
Dec 15 22:04:59 fedora kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Dec 15 22:04:59 fedora kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Dec 15 22:04:59 fedora kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Dec 15 22:04:59 fedora kernel: sd 0:0:0:0: [sda] Stopping disk
Dec 15 22:04:59 fedora kernel: PM: suspend devices took 0.375 seconds
Dec 15 22:04:59 fedora kernel: ACPI: EC: interrupt blocked
Dec 15 22:04:59 fedora kernel: ACPI: PM: Preparing to enter system sleep state S3
Dec 15 22:04:59 fedora kernel: ACPI: EC: event blocked
Dec 15 22:04:59 fedora kernel: ACPI: EC: EC stopped
Dec 15 22:04:59 fedora kernel: ACPI: PM: Saving platform NVS memory
Dec 15 22:04:59 fedora kernel: Disabling non-boot CPUs ...
Dec 15 22:04:59 fedora kernel: smpboot: CPU 1 is now offline
Dec 15 22:04:59 fedora kernel: smpboot: CPU 2 is now offline
Dec 15 22:04:59 fedora kernel: smpboot: CPU 3 is now offline
Dec 15 22:04:59 fedora kernel: ACPI: PM: Low-level resume complete
Dec 15 22:04:59 fedora kernel: ACPI: EC: EC started
Dec 15 22:04:59 fedora kernel: ACPI: PM: Restoring platform NVS memory
Dec 15 22:04:59 fedora kernel: Enabling non-boot CPUs ...
Dec 15 22:04:59 fedora kernel: x86: Booting SMP configuration:
Dec 15 22:04:59 fedora kernel: smpboot: Booting Node 0 Processor 1 APIC 0x2
Dec 15 22:04:59 fedora kernel: watchdog: BUG: soft lockup - CPU#1 stuck for 23s! [cpuhp/1:20]
Dec 15 22:04:59 fedora kernel: Modules linked in: ib_core xt_MASQUERADE xt_mark nft_compat tls uinput rfcomm snd_seq_dummy snd_hrtimer ip6table_nat tun nft_objref nf_conntrack_netbios_ns nf_conntrack_broadcast nft_fib_inet nft_fib_ipv4 nft_fib_ipv6 nft_fib nft_reject_inet nf_reject_ip>
Dec 15 22:04:59 fedora kernel:  kfifo_buf sbs snd_timer industrialio sbshc snd apple_bl soundcore apple_mfi_fastcharge zram i915 crct10dif_pclmul crc32_pclmul crc32c_intel polyval_clmulni polyval_generic drm_buddy drm_display_helper uas ghash_clmulni_intel cec usb_storage hid_apple tt>
Dec 15 22:04:59 fedora kernel: CPU: 1 PID: 20 Comm: cpuhp/1 Tainted: P           OEL     6.0.12-300.fc37.x86_64 #1
Dec 15 22:04:59 fedora kernel: Hardware name: Apple Inc. MacBookPro11,1/Mac-(redacted), BIOS 432.40.8.0.1 09/16/2021
Dec 15 22:04:59 fedora kernel: RIP: 0010:avtab_search_node+0x24/0x130
Dec 15 22:04:59 fedora kernel: Code: 84 00 00 00 00 00 0f 1f 44 00 00 48 89 fa 49 89 f0 48 85 ff 0f 84 e0 00 00 00 8b 47 0c 85 c0 0f 84 d5 00 00 00 41 0f b7 40 04 <0f> b7 0e 0f b7 76 02 89 c7 69 c0 51 2d 9e cc c1 c0 0f 69 c0 93 35
Dec 15 22:04:59 fedora kernel: RSP: 0000:ffffafd4000efbd0 EFLAGS: 00000206
Dec 15 22:04:59 fedora kernel: RAX: 0000000000000083 RBX: 0000000000000133 RCX: 0000000000000100
Dec 15 22:04:59 fedora kernel: RDX: ffff928087295930 RSI: ffffafd4000efc40 RDI: ffff928087295930
Dec 15 22:04:59 fedora kernel: RBP: ffffafd4000efd2c R08: ffffafd4000efc40 R09: 0000000000000000
Dec 15 22:04:59 fedora kernel: R10: 0000000000000000 R11: 000000000000002b R12: ffff9280842791c0
Dec 15 22:04:59 fedora kernel: R13: ffffafd4000efd40 R14: ffff9280842791c8 R15: 0000000000000164
Dec 15 22:04:59 fedora kernel: FS:  0000000000000000(0000) GS:ffff9281e7280000(0000) knlGS:0000000000000000
Dec 15 22:04:59 fedora kernel: CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Dec 15 22:04:59 fedora kernel: CR2: 0000000000000000 CR3: 00000001ba010001 CR4: 00000000001706e0
Dec 15 22:04:59 fedora kernel: Call Trace:
Dec 15 22:04:59 fedora kernel:  <TASK>
Dec 15 22:04:59 fedora kernel:  context_struct_compute_av+0x1ff/0x4a0
Dec 15 22:04:59 fedora kernel:  security_compute_av+0x13d/0x290
Dec 15 22:04:59 fedora kernel:  avc_compute_av.isra.0+0x58/0x1e0
Dec 15 22:04:59 fedora kernel:  avc_has_perm+0x182/0x190
Dec 15 22:04:59 fedora kernel:  ? is_hardlockup+0x60/0x60
Dec 15 22:04:59 fedora kernel:  security_perf_event_open+0x32/0x50
Dec 15 22:04:59 fedora kernel:  find_get_context+0x156/0x340
Dec 15 22:04:59 fedora kernel:  ? is_hardlockup+0x60/0x60
Dec 15 22:04:59 fedora kernel:  perf_event_create_kernel_counter+0x63/0x180
Dec 15 22:04:59 fedora kernel:  ? is_hardlockup+0x60/0x60
Dec 15 22:04:59 fedora kernel:  hardlockup_detector_event_create+0x3d/0x80
Dec 15 22:04:59 fedora kernel:  ? watchdog_enable+0xca/0x100
Dec 15 22:04:59 fedora kernel:  hardlockup_detector_perf_enable+0xa/0x40
Dec 15 22:04:59 fedora kernel:  watchdog_nmi_enable+0xa/0x20
Dec 15 22:04:59 fedora kernel:  lockup_detector_online_cpu+0x1d/0x30
Dec 15 22:04:59 fedora kernel:  cpuhp_invoke_callback+0xf8/0x460
Dec 15 22:04:59 fedora kernel:  ? sort_range+0x20/0x20
Dec 15 22:04:59 fedora kernel:  cpuhp_thread_fun+0xc7/0x140
Dec 15 22:04:59 fedora kernel:  smpboot_thread_fn+0x188/0x220
Dec 15 22:04:59 fedora kernel:  kthread+0xe9/0x110
Dec 15 22:04:59 fedora kernel:  ? kthread_complete_and_exit+0x20/0x20
Dec 15 22:04:59 fedora kernel:  ret_from_fork+0x22/0x30
Dec 15 22:04:59 fedora kernel:  </TASK>
Dec 15 22:04:59 fedora kernel: CPU1 is up
Dec 15 22:04:59 fedora kernel: smpboot: Booting Node 0 Processor 2 APIC 0x1
Dec 15 22:04:59 fedora kernel: CPU2 is up
Dec 15 22:04:59 fedora kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Dec 15 22:04:59 fedora kernel: CPU3 is up
Dec 15 22:04:59 fedora kernel: ACPI: PM: Waking up from system sleep state S3
Dec 15 22:04:59 fedora kernel: ACPI: EC: interrupt unblocked
Dec 15 22:04:59 fedora kernel: ACPI: EC: event unblocked
Dec 15 22:04:59 fedora kernel: sd 0:0:0:0: [sda] Starting disk
Dec 15 22:04:59 fedora kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Dec 15 22:04:59 fedora kernel: ata1.00: unexpected _GTF length (8)
Dec 15 22:04:59 fedora kernel: ata1.00: unexpected _GTF length (8)
Dec 15 22:04:59 fedora kernel: ata1.00: configured for UDMA/133
Dec 15 22:04:59 fedora kernel: PM: resume devices took 0.454 seconds
Dec 15 22:04:59 fedora kernel: OOM killer enabled.
Dec 15 22:04:59 fedora kernel: Restarting tasks ... done.
Dec 15 22:04:59 fedora kernel: random: crng reseeded on system resumption
Dec 15 22:04:59 fedora audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 15 22:04:59 fedora rtkit-daemon[721]: The canary thread is apparently starving. Taking action.
Dec 15 22:04:59 fedora systemd-logind[729]: Lid opened.
Dec 15 22:04:59 fedora tailscaled[1092]: monitor: time jumped (probably wake from sleep); synthesizing major change event
Dec 15 22:04:59 fedora tailscaled[1092]: LinkChange: all links down; pausing: interfaces.State{defaultRoute= ifs={} v4=false v6=false}
Dec 15 22:04:59 fedora tailscaled[1092]: dns: Set: {DefaultResolvers:[] Routes:{} SearchDomains:[] Hosts:0}
Dec 15 22:04:59 fedora tailscaled[1092]: dns: Resolvercfg: {Routes:{} Hosts:0 LocalDomains:[]}
Dec 15 22:04:59 fedora tailscaled[1092]: dns: OScfg: {Nameservers:[] SearchDomains:[] MatchDomains:[] Hosts:[]}
Dec 15 22:04:59 fedora tailscaled[1092]: wgengine: set DNS config again after major link change
Dec 15 22:04:59 fedora tailscaled[1092]: Rebind; defIf="", ips=[]
Dec 15 22:04:59 fedora tailscaled[1092]: magicsock: 0 active derp conns
Dec 15 22:04:59 fedora tailscaled[1092]: magicsock: ReSTUN("link-change-major") ignored; stopped, no private key
Dec 15 22:04:59 fedora kernel: video LNXVIDEO:00: Restoring backlight state
Dec 15 22:04:59 fedora kernel: PM: suspend exit
Dec 15 22:04:59 fedora plasmashell[24517]: [24512:24542:1215/220459.411957:ERROR:connection_factory_impl.cc(427)] Failed to connect to MCS endpoint with error -105
Dec 15 22:04:59 fedora rtkit-daemon[721]: Demoting known real-time threads.
Dec 15 22:04:59 fedora systemd-resolved[637]: Clock change detected. Flushing caches.
Dec 15 22:04:59 fedora rtkit-daemon[721]: Demoted 0 threads.
Dec 15 22:04:59 fedora systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Dec 15 22:04:59 fedora bluetoothd[865]: Controller resume with wake event 0x0
Dec 15 22:04:59 fedora kwin_wayland[23632]: kwin_libinput: Libinput: client bug: timer event8 keyboard: scheduled expiry is in the past (-205ms), your system is too slow
Dec 15 22:04:59 fedora audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 15 22:04:59 fedora audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Dec 15 22:04:59 fedora ModemManager[973]: <info>  [sleep-monitor] system is resuming
Dec 15 22:04:59 fedora systemd-resolved[637]: Flushed all caches.
Dec 15 22:04:59 fedora systemd-sleep[25443]: System returned from sleep state.
Dec 15 22:04:59 fedora systemd[1]: systemd-suspend.service: Deactivated successfully.
Dec 15 22:04:59 fedora systemd[1]: Finished systemd-suspend.service - System Suspend.
Dec 15 22:04:59 fedora systemd[1]: Stopped target sleep.target - Sleep.
Dec 15 22:04:59 fedora systemd[1]: Reached target suspend.target - Suspend.
Dec 15 22:04:59 fedora systemd[1]: Stopped target suspend.target - Suspend.
Dec 15 22:04:59 fedora systemd-logind[729]: Operation 'sleep' finished.
Dec 15 22:04:59 fedora NetworkManager[990]: <info>  [1671141899.5068] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Dec 15 22:04:59 fedora NetworkManager[990]: <info>  [1671141899.5071] device (wlp3s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Dec 15 22:04:59 fedora NetworkManager[990]: <info>  [1671141899.5097] manager: NetworkManager state is now CONNECTED_LOCAL

The time difference between 22:01:34 (filesystems sync) and 22:04:59 (when it actually woke) suggests it possibly isn’t fully asleep until you try to wake it up, at which point it goes through the dance of sleeping and waking, as they all happen in the same second (22:04:59) - even though the real time difference between closing the lid and opening the lid was maybe 10-15 seconds.