https://bugs.freedesktop.org/show_bug.cgi?id=107278
Bug ID: 107278
Summary: Raven: pci_pm_resume takes over 1 second
Product: DRI
Version: DRI git
Hardware: Other
OS: All
Status: NEW
Severity: normal
Priority: medium
Component: DRM/AMDgpu
Assignee: dri-devel@lists.freedesktop.org
Reporter: pmenzel+bugs.freedesk...@molgen.mpg.de
Created attachment 140700
--> https://bugs.freedesktop.org/attachment.cgi?id=140700=edit
Linux 4.18-rc5+ messages
Profiling the suspend and resume time [1] on a MSI B350M MORTAR (MS-7A37) with
AMD Ryzen 3 2200G with Radeon Vega Graphics with Linux 4.18-rc5+,
`pci_pm_suspend()` takes over one second, which is too long.
[ 76.483494] amdgpu :38:00.0: calling pci_pm_resume+0x0/0xa0 @ 1731,
parent: :00:08.1
[ 76.483499] pcieport :16:01.0: pci_pm_resume+0x0/0xa0 returned 0 after
14 usecs
[ 76.483514] pci :38:00.2: calling pci_pm_resume+0x0/0xa0 @ 1672, parent:
:00:08.1
[ 76.483528] reg-dummy reg-dummy: calling platform_pm_resume+0x0/0x40 @ 1653,
parent: platform
[ 76.483530] pci :38:00.2: pci_pm_resume+0x0/0xa0 returned 0 after 9
usecs
[ 76.483540] xhci_hcd :38:00.3: calling pci_pm_resume+0x0/0xa0 @ 1672,
parent: :00:08.1
[ 76.483543] reg-dummy reg-dummy: platform_pm_resume+0x0/0x40 returned 0
after 5 usecs
[ 76.483667] button PNP0C0C:00: calling acpi_button_resume+0x0/0x40 [button]
@ 1653, parent: LNXSYBUS:00
[ 76.483679] button PNP0C0C:00: acpi_button_resume+0x0/0x40 [button] returned
0 after 5 usecs
[ 76.483724] xhci_hcd :38:00.3: pci_pm_resume+0x0/0xa0 returned 0 after
173 usecs
[ 76.483727] platform PNP0800:00: calling platform_pm_resume+0x0/0x40 @ 1653,
parent: :00:14.3
[ 76.483733] xhci_hcd :38:00.4: calling pci_pm_resume+0x0/0xa0 @ 1672,
parent: :00:08.1
[ 76.483739] platform PNP0800:00: platform_pm_resume+0x0/0x40 returned 0
after 5 usecs
[ 76.483748] platform PNP0C0C:00: calling platform_pm_resume+0x0/0x40 @ 1653,
parent: platform
[ 76.483759] platform PNP0C0C:00: platform_pm_resume+0x0/0x40 returned 0
after 4 usecs
[ 76.483767] platform AMDI0030:00: calling platform_pm_resume+0x0/0x40 @
1653, parent: platform
[ 76.483778] platform AMDI0030:00: platform_pm_resume+0x0/0x40 returned 0
after 4 usecs
[ 76.483791] platform PNP0103:00: calling platform_pm_resume+0x0/0x40 @ 1653,
parent: platform
[ 76.483802] platform PNP0103:00: platform_pm_resume+0x0/0x40 returned 0
after 4 usecs
[ 76.483813] button LNXPWRBN:00: calling acpi_button_resume+0x0/0x40 [button]
@ 1653, parent: LNXSYSTM:00
[ 76.483825] button LNXPWRBN:00: acpi_button_resume+0x0/0x40 [button]
returned 0 after 5 usecs
[ 76.483841] system 00:00: calling pnp_bus_resume+0x0/0x90 @ 1653, parent:
pnp0
[ 76.483854] system 00:00: pnp_bus_resume+0x0/0x90 returned 0 after 6 usecs
[ 76.483862] system 00:01: calling pnp_bus_resume+0x0/0x90 @ 1653, parent:
pnp0
[ 76.483875] system 00:01: pnp_bus_resume+0x0/0x90 returned 0 after 6 usecs
[ 76.483883] rtc_cmos 00:02: calling pnp_bus_resume+0x0/0x90 @ 1653, parent:
pnp0
[ 76.483934] xhci_hcd :38:00.4: pci_pm_resume+0x0/0xa0 returned 0 after
190 usecs
[ 76.483943] snd_hda_intel :38:00.6: calling pci_pm_resume+0x0/0xa0 @
1672, parent: :00:08.1
[ 76.484150] ahci :39:00.0: calling pci_pm_resume+0x0/0xa0 @ 1730,
parent: :00:08.2
[ 76.484222] ahci :39:00.0: pci_pm_resume+0x0/0xa0 returned 0 after 66
usecs
[ 76.484234] r8169 :18:00.0: calling pci_pm_resume+0x0/0xa0 @ 1729,
parent: :16:01.0
[ 76.484291] rtc_cmos 00:02: pnp_bus_resume+0x0/0x90 returned 0 after 393
usecs
[ 76.484296] system 00:03: calling pnp_bus_resume+0x0/0x90 @ 1653, parent:
pnp0
[ 76.484302] system 00:03: pnp_bus_resume+0x0/0x90 returned 0 after 3 usecs
[ 76.484307] parport_pc 00:04: calling pnp_bus_resume+0x0/0x90 @ 1653,
parent: pnp0
[ 76.485185] [drm] PCIE GART of 1024M enabled (table at 0x00F40090).
[ 76.485220] [drm] PSP is resuming...
[ 76.486099] parport_pc 00:04: activated
[ 76.486105] parport_pc 00:04: pnp_bus_resume+0x0/0x90 returned 0 after 1751
usecs
[ 76.486111] serial 00:05: calling pnp_bus_resume+0x0/0x90 @ 1653, parent:
pnp0
[ 76.487493] serial 00:05: activated
[ 76.487975] snd_hda_intel :38:00.6: pci_pm_resume+0x0/0xa0 returned 0
after 3933 usecs
[ 76.487995] ata1: calling ata_port_pm_resume+0x0/0x50 [libata] @ 1672,
parent: :15:00.1
[ 76.488016] ata1: ata_port_pm_resume+0x0/0x50 [libata] returned 0 after 12
usecs
[ 76.488025] ata2: calling ata_port_pm_resume+0x0/0x50 [libata] @ 1672,
parent: :15:00.1
[ 76.488043] ata2: ata_port_pm_resume+0x0/0x50 [libata] returned 0 after 10
usecs
[ 76.488052] ata3: calling ata_port_pm_resume+0x0/0x50 [libata] @ 1672,
parent: :15:00.1
[ 76.488070] ata3: ata_port_pm_resume+0x0/0x50 [libata] returned 0 after 9
usecs
[ 76.488079] ata4: