Sporadically failing to wake up from suspend (via lid close event)

gtf21 · 2021-08-26 16:45:33

In the last week I have twice or thrice had it such that my laptop doesn't respond when trying to wake it from suspend. To suspend, I close the lid, then to wake I open the lid, try some keys (even the power button). The only fix is hard resetting it by holding the power button.

I noticed a few strange things in the logs (captured using `journalctl -b-1` immediately after rebooting), for example, in a log from two days ago (lid closed at 22:09, reopened at 22:23):

Aug 24 22:09:01 anaximander systemd-logind[614]: Lid closed.
Aug 24 22:09:01 anaximander systemd-logind[614]: Suspending...
Aug 24 22:09:01 anaximander systemd[1]: Reached target Sleep.
Aug 24 22:09:01 anaximander systemd[1]: Starting System Suspend...
Aug 24 22:09:01 anaximander systemd-sleep[1263277]: Entering sleep state 'suspend'...
Aug 24 22:09:01 anaximander kernel: PM: suspend entry (deep)
Aug 24 22:09:01 anaximander kernel: Filesystems sync: 0.047 seconds
Aug 24 22:23:06 anaximander kernel: Freezing user space processes ... (elapsed 0.002 seconds) done.
Aug 24 22:23:06 anaximander kernel: OOM killer disabled.
Aug 24 22:23:06 anaximander kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Aug 24 22:23:06 anaximander kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Aug 24 22:23:06 anaximander kernel: wlp0s20f3: deauthenticating from 00:19:70:4c:21:a8 by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 24 22:23:06 anaximander kernel: ACPI: EC: interrupt blocked
Aug 24 22:23:06 anaximander kernel: ACPI: Preparing to enter system sleep state S3
Aug 24 22:23:06 anaximander kernel: ACPI: EC: event blocked
Aug 24 22:23:06 anaximander kernel: ACPI: EC: EC stopped
Aug 24 22:23:06 anaximander kernel: PM: Saving platform NVS memory
Aug 24 22:23:06 anaximander kernel: Disabling non-boot CPUs ...
Aug 24 22:23:06 anaximander kernel: smpboot: CPU 1 is now offline
Aug 24 22:23:06 anaximander kernel: smpboot: CPU 2 is now offline
Aug 24 22:23:06 anaximander kernel: smpboot: CPU 3 is now offline
Aug 24 22:23:06 anaximander kernel: smpboot: CPU 4 is now offline
Aug 24 22:23:06 anaximander kernel: smpboot: CPU 5 is now offline
Aug 24 22:23:06 anaximander kernel: smpboot: CPU 6 is now offline
Aug 24 22:23:06 anaximander kernel: smpboot: CPU 7 is now offline
Aug 24 22:23:06 anaximander kernel: ACPI: Low-level resume complete
Aug 24 22:23:06 anaximander kernel: unchecked MSR access error: WRMSR to 0x83f (tried to write 0x00000000000000f6) at rIP: 0xffffffff84272ad4 (native_write_msr+0x4/0x20)
Aug 24 22:23:06 anaximander kernel: Call Trace:
Aug 24 22:23:06 anaximander kernel:  native_apic_msr_write+0x28/0x30
Aug 24 22:23:06 anaximander kernel:  arch_irq_work_raise+0x23/0x40
Aug 24 22:23:06 anaximander kernel:  irq_work_queue+0x2e/0x60
Aug 24 22:23:06 anaximander kernel:  __kfence_alloc+0x80/0x3c0
Aug 24 22:23:06 anaximander kernel:  ? acpi_get_sleep_type_data+0xaa/0x214
Aug 24 22:23:06 anaximander kernel:  kmem_cache_alloc_trace+0x1fa/0x2e0
Aug 24 22:23:06 anaximander kernel:  acpi_get_sleep_type_data+0xaa/0x214
Aug 24 22:23:06 anaximander kernel:  acpi_hw_legacy_wake_prep+0x57/0x10b
Aug 24 22:23:06 anaximander kernel:  acpi_leave_sleep_state_prep+0x37/0x69
Aug 24 22:23:06 anaximander kernel:  acpi_suspend_enter+0xa3/0x1f0
Aug 24 22:23:06 anaximander kernel:  suspend_devices_and_enter+0x707/0x7f0
Aug 24 22:23:06 anaximander kernel:  pm_suspend.cold+0x329/0x374
Aug 24 22:23:06 anaximander kernel:  state_store+0x71/0xd0
Aug 24 22:23:06 anaximander kernel:  kernfs_fop_write_iter+0x119/0x1b0
Aug 24 22:23:06 anaximander kernel:  new_sync_write+0x156/0x1f0
Aug 24 22:23:06 anaximander kernel:  vfs_write+0x1db/0x270
Aug 24 22:23:06 anaximander kernel:  ksys_write+0x67/0xe0
Aug 24 22:23:06 anaximander kernel:  do_syscall_64+0x5e/0x80
Aug 24 22:23:06 anaximander kernel:  ? exc_page_fault+0x78/0x180
Aug 24 22:23:06 anaximander kernel:  entry_SYSCALL_64_after_hwframe+0x44/0xae
Aug 24 22:23:06 anaximander kernel: RIP: 0033:0x7f07d263e907
Aug 24 22:23:06 anaximander kernel: Code: 0d 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7 0f 1f 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 51 c3 48 83 ec 28 48 89 54 24 18 48 89 74 24
Aug 24 22:23:06 anaximander kernel: RSP: 002b:00007ffedce7b7e8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
Aug 24 22:23:06 anaximander kernel: RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f07d263e907
Aug 24 22:23:06 anaximander kernel: RDX: 0000000000000004 RSI: 00007ffedce7b8d0 RDI: 0000000000000004
Aug 24 22:23:06 anaximander kernel: RBP: 00007ffedce7b8d0 R08: 000055e4b80ee220 R09: 00007f07d26d44e0
Aug 24 22:23:06 anaximander kernel: R10: 00007f07d26d43e0 R11: 0000000000000246 R12: 0000000000000004
Aug 24 22:23:06 anaximander kernel: R13: 000055e4b80ea3c0 R14: 0000000000000004 R15: 00007f07d2710700
Aug 24 22:23:06 anaximander kernel: ACPI: EC: EC started
Aug 24 22:23:06 anaximander kernel: PM: Restoring platform NVS memory
Aug 24 22:23:06 anaximander kernel: Enabling non-boot CPUs ...
Aug 24 22:23:06 anaximander kernel: x86: Booting SMP configuration:
Aug 24 22:23:06 anaximander kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Aug 24 22:23:06 anaximander kernel: CPU1 is up
Aug 24 22:23:06 anaximander kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Aug 24 22:23:06 anaximander kernel: CPU2 is up
Aug 24 22:23:06 anaximander kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Aug 24 22:23:06 anaximander kernel: CPU3 is up
Aug 24 22:23:06 anaximander kernel: smpboot: Booting Node 0 Processor 4 APIC 0x4
Aug 24 22:23:06 anaximander kernel: CPU4 is up
Aug 24 22:23:06 anaximander kernel: smpboot: Booting Node 0 Processor 5 APIC 0x5
Aug 24 22:23:06 anaximander kernel: CPU5 is up
Aug 24 22:23:06 anaximander kernel: smpboot: Booting Node 0 Processor 6 APIC 0x6
Aug 24 22:23:06 anaximander kernel: CPU6 is up
Aug 24 22:23:06 anaximander kernel: smpboot: Booting Node 0 Processor 7 APIC 0x7
Aug 24 22:23:06 anaximander kernel: CPU7 is up
Aug 24 22:23:06 anaximander kernel: ACPI: Waking up from system sleep state S3
Aug 24 22:23:06 anaximander kernel: ACPI: EC: interrupt unblocked
Aug 24 22:23:06 anaximander kernel: ACPI: EC: event unblocked
Aug 24 22:23:06 anaximander 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
Aug 24 22:23:06 anaximander 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
Aug 24 22:23:06 anaximander kernel: i915 0000:00:02.0: [drm] [ENCODER:118:DDI C/PHY C] is disabled/in DSI mode with an ungated DDI clock, gate it
Aug 24 22:23:06 anaximander kernel: nvme nvme0: Shutdown timeout set to 8 seconds
Aug 24 22:23:06 anaximander kernel: nvme nvme0: 8/0/0 default/read/poll queues
Aug 24 22:23:06 anaximander kernel: usb 1-7: reset high-speed USB device number 2 using xhci_hcd
Aug 24 22:23:06 anaximander kernel: OOM killer enabled.
Aug 24 22:23:06 anaximander systemd-networkd[460]: wlp0s20f3: Lost carrier
Aug 24 22:23:06 anaximander systemd-logind[614]: Lid opened.
Aug 24 22:23:06 anaximander kernel: Restarting tasks ... done.
Aug 24 22:23:06 anaximander dhcpcd[1263342]: sending signal TERM to pid 1190255
Aug 24 22:23:06 anaximander dhcpcd[1263342]: waiting for pid 1190255 to exit
Aug 24 22:23:06 anaximander dhcpcd[1190256]: received SIGTERM, stopping
Aug 24 22:23:06 anaximander dhcpcd[1190256]: wlp0s20f3: removing interface
Aug 24 22:23:06 anaximander kernel: video LNXVIDEO:00: Restoring backlight state
Aug 24 22:23:06 anaximander avahi-daemon[607]: Withdrawing address record for 192.168.1.17 on wlp0s20f3.
Aug 24 22:23:06 anaximander mullvad-daemon[1190648]: [talpid_core::dns][INFO] Resetting DNS
Aug 24 22:23:06 anaximander avahi-daemon[607]: Leaving mDNS multicast group on interface wlp0s20f3.IPv4 with address 192.168.1.17.
Aug 24 22:23:06 anaximander avahi-daemon[607]: Interface wlp0s20f3.IPv4 no longer relevant for mDNS.
Aug 24 22:23:06 anaximander avahi-daemon[607]: Got SIGHUP, reloading.
Aug 24 22:23:06 anaximander avahi-daemon[607]: No service file found in /etc/avahi/services.
Aug 24 22:23:06 anaximander mullvad-daemon[1190648]: [talpid_core::routing::imp::imp][DEBUG] Clearing routes
Aug 24 22:23:06 anaximander mullvad-daemon[1190648]: [mullvad_daemon][DEBUG] New tunnel state: Disconnecting(Block)
Aug 24 22:23:06 anaximander systemd-networkd[460]: wg-mullvad: Link DOWN
Aug 24 22:23:06 anaximander systemd-networkd[460]: wg-mullvad: Lost carrier
Aug 24 22:23:07 anaximander mullvad-daemon[1190648]: [talpid_core::tunnel_state_machine::connecting_state][DEBUG] Tunnel monitor exited with block reason: None
Aug 24 22:23:07 anaximander mullvad-daemon[1190648]: [talpid_core::firewall][INFO] Applying firewall policy: Blocked. Allowing LAN. Allowing endpoint 193.138.218.78:443 over TCP
Aug 24 22:23:07 anaximander audit[1190660]: NETFILTER_CFG table=mullvadmangle6:95 family=10 entries=5 op=nft_register_chain pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander audit[1190660]: NETFILTER_CFG table=mullvadmangle6:95 family=10 entries=6 op=nft_unregister_table pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander audit[1190660]: NETFILTER_CFG table=mullvadmangle4:95 family=2 entries=5 op=nft_register_chain pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander audit[1190660]: NETFILTER_CFG table=mullvadmangle4:95 family=2 entries=8 op=nft_unregister_table pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander audit[1190660]: NETFILTER_CFG table=mullvad:95 family=1 entries=69 op=nft_register_chain pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander audit[1190660]: NETFILTER_CFG table=mullvad:95 family=1 entries=80 op=nft_unregister_table pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander audit[1190648]: SYSCALL arch=c000003e syscall=44 success=yes exit=29672 a0=d a1=7f1064047640 a2=73e8 a3=0 items=0 ppid=1 pid=1190648 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="mullvad-daemon" exe=2F6F70742F4D756C6C7661642056504E2F7265736F75726365732F6D756C6C7661642D6461656D6F6E subj==unconfined key=(null)
Aug 24 22:23:07 anaximander audit: PROCTITLE proctitle=2F6F70742F4D756C6C7661642056504E2F7265736F75726365732F6D756C6C7661642D6461656D6F6E002D76002D2D64697361626C652D7374646F75742D74696D657374616D7073
Aug 24 22:23:07 anaximander mullvad-daemon[1190648]: [mullvad_daemon][DEBUG] New tunnel state: Error(ErrorState { cause: IsOffline, block_failure: None })
Aug 24 22:23:07 anaximander mullvad-daemon[1190648]: [mullvad_daemon][INFO] Blocking all network connections, reason: This device is offline, no tunnels can be established
Aug 24 22:23:07 anaximander kernel: audit: type=1325 audit(1629836587.039:249): table=mullvadmangle6:95 family=10 entries=5 op=nft_register_chain pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander kernel: audit: type=1325 audit(1629836587.039:249): table=mullvadmangle6:95 family=10 entries=6 op=nft_unregister_table pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander kernel: audit: type=1325 audit(1629836587.039:249): table=mullvadmangle4:95 family=2 entries=5 op=nft_register_chain pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander kernel: audit: type=1325 audit(1629836587.039:249): table=mullvadmangle4:95 family=2 entries=8 op=nft_unregister_table pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander kernel: audit: type=1325 audit(1629836587.039:249): table=mullvad:95 family=1 entries=69 op=nft_register_chain pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander kernel: audit: type=1325 audit(1629836587.039:249): table=mullvad:95 family=1 entries=80 op=nft_unregister_table pid=1190660 subj==unconfined comm="mullvad-daemon"
Aug 24 22:23:07 anaximander kernel: audit: type=1300 audit(1629836587.039:249): arch=c000003e syscall=44 success=yes exit=29672 a0=d a1=7f1064047640 a2=73e8 a3=0 items=0 ppid=1 pid=1190648 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="mullvad-daemon" exe=2F6F70742F4D756C6C7661642056504E2F7265736F75726365732F6D756C6C7661642D6461656D6F6E subj==unconfined key=(null)
Aug 24 22:23:07 anaximander kernel: audit: type=1327 audit(1629836587.039:249): proctitle=2F6F70742F4D756C6C7661642056504E2F7265736F75726365732F6D756C6C7661642D6461656D6F6E002D76002D2D64697361626C652D7374646F75742D74696D657374616D7073
Aug 24 22:23:07 anaximander systemd-sleep[1263277]: System returned from sleep state.
Aug 24 22:23:07 anaximander bluetoothd[747]: Controller resume with wake event 0x0
Aug 24 22:23:07 anaximander systemd[1]: systemd-suspend.service: Deactivated successfully.
Aug 24 22:23:07 anaximander systemd[1]: Finished System Suspend.
Aug 24 22:23:07 anaximander audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 24 22:23:07 anaximander audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 24 22:23:07 anaximander systemd[1]: Stopped target Sleep.
Aug 24 22:23:07 anaximander systemd[1]: Reached target Suspend.
Aug 24 22:23:07 anaximander systemd[1]: Stopped target Suspend.
Aug 24 22:23:07 anaximander systemd-logind[614]: Operation 'sleep' finished.
Aug 24 22:23:07 anaximander systemd-networkd[460]: lo: Reset carrier
Aug 24 22:23:07 anaximander kernel: PM: suspend exit
Aug 24 22:23:07 anaximander kernel: audit: type=1130 audit(1629836587.113:250): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 24 22:23:07 anaximander kernel: audit: type=1131 audit(1629836587.113:251): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 24 22:23:08 anaximander chronyd[629]: Forward time jump detected!
Aug 24 22:23:08 anaximander chronyd[629]: Can't synchronise: no selectable sources
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: regular scan timed out
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: Microcode SW error detected. Restarting 0x0.
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: Status: 0x00000040, count: 6
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: Loaded firmware version: 63.c04f3485.0 QuZ-a0-hr-b0-63.ucode
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000084 | NMI_INTERRUPT_UNKNOWN       
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x000022F0 | trm_hw_status0
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | trm_hw_status1
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x004CAD42 | branchlink2
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00011B36 | interruptlink1
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00011B36 | interruptlink2
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x004C2F1C | data1
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x01000000 | data2
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | data3
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x0003BB21 | beacon time
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x01430AC2 | tsf low
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | tsf hi
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | time gp1
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x014367F2 | time gp2
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000001 | uCode revision type
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000003F | uCode version major
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0xC04F3485 | uCode version minor
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000351 | hw version
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00C89004 | board version
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x80E4FD04 | hcmd
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00020000 | isr0
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr1
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x08F04002 | isr2
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x04C30A0C | isr3
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr4
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00110148 | last cmd Id
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x004C2F1C | wait_event
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_control
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_duration
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_mhvalid
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_addr_match
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000048 | lmpm_pmg_sel
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | timestamp
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000207C | flow_handler
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: Status: 0x00000040, count: 7
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x20000066 | NMI_INTERRUPT_HOST
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac branchlink1
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x8045544E | umac branchlink2
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0xC0084268 | umac interruptlink1
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0xC00846CA | umac interruptlink2
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x01000000 | umac data1
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0xC00846CA | umac data2
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac data3
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000003F | umac major
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0xC04F3485 | umac minor
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x014367EF | frame pointer
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0xC0885F0C | stack pointer
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x0019010D | last host cmd
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr status reg
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: IML/ROM dump:
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000003 | IML/ROM error/state
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000586E | IML/ROM data1
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000080 | IML/ROM WFPM_AUTH_KEY_0
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: Fseq Registers:
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x60000000 | FSEQ_ERROR_CODE
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x80290033 | FSEQ_TOP_INIT_VERSION
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00090006 | FSEQ_CNVIO_INIT_VERSION
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000A481 | FSEQ_OTP_VERSION
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000003 | FSEQ_TOP_CONTENT_VERSION
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x4552414E | FSEQ_ALIVE_TOKEN
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x20000302 | FSEQ_CNVI_ID
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x01300504 | FSEQ_CNVR_ID
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x20000302 | CNVI_AUX_MISC_CHIP
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x01300504 | CNVR_AUX_MISC_CHIP
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x05B0905B | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000025B | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 4 fired (delay=0ms).
Aug 24 22:23:27 anaximander kernel: ieee80211 phy0: Hardware restart was requested
Aug 24 22:23:27 anaximander kernel: wlp0s20f3: authenticate with 00:19:70:4c:21:a8
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: Failed to send MAC context (action:2): -5
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: failed to update MAC 40:ec:99:20:9c:cf
Aug 24 22:23:27 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:28 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:29 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:30 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:31 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:32 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:33 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:34 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:35 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:36 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:37 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:38 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:39 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:40 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:41 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:42 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:43 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:44 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:45 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:46 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:47 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:48 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:49 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:50 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:51 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:52 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:53 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:54 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:55 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:56 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:57 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:58 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:23:59 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:00 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:01 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:02 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:03 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:04 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:05 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:06 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:07 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:08 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:09 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:10 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:11 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:12 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:13 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:14 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:15 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:16 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 24 22:24:17 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5

