Re: 2.6.21 -- Lid button won't initiate suspend. Also, suspend didn't enter low-power state until I touched my mousepad.

From: Miles Lane
Date: Tue May 08 2007 - 12:33:59 EST


Sorry, Rafael. Resending since I forgot to "reply all" before.

On 5/8/07, Rafael J. Wysocki <rjw@xxxxxxx> wrote:
Hi,

On Tuesday, 8 May 2007 08:34, Miles Lane wrote:
> Firstly, all my attempts to get suspend to start by pressing my
> laptop's lid button are failing.
>
> Here's the log info from about eight attempts::

linux-acpi added to the CC list.

> [ 66.080135] ACPI: AC Adapter [ACAD] (on-line)
> [ 66.201949] ACPI: Battery Slot [BAT0] (battery present)
> [ 66.237885] input: Power Button (FF) as /class/input/input2
> [ 66.252274] ACPI: Power Button (FF) [PWRF]
> [ 66.267691] input: Lid Switch as /class/input/input3
> [ 66.280884] ACPI: Lid Switch [LID0]
> [ 66.296423] input: Sleep Button (CM) as /class/input/input4
> [ 66.309328] ACPI: Sleep Button (CM) [SLPB]
> [ 66.324930] No dock devices found.
> [ 66.369537] ACPI: CPU0 (power states: C1[C1] C2[C2] C3[C3])
> [ 66.386972] ACPI: Video Device [GFX0] (multi-head: yes rom: yes post: no)
> [ 50.683328] Time: acpi_pm clocksource has been installed.
> [ 53.729994] [ACPI Debug] String: [0x1C] "Temperature increasing: _Q80"
> [ 54.203327] eth0: link down
> [ 55.933327] PM: Adding info for No Bus:vcs7
> [ 55.933327] PM: Adding info for No Bus:vcsa7
> [ 56.396661] ppdev: user-space parallel port driver
> [ 56.449994] PM: Removing info for No Bus:vcs7
> [ 56.449994] PM: Removing info for No Bus:vcsa7
> [ 56.649994] PM: Adding info for No Bus:vcs7
> [ 56.649994] PM: Adding info for No Bus:vcsa7
> [ 57.483327] [drm] Initialized drm 1.1.0 20060810
> [ 57.533327] ACPI: PCI Interrupt 0000:00:02.0[A] -> GSI 16 (level,
> low) -> IRQ 18
> [ 57.539994] [drm] Initialized i915 1.6.0 20060119 on minor 0
> [ 57.543327] [drm] Initialized i915 1.6.0 20060119 on minor 1
> [ 60.216660] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
> [ 60.219993] PM: Adding info for No Bus:apm_bios
> [ 61.366660] Installing knfsd (copyright (C) 1996 okir@xxxxxxxxxxxx).
> [ 61.629993] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state
> recovery directory
> [ 61.689993] NFSD: starting 90-second grace period
> [ 62.766660] usb usb4: usb auto-resume
> [ 62.766660] usb usb4: finish resume
> [ 62.766660] hub 4-0:1.0: hub_resume
> [ 62.769993] usb usb4: wakeup_rh
> [ 62.806660] hub 4-0:1.0: state 7 ports 2 chg 0000 evt 0000
> [ 62.806660] usb usb3: usb auto-resume
> [ 62.806660] usb usb3: finish resume
> [ 62.806660] hub 3-0:1.0: hub_resume
> [ 62.806660] usb usb3: wakeup_rh
> [ 62.843327] hub 3-0:1.0: state 7 ports 2 chg 0000 evt 0000
> [ 62.843327] usb usb1: usb auto-resume
> [ 62.843327] usb usb1: finish resume
> [ 62.843327] hub 1-0:1.0: hub_resume
> [ 62.843327] usb usb1: wakeup_rh
> [ 62.879993] hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0000
> [ 62.879993] usb usb2: usb auto-resume
> [ 62.879993] usb usb2: finish resume
> [ 62.879993] hub 2-0:1.0: hub_resume
> [ 62.879993] ehci_hcd 0000:00:1d.7: resume root hub
> [ 62.913327] hub 2-0:1.0: state 7 ports 6 chg 0000 evt 0000
> [ 63.269993] Clocksource tsc unstable (delta = -166083281 ns)
> [ 63.796660] usb usb4: suspend_rh (auto-stop)
> [ 63.833326] usb usb3: suspend_rh (auto-stop)
> [ 63.869993] usb usb1: suspend_rh (auto-stop)
> [ 64.806660] hub 4-0:1.0: hub_suspend
> [ 64.806660] usb usb4: suspend_rh
> [ 64.806660] usb usb4: usb auto-suspend
> [ 64.843326] hub 3-0:1.0: hub_suspend
> [ 64.843326] usb usb3: suspend_rh
> [ 64.843326] usb usb3: usb auto-suspend
> [ 64.879993] hub 1-0:1.0: hub_suspend
> [ 64.879993] usb usb1: suspend_rh
> [ 64.879993] usb usb1: usb auto-suspend
> [ 64.913326] hub 2-0:1.0: hub_suspend
> [ 64.913326] ehci_hcd 0000:00:1d.7: suspend root hub
> [ 64.913326] usb usb2: usb auto-suspend
> [ 68.549993] usb usb4: usb auto-resume
> [ 68.549993] usb usb4: finish resume
> [ 68.553326] hub 4-0:1.0: hub_resume
> [ 68.553326] usb usb4: wakeup_rh
> [ 68.593326] hub 4-0:1.0: state 7 ports 2 chg 0000 evt 0000
> [ 68.593326] usb usb3: usb auto-resume
> [ 68.596659] usb usb3: finish resume
> [ 68.599993] hub 3-0:1.0: hub_resume
> [ 68.599993] usb usb3: wakeup_rh
> [ 68.626659] Bluetooth: Core ver 2.11
> [ 68.626659] PM: Adding info for platform:bluetooth
> [ 68.629993] NET: Registered protocol family 31
> [ 68.629993] Bluetooth: HCI device and connection manager initialized
> [ 68.629993] Bluetooth: HCI socket layer initialized
> [ 68.683326] hub 3-0:1.0: state 7 ports 2 chg 0000 evt 0000
> [ 68.686659] usb usb1: usb auto-resume
> [ 68.686659] usb usb1: finish resume
> [ 68.686659] hub 1-0:1.0: hub_resume
> [ 68.686659] usb usb1: wakeup_rh
> [ 68.723326] hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0000
> [ 68.723326] usb usb2: usb auto-resume
> [ 68.723326] usb usb2: finish resume
> [ 68.723326] hub 2-0:1.0: hub_resume
> [ 68.723326] ehci_hcd 0000:00:1d.7: resume root hub
> [ 68.746659] Bluetooth: L2CAP ver 2.8
> [ 68.746659] Bluetooth: L2CAP socket layer initialized
> [ 68.763326] hub 2-0:1.0: state 7 ports 6 chg 0000 evt 0000
> [ 68.826659] Bluetooth: RFCOMM socket layer initialized
> [ 68.826659] Bluetooth: RFCOMM TTY layer initialized
> [ 68.826659] Bluetooth: RFCOMM ver 1.8
> [ 69.583326] usb usb4: suspend_rh (auto-stop)
> [ 69.673326] usb usb3: suspend_rh (auto-stop)
> [ 69.713326] usb usb1: suspend_rh (auto-stop)
> [ 70.593326] hub 4-0:1.0: hub_suspend
> [ 70.593326] usb usb4: suspend_rh
> [ 70.593326] usb usb4: usb auto-suspend
> [ 70.686659] hub 3-0:1.0: hub_suspend
> [ 70.686659] usb usb3: suspend_rh
> [ 70.686659] usb usb3: usb auto-suspend
> [ 70.689992] [ACPI Debug] String: [0x1C] "Temperature increasing: _Q80"
> [ 73.759992] hub 1-0:1.0: hub_suspend
> [ 73.759992] usb usb1: suspend_rh
> [ 73.759992] usb usb1: usb auto-suspend
> [ 73.759992] hub 2-0:1.0: hub_suspend
> [ 73.759992] ehci_hcd 0000:00:1d.7: suspend root hub
> [ 73.759992] usb usb2: usb auto-suspend
> [ 89.083324] [ACPI Debug] String: [0x1C] "Temperature increasing: _Q80"
> [ 91.086657] [ACPI Debug] String: [0x1C] "Temperature increasing: _Q80"
> [ 93.116657] NET: Registered protocol family 10
>
> Then I tried initiating a suspend from the Gnome System menu. The
> suspend proceeded until the screen went black, but the disk stayed
> spun up and the LEDs on the keyboards stayed lit up. When I touched
> the synaptics mousepad, the system entered the low power state.

