[PlanetCCRMA] Suspend/Resume not working with 2.6.29.5-1.rt21.1.fc10.ccrma.x86_64.rt

Thu Jul 2 18:59:38 PDT 2009

On Wed, 2009-07-01 at 19:53 -0500, S C Rigler wrote:
> On Thu, 2009-06-25 at 22:27 -0700, Fernando Lopez-Lezcano wrote: 
> > > It's really suspending.  The power light is blinking like it normally
> > > does when suspended.  Just when the laptop lid is opened or the power
> > > button pressed it tries to wake up (the screen comes on and a blinking
> > > cursor appears for a second) and then it resets itself.
> > 
> > Ok, I was going to post to lkml to see if they can spot something, but
> > maybe you could look a bit more /var/log/messages to see what the
> > context is for that BUG statement (that is, what is happening before and
> > after - is this while powering down, trying to power up, etc), that
> > could maybe help them... 
> > 
> > Thanks for the report!
> > -- Fernando
> 
> Amazingly enough, I was finally able to get some information logged by
> following some of the steps in basic-pm-debugging.txt. 

Thanks a lot! Good work! I'll try to find time asap to report to lkml...
-- Fernando


>  This accomplished by doing "echo 1 > /sys/power/pm_trace; echo core
> > /sys/power/pm_test; echo mem > /sys/power/state."  Some of the other
> test modes also created the exact same log message.  Here it is with
> some context:
> 
> Jul  1 19:32:35 localhost kernel: PM: Syncing filesystems ... done.
> Jul  1 19:32:36 localhost kernel: [drm:i915_get_vblank_counter]
> *ERROR* trying to get vblank count for disabled pipe 0
> Jul  1 19:32:45 localhost kernel: Freezing user space processes ...
> (elapsed 0.00 seconds) done.
> Jul  1 19:32:45 localhost kernel: Freezing remaining freezable
> tasks ... (elapsed 0.00 seconds) done.
> Jul  1 19:32:45 localhost kernel: Suspending console(s) (use
> no_console_suspend to debug)
> Jul  1 19:32:45 localhost kernel: sd 0:0:0:0: [sda] Synchronizing SCSI
> cache
> Jul  1 19:32:45 localhost kernel: sd 0:0:0:0: [sda] Stopping disk
> Jul  1 19:32:45 localhost kernel: sdhci-pci 0000:09:09.1: PME#
> disabled
> Jul  1 19:32:45 localhost kernel: sdhci-pci 0000:09:09.1: PCI INT B
> disabled
> Jul  1 19:32:45 localhost kernel: r8169 0000:08:00.0: PME# enabled
> Jul  1 19:32:45 localhost kernel: r8169 0000:08:00.0: wake-up
> capability enabled by ACPI
> Jul  1 19:32:45 localhost kernel: iwlagn 0000:02:00.0: PCI INT A
> disabled
> Jul  1 19:32:45 localhost kernel: ata_piix 0000:00:1f.1: PCI INT A
> disabled
> Jul  1 19:32:45 localhost kernel: ehci_hcd 0000:00:1d.7: PCI INT A
> disabled
> Jul  1 19:32:45 localhost kernel: ehci_hcd 0000:00:1d.7: PME# disabled
> Jul  1 19:32:45 localhost kernel: uhci_hcd 0000:00:1d.2: PCI INT C
> disabled
> Jul  1 19:32:45 localhost kernel: uhci_hcd 0000:00:1d.1: PCI INT B
> disabled
> Jul  1 19:32:45 localhost kernel: uhci_hcd 0000:00:1d.0: PCI INT A
> disabled
> Jul  1 19:32:45 localhost kernel: HDA Intel 0000:00:1b.0: PCI INT A
> disabled
> Jul  1 19:32:45 localhost kernel: ehci_hcd 0000:00:1a.7: PCI INT C
> disabled
> Jul  1 19:32:45 localhost kernel: ehci_hcd 0000:00:1a.7: PME# disabled
> Jul  1 19:32:45 localhost kernel: uhci_hcd 0000:00:1a.1: PCI INT B
> disabled
> Jul  1 19:32:45 localhost kernel: uhci_hcd 0000:00:1a.0: PCI INT A
> disabled
> Jul  1 19:32:45 localhost kernel: ACPI: Preparing to enter system
> sleep state S3
> Jul  1 19:32:45 localhost kernel: Disabling non-boot CPUs ...
> Jul  1 19:32:45 localhost kernel: Broke affinity for irq 9
> Jul  1 19:32:45 localhost kernel: Broke affinity for irq 12
> Jul  1 19:32:45 localhost kernel: Broke affinity for irq 27
> Jul  1 19:32:45 localhost kernel: CPU 1 is now offline
> Jul  1 19:32:45 localhost kernel: SMP alternatives: switching to UP
> code
> Jul  1 19:32:45 localhost kernel: CPU1 is down
> Jul  1 19:32:45 localhost kernel: ricoh-mmc: Suspending.
> Jul  1 19:32:45 localhost kernel: ricoh-mmc: Controller is now
> re-enabled.
> Jul  1 19:32:45 localhost kernel: BUG: sleeping function called from
> invalid context at kernel/rtmutex.c:685
> Jul  1 19:32:45 localhost kernel: in_atomic(): 0, irqs_disabled(): 1,
> pid: 4322, name: bash
> Jul  1 19:32:45 localhost kernel: Pid: 4322, comm: bash Not tainted
> 2.6.29.5-rt22 #1
> Jul  1 19:32:45 localhost kernel: Call Trace:
> Jul  1 19:32:45 localhost kernel: [<ffffffff8144b3ff>] ?
> rt_spin_lock_slowlock+0x0/0x27e
> Jul  1 19:32:45 localhost kernel: [<ffffffff81047e30>] __might_sleep
> +0x11d/0x133
> Jul  1 19:32:45 localhost kernel: [<ffffffff81087d17>]
> rt_spin_lock_fastlock+0x43/0xa2
> Jul  1 19:32:45 localhost kernel: [<ffffffff8144c0c5>] rt_spin_lock
> +0x23/0x39
> Jul  1 19:32:45 localhost kernel: [<ffffffff8101a6df>]
> read_persistent_clock+0x24/0x58
> Jul  1 19:32:45 localhost kernel: [<ffffffff811fd63d>] ?
> pci_pm_suspend_noirq+0x43/0xb5
> Jul  1 19:32:45 localhost kernel: [<ffffffff8107dccd>]
> timekeeping_suspend+0x1d/0xb2
> Jul  1 19:32:45 localhost kernel: [<ffffffff812a3260>] sysdev_suspend
> +0x98/0x1f1
> Jul  1 19:32:45 localhost kernel: [<ffffffff812aa564>] ?
> device_power_down+0x55/0x141
> Jul  1 19:32:45 localhost kernel: [<ffffffff8108ecc8>]
> suspend_devices_and_enter+0x111/0x1c9
> Jul  1 19:32:45 localhost kernel: [<ffffffff8108ef42>] enter_state
> +0x172/0x1f0
> Jul  1 19:32:45 localhost kernel: [<ffffffff8108f086>] state_store
> +0xc6/0xfd
> Jul  1 19:32:45 localhost kernel: [<ffffffff810fc5f2>] ?
> alloc_pages_current+0xcc/0xed
> Jul  1 19:32:45 localhost kernel: [<ffffffff811e56c5>] kobj_attr_store
> +0x2a/0x40
> Jul  1 19:32:45 localhost kernel: [<ffffffff8116aaa7>]
> sysfs_write_file+0xee/0x137
> Jul  1 19:32:45 localhost kernel: [<ffffffff8110ab1c>] ?
> rw_verify_area+0x97/0xd1
> Jul  1 19:32:45 localhost kernel: [<ffffffff8110b380>] vfs_write
> +0xbe/0x130
> Jul  1 19:32:45 localhost kernel: [<ffffffff8110b4e8>] sys_write
> +0x56/0x93
> Jul  1 19:32:45 localhost kernel: [<ffffffff81013102>]
> system_call_fastpath+0x16/0x1b
> Jul  1 19:32:45 localhost kernel: Extended CMOS year: 2000
> Jul  1 19:32:45 localhost kernel: suspend debug: Waiting for 5
> seconds.
> Jul  1 19:32:45 localhost kernel: Extended CMOS year: 2000
> Jul  1 19:32:45 localhost kernel: ricoh-mmc: Resuming.
> Jul  1 19:32:45 localhost kernel: ricoh-mmc: Controller is now
> disabled.
> Jul  1 19:32:45 localhost kernel: Enabling non-boot CPUs ...
> 
>