And in a log from just now, something similar happens (lid closed around 16:20 and reopened at 18:14):

Aug 26 16:20:47 anaximander systemd-logind[663]: Lid closed.
Aug 26 16:20:47 anaximander systemd-logind[663]: Suspending...
Aug 26 16:20:47 anaximander systemd[1]: Reached target Sleep.
Aug 26 16:20:47 anaximander systemd[1]: Starting System Suspend...
Aug 26 16:20:47 anaximander systemd-sleep[442337]: Entering sleep state 'suspend'...
Aug 26 16:20:47 anaximander kernel: PM: suspend entry (deep)
Aug 26 16:20:47 anaximander kernel: Filesystems sync: 0.048 seconds
Aug 26 18:14:11 anaximander kernel: Freezing user space processes ... (elapsed 0.004 seconds) done.
Aug 26 18:14:11 anaximander kernel: OOM killer disabled.
Aug 26 18:14:11 anaximander kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Aug 26 18:14:11 anaximander kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Aug 26 18:14:11 anaximander kernel: wlp0s20f3: deauthenticating from 82:09:4c:91:04:0f by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 26 18:14:11 anaximander kernel: ACPI: EC: interrupt blocked
Aug 26 18:14:11 anaximander kernel: ACPI: Preparing to enter system sleep state S3
Aug 26 18:14:11 anaximander kernel: ACPI: EC: event blocked
Aug 26 18:14:11 anaximander kernel: ACPI: EC: EC stopped
Aug 26 18:14:11 anaximander kernel: PM: Saving platform NVS memory
Aug 26 18:14:11 anaximander kernel: Disabling non-boot CPUs ...
Aug 26 18:14:11 anaximander kernel: smpboot: CPU 1 is now offline
Aug 26 18:14:11 anaximander kernel: smpboot: CPU 2 is now offline
Aug 26 18:14:11 anaximander kernel: smpboot: CPU 3 is now offline
Aug 26 18:14:11 anaximander kernel: smpboot: CPU 4 is now offline
Aug 26 18:14:11 anaximander kernel: smpboot: CPU 5 is now offline
Aug 26 18:14:11 anaximander kernel: smpboot: CPU 6 is now offline
Aug 26 18:14:11 anaximander kernel: smpboot: CPU 7 is now offline
Aug 26 18:14:11 anaximander kernel: ACPI: Low-level resume complete
Aug 26 18:14:11 anaximander kernel: unchecked MSR access error: WRMSR to 0x83f (tried to write 0x00000000000000f6) at rIP: 0xffffffffad072ad4 (native_write_msr+0x4/0x20)
Aug 26 18:14:11 anaximander kernel: Call Trace:
Aug 26 18:14:11 anaximander kernel:  native_apic_msr_write+0x28/0x30
Aug 26 18:14:11 anaximander kernel:  arch_irq_work_raise+0x23/0x40
Aug 26 18:14:11 anaximander kernel:  irq_work_queue+0x2e/0x60
Aug 26 18:14:11 anaximander kernel:  __kfence_alloc+0x80/0x3c0
Aug 26 18:14:11 anaximander kernel:  ? acpi_get_sleep_type_data+0xaa/0x214
Aug 26 18:14:11 anaximander kernel:  kmem_cache_alloc_trace+0x1fa/0x2e0
Aug 26 18:14:11 anaximander kernel:  acpi_get_sleep_type_data+0xaa/0x214
Aug 26 18:14:11 anaximander kernel:  acpi_hw_legacy_wake_prep+0x57/0x10b
Aug 26 18:14:11 anaximander kernel:  acpi_leave_sleep_state_prep+0x37/0x69
Aug 26 18:14:11 anaximander kernel:  acpi_suspend_enter+0xa3/0x1f0
Aug 26 18:14:11 anaximander kernel:  suspend_devices_and_enter+0x707/0x7f0
Aug 26 18:14:11 anaximander kernel:  pm_suspend.cold+0x329/0x374
Aug 26 18:14:11 anaximander kernel:  state_store+0x71/0xd0
Aug 26 18:14:11 anaximander kernel:  kernfs_fop_write_iter+0x119/0x1b0
Aug 26 18:14:11 anaximander kernel:  new_sync_write+0x156/0x1f0
Aug 26 18:14:11 anaximander kernel:  vfs_write+0x1db/0x270
Aug 26 18:14:11 anaximander kernel:  ksys_write+0x67/0xe0
Aug 26 18:14:11 anaximander kernel:  do_syscall_64+0x5e/0x80
Aug 26 18:14:11 anaximander kernel:  ? exc_page_fault+0x78/0x180
Aug 26 18:14:11 anaximander kernel:  entry_SYSCALL_64_after_hwframe+0x44/0xae
Aug 26 18:14:11 anaximander kernel: RIP: 0033:0x7f0deb5f1907
Aug 26 18:14:11 anaximander kernel: Code: 0d 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7 0f 1f 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 51 c3 48 83 ec 28 48 89 54 24 18 48 89 74 24
Aug 26 18:14:11 anaximander kernel: RSP: 002b:00007ffd5a967fc8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
Aug 26 18:14:11 anaximander kernel: RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f0deb5f1907
Aug 26 18:14:11 anaximander kernel: RDX: 0000000000000004 RSI: 00007ffd5a9680b0 RDI: 0000000000000004
Aug 26 18:14:11 anaximander kernel: RBP: 00007ffd5a9680b0 R08: 00005655425ef220 R09: 00007f0deb6874e0
Aug 26 18:14:11 anaximander kernel: R10: 00007f0deb6873e0 R11: 0000000000000246 R12: 0000000000000004
Aug 26 18:14:11 anaximander kernel: R13: 00005655425eb3c0 R14: 0000000000000004 R15: 00007f0deb6c3700
Aug 26 18:14:11 anaximander kernel: ACPI: EC: EC started
Aug 26 18:14:11 anaximander kernel: PM: Restoring platform NVS memory
Aug 26 18:14:11 anaximander kernel: Enabling non-boot CPUs ...
Aug 26 18:14:11 anaximander kernel: x86: Booting SMP configuration:
Aug 26 18:14:11 anaximander kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Aug 26 18:14:11 anaximander kernel: CPU1 is up
Aug 26 18:14:11 anaximander kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Aug 26 18:14:11 anaximander kernel: CPU2 is up
Aug 26 18:14:11 anaximander kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Aug 26 18:14:11 anaximander kernel: CPU3 is up
Aug 26 18:14:11 anaximander kernel: smpboot: Booting Node 0 Processor 4 APIC 0x4
Aug 26 18:14:11 anaximander kernel: CPU4 is up
Aug 26 18:14:11 anaximander kernel: smpboot: Booting Node 0 Processor 5 APIC 0x5
Aug 26 18:14:11 anaximander kernel: CPU5 is up
Aug 26 18:14:11 anaximander kernel: smpboot: Booting Node 0 Processor 6 APIC 0x6
Aug 26 18:14:11 anaximander kernel: CPU6 is up
Aug 26 18:14:11 anaximander kernel: smpboot: Booting Node 0 Processor 7 APIC 0x7
Aug 26 18:14:11 anaximander kernel: CPU7 is up
Aug 26 18:14:11 anaximander kernel: ACPI: Waking up from system sleep state S3
Aug 26 18:14:11 anaximander kernel: ACPI: EC: interrupt unblocked
Aug 26 18:14:11 anaximander kernel: ACPI: EC: event unblocked
Aug 26 18:14:11 anaximander 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
Aug 26 18:14:11 anaximander 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
Aug 26 18:14:11 anaximander kernel: i915 0000:00:02.0: [drm] [ENCODER:118:DDI C/PHY C] is disabled/in DSI mode with an ungated DDI clock, gate it
Aug 26 18:14:11 anaximander kernel: nvme nvme0: Shutdown timeout set to 8 seconds
Aug 26 18:14:11 anaximander kernel: nvme nvme0: 8/0/0 default/read/poll queues
Aug 26 18:14:11 anaximander kernel: usb 1-7: reset high-speed USB device number 2 using xhci_hcd
Aug 26 18:14:11 anaximander kernel: OOM killer enabled.
Aug 26 18:14:11 anaximander systemd-logind[663]: Lid opened.
Aug 26 18:14:11 anaximander systemd-networkd[452]: wlp0s20f3: Lost carrier
Aug 26 18:14:11 anaximander systemd[1067]: Starting Duplicity Backup Service...
Aug 26 18:14:11 anaximander kernel: Restarting tasks ... done.
Aug 26 18:14:11 anaximander dhcpcd[442465]: sending signal TERM to pid 344357
Aug 26 18:14:11 anaximander dhcpcd[442465]: waiting for pid 344357 to exit
Aug 26 18:14:11 anaximander dhcpcd[344358]: received SIGTERM, stopping
Aug 26 18:14:11 anaximander dhcpcd[344358]: wlp0s20f3: removing interface
Aug 26 18:14:11 anaximander bluetoothd[655]: Controller resume with wake event 0x0
Aug 26 18:14:11 anaximander kernel: video LNXVIDEO:00: Restoring backlight state
Aug 26 18:14:11 anaximander run-backup[442406]: [info] --- BACKING UP /home/gideon ---
Aug 26 18:14:11 anaximander run-backup[442406]: [info] Including archives calendars .config contacts converge Desktop Documents dotfiles Downloads .local/share/fonts mail movies notes pictures projects sandbox .ssh
Aug 26 18:14:11 anaximander run-backup[442406]: [info] Encrypting with D8CDFC19BFE7B4215B61DB204881BEF44F7659BC
Aug 26 18:14:11 anaximander run-backup[442406]: [info] Signing with C008ABD87CEBED5C26D299E50CADE204D6E39292
Aug 26 18:14:11 anaximander run-backup[442406]: [info] Removing files older than 90D
Aug 26 18:14:11 anaximander avahi-daemon[654]: Withdrawing address record for 172.20.10.11 on wlp0s20f3.
Aug 26 18:14:11 anaximander avahi-daemon[654]: Leaving mDNS multicast group on interface wlp0s20f3.IPv4 with address 172.20.10.11.
Aug 26 18:14:11 anaximander mullvad-daemon[344919]: [talpid_core::dns][INFO] Resetting DNS
Aug 26 18:14:11 anaximander avahi-daemon[654]: Interface wlp0s20f3.IPv4 no longer relevant for mDNS.
Aug 26 18:14:11 anaximander avahi-daemon[654]: Got SIGHUP, reloading.
Aug 26 18:14:11 anaximander avahi-daemon[654]: No service file found in /etc/avahi/services.
Aug 26 18:14:11 anaximander mullvad-daemon[344919]: [talpid_core::routing::imp::imp][DEBUG] Clearing routes
Aug 26 18:14:11 anaximander mullvad-daemon[344919]: [mullvad_daemon][DEBUG] New tunnel state: Disconnecting(Block)
Aug 26 18:14:11 anaximander systemd-networkd[452]: wg-mullvad: Link DOWN
Aug 26 18:14:11 anaximander systemd-networkd[452]: wg-mullvad: Lost carrier
Aug 26 18:14:12 anaximander mullvad-daemon[344919]: [talpid_core::tunnel_state_machine::connecting_state][DEBUG] Tunnel monitor exited with block reason: None
Aug 26 18:14:12 anaximander mullvad-daemon[344919]: [talpid_core::firewall][INFO] Applying firewall policy: Blocked. Allowing LAN. Allowing endpoint 185.65.134.66:444 over TCP
Aug 26 18:14:12 anaximander audit[344931]: NETFILTER_CFG table=mullvadmangle6:30 family=10 entries=5 op=nft_register_chain pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander audit[344931]: NETFILTER_CFG table=mullvadmangle6:30 family=10 entries=6 op=nft_unregister_table pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander audit[344931]: NETFILTER_CFG table=mullvadmangle4:30 family=2 entries=5 op=nft_register_chain pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander audit[344931]: NETFILTER_CFG table=mullvadmangle4:30 family=2 entries=8 op=nft_unregister_table pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander audit[344931]: NETFILTER_CFG table=mullvad:30 family=1 entries=69 op=nft_register_chain pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander audit[344931]: NETFILTER_CFG table=mullvad:30 family=1 entries=80 op=nft_unregister_table pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander audit[344919]: SYSCALL arch=c000003e syscall=44 success=yes exit=29672 a0=d a1=7fda74047640 a2=73e8 a3=0 items=0 ppid=1 pid=344919 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="mullvad-daemon" exe=2F6F70742F4D756C6C7661642056504E2F7265736F75726365732F6D756C6C7661642D6461656D6F6E subj==unconfined key=(null)
Aug 26 18:14:12 anaximander audit: PROCTITLE proctitle=2F6F70742F4D756C6C7661642056504E2F7265736F75726365732F6D756C6C7661642D6461656D6F6E002D76002D2D64697361626C652D7374646F75742D74696D657374616D7073
Aug 26 18:14:12 anaximander mullvad-daemon[344919]: [mullvad_daemon][DEBUG] New tunnel state: Error(ErrorState { cause: IsOffline, block_failure: None })
Aug 26 18:14:12 anaximander mullvad-daemon[344919]: [mullvad_daemon][INFO] Blocking all network connections, reason: This device is offline, no tunnels can be established
Aug 26 18:14:12 anaximander kernel: audit: type=1325 audit(1629994452.059:218): table=mullvadmangle6:30 family=10 entries=5 op=nft_register_chain pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander kernel: audit: type=1325 audit(1629994452.059:218): table=mullvadmangle6:30 family=10 entries=6 op=nft_unregister_table pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander kernel: audit: type=1325 audit(1629994452.059:218): table=mullvadmangle4:30 family=2 entries=5 op=nft_register_chain pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander kernel: audit: type=1325 audit(1629994452.059:218): table=mullvadmangle4:30 family=2 entries=8 op=nft_unregister_table pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander kernel: audit: type=1325 audit(1629994452.059:218): table=mullvad:30 family=1 entries=69 op=nft_register_chain pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander kernel: audit: type=1325 audit(1629994452.059:218): table=mullvad:30 family=1 entries=80 op=nft_unregister_table pid=344931 subj==unconfined comm="mullvad-daemon"
Aug 26 18:14:12 anaximander kernel: audit: type=1300 audit(1629994452.059:218): arch=c000003e syscall=44 success=yes exit=29672 a0=d a1=7fda74047640 a2=73e8 a3=0 items=0 ppid=1 pid=344919 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="mullvad-daemon" exe=2F6F70742F4D756C6C7661642056504E2F7265736F75726365732F6D756C6C7661642D6461656D6F6E subj==unconfined key=(null)
Aug 26 18:14:12 anaximander kernel: audit: type=1327 audit(1629994452.059:218): proctitle=2F6F70742F4D756C6C7661642056504E2F7265736F75726365732F6D756C6C7661642D6461656D6F6E002D76002D2D64697361626C652D7374646F75742D74696D657374616D7073
Aug 26 18:14:12 anaximander systemd-sleep[442337]: System returned from sleep state.
Aug 26 18:14:12 anaximander systemd[1]: systemd-suspend.service: Deactivated successfully.
Aug 26 18:14:12 anaximander systemd[1]: Finished System Suspend.
Aug 26 18:14:12 anaximander audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 26 18:14:12 anaximander audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 26 18:14:12 anaximander systemd[1]: Stopped target Sleep.
Aug 26 18:14:12 anaximander systemd[1]: Reached target Suspend.
Aug 26 18:14:12 anaximander systemd[1]: Stopped target Suspend.
Aug 26 18:14:12 anaximander systemd-logind[663]: Operation 'sleep' finished.
Aug 26 18:14:12 anaximander systemd-networkd[452]: lo: Reset carrier
Aug 26 18:14:12 anaximander kernel: PM: suspend exit
Aug 26 18:14:12 anaximander kernel: audit: type=1130 audit(1629994452.103:219): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 26 18:14:12 anaximander kernel: audit: type=1131 audit(1629994452.103:220): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 26 18:14:20 anaximander run-backup[442531]: Traceback (innermost last):
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/bin/duplicity", line 87, in <module>
Aug 26 18:14:20 anaximander run-backup[442531]:     with_tempdir(main)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/bin/duplicity", line 70, in with_tempdir
Aug 26 18:14:20 anaximander run-backup[442531]:     fn()
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/duplicity/dup_main.py", line 1540, in main
Aug 26 18:14:20 anaximander run-backup[442531]:     action = commandline.ProcessCommandLine(sys.argv[1:])
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/duplicity/commandline.py", line 1220, in ProcessCommandLine
Aug 26 18:14:20 anaximander run-backup[442531]:     config.backend = backend.get_backend(args[0])
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/duplicity/backend.py", line 223, in get_backend
Aug 26 18:14:20 anaximander run-backup[442531]:     obj = get_backend_object(url_string)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/duplicity/backend.py", line 209, in get_backend_object
Aug 26 18:14:20 anaximander run-backup[442531]:     return factory(pu)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/duplicity/backends/b2backend.py", line 126, in __init__
Aug 26 18:14:20 anaximander run-backup[442531]:     self.service.authorize_account(u'production', account_id, account_key)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/home/gideon/.local/lib/python3.9/site-packages/logfury/v0_1/trace_call.py", line 84, in wrapper
Aug 26 18:14:20 anaximander run-backup[442531]:     return function(*wrapee_args, **wrapee_kwargs)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/b2sdk/api.py", line 159, in authorize_account
Aug 26 18:14:20 anaximander run-backup[442531]:     self.session.authorize_account(realm, application_key_id, application_key)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/b2sdk/session.py", line 117, in authorize_account
Aug 26 18:14:20 anaximander run-backup[442531]:     response = self.raw_api.authorize_account(realm_url, application_key_id, application_key)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/b2sdk/raw_api.py", line 344, in authorize_account
Aug 26 18:14:20 anaximander run-backup[442531]:     return self._post_json(realm_url, 'b2_authorize_account', auth)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/b2sdk/raw_api.py", line 338, in _post_json
Aug 26 18:14:20 anaximander run-backup[442531]:     return self.b2_http.post_json_return_json(url, headers, params)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/b2sdk/b2http.py", line 344, in post_json_return_json
Aug 26 18:14:20 anaximander run-backup[442531]:     return self.post_content_return_json(url, headers, data, try_count, params)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/b2sdk/b2http.py", line 310, in post_content_return_json
Aug 26 18:14:20 anaximander run-backup[442531]:     response = _translate_and_retry(do_post, try_count, post_params)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/b2sdk/b2http.py", line 137, in _translate_and_retry
Aug 26 18:14:20 anaximander run-backup[442531]:     return _translate_errors(fcn, post_params)
Aug 26 18:14:20 anaximander run-backup[442531]:   File "/usr/lib/python3.9/site-packages/b2sdk/b2http.py", line 86, in _translate_errors
Aug 26 18:14:20 anaximander run-backup[442531]:     raise B2ConnectionError(str(e0))
Aug 26 18:14:20 anaximander run-backup[442531]:  b2sdk.exception.B2ConnectionError: Connection error: HTTPSConnectionPool(host='api.backblazeb2.com', port=443): Max retries exceeded with url: /b2api/v2/b2_authorize_account (Caused by NewConnectionError('<urllib3.connection.HTTPSConnection object at 0x7f8ad66f5280>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution'))
Aug 26 18:14:20 anaximander systemd[1067]: duplicity.service: Main process exited, code=exited, status=30/n/a
Aug 26 18:14:20 anaximander systemd[1067]: duplicity.service: Failed with result 'exit-code'.
Aug 26 18:14:20 anaximander systemd[1067]: Failed to start Duplicity Backup Service.
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: regular scan timed out
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: Microcode SW error detected. Restarting 0x0.
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: Status: 0x00000040, count: 6
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: Loaded firmware version: 63.c04f3485.0 QuZ-a0-hr-b0-63.ucode
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000084 | NMI_INTERRUPT_UNKNOWN       
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x000022F0 | trm_hw_status0
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | trm_hw_status1
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x004CAD42 | branchlink2
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x000014C4 | interruptlink1
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x000014C4 | interruptlink2
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x004C2F1C | data1
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x01000000 | data2
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | data3
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x0003B0C5 | beacon time
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x014B21BA | tsf low
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | tsf hi
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | time gp1
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x014B7ED8 | time gp2
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000001 | uCode revision type
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000003F | uCode version major
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0xC04F3485 | uCode version minor
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000351 | hw version
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00C89004 | board version
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x80E3FD04 | hcmd
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00020000 | isr0
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x08000000 | isr1
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x08F04002 | isr2
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x04C3050C | isr3
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr4
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00110148 | last cmd Id
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x004C2F1C | wait_event
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_control
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_duration
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_mhvalid
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_addr_match
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000048 | lmpm_pmg_sel
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | timestamp
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00002060 | flow_handler
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump:
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: Status: 0x00000040, count: 7
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x20000066 | NMI_INTERRUPT_HOST
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac branchlink1
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x8045544E | umac branchlink2
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0xC0084268 | umac interruptlink1
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0xC00845D8 | umac interruptlink2
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x01000000 | umac data1
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0xC00845D8 | umac data2
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac data3
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000003F | umac major
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0xC04F3485 | umac minor
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x014B7ED5 | frame pointer
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0xC0887F0C | stack pointer
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x0019010D | last host cmd
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000000D | isr status reg
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: IML/ROM dump:
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000003 | IML/ROM error/state
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00005857 | IML/ROM data1
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000080 | IML/ROM WFPM_AUTH_KEY_0
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: Fseq Registers:
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x60000000 | FSEQ_ERROR_CODE
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x80290033 | FSEQ_TOP_INIT_VERSION
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00090006 | FSEQ_CNVIO_INIT_VERSION
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000A481 | FSEQ_OTP_VERSION
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x00000003 | FSEQ_TOP_CONTENT_VERSION
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x4552414E | FSEQ_ALIVE_TOKEN
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x20000302 | FSEQ_CNVI_ID
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x01300504 | FSEQ_CNVR_ID
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x20000302 | CNVI_AUX_MISC_CHIP
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x01300504 | CNVR_AUX_MISC_CHIP
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x05B0905B | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: 0x0000025B | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 4 fired (delay=0ms).
Aug 26 18:14:32 anaximander kernel: ieee80211 phy0: Hardware restart was requested
Aug 26 18:14:32 anaximander kernel: wlp0s20f3: authenticate with 00:19:70:4c:21:a8
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: Failed to send MAC context (action:2): -5
Aug 26 18:14:32 anaximander kernel: iwlwifi 0000:00:14.3: failed to update MAC 40:ec:99:20:9c:cf
Aug 26 18:14:33 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:34 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:35 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:36 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:37 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:38 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:39 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:40 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:41 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:42 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:43 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:44 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:45 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:46 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:47 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:48 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:49 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:50 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:51 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:52 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:53 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:54 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:55 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:56 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:57 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:58 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:14:59 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:00 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:01 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:02 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:03 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:04 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:05 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:06 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:06 anaximander systemd[1067]: Starting Mailbox Syncronisation Service...
Aug 26 18:15:06 anaximander systemd[1067]: mbsync.service: Main process exited, code=exited, status=1/FAILURE
Aug 26 18:15:06 anaximander systemd[1067]: mbsync.service: Failed with result 'exit-code'.
Aug 26 18:15:06 anaximander mbsync[442808]: Error: Cannot resolve server 'mail.runbox.com': Temporary failure in name resolution
Aug 26 18:15:06 anaximander mbsync[442808]: Error: Cannot resolve server 'imap.gmail.com': Temporary failure in name resolution
Aug 26 18:15:06 anaximander systemd[1067]: Failed to start Mailbox Syncronisation Service.
Aug 26 18:15:07 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:08 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:09 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:10 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:11 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:12 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:13 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:14 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:14 anaximander chronyd[671]: Forward time jump detected!
Aug 26 18:15:14 anaximander chronyd[671]: Can't synchronise: no selectable sources
Aug 26 18:15:15 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:16 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:17 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:18 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:19 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:20 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:21 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:22 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:23 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:24 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:25 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:26 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:27 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:28 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:29 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:30 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:31 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:32 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:33 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:34 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:35 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5
Aug 26 18:15:36 anaximander kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5

