Dear Radeon folks,

I hope this is the correct list to report that problem to.

Using Debian Wheezy using `linux-image-2.6.39-2-amd64` resuming from suspend takes a long time, that means until the GNOME Screensaver(?) shows up to ask for a password to login.

Looking at `dmesg` I am seeing the following lines.

$ dmesg […] [ 82.280034] wlan0: no IPv6 routers present [ 1829.798442] wlan0: deauthenticating from 00:11:22:33:44:55 by local choice (reason=3) [ 1829.820428] cfg80211: Calling CRDA for country: US [ 1832.274260] EXT4-fs (dm-1): re-mounted. Opts: errors=remount-ro,commit=0 [ 1832.838922] EXT4-fs (dm-3): re-mounted. Opts: commit=0 [ 1833.563403] PM: Syncing filesystems ... done. [ 1833.571094] PM: Preparing system for mem sleep [ 1833.644494] Freezing user space processes ... (elapsed 0.01 seconds) done. [ 1833.660156] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [ 1833.676160] PM: Entering mem sleep [ 1833.676359] Suspending console(s) (use no_console_suspend to debug) [ 1833.680983] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 1833.702385] brcmsmac 0000:08:00.0: PCI INT A disabled [ 1833.702432] HDA Intel 0000:00:14.2: PCI INT A disabled [ 1833.702461] ACPI handle has no context! [ 1833.702487] ehci_hcd 0000:00:13.2: PCI INT B disabled [ 1833.702512] ohci_hcd 0000:00:13.1: PCI INT A disabled [ 1833.702520] ohci_hcd 0000:00:13.0: PCI INT A disabled [ 1833.702549] ohci_hcd 0000:00:12.1: PCI INT A disabled [ 1833.702570] ohci_hcd 0000:00:12.0: PCI INT A disabled [ 1833.707120] sd 0:0:0:0: [sda] Stopping disk [ 1833.716176] ehci_hcd 0000:00:12.2: PCI INT B disabled [ 1834.850434] [drm] Disabling audio support [ 1834.866358] radeon 0000:01:00.0: PCI INT A disabled [ 1834.880183] PM: suspend of devices complete after 1203.647 msecs [ 1834.928203] PM: late suspend of devices complete after 48.014 msecs [ 1834.928248] ACPI: Preparing to enter system sleep state S3 [ 1834.977254] PM: Saving platform NVS memory [ 1834.987215] Disabling non-boot CPUs ... [ 1834.988617] CPU 1 is now offline [ 1834.989141] ACPI: Low-level resume complete [ 1834.989141] PM: Restoring platform NVS memory [ 1834.989141] PCI-DMA: Resuming GART IOMMU [ 1834.989141] PCI-DMA: Restoring GART aperture settings [ 1834.989141] Enabling non-boot CPUs ... [ 1834.989141] Booting Node 0 Processor 1 APIC 0x1 [ 1834.989141] smpboot cpu 1: start_ip = 9a000 [ 1835.100161] Switch to broadcast mode on CPU1 [ 1835.104120] Switched to NOHz mode on CPU #1 [ 1835.112375] NMI watchdog enabled, takes one hw-pmu counter. [ 1835.112573] CPU1 is up [ 1835.113496] ACPI: Waking up from system sleep state S3 [ 1835.115640] pcieport 0000:00:02.0: restoring config space at offset 0x7 (was 0x20007171, writing 0x7171) [ 1835.115646] pcieport 0000:00:02.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010) [ 1835.115650] pcieport 0000:00:02.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407) [ 1835.115674] pcieport 0000:00:04.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010) [ 1835.115678] pcieport 0000:00:04.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407) [ 1835.115699] pcieport 0000:00:05.0: restoring config space at offset 0x7 (was 0x1f1, writing 0x200001f1) [ 1835.115703] pcieport 0000:00:05.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010) [ 1835.115706] pcieport 0000:00:05.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407) [ 1835.115729] pcieport 0000:00:06.0: restoring config space at offset 0x3 (was 0x10000, writing 0x10010) [ 1835.115732] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407) [ 1835.115803] ahci 0000:00:11.0: restoring config space at offset 0x1 (was 0x2300003, writing 0x2300407) [ 1835.128103] ehci_hcd 0000:00:12.2: BAR 0: set to [mem 0xf2308500-0xf23085ff] (PCI address [0xf2308500-0xf23085ff]) [ 1835.128134] ehci_hcd 0000:00:12.2: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00013) [ 1835.144105] ehci_hcd 0000:00:13.2: BAR 0: set to [mem 0xf2308400-0xf23084ff] (PCI address [0xf2308400-0xf23084ff]) [ 1835.144136] ehci_hcd 0000:00:13.2: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00013) [ 1835.144296] radeon 0000:01:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [ 1835.144301] radeon 0000:01:00.0: restoring config space at offset 0x1 (was 0x100003, writing 0x100007) [ 1835.144348] brcmsmac 0000:08:00.0: restoring config space at offset 0x1 (was 0x100002, writing 0x100006) [ 1835.144487] PM: early resume of devices complete after 28.890 msecs [ 1835.144660] ohci_hcd 0000:00:12.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1835.144704] ohci_hcd 0000:00:12.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1835.144727] ehci_hcd 0000:00:12.2: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 1835.144787] ohci_hcd 0000:00:13.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [ 1835.144812] ohci_hcd 0000:00:13.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [ 1835.144834] ehci_hcd 0000:00:13.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 1835.144857] HDA Intel 0000:00:14.2: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1835.144875] radeon 0000:01:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [ 1835.144881] radeon 0000:01:00.0: setting latency timer to 64 [ 1835.144888] brcmsmac 0000:08:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [ 1835.144892] brcmsmac 0000:08:00.0: setting latency timer to 64 [ 1835.144931] sd 0:0:0:0: [sda] Starting disk [ 1835.146807] [drm] Loading RV710 Microcode [ 1835.436142] usb 2-2: reset high speed USB device number 2 using ehci_hcd [ 1835.636129] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 1835.659085] ata2.00: configured for UDMA/100 [ 1835.712641] usb 5-3: reset full speed USB device number 2 using ohci_hcd [ 1835.884133] btusb 5-3:1.0: no reset_resume for driver btusb? [ 1835.884136] btusb 5-3:1.1: no reset_resume for driver btusb? [ 1837.652115] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 1837.761502] ata1.00: configured for UDMA/100 [ 1895.904100] r600_cp: Failed to load firmware "radeon/RV710_pfp.bin" [ 1895.904103] [drm:rv770_startup] *ERROR* Failed to load firmware! [ 1895.904105] [drm:rv770_resume] *ERROR* r600 startup failed on resume [ 1896.968218] PM: resume of devices complete after 61823.672 msecs [ 1896.968709] PM: Finishing wakeup. [ 1896.968711] Restarting tasks ... done. […]

So there seems to be a long one minute delay before the following message?

[ 1895.904100] r600_cp: Failed to load firmware "radeon/RV710_pfp.bin"

Is searching for the firmware taking so long? I do not want to install the firmware being in non-free.

Please find the full output `dmesg` attached and please excuse the scarce information, since I do not have access to the system right now.

I looked through the Debian problem reports [1] and could not find anything. Additionally searching for this problem only showed up information about non working resume systems.

Thanks,

Paul

[1] http://bugs.debian.org/cgi-bin/pkgreport.cgi?ordering=normal;archive=0;src=l...