[ltp] Power drain workaround with radeonfb module?

[Felix E. Klee]

At Sun, 28 Oct 2007 22:39:21 +0200,
Marius Gedminas wrote:
> At this point, if you check dmesg, do you see the following two
> messages:
> 
>   radeonfb (0000:01:00.0): suspending to state: 2...
>   radeonfb (0000:01:00.0): switching to D2 state...
> 
> before the machine went to sleep?

Yes, I do:

  [31694.820000] radeonfb (0000:01:00.0): suspending for event: 2...
  [31694.820000] radeonfb (0000:01:00.0): switching to D2 state...

Still, my "system seems to be affected".  But it's not only the sleep.sh
test script that makes me realize the power drain problem.  In day to
day use, it's clear that the machine's battery runs down unusually quick
when the system is in suspend to RAM.

FWIW, the output of dmesg which was produced while running "./sleep.sh":

[140527.396000] uhci_hcd 0000:00:1d.2: remove, state 4
[140527.396000] usb usb3: USB disconnect, address 1
[140527.400000] uhci_hcd 0000:00:1d.2: USB bus 3 deregistered
[140527.400000] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
[140527.400000] uhci_hcd 0000:00:1d.1: remove, state 4
[140527.400000] usb usb2: USB disconnect, address 1
[140527.404000] uhci_hcd 0000:00:1d.1: USB bus 2 deregistered
[140527.404000] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
[140527.404000] uhci_hcd 0000:00:1d.0: remove, state 4
[140527.404000] usb usb1: USB disconnect, address 1
[140527.404000] uhci_hcd 0000:00:1d.0: USB bus 1 deregistered
[140527.404000] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
[140527.472000] ehci_hcd 0000:00:1d.7: remove, state 4
[140527.472000] usb usb4: USB disconnect, address 1
[140527.476000] ehci_hcd 0000:00:1d.7: USB bus 4 deregistered
[140527.476000] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
[140529.668000] PM: Preparing system for mem sleep
[140529.668000] Stopping tasks ... done.
[140529.684000] Suspending console(s)
[140529.684000] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
[140529.684000] sd 1:0:0:0: [sdb] Stopping disk
[140529.684000] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[140529.872000] sd 0:0:0:0: [sda] Stopping disk
[140530.984000] pnp: Device 00:0c disabled.
[140530.984000] pnp: Device 00:0b disabled.
[140530.984000] pnp: Device 00:0a disabled.
[140531.016000] ACPI: PCI interrupt for device 0000:02:01.0 disabled
[140531.032000] radeonfb (0000:01:00.0): suspending for event: 2...
[140531.032000] radeonfb (0000:01:00.0): switching to D2 state...
[140531.688000] ACPI: PCI interrupt for device 0000:00:1f.6 disabled
[140531.704000] ACPI: PCI interrupt for device 0000:00:1f.5 disabled
[140531.704000] ACPI: PCI interrupt for device 0000:00:1f.1 disabled
[140531.704000] pci_set_power_state(): 0000:00:1f.1: state=3, current state=5
[140531.712000] Disabling non-boot CPUs ...
[140531.712000] PM: Entering mem sleep
[140531.712000] Back to C!
[140531.712000] PM: Finishing wakeup.
[140531.712000] PM: Writing back config space on device 0000:00:01.0 at offset 7 (was 2a03030, writing 22a03030)
[140531.712000] PM: Writing back config space on device 0000:00:1d.0 at offset f (was 100, writing 10b)
[140531.712000] PM: Writing back config space on device 0000:00:1d.0 at offset 8 (was 1, writing 1801)
[140531.712000] PM: Writing back config space on device 0000:00:1d.0 at offset 1 (was 2800000, writing 2800001)
[140531.712000] PM: Writing back config space on device 0000:00:1d.1 at offset f (was 200, writing 20b)
[140531.712000] PM: Writing back config space on device 0000:00:1d.1 at offset 8 (was 1, writing 1821)
[140531.712000] PM: Writing back config space on device 0000:00:1d.1 at offset 1 (was 2800000, writing 2800001)
[140531.712000] PM: Writing back config space on device 0000:00:1d.2 at offset f (was 300, writing 30b)
[140531.712000] PM: Writing back config space on device 0000:00:1d.2 at offset 8 (was 1, writing 1841)
[140531.712000] PM: Writing back config space on device 0000:00:1d.2 at offset 1 (was 2800000, writing 2800001)
[140531.712000] PM: Writing back config space on device 0000:00:1d.7 at offset f (was 400, writing 40b)
[140531.712000] PM: Writing back config space on device 0000:00:1d.7 at offset 4 (was 0, writing c0000000)
[140531.712000] PM: Writing back config space on device 0000:00:1d.7 at offset 1 (was 2900000, writing 2900102)
[140531.712000] PCI: Setting latency timer of device 0000:00:1e.0 to 64
[140531.712000] PM: Writing back config space on device 0000:00:1f.1 at offset 9 (was 0, writing 50000000)
[140531.712000] PM: Writing back config space on device 0000:00:1f.1 at offset 1 (was 2800005, writing 2800007)
[140531.712000] ACPI: PCI Interrupt 0000:00:1f.1[A] -> Link [LNKC] -> GSI 11 (level, low) -> IRQ 11
[140531.712000] PCI: Setting latency timer of device 0000:00:1f.1 to 64
[140531.712000] PM: Writing back config space on device 0000:00:1f.5 at offset 1 (was 2900007, writing 2900003)
[140531.712000] ACPI: PCI Interrupt 0000:00:1f.5[B] -> Link [LNKB] -> GSI 11 (level, low) -> IRQ 11
[140531.712000] PCI: Setting latency timer of device 0000:00:1f.5 to 64
[140531.900000] ata1.00: configured for UDMA/100
[140531.900000] ata2.00: configured for UDMA/100
[140531.900000] sd 0:0:0:0: [sda] 195371568 512-byte hardware sectors (100030 MB)
[140531.900000] sd 0:0:0:0: [sda] Write Protect is off
[140531.900000] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[140531.900000] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[140531.900000] sd 1:0:0:0: [sdb] 78140160 512-byte hardware sectors (40008 MB)
[140531.900000] sd 1:0:0:0: [sdb] Write Protect is off
[140531.900000] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[140531.900000] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[140532.740000] PM: Writing back config space on device 0000:00:1f.6 at offset 1 (was 2900005, writing 2900001)
[140532.740000] ACPI: PCI Interrupt 0000:00:1f.6[B] -> Link [LNKB] -> GSI 11 (level, low) -> IRQ 11
[140532.740000] PCI: Setting latency timer of device 0000:00:1f.6 to 64
[140533.744000] radeonfb (0000:01:00.0): resuming from state: 2...
[140533.744000] radeonfb (0000:01:00.0): switching to D0 state...
[140534.620000] PM: Writing back config space on device 0000:02:01.0 at offset f (was ff0100, writing ff010b)
[140534.620000] PM: Writing back config space on device 0000:02:01.0 at offset 6 (was 1, writing 8001)
[140534.620000] PM: Writing back config space on device 0000:02:01.0 at offset 5 (was 0, writing c0200000)
[140534.620000] PM: Writing back config space on device 0000:02:01.0 at offset 4 (was 0, writing c0220000)
[140534.620000] PM: Writing back config space on device 0000:02:01.0 at offset 3 (was 0, writing 4008)
[140534.620000] PM: Writing back config space on device 0000:02:01.0 at offset 1 (was 2300000, writing 2300117)
[140534.620000] ACPI: PCI Interrupt 0000:02:01.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
[140534.652000] PM: Writing back config space on device 0000:02:02.0 at offset 1 (was 2900116, writing 2900112)
[140534.652000] pnp: Device 00:0a activated.
[140534.652000] pnp: Device 00:0b activated.
[140534.656000] pnp: Device 00:0c activated.
[140534.896000] sd 0:0:0:0: [sda] Starting disk
[140534.928000] sd 1:0:0:0: [sdb] Starting disk
[140534.952000] Restarting tasks ... done.
[140535.200000] ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link [LNKH] -> GSI 11 (level, low) -> IRQ 11
[140535.200000] PCI: Setting latency timer of device 0000:00:1d.7 to 64
[140535.200000] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[140535.208000] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 1
[140535.208000] ehci_hcd 0000:00:1d.7: debug port 1
[140535.208000] PCI: cache line size of 32 is not supported by device 0000:00:1d.7
[140535.208000] ehci_hcd 0000:00:1d.7: irq 11, io mem 0xc0000000
[140535.212000] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[140535.224000] usb usb1: configuration #1 chosen from 1 choice
[140535.332000] hub 1-0:1.0: USB hub found
[140535.332000] hub 1-0:1.0: 6 ports detected
[140535.500000] USB Universal Host Controller Interface driver v3.0
[140535.508000] ACPI: PCI Interrupt 0000:00:1d.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
[140535.508000] PCI: Setting latency timer of device 0000:00:1d.0 to 64
[140535.508000] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[140535.512000] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[140535.512000] uhci_hcd 0000:00:1d.0: irq 11, io base 0x00001800
[140535.524000] usb usb2: configuration #1 chosen from 1 choice
[140535.596000] hub 2-0:1.0: USB hub found
[140535.596000] hub 2-0:1.0: 2 ports detected
[140535.700000] ACPI: PCI Interrupt 0000:00:1d.1[B] -> Link [LNKD] -> GSI 11 (level, low) -> IRQ 11
[140535.700000] PCI: Setting latency timer of device 0000:00:1d.1 to 64
[140535.700000] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[140535.700000] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
[140535.700000] uhci_hcd 0000:00:1d.1: irq 11, io base 0x00001820
[140535.704000] usb usb3: configuration #1 chosen from 1 choice
[140535.704000] hub 3-0:1.0: USB hub found
[140535.704000] hub 3-0:1.0: 2 ports detected
[140535.808000] ACPI: PCI Interrupt 0000:00:1d.2[C] -> Link [LNKC] -> GSI 11 (level, low) -> IRQ 11
[140535.808000] PCI: Setting latency timer of device 0000:00:1d.2 to 64
[140535.808000] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[140535.808000] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[140535.808000] uhci_hcd 0000:00:1d.2: irq 11, io base 0x00001840
[140535.936000] usb usb4: configuration #1 chosen from 1 choice
[140535.936000] hub 4-0:1.0: USB hub found
[140535.936000] hub 4-0:1.0: 2 ports detected
[140536.200000] usbcore: registered new interface driver hiddev
[140536.200000] usbcore: registered new interface driver usbhid
[140536.200000] /build/buildd/linux-source-2.6.22-2.6.22/drivers/hid/usbhid/hid-core.c: v2.6:USB HID core driver
[140536.268000] e1000: eth0: e1000_watchdog: NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX

-- 
Felix E. Klee
Jabber/Google Talk: feklee@jabber.org, SIP: 9779619@sipgate.de
ICQ: 158124695, Yahoo!: feklee, AIM: felix.klee@inka.de
Gizmo: felixklee, Skype: felix.klee