Here's an example, from the same log, of what happens when it does not crash on wake:

Aug 25 18:06:42 anaximander systemd-logind[663]: Lid closed.
Aug 25 18:06:42 anaximander systemd-logind[663]: Suspending...
Aug 25 18:06:42 anaximander systemd[1]: Reached target Sleep.
Aug 25 18:06:42 anaximander systemd[1]: Starting System Suspend...
Aug 25 18:06:42 anaximander systemd-sleep[227318]: Entering sleep state 'suspend'...
Aug 25 18:06:42 anaximander kernel: PM: suspend entry (deep)
Aug 25 18:06:42 anaximander kernel: Filesystems sync: 0.035 seconds
Aug 25 23:43:34 anaximander kernel: Freezing user space processes ... (elapsed 0.003 seconds) done.
Aug 25 23:43:34 anaximander kernel: OOM killer disabled.
Aug 25 23:43:34 anaximander kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Aug 25 23:43:34 anaximander kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Aug 25 23:43:34 anaximander kernel: wlp0s20f3: deauthenticating from 00:19:70:4c:21:a8 by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 25 23:43:34 anaximander kernel: ACPI: EC: interrupt blocked
Aug 25 23:43:34 anaximander kernel: ACPI: Preparing to enter system sleep state S3
Aug 25 23:43:34 anaximander kernel: ACPI: EC: event blocked
Aug 25 23:43:34 anaximander kernel: ACPI: EC: EC stopped
Aug 25 23:43:34 anaximander kernel: PM: Saving platform NVS memory
Aug 25 23:43:34 anaximander kernel: Disabling non-boot CPUs ...
Aug 25 23:43:34 anaximander kernel: IRQ 137: no longer affine to CPU1
Aug 25 23:43:34 anaximander kernel: smpboot: CPU 1 is now offline
Aug 25 23:43:34 anaximander kernel: IRQ 138: no longer affine to CPU2
Aug 25 23:43:34 anaximander kernel: smpboot: CPU 2 is now offline
Aug 25 23:43:34 anaximander kernel: IRQ 139: no longer affine to CPU3
Aug 25 23:43:34 anaximander kernel: smpboot: CPU 3 is now offline
Aug 25 23:43:34 anaximander kernel: IRQ 140: no longer affine to CPU4
Aug 25 23:43:34 anaximander kernel: smpboot: CPU 4 is now offline
Aug 25 23:43:34 anaximander kernel: IRQ 141: no longer affine to CPU5
Aug 25 23:43:34 anaximander kernel: smpboot: CPU 5 is now offline
Aug 25 23:43:34 anaximander kernel: IRQ 142: no longer affine to CPU6
Aug 25 23:43:34 anaximander kernel: smpboot: CPU 6 is now offline
Aug 25 23:43:34 anaximander kernel: IRQ 143: no longer affine to CPU7
Aug 25 23:43:34 anaximander kernel: smpboot: CPU 7 is now offline
Aug 25 23:43:34 anaximander kernel: ACPI: Low-level resume complete
Aug 25 23:43:34 anaximander kernel: ACPI: EC: EC started
Aug 25 23:43:34 anaximander kernel: PM: Restoring platform NVS memory
Aug 25 23:43:34 anaximander kernel: Enabling non-boot CPUs ...
Aug 25 23:43:34 anaximander kernel: x86: Booting SMP configuration:
Aug 25 23:43:34 anaximander kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Aug 25 23:43:34 anaximander kernel: CPU1 is up
Aug 25 23:43:34 anaximander kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Aug 25 23:43:34 anaximander kernel: CPU2 is up
Aug 25 23:43:34 anaximander kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Aug 25 23:43:34 anaximander kernel: CPU3 is up
Aug 25 23:43:34 anaximander kernel: smpboot: Booting Node 0 Processor 4 APIC 0x4
Aug 25 23:43:34 anaximander kernel: CPU4 is up
Aug 25 23:43:34 anaximander kernel: smpboot: Booting Node 0 Processor 5 APIC 0x5
Aug 25 23:43:34 anaximander kernel: CPU5 is up
Aug 25 23:43:34 anaximander kernel: smpboot: Booting Node 0 Processor 6 APIC 0x6
Aug 25 23:43:34 anaximander kernel: CPU6 is up
Aug 25 23:43:34 anaximander kernel: smpboot: Booting Node 0 Processor 7 APIC 0x7
Aug 25 23:43:34 anaximander kernel: CPU7 is up
Aug 25 23:43:34 anaximander kernel: ACPI: Waking up from system sleep state S3
Aug 25 23:43:34 anaximander kernel: ACPI: EC: interrupt unblocked
Aug 25 23:43:34 anaximander kernel: ACPI: EC: event unblocked
Aug 25 23:43:34 anaximander 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
Aug 25 23:43:34 anaximander 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
Aug 25 23:43:34 anaximander kernel: i915 0000:00:02.0: [drm] [ENCODER:118:DDI C/PHY C] is disabled/in DSI mode with an ungated DDI clock, gate it
Aug 25 23:43:34 anaximander kernel: nvme nvme0: Shutdown timeout set to 8 seconds
Aug 25 23:43:34 anaximander kernel: nvme nvme0: 8/0/0 default/read/poll queues
Aug 25 23:43:34 anaximander kernel: usb 1-7: reset high-speed USB device number 2 using xhci_hcd
Aug 25 23:43:34 anaximander kernel: OOM killer enabled.
Aug 25 23:43:34 anaximander kernel: Restarting tasks ... done.
Aug 25 23:43:34 anaximander systemd-logind[663]: Lid opened.
Aug 25 23:43:34 anaximander systemd-networkd[452]: wlp0s20f3: Lost carrier
Aug 25 23:43:34 anaximander dhcpcd[227458]: sending signal TERM to pid 1014
Aug 25 23:43:34 anaximander dhcpcd[227458]: waiting for pid 1014 to exit
Aug 25 23:43:34 anaximander dhcpcd[1015]: received SIGTERM, stopping
Aug 25 23:43:34 anaximander dhcpcd[1015]: wlp0s20f3: removing interface
Aug 25 23:43:34 anaximander kernel: video LNXVIDEO:00: Restoring backlight state
Aug 25 23:43:34 anaximander bluetoothd[655]: Controller resume with wake event 0x0
Aug 25 23:43:34 anaximander avahi-daemon[654]: Withdrawing address record for 192.168.1.17 on wlp0s20f3.
Aug 25 23:43:34 anaximander mullvad-daemon[1771]: [talpid_core::dns][INFO] Resetting DNS
Aug 25 23:43:34 anaximander avahi-daemon[654]: Leaving mDNS multicast group on interface wlp0s20f3.IPv4 with address 192.168.1.17.
Aug 25 23:43:34 anaximander avahi-daemon[654]: Interface wlp0s20f3.IPv4 no longer relevant for mDNS.
Aug 25 23:43:34 anaximander avahi-daemon[654]: Got SIGHUP, reloading.
Aug 25 23:43:34 anaximander avahi-daemon[654]: No service file found in /etc/avahi/services.
Aug 25 23:43:34 anaximander mullvad-daemon[1771]: [talpid_core::routing::imp::imp][DEBUG] Clearing routes
Aug 25 23:43:34 anaximander mullvad-daemon[1771]: [mullvad_daemon][DEBUG] New tunnel state: Disconnecting(Block)
Aug 25 23:43:34 anaximander systemd-networkd[452]: wg-mullvad: Link DOWN
Aug 25 23:43:34 anaximander systemd-networkd[452]: wg-mullvad: Lost carrier
Aug 25 23:43:35 anaximander dunst[1365]: WARNING: No icon found in path: 'network-wireless'
Aug 25 23:43:35 anaximander systemd-sleep[227318]: System returned from sleep state.
Aug 25 23:43:35 anaximander kernel: PM: suspend exit
Aug 25 23:43:35 anaximander systemd[1]: systemd-suspend.service: Deactivated successfully.
Aug 25 23:43:35 anaximander systemd[1]: Finished System Suspend.


