Re: [Regression] [PCI/ASPM] [ASUS PN51] Reboot on resume attempt (bisect done; commit found)

Michael Schaller
2024-01-05T11:19:14Z

[-- Attachment #1: Type: text/plain, Size: 489 bytes --]

On 05.01.24 04:25, Kai-Heng Feng wrote:
> Just wondering, does `echo 0 > /sys/power/pm_asysnc` help?

Yes, `echo 0 | sudo tee /sys/power/pm_async` does indeed also result in
a working resume. I've tested this on kernel 6.6.9 (which still has
commit 08d0cc5f3426). I've also attached the relevant dmesg output of
the suspend/resume cycle in case this helps.

Furthermore does this mean that commit 08d0cc5f3426 isn't at fault but
rather that we are dealing with a timing issue?

Michael

[-- Attachment #2: dmesg-with-pm-async-disabled.txt --]
[-- Type: text/plain, Size: 10164 bytes --]

[Fr Jan 5 11:45:47 2024] wlp3s0: deauthenticating from b0:e4:d5:7e:dc:d5 by local choice (Reason: 3=DEAUTH_LEAVING)
[Fr Jan 5 11:45:47 2024] PM: suspend entry (deep)
[Fr Jan 5 11:45:47 2024] Filesystems sync: 0.003 seconds
[Fr Jan 5 11:45:47 2024] Freezing user space processes
[Fr Jan 5 11:45:47 2024] Freezing user space processes completed (elapsed 0.001 seconds)
[Fr Jan 5 11:45:47 2024] OOM killer disabled.
[Fr Jan 5 11:45:47 2024] Freezing remaining freezable tasks
[Fr Jan 5 11:45:47 2024] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
[Fr Jan 5 11:45:47 2024] printk: Suspending console(s) (use no_console_suspend to debug)
[Fr Jan 5 11:45:48 2024] ACPI: EC: interrupt blocked
[Fr Jan 5 11:45:48 2024] ACPI: PM: Preparing to enter system sleep state S3
[Fr Jan 5 11:45:48 2024] ACPI: EC: event blocked
[Fr Jan 5 11:45:48 2024] ACPI: EC: EC stopped
[Fr Jan 5 11:45:48 2024] ACPI: PM: Saving platform NVS memory
[Fr Jan 5 11:45:48 2024] Disabling non-boot CPUs ...
[Fr Jan 5 11:45:48 2024] smpboot: CPU 1 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 2 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 3 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 4 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 5 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 6 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 7 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 8 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 9 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 10 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 11 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 12 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 13 is now offline
[Fr Jan 5 11:45:48 2024] smpboot: CPU 14 is now offline
[Fr Jan 5 11:45:48 2024] Spectre V2 : Update user space SMT mitigation: STIBP off
[Fr Jan 5 11:45:49 2024] smpboot: CPU 15 is now offline
[Fr Jan 5 11:45:49 2024] ACPI: PM: Low-level resume complete
[Fr Jan 5 11:45:49 2024] ACPI: EC: EC started
[Fr Jan 5 11:45:49 2024] ACPI: PM: Restoring platform NVS memory
[Fr Jan 5 11:45:49 2024] AMD-Vi: Virtual APIC enabled
[Fr Jan 5 11:45:49 2024] AMD-Vi: Virtual APIC enabled
[Fr Jan 5 11:45:49 2024] LVT offset 0 assigned for vector 0x400
[Fr Jan 5 11:45:49 2024] Enabling non-boot CPUs ...
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 1 APIC 0x1
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P001: Found 3 idle states
[Fr Jan 5 11:45:49 2024] Spectre V2 : Update user space SMT mitigation: STIBP always-on
[Fr Jan 5 11:45:49 2024] CPU1 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 2 APIC 0x2
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P002: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU2 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 3 APIC 0x3
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P003: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU3 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 4 APIC 0x4
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P004: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU4 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 5 APIC 0x5
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P005: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU5 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 6 APIC 0x6
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P006: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU6 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 7 APIC 0x7
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P007: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU7 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 8 APIC 0x8
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P008: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU8 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 9 APIC 0x9
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P009: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU9 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 10 APIC 0xa
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P00A: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU10 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 11 APIC 0xb
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P00B: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU11 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 12 APIC 0xc
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P00C: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU12 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 13 APIC 0xd
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P00D: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU13 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 14 APIC 0xe
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P00E: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU14 is up
[Fr Jan 5 11:45:49 2024] smpboot: Booting Node 0 Processor 15 APIC 0xf
[Fr Jan 5 11:45:49 2024] ACPI: \_SB_.PLTF.P00F: Found 3 idle states
[Fr Jan 5 11:45:49 2024] CPU15 is up
[Fr Jan 5 11:45:49 2024] ACPI: PM: Waking up from system sleep state S3
[Fr Jan 5 11:45:49 2024] ACPI: EC: interrupt unblocked
[Fr Jan 5 11:45:49 2024] ACPI: EC: event unblocked
[Fr Jan 5 11:45:49 2024] pci 0000:00:00.2: can't derive routing for PCI INT A
[Fr Jan 5 11:45:49 2024] pci 0000:00:00.2: PCI INT A: no GSI
[Fr Jan 5 11:45:49 2024] xhci_hcd 0000:01:00.0: xHC error in resume, USBSTS 0x401, Reinit
[Fr Jan 5 11:45:49 2024] usb usb1: root hub lost power or was reset
[Fr Jan 5 11:45:49 2024] usb usb2: root hub lost power or was reset
[Fr Jan 5 11:45:49 2024] [drm] PCIE GART of 1024M enabled.
[Fr Jan 5 11:45:49 2024] [drm] PTB located at 0x000000F41FC00000
[Fr Jan 5 11:45:49 2024] [drm] PSP is resuming...
[Fr Jan 5 11:45:49 2024] nvme nvme0: 15/0/0 default/read/poll queues
[Fr Jan 5 11:45:49 2024] [drm] reserve 0x400000 from 0xf41f800000 for PSP TMR
[Fr Jan 5 11:45:49 2024] amdgpu 0000:05:00.0: amdgpu: RAS: optional ras ta ucode is not available
[Fr Jan 5 11:45:49 2024] amdgpu 0000:05:00.0: amdgpu: RAP: optional rap ta ucode is not available
[Fr Jan 5 11:45:49 2024] amdgpu 0000:05:00.0: amdgpu: SECUREDISPLAY: securedisplay ta ucode is not available
[Fr Jan 5 11:45:49 2024] amdgpu 0000:05:00.0: amdgpu: SMU is resuming...
[Fr Jan 5 11:45:49 2024] amdgpu 0000:05:00.0: amdgpu: dpm has been disabled
[Fr Jan 5 11:45:49 2024] amdgpu 0000:05:00.0: amdgpu: SMU is resumed successfully!
[Fr Jan 5 11:45:49 2024] [drm] DMUB hardware initialized: version=0x01010027
[Fr Jan 5 11:45:50 2024] [drm] kiq ring mec 2 pipe 1 q 0
[Fr Jan 5 11:45:50 2024] [drm] VCN decode and encode initialized successfully(under DPG Mode).
[Fr Jan 5 11:45:50 2024] [drm] JPEG decode initialized successfully.
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring gfx uses VM inv eng 0 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring gfx_low uses VM inv eng 1 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring gfx_high uses VM inv eng 4 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring comp_1.0.0 uses VM inv eng 5 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring comp_1.1.0 uses VM inv eng 6 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring comp_1.2.0 uses VM inv eng 7 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring comp_1.3.0 uses VM inv eng 8 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring comp_1.0.1 uses VM inv eng 9 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring comp_1.1.1 uses VM inv eng 10 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring comp_1.2.1 uses VM inv eng 11 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring comp_1.3.1 uses VM inv eng 12 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring kiq_0.2.1.0 uses VM inv eng 13 on hub 0
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring sdma0 uses VM inv eng 0 on hub 8
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring vcn_dec uses VM inv eng 1 on hub 8
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring vcn_enc0 uses VM inv eng 4 on hub 8
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring vcn_enc1 uses VM inv eng 5 on hub 8
[Fr Jan 5 11:45:50 2024] amdgpu 0000:05:00.0: amdgpu: ring jpeg_dec uses VM inv eng 6 on hub 8
[Fr Jan 5 11:45:50 2024] ata2: SATA link down (SStatus 0 SControl 300)
[Fr Jan 5 11:45:50 2024] ata1: SATA link down (SStatus 0 SControl 300)
[Fr Jan 5 11:45:51 2024] OOM killer enabled.
[Fr Jan 5 11:45:51 2024] Restarting tasks ... done.
[Fr Jan 5 11:45:51 2024] random: crng reseeded on system resumption
[Fr Jan 5 11:45:51 2024] thermal thermal_zone0: failed to read out thermal zone (-61)
[Fr Jan 5 11:45:51 2024] PM: suspend exit
[Fr Jan 5 11:45:51 2024] ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
[Fr Jan 5 11:45:51 2024] ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
[Fr Jan 5 11:45:51 2024] ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
[Fr Jan 5 11:45:51 2024] ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
[Fr Jan 5 11:45:51 2024] ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
[Fr Jan 5 11:45:51 2024] ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
[Fr Jan 5 11:45:51 2024] ACPI: \: failed to evaluate _DSM bf0212f2-788f-c64d-a5b3-1f738e285ade (0x1001)
[Fr Jan 5 11:45:54 2024] wlp3s0: authenticate with b0:e4:d5:7e:dc:d5
[Fr Jan 5 11:45:54 2024] wlp3s0: send auth to b0:e4:d5:7e:dc:d5 (try 1/3)
[Fr Jan 5 11:45:54 2024] wlp3s0: authenticated
[Fr Jan 5 11:45:54 2024] wlp3s0: associate with b0:e4:d5:7e:dc:d5 (try 1/3)
[Fr Jan 5 11:45:54 2024] wlp3s0: RX AssocResp from b0:e4:d5:7e:dc:d5 (capab=0x1011 status=0 aid=5)
[Fr Jan 5 11:45:54 2024] wlp3s0: associated
[Fr Jan 5 11:45:55 2024] wlp3s0: Limiting TX power to 23 (23 - 0) dBm as advertised by b0:e4:d5:7e:dc:d5