Did it wake up? Your log ends at the "early resume" stage.

Yes.

> On subsequent attempts to enter the suspend state, I didn't need to
> touch the mouse to get it to work.
>
> Here is the log information from the flawed suspend process. I don't
> see anything indicating the mousepad activity:

It probably only generated an interrupt that allowed the system to complete
the suspend.

Do you have CONFIG_NO_HZ set?

Yes.

> [ 540.193279] PM: Preparing system for mem sleep
> [ 540.203279] Stopping tasks ... done.
> [ 541.229945] Suspending console(s)
> [ 541.229945] platform bluetooth: suspend
> [ 541.229945] ac97 0-0:unknown codec: suspend
> [ 541.229945] iTCO_wdt iTCO_wdt: suspend
> [ 541.229945] usbdev4.1: PM: suspend 0->2, parent usb4 already 2
> [ 541.229945] usbdev4.1_ep81: PM: suspend 0->2, parent 4-0:1.0 already 2
> [ 541.229945] hub 4-0:1.0: PM: suspend 2-->2
> [ 541.229945] hub 4-0:1.0: PM: suspend 2->2, parent usb4 already 2
> [ 541.229945] usbdev4.1_ep00: PM: suspend 0->2, parent usb4 already 2
> [ 541.229945] usb usb4: PM: suspend 2-->2
> [ 541.229945] usbdev3.1: PM: suspend 0->2, parent usb3 already 2
> [ 541.229945] usbdev3.1_ep81: PM: suspend 0->2, parent 3-0:1.0 already 2
> [ 541.229945] hub 3-0:1.0: PM: suspend 2-->2
> [ 541.229945] hub 3-0:1.0: PM: suspend 2->2, parent usb3 already 2
> [ 541.229945] usbdev3.1_ep00: PM: suspend 0->2, parent usb3 already 2
> [ 541.229945] usb usb3: PM: suspend 2-->2
> [ 541.229945] usbdev2.1: PM: suspend 0->2, parent usb2 already 2
> [ 541.229945] usbdev2.1_ep81: PM: suspend 0->2, parent 2-0:1.0 already 2
> [ 541.229945] hub 2-0:1.0: PM: suspend 2-->2
> [ 541.229945] hub 2-0:1.0: PM: suspend 2->2, parent usb2 already 2
> [ 541.229945] usbdev2.1_ep00: PM: suspend 0->2, parent usb2 already 2
> [ 541.229945] usb usb2: PM: suspend 2-->2
> [ 541.229945] sr 1:0:0:0: suspend
> [ 541.229945] sd 0:0:0:0: suspend
> [ 542.726612] usbdev1.1: PM: suspend 0->2, parent usb1 already 2
> [ 542.726612] usbdev1.1_ep81: PM: suspend 0->2, parent 1-0:1.0 already 2
> [ 542.726612] hub 1-0:1.0: PM: suspend 2-->2
> [ 542.726612] hub 1-0:1.0: PM: suspend 2->2, parent usb1 already 2
> [ 542.726612] usbdev1.1_ep00: PM: suspend 0->2, parent usb1 already 2
> [ 542.726612] usb usb1: PM: suspend 2-->2
> [ 542.726612] psmouse serio1: suspend
> [ 543.093279] atkbd serio0: suspend
> [ 546.719945] i8042 i8042: suspend
> [ 546.719945] serial8250 serial8250: suspend
> [ 546.719945] vesafb vesafb.0: suspend
> [ 546.719945] platform pcspkr: suspend
> [ 546.719945] i8042 aux 00:06: suspend
> [ 546.719945] i8042 kbd 00:05: suspend
> [ 546.719945] system 00:04: suspend
> [ 546.719945] pnp 00:03: suspend
> [ 546.719945] pnp 00:02: suspend
> [ 546.719945] pnp 00:01: suspend, may wakeup
> [ 546.719945] pnp 00:00: suspend
> [ 546.719945] sdhci 0000:01:09.4: suspend
> [ 546.719945] sdhci [sdhci_suspend()]: Suspending...
> [ 546.719945] mmc0: clock 0Hz busmode 1 powermode 0 cs 0 Vdd 0 width 0 timing 0
> [ 546.719945] mmc1: clock 0Hz busmode 1 powermode 0 cs 0 Vdd 0 width 0 timing 0
> [ 546.719945] mmc2: clock 0Hz busmode 1 powermode 0 cs 0 Vdd 0 width 0 timing 0
> [ 546.719945] ACPI: PCI interrupt for device 0000:01:09.4 disabled
> [ 546.733278] tifm_7xx1 0000:01:09.3: suspend
> [ 546.733278] ACPI: PCI interrupt for device 0000:01:09.3 disabled
> [ 546.746611] ohci1394 0000:01:09.2: suspend
> [ 546.759945] yenta_cardbus 0000:01:09.0: suspend
> [ 546.759945] pci 0000:01:06.0: suspend
> [ 546.759945] pci 0000:01:00.0: suspend
> [ 546.759945] pci 0000:00:1f.6: suspend
> [ 546.759945] Intel ICH 0000:00:1f.5: suspend
> [ 546.759945] codec_write 0: semaphore is not ready for register 0x26
> [ 546.759945] ACPI: PCI interrupt for device 0000:00:1f.5 disabled
> [ 546.759945] pci 0000:00:1f.3: suspend
> [ 546.759945] ata_piix 0000:00:1f.1: suspend
> [ 546.759945] ACPI: PCI interrupt for device 0000:00:1f.1 disabled
> [ 546.759945] pci_set_power_state(): 0000:00:1f.1: state=3, current state=5
> [ 546.759945] pci 0000:00:1f.0: suspend
> [ 546.759945] pci 0000:00:1e.0: suspend
> [ 546.759945] ehci_hcd 0000:00:1d.7: suspend, may wakeup
> [ 546.759945] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
> [ 546.773278] ehci_hcd 0000:00:1d.7: --> PCI D3/wakeup
> [ 546.773278] uhci_hcd 0000:00:1d.2: suspend
> [ 546.773278] uhci_hcd 0000:00:1d.2: uhci_suspend
> [ 546.773278] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
> [ 546.773278] uhci_hcd 0000:00:1d.2: --> PCI D0/legacy
> [ 546.773278] uhci_hcd 0000:00:1d.1: suspend
> [ 546.773278] uhci_hcd 0000:00:1d.1: uhci_suspend
> [ 546.773278] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
> [ 546.773278] uhci_hcd 0000:00:1d.1: --> PCI D0/legacy
> [ 546.773278] uhci_hcd 0000:00:1d.0: suspend
> [ 546.773278] uhci_hcd 0000:00:1d.0: uhci_suspend
> [ 546.773278] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
> [ 546.773278] uhci_hcd 0000:00:1d.0: --> PCI D0/legacy
> [ 546.773278] pci 0000:00:02.1: suspend
> [ 546.773278] pci 0000:00:02.0: suspend
> [ 546.773278] pci 0000:00:00.3: suspend
> [ 546.773278] pci 0000:00:00.1: suspend
> [ 546.773278] agpgart-intel 0000:00:00.0: suspend
> [ 546.773278] acpi thermal:01: suspend
> [ 546.773278] acpi thermal:00: suspend
> [ 546.773278] acpi pnp0c14:00: suspend
> [ 546.773278] acpi device:18: suspend
> [ 546.773278] acpi device:17: suspend
> [ 546.773278] acpi device:16: suspend
> [ 546.773278] acpi device:15: suspend
> [ 546.773278] acpi device:14: suspend
> [ 546.773278] acpi device:13: suspend
> [ 546.773278] acpi device:12: suspend
> [ 546.773278] acpi device:11: suspend
> [ 546.773278] acpi device:10: suspend
> [ 546.773278] acpi device:0f: suspend
> [ 546.773278] acpi device:0e: suspend
> [ 546.773278] acpi device:0d: suspend
> [ 546.773278] acpi device:0c: suspend
> [ 546.773278] acpi device:0b: suspend
> [ 546.773278] acpi SYN0110:00: suspend
> [ 546.773278] acpi PNP0303:00: suspend
> [ 546.773278] ec PNP0C09:00: suspend
> [ 546.773278] acpi PNP0C02:00: suspend
> [ 546.773278] acpi PNP0200:00: suspend
> [ 546.773278] acpi PNP0C04:00: suspend
> [ 546.773278] acpi PNP0B00:00: suspend
> [ 546.773278] acpi PNP0000:00: suspend
> [ 546.773278] acpi PNP0100:00: suspend
> [ 546.773278] pci_link PNP0C0F:07: suspend
> [ 546.773278] pci_link PNP0C0F:06: suspend
> [ 546.773278] pci_link PNP0C0F:05: suspend
> [ 546.773278] pci_link PNP0C0F:04: suspend
> [ 546.773278] pci_link PNP0C0F:03: suspend
> [ 546.773278] pci_link PNP0C0F:02: suspend
> [ 546.773278] pci_link PNP0C0F:01: suspend
> [ 546.773278] pci_link PNP0C0F:00: suspend
> [ 546.773278] acpi device:0a: suspend
> [ 546.773278] acpi device:09: suspend
> [ 546.773278] acpi device:08: suspend
> [ 546.773278] acpi device:07: suspend
> [ 546.773278] acpi device:06: suspend
> [ 546.773278] acpi device:05: suspend
> [ 546.773278] acpi device:04: suspend
> [ 546.773278] acpi device:03: suspend
> [ 546.773278] acpi device:02: suspend
> [ 546.773278] video video:00: suspend
> [ 546.773278] acpi device:01: suspend
> [ 546.773278] pci_root PNP0A03:00: suspend
> [ 546.773278] button PNP0C0E:00: suspend
> [ 546.773278] button PNP0C0D:00: suspend
> [ 546.773278] battery PNP0C0A:00: suspend
> [ 546.773278] ac ACPI0003:00: suspend
> [ 546.773278] acpi device:00: suspend
> [ 546.773278] processor ACPI0007:00: suspend
> [ 546.773278] button button_power:00: suspend
> [ 546.773278] acpi acpi_system:00: suspend
> [ 546.773278] PM: Entering mem sleep
> [ 546.773278] platform bluetooth: LATE suspend
> [ 546.773278] iTCO_wdt iTCO_wdt: LATE suspend
> [ 546.773278] serial8250 serial8250: LATE suspend
> [ 546.773278] vesafb vesafb.0: LATE suspend
> [ 546.773278] platform pcspkr: LATE suspend
> [ 546.773278] sdhci 0000:01:09.4: LATE suspend
> [ 546.773278] tifm_7xx1 0000:01:09.3: LATE suspend
> [ 546.773278] ohci1394 0000:01:09.2: LATE suspend
> [ 546.773278] yenta_cardbus 0000:01:09.0: LATE suspend
> [ 546.773278] pci 0000:01:06.0: LATE suspend
> [ 546.773278] pci 0000:01:00.0: LATE suspend
> [ 546.773278] pci 0000:00:1f.6: LATE suspend
> [ 546.773278] Intel ICH 0000:00:1f.5: LATE suspend
> [ 546.773278] pci 0000:00:1f.3: LATE suspend
> [ 546.773278] pci 0000:00:1f.0: LATE suspend
> [ 546.773278] pci 0000:00:1e.0: LATE suspend
> [ 546.773278] pci 0000:00:02.1: LATE suspend
> [ 546.773278] pci 0000:00:02.0: LATE suspend
> [ 546.773278] pci 0000:00:00.3: LATE suspend
> [ 546.773278] pci 0000:00:00.1: LATE suspend
> [ 546.773278] agpgart-intel 0000:00:00.0: LATE suspend
> [ 546.773278] hwsleep-0323 [03] enter_sleep_state : Entering
> sleep state [S3]
> [ 546.773278] Intel machine check architecture supported.
> [ 546.773278] Intel machine check reporting enabled on CPU#0.
> [ 546.773278] Back to C!
> [ 546.773278] agpgart-intel 0000:00:00.0: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:00.0
> [ 546.773278] pci 0000:00:00.1: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:00.1
> [ 546.773278] pci 0000:00:00.3: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:00.3
> [ 546.773278] pci 0000:00:02.0: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:02.0
> [ 546.773278] pci 0000:00:02.1: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:02.1
> [ 546.773278] uhci_hcd 0000:00:1d.0: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1d.0
> [ 546.773278] uhci_hcd 0000:00:1d.1: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1d.1
> [ 546.773278] uhci_hcd 0000:00:1d.2: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1d.2
> [ 546.773278] ehci_hcd 0000:00:1d.7: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1d.7
> [ 546.773278] pci 0000:00:1e.0: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1e.0
> [ 546.773278] pci 0000:00:1f.0: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be541 for 0000:00:1f.0
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1f.0
> [ 546.773278] ata_piix 0000:00:1f.1: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1f.1
> [ 546.773278] pci 0000:00:1f.3: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1f.3
> [ 546.773278] Intel ICH 0000:00:1f.5: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1f.5
> [ 546.773278] pci 0000:00:1f.6: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:00:1f.6
> [ 546.773278] pci 0000:01:00.0: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:01:00.0
> [ 546.773278] pci 0000:01:06.0: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:01:06.0
> [ 546.773278] yenta_cardbus 0000:01:09.0: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:01:09.0
> [ 546.773278] ohci1394 0000:01:09.2: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:01:09.2
> [ 546.773278] tifm_7xx1 0000:01:09.3: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:01:09.3
> [ 546.773278] sdhci 0000:01:09.4: EARLY resume
> [ 546.773278] PCI: Calling quirk c01be2f7 for 0000:01:09.4
> [ 546.773278] platform pcspkr: EARLY resume
> [ 546.773278] vesafb vesafb.0: EARLY resume
> [ 546.773278] serial8250 serial8250: EARLY resume
-
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/