Aug 26 00:04:52 anaximander systemd-logind[663]: Lid closed.
Aug 26 00:04:52 anaximander systemd-logind[663]: Suspending...
Aug 26 00:04:52 anaximander systemd[1]: Reached target Sleep.
Aug 26 00:04:52 anaximander systemd[1]: Starting System Suspend...
Aug 26 00:04:52 anaximander systemd-sleep[288460]: Entering sleep state 'suspend'...
Aug 26 00:04:52 anaximander kernel: PM: suspend entry (deep)
Aug 26 00:04:52 anaximander kernel: Filesystems sync: 0.027 seconds
Aug 26 15:27:54 anaximander kernel: Freezing user space processes ... (elapsed 0.003 seconds) done.
Aug 26 15:27:54 anaximander kernel: OOM killer disabled.
Aug 26 15:27:54 anaximander kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Aug 26 15:27:54 anaximander kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Aug 26 15:27:54 anaximander kernel: wlp0s20f3: deauthenticating from 00:19:70:4c:21:a8 by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 26 15:27:54 anaximander kernel: ACPI: EC: interrupt blocked
Aug 26 15:27:54 anaximander kernel: ACPI: Preparing to enter system sleep state S3
Aug 26 15:27:54 anaximander kernel: ACPI: EC: event blocked
Aug 26 15:27:54 anaximander kernel: ACPI: EC: EC stopped
Aug 26 15:27:54 anaximander kernel: PM: Saving platform NVS memory
Aug 26 15:27:54 anaximander kernel: Disabling non-boot CPUs ...
Aug 26 15:27:54 anaximander kernel: smpboot: CPU 1 is now offline
Aug 26 15:27:54 anaximander kernel: smpboot: CPU 2 is now offline
Aug 26 15:27:54 anaximander kernel: smpboot: CPU 3 is now offline
Aug 26 15:27:54 anaximander kernel: smpboot: CPU 4 is now offline
Aug 26 15:27:54 anaximander kernel: smpboot: CPU 5 is now offline
Aug 26 15:27:54 anaximander kernel: smpboot: CPU 6 is now offline
Aug 26 15:27:54 anaximander kernel: smpboot: CPU 7 is now offline
Aug 26 15:27:54 anaximander kernel: ACPI: Low-level resume complete
Aug 26 15:27:54 anaximander kernel: ACPI: EC: EC started
Aug 26 15:27:54 anaximander kernel: PM: Restoring platform NVS memory
Aug 26 15:27:54 anaximander kernel: Enabling non-boot CPUs ...
Aug 26 15:27:54 anaximander kernel: x86: Booting SMP configuration:
Aug 26 15:27:54 anaximander kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Aug 26 15:27:54 anaximander kernel: CPU1 is up
Aug 26 15:27:54 anaximander kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Aug 26 15:27:54 anaximander kernel: CPU2 is up
Aug 26 15:27:54 anaximander kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Aug 26 15:27:54 anaximander kernel: CPU3 is up
Aug 26 15:27:54 anaximander kernel: smpboot: Booting Node 0 Processor 4 APIC 0x4
Aug 26 15:27:54 anaximander kernel: CPU4 is up
Aug 26 15:27:54 anaximander kernel: smpboot: Booting Node 0 Processor 5 APIC 0x5
Aug 26 15:27:54 anaximander kernel: CPU5 is up
Aug 26 15:27:54 anaximander kernel: smpboot: Booting Node 0 Processor 6 APIC 0x6
Aug 26 15:27:54 anaximander kernel: CPU6 is up
Aug 26 15:27:54 anaximander kernel: smpboot: Booting Node 0 Processor 7 APIC 0x7
Aug 26 15:27:54 anaximander kernel: CPU7 is up
Aug 26 15:27:54 anaximander kernel: ACPI: Waking up from system sleep state S3
Aug 26 15:27:54 anaximander kernel: ACPI: EC: interrupt unblocked
Aug 26 15:27:54 anaximander kernel: ACPI: EC: event unblocked
Aug 26 15:27:54 anaximander 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
Aug 26 15:27:54 anaximander 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
Aug 26 15:27:54 anaximander kernel: i915 0000:00:02.0: [drm] [ENCODER:118:DDI C/PHY C] is disabled/in DSI mode with an ungated DDI clock, gate it
Aug 26 15:27:54 anaximander kernel: nvme nvme0: Shutdown timeout set to 8 seconds
Aug 26 15:27:54 anaximander kernel: nvme nvme0: 8/0/0 default/read/poll queues
Aug 26 15:27:54 anaximander kernel: usb 1-7: reset high-speed USB device number 2 using xhci_hcd
Aug 26 15:27:54 anaximander kernel: OOM killer enabled.
Aug 26 15:27:54 anaximander kernel: Restarting tasks ... done.
Aug 26 15:27:54 anaximander systemd-networkd[452]: wlp0s20f3: Lost carrier
Aug 26 15:27:54 anaximander systemd-logind[663]: Lid opened.
Aug 26 15:27:54 anaximander systemd[1]: Starting Local backup snapshots...
Aug 26 15:27:54 anaximander systemd[1]: Starting Update virus database...
Aug 26 15:27:54 anaximander dhcpcd[288544]: sending signal TERM to pid 227722
Aug 26 15:27:54 anaximander dhcpcd[288544]: waiting for pid 227722 to exit
Aug 26 15:27:54 anaximander dhcpcd[227723]: received SIGTERM, stopping
Aug 26 15:27:54 anaximander dhcpcd[227723]: wlp0s20f3: removing interface
Aug 26 15:27:54 anaximander dhcpcd[227723]: dhcpcd exited
Aug 26 15:27:54 anaximander freshclam[288520]: ClamAV update process started at Thu Aug 26 15:27:54 2021
Aug 26 15:27:54 anaximander take-snapshot[288518]: ERROR: Missing drive 7259d75b-a70e-4a68-9fef-480eb260ae7f, aborting...
Aug 26 15:27:54 anaximander systemd[1]: backup-snapshots.service: Main process exited, code=exited, status=102/n/a
Aug 26 15:27:54 anaximander systemd[1]: backup-snapshots.service: Failed with result 'exit-code'.
Aug 26 15:27:54 anaximander systemd[1]: Failed to start Local backup snapshots.
Aug 26 15:27:54 anaximander audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=backup-snapshots comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
Aug 26 15:27:54 anaximander kernel: audit: type=1130 audit(1629984474.746:169): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=backup-snapshots comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
Aug 26 15:27:54 anaximander bluetoothd[655]: Controller resume with wake event 0x0
Aug 26 15:27:54 anaximander kernel: video LNXVIDEO:00: Restoring backlight state
Aug 26 15:27:54 anaximander avahi-daemon[654]: Withdrawing address record for 192.168.1.17 on wlp0s20f3.
Aug 26 15:27:54 anaximander avahi-daemon[654]: Leaving mDNS multicast group on interface wlp0s20f3.IPv4 with address 192.168.1.17.
Aug 26 15:27:54 anaximander mullvad-daemon[228130]: [talpid_core::dns][INFO] Resetting DNS
Aug 26 15:27:54 anaximander avahi-daemon[654]: Interface wlp0s20f3.IPv4 no longer relevant for mDNS.
Aug 26 15:27:54 anaximander avahi-daemon[654]: Got SIGHUP, reloading.
Aug 26 15:27:54 anaximander avahi-daemon[654]: No service file found in /etc/avahi/services.
Aug 26 15:27:54 anaximander mullvad-daemon[228130]: [talpid_core::routing::imp::imp][DEBUG] Clearing routes
Aug 26 15:27:54 anaximander mullvad-daemon[228130]: [mullvad_daemon][DEBUG] New tunnel state: Disconnecting(Block)
Aug 26 15:27:54 anaximander systemd-networkd[452]: wg-mullvad: Link DOWN
Aug 26 15:27:54 anaximander systemd-networkd[452]: wg-mullvad: Lost carrier
Aug 26 15:27:55 anaximander systemd-sleep[288460]: System returned from sleep state.
Aug 26 15:27:55 anaximander kernel: PM: suspend exit
Aug 26 15:27:55 anaximander systemd[1]: systemd-suspend.service: Deactivated successfully.
Aug 26 15:27:55 anaximander systemd[1]: Finished System Suspend.
Aug 26 15:27:55 anaximander audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 26 15:27:55 anaximander audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 26 15:27:55 anaximander systemd[1]: Stopped target Sleep.
Aug 26 15:27:55 anaximander kernel: audit: type=1130 audit(1629984475.093:170): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 26 15:27:55 anaximander kernel: audit: type=1131 audit(1629984475.093:171): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 26 15:27:55 anaximander systemd[1]: Reached target Suspend.
Aug 26 15:27:55 anaximander systemd[1]: Stopped target Suspend.
Aug 26 15:27:55 anaximander systemd-logind[663]: Operation 'sleep' finished.
Aug 26 15:27:55 anaximander systemd-networkd[452]: lo: Reset carrier

What I've noticed in the events which result in a crash is the following happens:

Aug 26 18:14:11 anaximander kernel: ACPI: Low-level resume complete
Aug 26 18:14:11 anaximander kernel: unchecked MSR access error: WRMSR to 0x83f (tried to write 0x00000000000000f6) at rIP: 0xffffffffad072ad4 (native_write_msr+0x4/0x20)
Aug 26 18:14:11 anaximander kernel: Call Trace:
Aug 26 18:14:11 anaximander kernel:  native_apic_msr_write+0x28/0x30
Aug 26 18:14:11 anaximander kernel:  arch_irq_work_raise+0x23/0x40
Aug 26 18:14:11 anaximander kernel:  irq_work_queue+0x2e/0x60
Aug 26 18:14:11 anaximander kernel:  __kfence_alloc+0x80/0x3c0
Aug 26 18:14:11 anaximander kernel:  ? acpi_get_sleep_type_data+0xaa/0x214
Aug 26 18:14:11 anaximander kernel:  kmem_cache_alloc_trace+0x1fa/0x2e0
Aug 26 18:14:11 anaximander kernel:  acpi_get_sleep_type_data+0xaa/0x214
Aug 26 18:14:11 anaximander kernel:  acpi_hw_legacy_wake_prep+0x57/0x10b
Aug 26 18:14:11 anaximander kernel:  acpi_leave_sleep_state_prep+0x37/0x69
Aug 26 18:14:11 anaximander kernel:  acpi_suspend_enter+0xa3/0x1f0
Aug 26 18:14:11 anaximander kernel:  suspend_devices_and_enter+0x707/0x7f0
Aug 26 18:14:11 anaximander kernel:  pm_suspend.cold+0x329/0x374
Aug 26 18:14:11 anaximander kernel:  state_store+0x71/0xd0
Aug 26 18:14:11 anaximander kernel:  kernfs_fop_write_iter+0x119/0x1b0
Aug 26 18:14:11 anaximander kernel:  new_sync_write+0x156/0x1f0
Aug 26 18:14:11 anaximander kernel:  vfs_write+0x1db/0x270
Aug 26 18:14:11 anaximander kernel:  ksys_write+0x67/0xe0
Aug 26 18:14:11 anaximander kernel:  do_syscall_64+0x5e/0x80
Aug 26 18:14:11 anaximander kernel:  ? exc_page_fault+0x78/0x180
Aug 26 18:14:11 anaximander kernel:  entry_SYSCALL_64_after_hwframe+0x44/0xae
Aug 26 18:14:11 anaximander kernel: RIP: 0033:0x7f0deb5f1907
Aug 26 18:14:11 anaximander kernel: Code: 0d 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7 0f 1f 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 51 c3 48 83 ec 28 48 89 54 24 18 48 89 74 24
Aug 26 18:14:11 anaximander kernel: RSP: 002b:00007ffd5a967fc8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
Aug 26 18:14:11 anaximander kernel: RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f0deb5f1907
Aug 26 18:14:11 anaximander kernel: RDX: 0000000000000004 RSI: 00007ffd5a9680b0 RDI: 0000000000000004
Aug 26 18:14:11 anaximander kernel: RBP: 00007ffd5a9680b0 R08: 00005655425ef220 R09: 00007f0deb6874e0
Aug 26 18:14:11 anaximander kernel: R10: 00007f0deb6873e0 R11: 0000000000000246 R12: 0000000000000004
Aug 26 18:14:11 anaximander kernel: R13: 00005655425eb3c0 R14: 0000000000000004 R15: 00007f0deb6c3700
Aug 26 18:14:11 anaximander kernel: ACPI: EC: EC started

I found this bug [1] in bugzilla.kernel.org which suggests that a similar problem was fixed in 5.1.12 (I'm on 5.13.12, running on a System76 Lemur Pro). There's also this thread [2] on the OpenSUSE forum which has the same problem, but suggests it was fixed in an earlier kernel version.

I'm not sure how else to proceed to either debug / fix (especially difficult as it seems to be randomly occurring, I haven't determined the rate yet). If anyone understands this bug I would greatly appreciate some guidance.

[1]: https://bugzilla.kernel.org/show_bug.cgi?id=203637
[2]: https://forums.opensuse.org/showthread. … om-suspend

Last edited by gtf21 (2021-08-26 16:46:09)

Spielkind · 2021-09-02 06:04:42

I see the same behavior (sporadic suspend resume issues, system freeze & fan spins up) on my X280, so you are not alone:

Sep 01 20:58:08 xpaddy kernel: PM: suspend entry (deep)
Sep 01 20:58:08 xpaddy kernel: Filesystems sync: 0.015 seconds
Sep 02 07:38:06 xpaddy kernel: Freezing user space processes ... (elapsed 0.006 seconds) done.
Sep 02 07:38:06 xpaddy kernel: OOM killer disabled.
Sep 02 07:38:06 xpaddy kernel: Freezing remaining freezable tasks ... (elapsed 0.002 seconds) done.
Sep 02 07:38:06 xpaddy kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Sep 02 07:38:06 xpaddy kernel: e1000e: EEE TX LPI TIMER: 00000011
Sep 02 07:38:06 xpaddy kernel: ACPI: EC: interrupt blocked
Sep 02 07:38:06 xpaddy kernel: ACPI: Preparing to enter system sleep state S3
Sep 02 07:38:06 xpaddy kernel: ACPI: EC: event blocked
Sep 02 07:38:06 xpaddy kernel: ACPI: EC: EC stopped
Sep 02 07:38:06 xpaddy kernel: PM: Saving platform NVS memory
Sep 02 07:38:06 xpaddy kernel: Disabling non-boot CPUs ...
Sep 02 07:38:06 xpaddy kernel: smpboot: CPU 1 is now offline
Sep 02 07:38:06 xpaddy kernel: smpboot: CPU 2 is now offline
Sep 02 07:38:06 xpaddy kernel: smpboot: CPU 3 is now offline
Sep 02 07:38:06 xpaddy kernel: smpboot: CPU 4 is now offline
Sep 02 07:38:06 xpaddy kernel: smpboot: CPU 5 is now offline
Sep 02 07:38:06 xpaddy kernel: smpboot: CPU 6 is now offline
Sep 02 07:38:06 xpaddy kernel: smpboot: CPU 7 is now offline
Sep 02 07:38:06 xpaddy kernel: ACPI: Low-level resume complete
Sep 02 07:38:06 xpaddy kernel: unchecked MSR access error: WRMSR to 0x83f (tried to write 0x00000000000000f6) at rIP: 0xffffffff86672ad4 (native_write_msr+0x4/0x20)
Sep 02 07:38:06 xpaddy kernel: Call Trace:
Sep 02 07:38:06 xpaddy kernel:  native_apic_msr_write+0x28/0x30
Sep 02 07:38:06 xpaddy kernel:  arch_irq_work_raise+0x26/0x40
Sep 02 07:38:06 xpaddy kernel:  irq_work_queue+0x2e/0x60
Sep 02 07:38:06 xpaddy kernel:  __kfence_alloc+0x80/0x3c0
Sep 02 07:38:06 xpaddy kernel:  ? acpi_get_sleep_type_data+0xaa/0x214
Sep 02 07:38:06 xpaddy kernel:  kmem_cache_alloc_trace+0x1fa/0x2e0
Sep 02 07:38:06 xpaddy kernel:  acpi_get_sleep_type_data+0xaa/0x214
Sep 02 07:38:06 xpaddy kernel:  acpi_hw_legacy_wake_prep+0x57/0x10b
Sep 02 07:38:06 xpaddy kernel:  acpi_leave_sleep_state_prep+0x37/0x69
Sep 02 07:38:06 xpaddy kernel:  acpi_suspend_enter+0xa3/0x1f0
Sep 02 07:38:06 xpaddy kernel:  suspend_devices_and_enter+0x70a/0x7f0
Sep 02 07:38:06 xpaddy kernel:  pm_suspend.cold+0x329/0x374
Sep 02 07:38:06 xpaddy kernel:  state_store+0x71/0xd0
Sep 02 07:38:06 xpaddy kernel:  kernfs_fop_write_iter+0x11c/0x1b0
Sep 02 07:38:06 xpaddy kernel:  new_sync_write+0x159/0x1f0
Sep 02 07:38:06 xpaddy kernel:  vfs_write+0x1db/0x270
Sep 02 07:38:06 xpaddy kernel:  ksys_write+0x67/0xe0
Sep 02 07:38:06 xpaddy kernel:  do_syscall_64+0x61/0x80
Sep 02 07:38:06 xpaddy kernel:  ? syscall_exit_to_user_mode+0x23/0x50
Sep 02 07:38:06 xpaddy kernel:  ? do_syscall_64+0x6e/0x80
Sep 02 07:38:06 xpaddy kernel:  ? handle_mm_fault+0xdb/0x2c0
Sep 02 07:38:06 xpaddy kernel:  ? do_user_addr_fault+0x1e8/0x690
Sep 02 07:38:06 xpaddy kernel:  ? exc_page_fault+0x78/0x180
Sep 02 07:38:06 xpaddy kernel:  entry_SYSCALL_64_after_hwframe+0x44/0xae
Sep 02 07:38:06 xpaddy kernel: RIP: 0033:0x7f085ee12907
Sep 02 07:38:06 xpaddy kernel: Code: 0d 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7 0f 1f 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 51 c3 48 83 ec 28 48 89 54 24 18 48 89 74 24
Sep 02 07:38:06 xpaddy kernel: RSP: 002b:00007ffd71b0eab8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
Sep 02 07:38:06 xpaddy kernel: RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f085ee12907
Sep 02 07:38:06 xpaddy kernel: RDX: 0000000000000004 RSI: 00007ffd71b0eba0 RDI: 0000000000000004
Sep 02 07:38:06 xpaddy kernel: RBP: 00007ffd71b0eba0 R08: 0000562aef3d9180 R09: 00007f085eea84e0
Sep 02 07:38:06 xpaddy kernel: R10: 00007f085eea83e0 R11: 0000000000000246 R12: 0000000000000004
Sep 02 07:38:06 xpaddy kernel: R13: 0000562aef3d53c0 R14: 0000000000000004 R15: 00007f085eee4700
Sep 02 07:38:06 xpaddy kernel: ACPI: EC: EC started
Sep 02 07:38:06 xpaddy kernel: PM: Restoring platform NVS memory
Sep 02 07:38:06 xpaddy kernel: Enabling non-boot CPUs ...

I opened a new Bug with the stack trace at kernel.org:
https://bugzilla.kernel.org/show_bug.cgi?id=214271

Last edited by Spielkind (2021-09-02 06:22:02)

aurelieng · 2021-10-16 10:03:29

likewise, t480s.

roccobaroccoSC · 2021-10-16 14:10:17

+1, W520
Also, I put the machine to sleep always via the Plasma GUI menu. So closing the lid is not in play in my case.

mihai.bazon · 2021-11-29 08:28:02

I think I can safely say that it happens when I suspend while on AC, and resume on battery. Kernel 5.15.5-arch1-1 here, but it started about 2 months ago when I was on the LTS kernel. I'm not sure how to access the relevant logs. I'd love to get a solution; suspend/resume has been reliable for years and it's a pita to have it failing randomly.. :-\

seth · 2021-11-29 15:26:40

https://git.kernel.org/pub/scm/linux/ke … e6a841cc5c is supposed to show up in 5.16 and reported to fix the problem causing the backtrace in this thread.

ackerleytng · 2022-01-13 06:33:46

Based on https://github.com/torvalds/linux/commi … e6a841cc5c, does it mean that 5.16 has this merged in?

seth · 2022-01-13 07:18:38

You can check the commits presence in a particular version:
https://git.kernel.org/pub/scm/linux/ke … e6a841cc5c

mihai.bazon · 2022-01-27 04:47:59

Still happens with 5.16.0-arch1-1, seemingly in the same conditions (power dis/connect while suspended).

seth · 2022-01-27 07:28:23

mihai.bazon wrote:

I'm not sure how to access the relevant logs.

sudo journalctl -b -1

forthe previous boot - check if you see stack traces that end in "native_apic_msr_write"

I think I can safely say that it happens when I suspend while on AC, and resume on battery.

Is a fairly specific condition that has not been mentioned or confirmed by anyone else in this thread, so you may face a different situation.
Can you "rescue" the resume by putting the system on AC after you attempted to wak it?

Arch Linux

#1 2021-08-26 16:45:33

Sporadically failing to wake up from suspend (via lid close event)

#2 2021-09-02 06:04:42

Re: Sporadically failing to wake up from suspend (via lid close event)

#3 2021-10-16 10:03:29

Re: Sporadically failing to wake up from suspend (via lid close event)

#4 2021-10-16 14:10:17

Re: Sporadically failing to wake up from suspend (via lid close event)

#5 2021-11-29 08:28:02

Re: Sporadically failing to wake up from suspend (via lid close event)

#6 2021-11-29 15:26:40

Re: Sporadically failing to wake up from suspend (via lid close event)

#7 2022-01-13 06:33:46

Re: Sporadically failing to wake up from suspend (via lid close event)

#8 2022-01-13 07:18:38

Re: Sporadically failing to wake up from suspend (via lid close event)

#9 2022-01-27 04:47:59

Re: Sporadically failing to wake up from suspend (via lid close event)

#10 2022-01-27 07:28:23

Re: Sporadically failing to wake up from suspend (via lid close event)

Board footer