[The full log for this bug is at http://bugs.debian.org/677472]

On Thu, 2012-06-14 at 09:28 -0700, Octavio Alvarez wrote:
> Under normal circumstances the system simply does not suspend. It appears
> to go all the way to suspension (screen off, disks off, etc.) but when it
> appears that it is going to go off, something prevents it. System doesn't
> hang, it just fails at the very last moment of suspension.
>
> I tried debugging using pm_test. I set it to "core" but suspend_stats
> doesn't catch anything:
>
> # cat /sys/kernel/debug/suspend_stats
> success: 12
> fail: 0
> failed_freeze: 0
> failed_prepare: 0
> failed_suspend: 0
> failed_suspend_noirq: 0
> failed_resume: 0
> failed_resume_noirq: 0
> failures:
> last_failed_dev:
>
> last_failed_errno: 0
> 0
> last_failed_step:
>
> Even with pm_test set to "none", suspend_stats increases the "success"
> value.

This indicates that the system is woken up immediately after it
suspends. From the kernel point of view (but not a practical point of
view!) this is different from failing to suspend.

> As I said earlier, removing ohci_hcd lets suspend work again.

So the USB controller (OHCI) has for some reason started waking up the
system. As I suspected, this system has an Nvidia chipset:

00:0b.0 USB controller [0c03]: NVIDIA Corporation MCP51 USB Controller [10de:026d] (rev a2) (prog-if 10 [OHCI])
Subsystem: ASUSTeK Computer Inc. A8N-VM CSM Mainboard [1043:81bc]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0 (750ns min, 250ns max)
Interrupt: pin A routed to IRQ 23
Region 0: Memory at d5006000 (32-bit, non-prefetchable) [size=4K]
Capabilities: <access denied>
Kernel driver in use: ohci_hcd

The Nvidia implementation of OHCI is unusual in some ways and has
prompted a number of changes to power management. The last one was:

commit c61875977458637226ab093a35d200f2d5789787
Author: Alan Stern <stern@rowland.harvard.edu>
Date: Thu Nov 17 16:41:45 2011 -0500

OHCI: final fix for NVIDIA problems (I hope)

But it looks like we have to disappoint Alan again.

Ben.

> Also, I didn't find anything wrong in /var/log/pm-suspend.log. I may paste
> it in another message if you want because it's 4000 lines long.


--
Ben Hutchings
I say we take off; nuke the site from orbit. It's the only way to be sure.

On Sun, 24 Jun 2012, Ben Hutchings wrote:

> [The full log for this bug is at http://bugs.debian.org/677472]
>
> On Thu, 2012-06-14 at 09:28 -0700, Octavio Alvarez wrote:
> > Under normal circumstances the system simply does not suspend. It appears
> > to go all the way to suspension (screen off, disks off, etc.) but when it
> > appears that it is going to go off, something prevents it. System doesn't
> > hang, it just fails at the very last moment of suspension.
> >
> > I tried debugging using pm_test. I set it to "core" but suspend_stats
> > doesn't catch anything:
> >
> > # cat /sys/kernel/debug/suspend_stats
> > success: 12
> > fail: 0
> > failed_freeze: 0
> > failed_prepare: 0
> > failed_suspend: 0
> > failed_suspend_noirq: 0
> > failed_resume: 0
> > failed_resume_noirq: 0
> > failures:
> > last_failed_dev:
> >
> > last_failed_errno: 0
> > 0
> > last_failed_step:
> >
> > Even with pm_test set to "none", suspend_stats increases the "success"
> > value.
>
> This indicates that the system is woken up immediately after it
> suspends. From the kernel point of view (but not a practical point of
> view!) this is different from failing to suspend.
>
> > As I said earlier, removing ohci_hcd lets suspend work again.
>
> So the USB controller (OHCI) has for some reason started waking up the
> system. As I suspected, this system has an Nvidia chipset:
>
> 00:0b.0 USB controller [0c03]: NVIDIA Corporation MCP51 USB Controller [10de:026d] (rev a2) (prog-if 10 [OHCI])
> Subsystem: ASUSTeK Computer Inc. A8N-VM CSM Mainboard [1043:81bc]
> Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
> Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
> Latency: 0 (750ns min, 250ns max)
> Interrupt: pin A routed to IRQ 23
> Region 0: Memory at d5006000 (32-bit, non-prefetchable) [size=4K]
> Capabilities: <access denied>
> Kernel driver in use: ohci_hcd
>
> The Nvidia implementation of OHCI is unusual in some ways and has
> prompted a number of changes to power management. The last one was:
>
> commit c61875977458637226ab093a35d200f2d5789787
> Author: Alan Stern <stern@rowland.harvard.edu>
> Date: Thu Nov 17 16:41:45 2011 -0500
>
> OHCI: final fix for NVIDIA problems (I hope)

Actually that wasn't exactly a power management change, although it was
vaguely related. It really was more concerned with initialization and
shutdown.

> But it looks like we have to disappoint Alan again.

Well, this sounds like a different problem.

What happens if Octavio disables wakeup for that controller before
suspending?

echo disabled >/sys/bus/pci/devices/0000:00:0b.0/power/wakeup

Alan Stern




--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: Pine.LNX.4.44L0.1206251025280.1770-100000@iolanthe.rowland.org">http://lists.debian.org/Pine.LNX.4.44L0.1206251025280.1770-100000@iolanthe.rowland.org

On Mon, 25 Jun 2012 07:33:11 -0700, Alan Stern <stern@rowland.harvard.edu>
wrote:


What happens if Octavio disables wakeup for that controller before
suspending?

echo disabled >/sys/bus/pci/devices/0000:00:0b.0/power/wakeup


On kernel 3.2, it lets suspend work again.

For kernel 3.4, I'll break it into two parts: the going asleep and the
wakening back.

For the going asleep part, it works just like 3.2. It previously went
"almost" asleep, but with "echo disabled > wakeup" it suspends correctly.

For the wakening back part, with both settings the PC locks up requiring a
mechanical (power supply switch) power cycle to bring the computer back.
Not even the 5-sec power button cycle helps. I guess this is a different
bug, so I'll try to troubleshoot it and open a different one.


--
Octavio.



--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: http://lists.debian.org/op.wggy1mix6g6bxc@alvarezp-ws

On Mon, 25 Jun 2012, Octavio Alvarez wrote:

> On Mon, 25 Jun 2012 07:33:11 -0700, Alan Stern <stern@rowland.harvard.edu>
> wrote:
>
> > What happens if Octavio disables wakeup for that controller before
> > suspending?
> >
> > echo disabled >/sys/bus/pci/devices/0000:00:0b.0/power/wakeup
>
> On kernel 3.2, it lets suspend work again.

It might be worthwhile tracking down the reason for the immediate
wakeup. If you build a kernel with CONFIG_USB_DEBUG enabled, what
shows up in /sys/kernel/debug/usb/ohci/*/registers? And what shows up
in /sys/kernel/debug/usb/devices?

Also, what does the "lspci -vv" output show for the controller if you
run it with superuser permissions?

> For kernel 3.4, I'll break it into two parts: the going asleep and the
> wakening back.
>
> For the going asleep part, it works just like 3.2. It previously went
> "almost" asleep, but with "echo disabled > wakeup" it suspends correctly.
>
> For the wakening back part, with both settings the PC locks up requiring a
> mechanical (power supply switch) power cycle to bring the computer back.
> Not even the 5-sec power button cycle helps. I guess this is a different
> bug, so I'll try to troubleshoot it and open a different one.

And yet the PC doesn't lock up if you unbind ohci-hcd before
suspending?

Maybe you can do a git bisection to find what changed between 3.2 and
3.4 to cause this behavior.

Alan Stern




--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: Pine.LNX.4.44L0.1206251437040.1770-100000@iolanthe.rowland.org">http://lists.debian.org/Pine.LNX.4.44L0.1206251437040.1770-100000@iolanthe.rowland.org

Hi Octavio,

Quick instructions:

Alan Stern wrote:

> It might be worthwhile tracking down the reason for the immediate
> wakeup. If you build a kernel with CONFIG_USB_DEBUG enabled, what
> shows up in /sys/kernel/debug/usb/ohci/*/registers? And what shows up
> in /sys/kernel/debug/usb/devices?

0. prerequisites:

apt-get install git build-essential

1. get the kernel history, if you don't already have it:

git clone
git://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git

2. fetch point releases:

cd linux
git remote add stable
git://git.kernel.org/pub/scm/linux/kernel/git/stable/linux-stable.git
git fetch stable

3. configure, build:

git checkout stable/linux-3.2.y
cp /boot/config-$(uname -r) .config; # current configuration
scripts/config --disable DEBUG_INFO
make localmodconfig; # optional: minimize configuration
scripts/config --enable USB_DEBUG
make deb-pkg; # optionally with -j<num> for parallel build

4. test:

dpkg -i ../<name of package>; # as root
reboot
mount -t debugfs debugfs /sys/kernel/debug
grep .
/sys/kernel/debug/usb/ohci/*/registers
/sys/kernel/debug/usb/devices

> Also, what does the "lspci -vv" output show for the controller if you
> run it with superuser permissions?

This one's easier (no rebuild necessary).

>> For kernel 3.4, I'll break it into two parts: the going asleep and the
>> wakening back.
[...]
>> For the wakening back part, with both settings the PC locks up requiring a
>> mechanical (power supply switch) power cycle to bring the computer back.
>> Not even the 5-sec power button cycle helps. I guess this is a different
>> bug, so I'll try to troubleshoot it and open a different one.
[...]
> Maybe you can do a git bisection to find what changed between 3.2 and
> 3.4 to cause this behavior.

This works like so:

cd linux
git bisect start v3.4.1 stable/linux-3.2.y

# git checkouts out a version halfway between to test, so try it:
make deb-pkg; # maybe with -j4
dpkg -i ../<name of package>; # as root
reboot
... test test test ...

cd linux
git bisect bad; # if resume locks up
git bisect good; # if it resumes fine
git bisect skip; # if some other bug makes it hard to test

# git checks out a version halfway between to test, so:
make deb-pkg; # maybe with -j4
dpkg -i ../<name of package>; # as root
... and so on ...

After enough cycles it will find the "first bad commit". If you get
bored before then:

# if the gitk package is installed,
# to see the current regression range narrowing at any step
git bisect visualize

# to print a log of tests you've run so far, which lets someone
# else pick up where you left off
git bisect log

# to abandon the bisect
git bisect reset

See [1] for details.

Hope that helps,
Jonathan

[1] http://git-htmldocs.googlecode.com/git/git-bisect-lk2009.html



--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: http://lists.debian.org/20120628032731.GB3657@burratino

Hi, Alan!

So, after about more than a week of bisecting, and thanks to Jonathan
Nieder's

more-than-precise instructions, the results are in.

On Mon, 25 Jun 2012 11:41:31 -0700, Alan Stern <stern@rowland.harvard.edu>
wrote:



On Mon, 25 Jun 2012, Octavio Alvarez wrote:

On Mon, 25 Jun 2012 07:33:11 -0700, Alan Stern
<stern@rowland.harvard.edu>

wrote:

> What happens if Octavio disables wakeup for that controller before
> suspending?
>
> echo disabled >/sys/bus/pci/devices/0000:00:0b.0/power/wakeup

On kernel 3.2, it lets suspend work again.


If you build a kernel with CONFIG_USB_DEBUG enabled, what
shows up in /sys/kernel/debug/usb/ohci/*/registers?


[Sat Jul 07 12:49:27 -0700 -- alvarezp@octavio:/sys/kernel/debug/usb]
$ grep . ohci/*/registers
bus pci, device 0000:00:0b.0
OHCI Host Controller
ohci_hcd
OHCI 1.0, NO legacy support registers, rh state running
control 0x68f RWE RWC HCFS=operational IE PLE CBSR=3
cmdstatus 0x00000 SOC=0
intrstatus 0x00000024 FNO SF
intrenable 0x8000004a MIE RHSC RD WDH
ed_controlhead 2edac040
hcca frame 0x5fce
fmintvl 0xa7782edf FIT FSMPS=0xa778 FI=0x2edf
fmremaining 0x80002e53 FRT FR=0x2e53
periodicstart 0x2a2f
lsthresh 0x0628
hub poll timer off
roothub.a 01000208 POTPGT=1 NPS NDP=8(8)
roothub.b 00000000 PPCM=0000 DR=0000
roothub.status 00008000 DRWE
roothub.portstatus [0] 0x00000100 PPS
roothub.portstatus [1] 0x00000100 PPS
roothub.portstatus [2] 0x00000100 PPS
roothub.portstatus [3] 0x00000100 PPS
roothub.portstatus [4] 0x00000303 LSDA PPS PES CCS
roothub.portstatus [5] 0x00000303 LSDA PPS PES CCS
roothub.portstatus [6] 0x00000101 PPS CCS
roothub.portstatus [7] 0x00000100 PPS



And what shows up in /sys/kernel/debug/usb/devices?


[Sat Jul 07 12:49:54 -0700 -- alvarezp@octavio:/sys/kernel/debug/usb]
$ cat devices

T: Bus=02 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#= 1 Spd=12 MxCh= 8
B: Alloc= 29/900 us ( 3%), #Int= 3, #Iso= 0
D: Ver= 1.10 Cls=09(hub ) Sub=00 Prot=00 MxPS=64 #Cfgs= 1
P: Vendor=1d6b ProdID=0001 Rev= 3.03
S: Manufacturer=Linux 3.3.0+ ohci_hcd
S: Product=OHCI Host Controller
S: SerialNumber=0000:00:0b.0
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr= 0mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub ) Sub=00 Prot=00 Driver=hub
E: Ad=81(I) Atr=03(Int.) MxPS= 2 Ivl=255ms

T: Bus=02 Lev=01 Prnt=01 Port=04 Cnt=01 Dev#= 2 Spd=1.5 MxCh= 0
D: Ver= 2.00 Cls=00(>ifc ) Sub=00 Prot=00 MxPS= 8 #Cfgs= 1
P: Vendor=046d ProdID=c05a Rev=54.00
S: Manufacturer=Logitech
S: Product=USB Optical Mouse
C:* #Ifs= 1 Cfg#= 1 Atr=a0 MxPwr= 98mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=03(HID ) Sub=01 Prot=02 Driver=usbhid
E: Ad=81(I) Atr=03(Int.) MxPS= 6 Ivl=10ms

T: Bus=02 Lev=01 Prnt=01 Port=05 Cnt=02 Dev#= 3 Spd=1.5 MxCh= 0
D: Ver= 1.10 Cls=00(>ifc ) Sub=00 Prot=00 MxPS= 8 #Cfgs= 1
P: Vendor=046d ProdID=c31d Rev=66.00
S: Manufacturer=Logitech
S: Product=USB Keyboard
C:* #Ifs= 2 Cfg#= 1 Atr=a0 MxPwr= 90mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=03(HID ) Sub=01 Prot=01 Driver=usbhid
E: Ad=81(I) Atr=03(Int.) MxPS= 8 Ivl=10ms
I:* If#= 1 Alt= 0 #EPs= 1 Cls=03(HID ) Sub=00 Prot=00 Driver=usbhid
E: Ad=82(I) Atr=03(Int.) MxPS= 4 Ivl=255ms

T: Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#= 1 Spd=480 MxCh= 8
B: Alloc= 0/800 us ( 0%), #Int= 0, #Iso= 0
D: Ver= 2.00 Cls=09(hub ) Sub=00 Prot=00 MxPS=64 #Cfgs= 1
P: Vendor=1d6b ProdID=0002 Rev= 3.03
S: Manufacturer=Linux 3.3.0+ ehci_hcd
S: Product=EHCI Host Controller
S: SerialNumber=0000:00:0b.1
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr= 0mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub ) Sub=00 Prot=00 Driver=hub
E: Ad=81(I) Atr=03(Int.) MxPS= 4 Ivl=256ms



Also, what does the "lspci -vv" output show for the controller if you
run it with superuser permissions?


[Sat Jul 07 12:50:10 -0700 -- alvarezp@octavio:/sys/kernel/debug/usb]
$ sudo lspci -vv -s 0000:00:0b.1
00:0b.1 USB controller: NVIDIA Corporation MCP51 USB Controller (rev a2)
(prog-if 20 [EHCI])

Subsystem: ASUSTeK Computer Inc. A8N-VM CSM Mainboard
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort-
<MAbort- >SERR- <PERR- INTx-

Latency: 0 (750ns min, 250ns max)
Interrupt: pin B routed to IRQ 22
Region 0: Memory at d5007000 (32-bit, non-prefetchable) [size=256]
Capabilities: [44] Debug port: BAR=1 offset=0098
Capabilities: [80] Power Management version 2
Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=0mA
PME(D0+,D1+,D2+,D3hot+,D3cold+)

Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-
Kernel driver in use: ehci_hcd

I also bisected the "3.2 doesn't sleep due to ohci" problem and found this:

commit a6eeeb9f45b5a417f574f3bc799b7122270bf59b
Author: Alan Stern <stern@rowland.harvard.edu>
Date: Mon Sep 26 11:23:38 2011 -0400

USB: Update USB default wakeup settings

This patch (as1486) implements the kernel's new wakeup policy for USB
host controllers. Since they don't generate wakeup requests on their
but merely forward requests from their root hubs toward the CPU, they
should be enabled for wakeup by default.

Also, to be compliant with both the old and new policies, root hubs
should not be enabled for remote wakeup by default. Userspace must
enable it explicitly if it is desired.

Signed-off-by: Alan Stern <stern@rowland.harvard.edu>
Signed-off-by: Greg Kroah-Hartman <gregkh@suse.de>


For the [3.4] wakening back part, with both settings the PC locks up
requiring a

mechanical (power supply switch) power cycle to bring the computer back.
Not even the 5-sec power button cycle helps. I guess this is a different
bug, so I'll try to troubleshoot it and open a different one.


And yet the PC doesn't lock up if you unbind ohci-hcd before
suspending?


It suspends but it locks-up while waking up.


Maybe you can do a git bisection to find what changed between 3.2 and
3.4 to cause this behavior.


commit 2feec47d4c5f80b05f1650f5a24865718978eea4
Author: Bob Moore <robert.moore@intel.com>
Date: Tue Feb 14 15:00:53 2012 +0800

ACPICA: ACPI 5: Support for new FADT SleepStatus, SleepControl
registers


Adds sleep and wake support for systems with these registers.
One new file, hwxfsleep.c

Signed-off-by: Bob Moore <robert.moore@intel.com>
Signed-off-by: Len Brown <len.brown@intel.com>




--
Octavio.



--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: http://lists.debian.org/op.wg3en6ij6g6bxc@localhost.localdomain

On Sat, 7 Jul 2012, Octavio Alvarez wrote:

> > If you build a kernel with CONFIG_USB_DEBUG enabled, what
> > shows up in /sys/kernel/debug/usb/ohci/*/registers?
>
> [Sat Jul 07 12:49:27 -0700 -- alvarezp@octavio:/sys/kernel/debug/usb]
> $ grep . ohci/*/registers
> bus pci, device 0000:00:0b.0
> OHCI Host Controller
> ohci_hcd
> OHCI 1.0, NO legacy support registers, rh state running
> control 0x68f RWE RWC HCFS=operational IE PLE CBSR=3
> cmdstatus 0x00000 SOC=0
> intrstatus 0x00000024 FNO SF
> intrenable 0x8000004a MIE RHSC RD WDH
> ed_controlhead 2edac040
> hcca frame 0x5fce
> fmintvl 0xa7782edf FIT FSMPS=0xa778 FI=0x2edf
> fmremaining 0x80002e53 FRT FR=0x2e53
> periodicstart 0x2a2f
> lsthresh 0x0628
> hub poll timer off
> roothub.a 01000208 POTPGT=1 NPS NDP=8(8)
> roothub.b 00000000 PPCM=0000 DR=0000
> roothub.status 00008000 DRWE
> roothub.portstatus [0] 0x00000100 PPS
> roothub.portstatus [1] 0x00000100 PPS
> roothub.portstatus [2] 0x00000100 PPS
> roothub.portstatus [3] 0x00000100 PPS
> roothub.portstatus [4] 0x00000303 LSDA PPS PES CCS
> roothub.portstatus [5] 0x00000303 LSDA PPS PES CCS
> roothub.portstatus [6] 0x00000101 PPS CCS
> roothub.portstatus [7] 0x00000100 PPS

That's normal, except for the status of port 6 (which actually is port
7, since we normally count ports starting from 1). The port shows
Current Connect Status, so something is connected to it -- but what?

Can you post a complete dmesg log showing bootup with CONFIG_USB_DEBUG
enabled?

> > And what shows up in /sys/kernel/debug/usb/devices?
>
> [Sat Jul 07 12:49:54 -0700 -- alvarezp@octavio:/sys/kernel/debug/usb]
> $ cat devices
...

Pretty much normal.

> > Also, what does the "lspci -vv" output show for the controller if you
> > run it with superuser permissions?
>
> [Sat Jul 07 12:50:10 -0700 -- alvarezp@octavio:/sys/kernel/debug/usb]
> $ sudo lspci -vv -s 0000:00:0b.1

0b.1 is the EHCI controller. We want to see the OHCI controller, 0b.0.

> I also bisected the "3.2 doesn't sleep due to ohci" problem and found this:
>
> commit a6eeeb9f45b5a417f574f3bc799b7122270bf59b
> Author: Alan Stern <stern@rowland.harvard.edu>
> Date: Mon Sep 26 11:23:38 2011 -0400
>
> USB: Update USB default wakeup settings

Yes, that commit enables wakeup for USB host controllers by default.
Before that, you had to enable wakeup by hand. The question is: Why
does the controller think it needs to wake up the system?

Can you also post a dmesg log showing a full suspend/immediate-resume
cycle with CONFIG_USB_DEBUG enabled?

> > And yet the PC doesn't lock up if you unbind ohci-hcd before
> > suspending?
>
> It suspends but it locks-up while waking up.
>
> > Maybe you can do a git bisection to find what changed between 3.2 and
> > 3.4 to cause this behavior.
>
> commit 2feec47d4c5f80b05f1650f5a24865718978eea4
> Author: Bob Moore <robert.moore@intel.com>
> Date: Tue Feb 14 15:00:53 2012 +0800
>
> ACPICA: ACPI 5: Support for new FADT SleepStatus, SleepControl
> registers
>
> Adds sleep and wake support for systems with these registers.
> One new file, hwxfsleep.c
>
> Signed-off-by: Bob Moore <robert.moore@intel.com>
> Signed-off-by: Len Brown <len.brown@intel.com>

Yes, okay, that is indeed totally separate. You should bring that
issue up with Bob Moore and Len Brown on the linux-acpi mailing list.

Alan Stern




--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: Pine.LNX.4.44L0.1207072208270.508-100000@netrider.rowland.org">http://lists.debian.org/Pine.LNX.4.44L0.1207072208270.508-100000@netrider.rowland.org

Hi,

Quick administrivia.

Alan Stern wrote:

> Yes, that commit enables wakeup for USB host controllers by default.
> Before that, you had to enable wakeup by hand. The question is: Why
> does the controller think it needs to wake up the system?

Yotam Benshalom from https://bugzilla.kernel.org/show_bug.cgi?id=43081
(cc-ed) is experiencing the same symptoms (Nvidia MCP79 OHCI
controller producing immediate wakeups when he tries to resume,
bisects to a6eeeb9f45b5).

> On Sat, 7 Jul 2012, Octavio Alvarez wrote:

>> commit 2feec47d4c5f80b05f1650f5a24865718978eea4
>> Author: Bob Moore <robert.moore@intel.com>
>> Date: Tue Feb 14 15:00:53 2012 +0800
>>
>> ACPICA: ACPI 5: Support for new FADT SleepStatus, SleepControl
>> registers
[...]
> Yes, okay, that is indeed totally separate. You should bring that
> issue up with Bob Moore and Len Brown on the linux-acpi mailing list.

The Debian bug for this one is <http://bugs.debian.org/680707>.
Please cc me or 680707@bugs.debian.org if bringing it up with ACPI
folks so we can track the discussion.

Thanks again for your hard work.

Ciao,
Jonathan



--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: http://lists.debian.org/20120708025730.GE2961@burratino

On Sat, 07 Jul 2012 19:23:08 -0700, Alan Stern <stern@rowland.harvard.edu>
wrote:



> Also, what does the "lspci -vv" output show for the controller if you
> run it with superuser permissions?

[Sat Jul 07 12:50:10 -0700 -- alvarezp@octavio:/sys/kernel/debug/usb]
$ sudo lspci -vv -s 0000:00:0b.1


0b.1 is the EHCI controller. We want to see the OHCI controller, 0b.0.


Sorry about that.

[Sat Jul 07 20:41:28 -0700 -- alvarezp@octavio:~]
$ sudo lspci -vv -s 0000:00:0b.0
00:0b.0 USB controller: NVIDIA Corporation MCP51 USB Controller (rev a2)
(prog-if 10 [OHCI])

Subsystem: ASUSTeK Computer Inc. A8N-VM CSM Mainboard
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort-
<MAbort- >SERR- <PERR- INTx-

Latency: 0 (750ns min, 250ns max)
Interrupt: pin A routed to IRQ 20
Region 0: Memory at d5006000 (32-bit, non-prefetchable) [size=4K]
Capabilities: [44] Power Management version 2
Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=0mA
PME(D0+,D1+,D2+,D3hot+,D3cold+)

Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-
Kernel driver in use: ohci_hcd


I also bisected the "3.2 doesn't sleep due to ohci" problem and found
this:


commit a6eeeb9f45b5a417f574f3bc799b7122270bf59b
Author: Alan Stern <stern@rowland.harvard.edu>
Date: Mon Sep 26 11:23:38 2011 -0400

USB: Update USB default wakeup settings


Yes, that commit enables wakeup for USB host controllers by default.
Before that, you had to enable wakeup by hand. The question is: Why
does the controller think it needs to wake up the system?

Can you also post a dmesg log showing a full suspend/immediate-resume
cycle with CONFIG_USB_DEBUG enabled?


Will do as soon as I reboot into a suitable kernel.

Thanks for your help.

--
Octavio.



--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: http://lists.debian.org/op.wg3zgrst6g6bxc@localhost.localdomain

On Sat, 07 Jul 2012 19:23:08 -0700, Alan Stern <stern@rowland.harvard.edu>
wrote:



roothub.portstatus [4] 0x00000303 LSDA PPS PES CCS
roothub.portstatus [5] 0x00000303 LSDA PPS PES CCS
roothub.portstatus [6] 0x00000101 PPS CCS


That's normal, except for the status of port 6 (which actually is port
7, since we normally count ports starting from 1). The port shows
Current Connect Status, so something is connected to it -- but what?


It looks like it was my pendrive. Disconnecting the mouse and keyboard
cleared CCS on [4] and [5] (not necesariliy in that order).

Removing my pen drive cleared CCS on [6].

This is ohci/*/registers without my pendrive.

[Sat Jul 07 23:24:21 -0700 -- alvarezp@octavio:/sys/kernel/debug/usb]
$ cat ohci/0000:00:0b.0/registers
bus pci, device 0000:00:0b.0
OHCI Host Controller
ohci_hcd
OHCI 1.0, NO legacy support registers, rh state running
control 0x68f RWE RWC HCFS=operational IE PLE CBSR=3
cmdstatus 0x00000 SOC=0
intrstatus 0x00000024 FNO SF
intrenable 0x8000004a MIE RHSC RD WDH
ed_controlhead 2ed43000
hcca frame 0x438b
fmintvl 0x27782edf FSMPS=0x2778 FI=0x2edf
fmremaining 0x00000819 FR=0x0819
periodicstart 0x2a2f
lsthresh 0x0628
hub poll timer off
roothub.a 01000208 POTPGT=1 NPS NDP=8(8)
roothub.b 00000000 PPCM=0000 DR=0000
roothub.status 00008000 DRWE
roothub.portstatus [0] 0x00000100 PPS
roothub.portstatus [1] 0x00000100 PPS
roothub.portstatus [2] 0x00000100 PPS
roothub.portstatus [3] 0x00000100 PPS
roothub.portstatus [4] 0x00000303 LSDA PPS PES CCS
roothub.portstatus [5] 0x00000303 LSDA PPS PES CCS
roothub.portstatus [6] 0x00000100 PPS
roothub.portstatus [7] 0x00000100 PPS

FYI, bisecting and other testing was done pretty much without my
pendrive all the time.

I just tested suspend, just for the sake of it, and it still
wakes up right after sleep.


Can you post a complete dmesg log showing bootup with CONFIG_USB_DEBUG
enabled?


Sure. This is it, up to second 178 (last msg is in 81), before pushing the
suspend button.

(done without my pen drive)

[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 3.3.0+ (alvarezp@octavio) (gcc version 4.7.1
(Debian 4.7.1-2) ) #38 SMP Sat Jul 7 11:10:13 PDT 2012

[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 000000000009f000 (usable)
[ 0.000000] BIOS-e820: 000000000009f000 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 00000000bfef0000 (usable)
[ 0.000000] BIOS-e820: 00000000bfef0000 - 00000000bfef3000 (ACPI NVS)
[ 0.000000] BIOS-e820: 00000000bfef3000 - 00000000bff00000 (ACPI data)
[ 0.000000] BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
[ 0.000000] BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] DMI 2.4 present.
[ 0.000000] DMI: System manufacturer System Product Name/P5NSLI, BIOS
ASUS P5NSLI ACPI BIOS Revision 1801 11/18/2008
[ 0.000000] e820 update range: 0000000000000000 - 0000000000010000
(usable) ==> (reserved)
[ 0.000000] e820 remove range: 00000000000a0000 - 0000000000100000
(usable)

[ 0.000000] last_pfn = 0xbfef0 max_arch_pfn = 0x1000000
[ 0.000000] MTRR default type: uncachable
[ 0.000000] MTRR fixed ranges enabled:
[ 0.000000] 00000-9FFFF write-back
[ 0.000000] A0000-BFFFF uncachable
[ 0.000000] C0000-CDFFF write-protect
[ 0.000000] CE000-FFFFF uncachable
[ 0.000000] MTRR variable ranges enabled:
[ 0.000000] 0 base 000000000 mask F80000000 write-back
[ 0.000000] 1 base 080000000 mask FC0000000 write-back
[ 0.000000] 2 base 0BFF00000 mask FFFF00000 uncachable
[ 0.000000] 3 disabled
[ 0.000000] 4 disabled
[ 0.000000] 5 disabled
[ 0.000000] 6 disabled
[ 0.000000] 7 disabled
[ 0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new
0x7010600070106

[ 0.000000] found SMP MP-table at [c00f5d50] f5d50
[ 0.000000] initial memory mapped : 0 - 01a00000
[ 0.000000] Base memory trampoline at [c009b000] 9b000 size 16384
[ 0.000000] init_memory_mapping: 0000000000000000-000000002f1fe000
[ 0.000000] 0000000000 - 0000200000 page 4k
[ 0.000000] 0000200000 - 002f000000 page 2M
[ 0.000000] 002f000000 - 002f1fe000 page 4k
[ 0.000000] kernel direct mapping tables up to 2f1fe000 @
19fa000-1a00000

[ 0.000000] RAMDISK: 36c0a000 - 375fd000
[ 0.000000] Allocated new RAMDISK: 2e80b000 - 2f1fd777
[ 0.000000] Move RAMDISK from 0000000036c0a000 - 00000000375fc776 to
2e80b000 - 2f1fd776

[ 0.000000] ACPI: RSDP 000f7900 00024 (v02 Nvidia)
[ 0.000000] ACPI: XSDT bfef30c0 0004C (v01 Nvidia ASUSACPI 42302E31
AWRD 00000000)
[ 0.000000] ACPI: FACP bfefa600 000F4 (v03 Nvidia ASUSACPI 42302E31
AWRD 00000000)
[ 0.000000] ACPI: DSDT bfef3240 07354 (v01 NVIDIA ASUSACPI 00001000
MSFT 03000000)

[ 0.000000] ACPI: FACS bfef0000 00040
[ 0.000000] ACPI: HPET bfefa840 00038 (v01 Nvidia ASUSACPI 42302E31
AWRD 00000098)
[ 0.000000] ACPI: MCFG bfefa8c0 0003C (v01 Nvidia ASUSACPI 42302E31
AWRD 00000000)
[ 0.000000] ACPI: APIC bfefa740 0008E (v01 Nvidia ASUSACPI 42302E31
AWRD 00000000)
[ 0.000000] ACPI: SSDT bfefae20 0032C (v01 Nvidia ASUSACPI 42302E31
AWRD 00000000)

[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] 2316MB HIGHMEM available.
[ 0.000000] 753MB LOWMEM available.
[ 0.000000] mapped low ram: 0 - 2f1fe000
[ 0.000000] low ram: 0 - 2f1fe000
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0x00000010 -> 0x00001000
[ 0.000000] Normal 0x00001000 -> 0x0002f1fe
[ 0.000000] HighMem 0x0002f1fe -> 0x000bfef0
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] Early memory PFN ranges
[ 0.000000] 0: 0x00000010 -> 0x0000009f
[ 0.000000] 0: 0x00000100 -> 0x000bfef0
[ 0.000000] On node 0 totalpages: 786047
[ 0.000000] free_area_init_node: node 0, pgdat c1425380, node_mem_map
ed00a200

[ 0.000000] DMA zone: 32 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 3951 pages, LIFO batch:0
[ 0.000000] Normal zone: 1476 pages used for memmap
[ 0.000000] Normal zone: 187450 pages, LIFO batch:31
[ 0.000000] HighMem zone: 4634 pages used for memmap
[ 0.000000] HighMem zone: 588504 pages, LIFO batch:31
[ 0.000000] Using APIC driver default
[ 0.000000] ACPI: PM-Timer IO Port: 0x4008
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] disabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] disabled)
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1])
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1])
[ 0.000000] ACPI: IOAPIC (id[0x04] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 4, version 17, address 0xfec00000, GSI
0-23

[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 14 global_irq 14 high edge)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 15 global_irq 15 high edge)
[ 0.000000] ACPI: IRQ9 used by override.
[ 0.000000] ACPI: IRQ14 used by override.
[ 0.000000] ACPI: IRQ15 used by override.
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] ACPI: HPET id: 0x10de8201 base: 0xfeff0000
[ 0.000000] SMP: Allowing 4 CPUs, 2 hotplug CPUs
[ 0.000000] nr_irqs_gsi: 40
[ 0.000000] PM: Registered nosave memory: 000000000009f000 -
00000000000a0000
[ 0.000000] PM: Registered nosave memory: 00000000000a0000 -
00000000000f0000
[ 0.000000] PM: Registered nosave memory: 00000000000f0000 -
0000000000100000
[ 0.000000] Allocating PCI resources starting at bff00000 (gap:
bff00000:20100000)

[ 0.000000] Booting paravirtualized kernel on bare hardware
[ 0.000000] setup_percpu: NR_CPUS:32 nr_cpumask_bits:32 nr_cpu_ids:4
nr_node_ids:1
[ 0.000000] PERCPU: Embedded 13 pages/cpu @ecc00000 s32384 r0 d20864
u524288

[ 0.000000] pcpu-alloc: s32384 r0 d20864 u524288 alloc=1*2097152
[ 0.000000] pcpu-alloc: [0] 0 1 2 3
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on.
Total pages: 779905
[ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-3.3.0+
root=UUID=f8b60c34-b27c-4517-be16-efe785260da8 ro quiet splash vmalloc=256M

[ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288
bytes)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144
bytes)

[ 0.000000] Initializing CPU#0
[ 0.000000] allocated 6289152 bytes of page_cgroup
[ 0.000000] please try 'cgroup_disable=memory' option if you don't want
memory cgroups

[ 0.000000] Initializing HighMem for node 0 (0002f1fe:000bfef0)
[ 0.000000] Memory: 3096900k/3144640k available (2911k kernel code,
47288k reserved, 1364k data, 408k init, 2372552k highmem)

[ 0.000000] virtual kernel memory layout:
[ 0.000000] fixmap : 0xffd36000 - 0xfffff000 (2852 kB)
[ 0.000000] pkmap : 0xffa00000 - 0xffc00000 (2048 kB)
[ 0.000000] vmalloc : 0xef9fe000 - 0xff9fe000 ( 256 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xef1fe000 ( 753 MB)
[ 0.000000] .init : 0xc142d000 - 0xc1493000 ( 408 kB)
[ 0.000000] .data : 0xc12d7e9a - 0xc142cf80 (1364 kB)
[ 0.000000] .text : 0xc1000000 - 0xc12d7e9a (2911 kB)
[ 0.000000] Checking if this processor honours the WP bit even in
supervisor mode...Ok.

[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] RCU dyntick-idle grace-period acceleration is enabled.
[ 0.000000] NR_IRQS:2304 nr_irqs:712 16
[ 0.000000] CPU 0 irqstacks, hard=ec006000 soft=ec008000
[ 0.000000] Extended CMOS year: 2000
[ 0.000000] spurious 8259A interrupt: IRQ7.
[ 0.000000] Console: colour VGA+ 80x25
[ 0.000000] console [tty0] enabled
[ 0.000000] hpet clockevent registered
[ 0.000000] Fast TSC calibration using PIT
[ 0.000000] Detected 1799.875 MHz processor.
[ 0.004002] Calibrating delay loop (skipped), value calculated using
timer frequency.. 3599.75 BogoMIPS (lpj=7199500)

[ 0.004007] pid_max: default: 32768 minimum: 301
[ 0.004049] Security Framework initialized
[ 0.004053] AppArmor: AppArmor disabled by boot time parameter
[ 0.004072] Mount-cache hash table entries: 512
[ 0.004202] Initializing cgroup subsys cpuacct
[ 0.004205] Initializing cgroup subsys memory
[ 0.004215] Initializing cgroup subsys devices
[ 0.004217] Initializing cgroup subsys freezer
[ 0.004220] Initializing cgroup subsys net_cls
[ 0.004222] Initializing cgroup subsys blkio
[ 0.004229] Initializing cgroup subsys perf_event
[ 0.004260] CPU: Physical Processor ID: 0
[ 0.004262] CPU: Processor Core ID: 0
[ 0.004265] mce: CPU supports 6 MCE banks
[ 0.004274] CPU0: Thermal monitoring enabled (TM2)
[ 0.004277] using mwait in idle threads.
[ 0.004762] ACPI: Core revision 20120111
[ 0.012054] Enabling APIC mode: Flat. Using 1 I/O APICs
[ 0.012567] ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1
[ 0.020001] ..MP-BIOS bug: 8254 timer not connected to IO-APIC
[ 0.020001] ...trying to set up timer (IRQ0) through the 8259A ...
[ 0.020001] ..... (found apic 0 pin 0) ...
[ 0.062361] ....... works.
[ 0.062363] CPU0: Intel(R) Core(TM)2 CPU 4300 @ 1.80GHz
stepping 02
[ 0.064003] Performance Events: PEBS fmt0-, Core2 events, Intel PMU
driver.

[ 0.064003] PEBS disabled due to CPU errata.
[ 0.064003] ... version: 2
[ 0.064003] ... bit width: 40
[ 0.064003] ... generic registers: 2
[ 0.064003] ... value mask: 000000ffffffffff
[ 0.064003] ... max period: 000000007fffffff
[ 0.064003] ... fixed-purpose events: 3
[ 0.064003] ... event mask: 0000000700000003
[ 0.064003] NMI watchdog enabled, takes one hw-pmu counter.
[ 0.064003] CPU 1 irqstacks, hard=ec0d4000 soft=ec0d6000
[ 0.064003] Booting Node 0, Processors #1
[ 0.064003] smpboot cpu 1: start_ip = 9b000
[ 0.008000] Initializing CPU#1
[ 0.072050] NMI watchdog enabled, takes one hw-pmu counter.
[ 0.072093] Brought up 2 CPUs
[ 0.072096] Total of 2 processors activated (7199.50 BogoMIPS).
[ 0.076098] devtmpfs: initialized
[ 0.076208] PM: Registering ACPI NVS region at bfef0000 (12288 bytes)
[ 0.076230] print_constraints: dummy:
[ 0.076298] NET: Registered protocol family 16
[ 0.076419] ACPI: bus type pci registered
[ 0.076499] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem
0xe0000000-0xefffffff] (base 0xe0000000)
[ 0.076503] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in
E820

[ 0.076505] PCI: Using MMCONFIG for extended config space
[ 0.076508] PCI: Using configuration type 1 for base access
[ 0.077096] bio: create slab <bio-0> at 0
[ 0.077096] ACPI: Added _OSI(Module Device)
[ 0.077096] ACPI: Added _OSI(Processor Device)
[ 0.077096] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.077096] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.080473] ACPI: EC: Look up EC in DSDT
[ 0.087264] ACPI: SSDT bfefa940 00175 (v01 Nvidia ASUSACPI 42302E31
AWRD 00000000)

[ 0.087654] ACPI: Dynamic OEM Table Load:
[ 0.087658] ACPI: SSDT (null) 00175 (v01 Nvidia ASUSACPI 42302E31
AWRD 00000000)
[ 0.087828] ACPI: SSDT bfefad50 000CE (v01 Nvidia ASUSACPI 42302E31
AWRD 00000000)

[ 0.088219] ACPI: Dynamic OEM Table Load:
[ 0.088223] ACPI: SSDT (null) 000CE (v01 Nvidia ASUSACPI 42302E31
AWRD 00000000)

[ 0.088604] ACPI: Interpreter enabled
[ 0.088621] ACPI: (supports S0 S1 S3 S4 S5)
[ 0.088648] ACPI: Using IOAPIC for interrupt routing
[ 0.096193] ACPI: No dock devices found.
[ 0.096202] PCI: Using host bridge windows from ACPI; if necessary, use
"pci=nocrs" and report a bug

[ 0.096265] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.096349] pci_root PNP0A08:00: host bridge window [io 0x0000-0x0cf7]
[ 0.096352] pci_root PNP0A08:00: host bridge window [io 0x0d00-0xffff]
[ 0.096356] pci_root PNP0A08:00: host bridge window [mem
0x000a0000-0x000bffff]
[ 0.096359] pci_root PNP0A08:00: host bridge window [mem
0x000c0000-0x000dffff]
[ 0.096363] pci_root PNP0A08:00: host bridge window [mem
0xbff00000-0xfebfffff]

[ 0.096408] PCI host bridge to bus 0000:00
[ 0.096411] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7]
[ 0.096414] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff]
[ 0.096417] pci_bus 0000:00: root bus resource [mem
0x000a0000-0x000bffff]
[ 0.096420] pci_bus 0000:00: root bus resource [mem
0x000c0000-0x000dffff]
[ 0.096423] pci_bus 0000:00: root bus resource [mem
0xbff00000-0xfebfffff]

[ 0.096436] pci 0000:00:00.0: [10de:0071] type 0 class 0x000600
[ 0.096491] pci 0000:00:00.1: [10de:007f] type 0 class 0x000500
[ 0.096542] pci 0000:00:00.2: [10de:0075] type 0 class 0x000500
[ 0.096602] pci 0000:00:00.3: [10de:006f] type 0 class 0x000500
[ 0.096662] pci 0000:00:00.4: [10de:00b4] type 0 class 0x000500
[ 0.096727] pci 0000:00:01.0: [10de:0076] type 0 class 0x000500
[ 0.096777] pci 0000:00:01.1: [10de:0078] type 0 class 0x000500
[ 0.096828] pci 0000:00:01.2: [10de:0079] type 0 class 0x000500
[ 0.096878] pci 0000:00:01.3: [10de:007a] type 0 class 0x000500
[ 0.096929] pci 0000:00:01.4: [10de:007b] type 0 class 0x000500
[ 0.096979] pci 0000:00:01.5: [10de:007c] type 0 class 0x000500
[ 0.097030] pci 0000:00:01.6: [10de:007d] type 0 class 0x000500
[ 0.097087] pci 0000:00:02.0: [10de:007e] type 1 class 0x000604
[ 0.097136] pci 0000:00:02.0: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.097159] pci 0000:00:04.0: [10de:007e] type 1 class 0x000604
[ 0.097207] pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.097227] pci 0000:00:05.0: [10de:007e] type 1 class 0x000604
[ 0.097275] pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.097296] pci 0000:00:06.0: [10de:007e] type 1 class 0x000604
[ 0.097344] pci 0000:00:06.0: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.097365] pci 0000:00:07.0: [10de:007e] type 1 class 0x000604
[ 0.097413] pci 0000:00:07.0: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.097449] pci 0000:00:09.0: [10de:0270] type 0 class 0x000500
[ 0.097676] pci 0000:00:0a.0: [10de:0260] type 0 class 0x000601
[ 0.097753] pci 0000:00:0a.1: [10de:0264] type 0 class 0x000c05
[ 0.097804] pci 0000:00:0a.1: reg 20: [io 0x5000-0x503f]
[ 0.097814] pci 0000:00:0a.1: reg 24: [io 0x5100-0x513f]
[ 0.097861] pci 0000:00:0a.1: PME# supported from D3hot D3cold
[ 0.097883] pci 0000:00:0a.2: [10de:0272] type 0 class 0x000500
[ 0.097969] pci 0000:00:0b.0: [10de:026d] type 0 class 0x000c03
[ 0.097986] pci 0000:00:0b.0: reg 10: [mem 0xd5006000-0xd5006fff]
[ 0.098053] pci 0000:00:0b.0: supports D1 D2
[ 0.098056] pci 0000:00:0b.0: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.098077] pci 0000:00:0b.1: [10de:026e] type 0 class 0x000c03
[ 0.098094] pci 0000:00:0b.1: reg 10: [mem 0xd5007000-0xd50070ff]
[ 0.098168] pci 0000:00:0b.1: supports D1 D2
[ 0.098171] pci 0000:00:0b.1: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.098203] pci 0000:00:0d.0: [10de:0265] type 0 class 0x000101
[ 0.098243] pci 0000:00:0d.0: reg 20: [io 0xf000-0xf00f]
[ 0.098297] pci 0000:00:0e.0: [10de:0266] type 0 class 0x000101
[ 0.098316] pci 0000:00:0e.0: reg 10: [io 0x09f0-0x09f7]
[ 0.098325] pci 0000:00:0e.0: reg 14: [io 0x0bf0-0x0bf3]
[ 0.098334] pci 0000:00:0e.0: reg 18: [io 0x0970-0x0977]
[ 0.098343] pci 0000:00:0e.0: reg 1c: [io 0x0b70-0x0b73]
[ 0.098353] pci 0000:00:0e.0: reg 20: [io 0xd400-0xd40f]
[ 0.098362] pci 0000:00:0e.0: reg 24: [mem 0xd5004000-0xd5004fff]
[ 0.098428] pci 0000:00:0f.0: [10de:0267] type 0 class 0x000101
[ 0.098446] pci 0000:00:0f.0: reg 10: [io 0x09e0-0x09e7]
[ 0.098456] pci 0000:00:0f.0: reg 14: [io 0x0be0-0x0be3]
[ 0.098465] pci 0000:00:0f.0: reg 18: [io 0x0960-0x0967]
[ 0.098474] pci 0000:00:0f.0: reg 1c: [io 0x0b60-0x0b63]
[ 0.098483] pci 0000:00:0f.0: reg 20: [io 0xe800-0xe80f]
[ 0.098493] pci 0000:00:0f.0: reg 24: [mem 0xd5005000-0xd5005fff]
[ 0.098555] pci 0000:00:10.0: [10de:026f] type 1 class 0x000604
[ 0.098633] pci 0000:00:10.1: [10de:026c] type 0 class 0x000403
[ 0.098653] pci 0000:00:10.1: reg 10: [mem 0xd5000000-0xd5003fff]
[ 0.098735] pci 0000:00:10.1: PME# supported from D3hot D3cold
[ 0.098820] pci 0000:01:00.0: [1002:68e1] type 0 class 0x000300
[ 0.098837] pci 0000:01:00.0: reg 10: [mem 0xc0000000-0xcfffffff 64bit
pref]

[ 0.098850] pci 0000:01:00.0: reg 18: [mem 0xd1000000-0xd101ffff 64bit]
[ 0.098860] pci 0000:01:00.0: reg 20: [io 0xa000-0xa0ff]
[ 0.098876] pci 0000:01:00.0: reg 30: [mem 0xd0000000-0xd001ffff pref]
[ 0.098914] pci 0000:01:00.0: supports D1 D2
[ 0.098940] pci 0000:01:00.1: [1002:aa68] type 0 class 0x000403
[ 0.098956] pci 0000:01:00.1: reg 10: [mem 0xd1020000-0xd1023fff 64bit]
[ 0.099027] pci 0000:01:00.1: supports D1 D2
[ 0.104024] pci 0000:00:02.0: PCI bridge to [bus 01-01]
[ 0.104032] pci 0000:00:02.0: bridge window [io 0xa000-0xafff]
[ 0.104038] pci 0000:00:02.0: bridge window [mem
0xd0000000-0xd1ffffff]
[ 0.104047] pci 0000:00:02.0: bridge window [mem
0xc0000000-0xcfffffff 64bit pref]

[ 0.104115] pci 0000:02:00.0: [bdbd:a117] type 0 class 0x000400
[ 0.104141] pci 0000:02:00.0: reg 10: [mem 0xd2000000-0xd20fffff]
[ 0.104169] pci 0000:02:00.0: reg 14: [mem 0xd2100000-0xd21fffff 64bit]
[ 0.104312] pci 0000:02:00.0: supports D1 D2
[ 0.104314] pci 0000:02:00.0: PME# supported from D0 D1 D2 D3hot
[ 0.112025] pci 0000:00:04.0: PCI bridge to [bus 02-02]
[ 0.112035] pci 0000:00:04.0: bridge window [mem
0xd2000000-0xd2ffffff]

[ 0.112082] pci 0000:00:05.0: PCI bridge to [bus 03-03]
[ 0.112131] pci 0000:00:06.0: PCI bridge to [bus 04-04]
[ 0.112180] pci 0000:00:07.0: PCI bridge to [bus 05-05]
[ 0.112261] pci 0000:06:07.0: [1186:1300] type 0 class 0x000200
[ 0.112281] pci 0000:06:07.0: reg 10: [io 0xb000-0xb0ff]
[ 0.112293] pci 0000:06:07.0: reg 14: [mem 0xd4004000-0xd40040ff]
[ 0.112367] pci 0000:06:07.0: supports D1 D2
[ 0.112370] pci 0000:06:07.0: PME# supported from D1 D2 D3hot D3cold
[ 0.112400] pci 0000:06:0c.0: [11ab:4320] type 0 class 0x000200
[ 0.112422] pci 0000:06:0c.0: reg 10: [mem 0xd4000000-0xd4003fff]
[ 0.112434] pci 0000:06:0c.0: reg 14: [io 0xb400-0xb4ff]
[ 0.112480] pci 0000:06:0c.0: reg 30: [mem 0xd3000000-0xd301ffff pref]
[ 0.112521] pci 0000:06:0c.0: supports D1 D2
[ 0.112523] pci 0000:06:0c.0: PME# supported from D0 D1 D2 D3hot D3cold
[ 0.112561] pci 0000:00:10.0: PCI bridge to [bus 06-06] (subtractive
decode)

[ 0.112566] pci 0000:00:10.0: bridge window [io 0xb000-0xbfff]
[ 0.112571] pci 0000:00:10.0: bridge window [mem
0xd3000000-0xd4ffffff]
[ 0.112576] pci 0000:00:10.0: bridge window [io 0x0000-0x0cf7]
(subtractive decode)
[ 0.112580] pci 0000:00:10.0: bridge window [io 0x0d00-0xffff]
(subtractive decode)
[ 0.112583] pci 0000:00:10.0: bridge window [mem
0x000a0000-0x000bffff] (subtractive decode)
[ 0.112587] pci 0000:00:10.0: bridge window [mem
0x000c0000-0x000dffff] (subtractive decode)
[ 0.112590] pci 0000:00:10.0: bridge window [mem
0xbff00000-0xfebfffff] (subtractive decode)

[ 0.112612] pci_bus 0000:00: on NUMA node 0
[ 0.112617] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[ 0.112909] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.HUB0._PRT]
[ 0.113094] pci0000:00: Requesting ACPI _OSC control (0x1d)
[ 0.113098] pci0000:00: ACPI _OSC request failed (AE_NOT_FOUND),
returned control mask: 0x1d

[ 0.113101] ACPI _OSC control for PCIe not granted, disabling ASPM
[ 0.155253] ACPI: PCI Interrupt Link [LNK1] (IRQs 5 7 9 10 11 14 15)
*0, disabled.

[ 0.155318] ACPI: PCI Interrupt Link [LNK2] (IRQs *5 7 9 10 11 14 15)
[ 0.155378] ACPI: PCI Interrupt Link [LNK3] (IRQs 5 7 9 10 *11 14 15)
[ 0.155436] ACPI: PCI Interrupt Link [LNK4] (IRQs 5 7 9 10 11 14 15)
*0, disabled.

[ 0.155497] ACPI: PCI Interrupt Link [LNK5] (IRQs 5 7 9 *10 11 14 15)
[ 0.155557] ACPI: PCI Interrupt Link [LNK6] (IRQs 5 7 9 10 *11 14 15)
[ 0.155614] ACPI: PCI Interrupt Link [LNK7] (IRQs 5 7 9 10 11 14 15)
*0, disabled.
[ 0.155673] ACPI: PCI Interrupt Link [LNK8] (IRQs 5 7 9 10 11 14 15)
*0, disabled.

[ 0.155734] ACPI: PCI Interrupt Link [LUBA] (IRQs *5 7 9 10 11 14 15)
[ 0.155792] ACPI: PCI Interrupt Link [LUBB] (IRQs 5 7 9 10 11 14 15)
*0, disabled.
[ 0.155851] ACPI: PCI Interrupt Link [LMAC] (IRQs 5 7 9 10 11 14 15)
*0, disabled.
[ 0.155910] ACPI: PCI Interrupt Link [LACI] (IRQs 5 7 9 10 11 14 15)
*0, disabled.

[ 0.155970] ACPI: PCI Interrupt Link [LAZA] (IRQs *5 7 9 10 11 14 15)
[ 0.156049] ACPI: PCI Interrupt Link [LPMU] (IRQs 5 7 9 10 11 14 15)
*0, disabled.
[ 0.156108] ACPI: PCI Interrupt Link [LMCI] (IRQs 5 7 9 10 11 14 15)
*0, disabled.

[ 0.156169] ACPI: PCI Interrupt Link [LSMB] (IRQs 5 7 9 10 *11 14 15)
[ 0.156229] ACPI: PCI Interrupt Link [LUB2] (IRQs 5 7 9 *10 11 14 15)
[ 0.156289] ACPI: PCI Interrupt Link [LIDE] (IRQs 5 7 9 10 11 14 15)
*0, disabled.

[ 0.156350] ACPI: PCI Interrupt Link [LSID] (IRQs 5 7 9 10 *11 14 15)
[ 0.156416] ACPI: PCI Interrupt Link [LFID] (IRQs 5 7 9 *10 11 14 15)
[ 0.156523] ACPI: PCI Interrupt Link [APC1] (IRQs 16) *0, disabled.
[ 0.156620] ACPI: PCI Interrupt Link [APC2] (IRQs 17) *0
[ 0.156716] ACPI: PCI Interrupt Link [APC3] (IRQs 18) *0
[ 0.156812] ACPI: PCI Interrupt Link [APC4] (IRQs 19) *0, disabled.
[ 0.156908] ACPI: PCI Interrupt Link [APC5] (IRQs 16) *0
[ 0.157004] ACPI: PCI Interrupt Link [APC6] (IRQs 16) *0
[ 0.157100] ACPI: PCI Interrupt Link [APC7] (IRQs 16) *0, disabled.
[ 0.157196] ACPI: PCI Interrupt Link [APC8] (IRQs 16) *0, disabled.
[ 0.157292] ACPI: PCI Interrupt Link [APCF] (IRQs 20 21 22 23) *0
[ 0.157389] ACPI: PCI Interrupt Link [APCG] (IRQs 20 21 22 23) *0,
disabled.
[ 0.157487] ACPI: PCI Interrupt Link [APCH] (IRQs 20 21 22 23) *0,
disabled.
[ 0.157586] ACPI: PCI Interrupt Link [APCJ] (IRQs 20 21 22 23) *0,
disabled.
[ 0.157684] ACPI: PCI Interrupt Link [APMU] (IRQs 20 21 22 23) *0,
disabled.

[ 0.157782] ACPI: PCI Interrupt Link [AAZA] (IRQs 20 21 22 23) *0
[ 0.157879] ACPI: PCI Interrupt Link [APCK] (IRQs 20 21 22 23) *0,
disabled.

[ 0.157977] ACPI: PCI Interrupt Link [APCS] (IRQs 20 21 22 23) *0
[ 0.158074] ACPI: PCI Interrupt Link [APCL] (IRQs 20 21 22 23) *0
[ 0.158173] ACPI: PCI Interrupt Link [APCM] (IRQs 20 21 22 23) *0,
disabled.
[ 0.158271] ACPI: PCI Interrupt Link [APCZ] (IRQs 20 21 22 23) *0,
disabled.

[ 0.158369] ACPI: PCI Interrupt Link [APSI] (IRQs 20 21 22 23) *0
[ 0.158466] ACPI: PCI Interrupt Link [APSJ] (IRQs 20 21 22 23) *0
[ 0.158552] vgaarb: device added:
PCI:0000:01:00.0,decodes=io+mem,owns=io+mem,locks= none

[ 0.158552] vgaarb: loaded
[ 0.158552] vgaarb: bridge control possible 0000:01:00.0
[ 0.158552] PCI: Using ACPI for IRQ routing
[ 0.167981] PCI: pci_cache_line_size set to 64 bytes
[ 0.168110] reserve RAM buffer: 000000000009f000 - 000000000009ffff
[ 0.168114] reserve RAM buffer: 00000000bfef0000 - 00000000bfffffff
[ 0.168246] HPET: 3 timers in total, 0 timers will be used for per-cpu
timer

[ 0.168255] hpet0: at MMIO 0xfeff0000, IRQs 2, 8, 31
[ 0.168260] hpet0: 3 comparators, 32-bit 25.000000 MHz counter
[ 0.172049] Switching to clocksource hpet
[ 0.174383] pnp: PnP ACPI init
[ 0.174400] ACPI: bus type pnp registered
[ 0.174486] pnp 00:00: [bus 00-ff]
[ 0.174490] pnp 00:00: [io 0x0cf8-0x0cff]
[ 0.174493] pnp 00:00: [io 0x0000-0x0cf7 window]
[ 0.174496] pnp 00:00: [io 0x0d00-0xffff window]
[ 0.174499] pnp 00:00: [mem 0x000a0000-0x000bffff window]
[ 0.174502] pnp 00:00: [mem 0x000c0000-0x000dffff window]
[ 0.174505] pnp 00:00: [mem 0xbff00000-0xfebfffff window]
[ 0.174580] pnp 00:00: Plug and Play ACPI device, IDs PNP0a08 PNP0a03
(active)

[ 0.174609] pnp 00:01: [io 0x4000-0x407f]
[ 0.174612] pnp 00:01: [io 0x4080-0x40ff]
[ 0.174614] pnp 00:01: [io 0x4400-0x447f]
[ 0.174617] pnp 00:01: [io 0x4480-0x44ff]
[ 0.174619] pnp 00:01: [io 0x4800-0x487f]
[ 0.174622] pnp 00:01: [io 0x4880-0x48ff]
[ 0.174695] system 00:01: [io 0x4000-0x407f] has been reserved
[ 0.174699] system 00:01: [io 0x4080-0x40ff] has been reserved
[ 0.174702] system 00:01: [io 0x4400-0x447f] has been reserved
[ 0.174706] system 00:01: [io 0x4480-0x44ff] has been reserved
[ 0.174709] system 00:01: [io 0x4800-0x487f] has been reserved
[ 0.174713] system 00:01: [io 0x4880-0x48ff] has been reserved
[ 0.174717] system 00:01: Plug and Play ACPI device, IDs PNP0c02
(active)

[ 0.174787] pnp 00:02: [io 0x0010-0x001f]
[ 0.174790] pnp 00:02: [io 0x0022-0x003f]
[ 0.174792] pnp 00:02: [io 0x0044-0x005f]
[ 0.174794] pnp 00:02: [io 0x0062-0x0063]
[ 0.174797] pnp 00:02: [io 0x0065-0x006f]
[ 0.174799] pnp 00:02: [io 0x0074-0x007f]
[ 0.174802] pnp 00:02: [io 0x0091-0x0093]
[ 0.174804] pnp 00:02: [io 0x00a2-0x00bf]
[ 0.174807] pnp 00:02: [io 0x00e0-0x00ef]
[ 0.174809] pnp 00:02: [io 0x04d0-0x04d1]
[ 0.174812] pnp 00:02: [io 0x0800-0x087f]
[ 0.174814] pnp 00:02: [io 0x0290-0x0297]
[ 0.174817] pnp 00:02: [io 0x0880-0x088f]
[ 0.174899] system 00:02: [io 0x04d0-0x04d1] has been reserved
[ 0.174903] system 00:02: [io 0x0800-0x087f] has been reserved
[ 0.174907] system 00:02: [io 0x0290-0x0297] has been reserved
[ 0.174911] system 00:02: [io 0x0880-0x088f] has been reserved
[ 0.174915] system 00:02: Plug and Play ACPI device, IDs PNP0c02
(active)

[ 0.174930] pnp 00:03: [dma 4]
[ 0.174932] pnp 00:03: [io 0x0000-0x000f]
[ 0.174935] pnp 00:03: [io 0x0080-0x0090]
[ 0.174938] pnp 00:03: [io 0x0094-0x009f]
[ 0.174940] pnp 00:03: [io 0x00c0-0x00df]
[ 0.174980] pnp 00:03: Plug and Play ACPI device, IDs PNP0200 (active)
[ 0.175040] pnp 00:04: [irq 0 disabled]
[ 0.175050] pnp 00:04: [irq 8]
[ 0.175053] pnp 00:04: [mem 0xfeff0000-0xfeff03ff]
[ 0.175097] pnp 00:04: Plug and Play ACPI device, IDs PNP0103 (active)
[ 0.175132] pnp 00:05: [io 0x0070-0x0073]
[ 0.175174] pnp 00:05: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 0.175185] pnp 00:06: [io 0x0061]
[ 0.175228] pnp 00:06: Plug and Play ACPI device, IDs PNP0800 (active)
[ 0.175239] pnp 00:07: [io 0x00f0-0x00ff]
[ 0.175246] pnp 00:07: [irq 13]
[ 0.175289] pnp 00:07: Plug and Play ACPI device, IDs PNP0c04 (active)
[ 0.175619] pnp 00:08: [io 0x03f8-0x03ff]
[ 0.175625] pnp 00:08: [irq 4]
[ 0.175708] pnp 00:08: Plug and Play ACPI device, IDs PNP0501 (active)
[ 0.176234] pnp 00:09: [io 0x0378-0x037f]
[ 0.176237] pnp 00:09: [io 0x0778-0x077b]
[ 0.176243] pnp 00:09: [irq 7]
[ 0.176246] pnp 00:09: [dma 3]
[ 0.176334] pnp 00:09: Plug and Play ACPI device, IDs PNP0401 (active)
[ 0.177132] pnp 00:0a: [mem 0xe0000000-0xefffffff]
[ 0.177223] system 00:0a: [mem 0xe0000000-0xefffffff] has been reserved
[ 0.177228] system 00:0a: Plug and Play ACPI device, IDs PNP0c02
(active)

[ 0.177337] pnp 00:0b: [mem 0x000f0000-0x000f3fff]
[ 0.177340] pnp 00:0b: [mem 0x000f4000-0x000f7fff]
[ 0.177343] pnp 00:0b: [mem 0x000f8000-0x000fbfff]
[ 0.177346] pnp 00:0b: [mem 0x000fc000-0x000fffff]
[ 0.177348] pnp 00:0b: [mem 0xfeff0000-0xfeff00ff]
[ 0.177351] pnp 00:0b: [mem 0xbfef0000-0xbfefffff]
[ 0.177354] pnp 00:0b: [mem 0xffff0000-0xffffffff]
[ 0.177356] pnp 00:0b: [mem 0x00000000-0x0009ffff]
[ 0.177359] pnp 00:0b: [mem 0x00100000-0xbfeeffff]
[ 0.177362] pnp 00:0b: [mem 0xfec00000-0xfec00fff]
[ 0.177365] pnp 00:0b: [mem 0xfee00000-0xfee00fff]
[ 0.177367] pnp 00:0b: [mem 0xfeff0000-0xfeff03ff]
[ 0.177471] system 00:0b: [mem 0x000f0000-0x000f3fff] could not be
reserved
[ 0.177475] system 00:0b: [mem 0x000f4000-0x000f7fff] could not be
reserved
[ 0.177479] system 00:0b: [mem 0x000f8000-0x000fbfff] could not be
reserved
[ 0.177483] system 00:0b: [mem 0x000fc000-0x000fffff] could not be
reserved

[ 0.177487] system 00:0b: [mem 0xfeff0000-0xfeff00ff] has been reserved
[ 0.177490] system 00:0b: [mem 0xbfef0000-0xbfefffff] could not be
reserved

[ 0.177494] system 00:0b: [mem 0xffff0000-0xffffffff] has been reserved
[ 0.177498] system 00:0b: [mem 0x00000000-0x0009ffff] could not be
reserved
[ 0.177502] system 00:0b: [mem 0x00100000-0xbfeeffff] could not be
reserved
[ 0.177506] system 00:0b: [mem 0xfec00000-0xfec00fff] could not be
reserved

[ 0.177509] system 00:0b: [mem 0xfee00000-0xfee00fff] has been reserved
[ 0.177513] system 00:0b: [mem 0xfeff0000-0xfeff03ff] could not be
reserved
[ 0.177517] system 00:0b: Plug and Play ACPI device, IDs PNP0c01
(active)

[ 0.177530] pnp: PnP ACPI: found 12 devices
[ 0.177533] ACPI: ACPI bus type pnp unregistered
[ 0.177537] PnPBIOS: Disabled by ACPI PNP
[ 0.215344] PCI: max bus depth: 1 pci_try_num: 2
[ 0.215380] pci 0000:00:02.0: PCI bridge to [bus 01-01]
[ 0.215384] pci 0000:00:02.0: bridge window [io 0xa000-0xafff]
[ 0.215389] pci 0000:00:02.0: bridge window [mem
0xd0000000-0xd1ffffff]
[ 0.215394] pci 0000:00:02.0: bridge window [mem
0xc0000000-0xcfffffff 64bit pref]

[ 0.215400] pci 0000:00:04.0: PCI bridge to [bus 02-02]
[ 0.215405] pci 0000:00:04.0: bridge window [mem
0xd2000000-0xd2ffffff]

[ 0.215412] pci 0000:00:05.0: PCI bridge to [bus 03-03]
[ 0.215421] pci 0000:00:06.0: PCI bridge to [bus 04-04]
[ 0.215430] pci 0000:00:07.0: PCI bridge to [bus 05-05]
[ 0.215440] pci 0000:00:10.0: PCI bridge to [bus 06-06]
[ 0.215443] pci 0000:00:10.0: bridge window [io 0xb000-0xbfff]
[ 0.215449] pci 0000:00:10.0: bridge window [mem
0xd3000000-0xd4ffffff]

[ 0.215477] pci 0000:00:10.0: setting latency timer to 64
[ 0.215481] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7]
[ 0.215484] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff]
[ 0.215487] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff]
[ 0.215491] pci_bus 0000:00: resource 7 [mem 0x000c0000-0x000dffff]
[ 0.215494] pci_bus 0000:00: resource 8 [mem 0xbff00000-0xfebfffff]
[ 0.215497] pci_bus 0000:01: resource 0 [io 0xa000-0xafff]
[ 0.215500] pci_bus 0000:01: resource 1 [mem 0xd0000000-0xd1ffffff]
[ 0.215503] pci_bus 0000:01: resource 2 [mem 0xc0000000-0xcfffffff
64bit pref]

[ 0.215507] pci_bus 0000:02: resource 1 [mem 0xd2000000-0xd2ffffff]
[ 0.215510] pci_bus 0000:06: resource 0 [io 0xb000-0xbfff]
[ 0.215513] pci_bus 0000:06: resource 1 [mem 0xd3000000-0xd4ffffff]
[ 0.215517] pci_bus 0000:06: resource 4 [io 0x0000-0x0cf7]
[ 0.215520] pci_bus 0000:06: resource 5 [io 0x0d00-0xffff]
[ 0.215523] pci_bus 0000:06: resource 6 [mem 0x000a0000-0x000bffff]
[ 0.215526] pci_bus 0000:06: resource 7 [mem 0x000c0000-0x000dffff]
[ 0.215529] pci_bus 0000:06: resource 8 [mem 0xbff00000-0xfebfffff]
[ 0.215602] NET: Registered protocol family 2
[ 0.215687] IP route cache hash table entries: 32768 (order: 5, 131072
bytes)
[ 0.215985] TCP established hash table entries: 131072 (order: 8,
1048576 bytes)

[ 0.216475] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.216711] TCP: Hash tables configured (established 131072 bind 65536)
[ 0.216714] TCP reno registered
[ 0.216718] UDP hash table entries: 512 (order: 2, 16384 bytes)
[ 0.216728] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[ 0.216833] NET: Registered protocol family 1
[ 0.217185] ACPI: PCI Interrupt Link [APCF] enabled at IRQ 23
[ 0.288303] ACPI: PCI Interrupt Link [APCL] enabled at IRQ 22
[ 0.288398] pci 0000:01:00.0: Boot video device
[ 0.288413] PCI: CLS 32 bytes, default 64
[ 0.288467] Unpacking initramfs...
[ 0.608491] Freeing initrd memory: 10188k freed
[ 0.614580] audit: initializing netlink socket (disabled)
[ 0.614605] type=2000 audit(1341728492.612:1): initialized
[ 0.630767] highmem bounce pool size: 64 pages
[ 0.630774] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[ 0.631495] VFS: Disk quotas dquot_6.5.2
[ 0.631541] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.631638] msgmni has been set to 1434
[ 0.631895] alg: No test for stdrng (krng)
[ 0.631931] Block layer SCSI generic (bsg) driver version 0.4 loaded
(major 253)

[ 0.631936] io scheduler noop registered
[ 0.631938] io scheduler deadline registered
[ 0.631963] io scheduler cfq registered (default)
[ 0.632146] pcieport 0000:00:02.0: irq 40 for MSI/MSI-X
[ 0.632233] pcieport 0000:00:04.0: irq 41 for MSI/MSI-X
[ 0.632315] pcieport 0000:00:05.0: irq 42 for MSI/MSI-X
[ 0.632399] pcieport 0000:00:06.0: irq 43 for MSI/MSI-X
[ 0.632480] pcieport 0000:00:07.0: irq 44 for MSI/MSI-X
[ 0.632572] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[ 0.632600] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[ 0.632603] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[ 0.633084] nvidiafb_setup START
[ 0.633190] intel_idle: MWAIT substates: 0x20
[ 0.633192] intel_idle: does not run on family 6 model 15
[ 0.633236] GHES: HEST is not enabled!
[ 0.633254] isapnp: Scanning for PnP cards...
[ 0.986305] isapnp: No Plug & Play device found
[ 0.986387] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 1.006842] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 1.027725] 00:08: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 1.028068] Linux agpgart interface v0.103
[ 1.028351] i8042: PNP: No PS/2 controller found. Probing ports
directly.

[ 1.031691] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 1.031699] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 1.031852] mousedev: PS/2 mouse device common for all mice
[ 1.031919] rtc_cmos 00:05: RTC can wake from S4
[ 1.032070] rtc_cmos 00:05: rtc core: registered rtc_cmos as rtc0
[ 1.032104] rtc0: alarms up to one year, y3k, 242 bytes nvram, hpet irqs
[ 1.032116] cpuidle: using governor ladder
[ 1.032119] cpuidle: using governor menu
[ 1.032373] TCP cubic registered
[ 1.032425] NET: Registered protocol family 10
[ 1.033029] Mobile IPv6
[ 1.033033] NET: Registered protocol family 17
[ 1.033038] Registering the dns_resolver key type
[ 1.033058] Using IPI No-Shortcut mode
[ 1.033205] PM: Hibernation image not present or could not be loaded.
[ 1.033216] registered taskstats version 1
[ 1.033876] rtc_cmos 00:05: setting system clock to 2012-07-08 06:21:33
UTC (1341728493)

[ 1.033919] Initializing network drop monitor service
[ 1.034023] Freeing unused kernel memory: 408k freed
[ 1.034254] Write protecting the kernel text: 2912k
[ 1.034296] Write protecting the kernel read-only data: 1100k
[ 1.034298] NX-protecting the kernel data: 3232k
[ 1.091810] udevd[52]: starting version 175
[ 1.147454] usbcore: registered new interface driver usbfs
[ 1.147484] usbcore: registered new interface driver hub
[ 1.155304] SCSI subsystem initialized
[ 1.157254] usbcore: registered new device driver usb
[ 1.157955] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.157960] ehci_hcd: block sizes: qh 68 qtd 96 itd 160 sitd 96
[ 1.158057] ehci_hcd 0000:00:0b.1: setting latency timer to 64
[ 1.158061] ehci_hcd 0000:00:0b.1: EHCI Host Controller
[ 1.158089] drivers/usb/core/inode.c: creating file 'devices'
[ 1.158094] drivers/usb/core/inode.c: creating file '001'
[ 1.158099] ehci_hcd 0000:00:0b.1: new USB bus registered, assigned bus
number 1
[ 1.158111] ehci_hcd 0000:00:0b.1: reset hcs_params 0x101888 dbg=1 cc=1
pcc=8 !ppc ports=8

[ 1.158122] ehci_hcd 0000:00:0b.1: reset portroute 0 0 0 0 0 0 0 0
[ 1.158126] ehci_hcd 0000:00:0b.1: reset hcc_params a086 caching frame
256/512/1024 park

[ 1.158142] ehci_hcd 0000:00:0b.1: park 0
[ 1.158150] ehci_hcd 0000:00:0b.1: debug port 1
[ 1.158156] ehci_hcd 0000:00:0b.1: reset command 0080b02 park=3
ithresh=8 period=1024 Reset HALT
[ 1.158166] ehci_hcd 0000:00:0b.1: cache line size of 32 is not
supported

[ 1.158169] ehci_hcd 0000:00:0b.1: supports USB remote wakeup
[ 1.158196] ehci_hcd 0000:00:0b.1: irq 22, io mem 0xd5007000
[ 1.158202] ehci_hcd 0000:00:0b.1: init command 0010005 (park)=0
ithresh=1 period=512 RUN
[ 1.167291] input: Power Button as
/devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input0

[ 1.167301] ACPI: Power Button [PWRB]
[ 1.167414] input: Power Button as
/devices/LNXSYSTM:00/LNXPWRBN:00/input/input1

[ 1.167419] ACPI: Power Button [PWRF]
[ 1.168098] ehci_hcd 0000:00:0b.1: USB 2.0 started, EHCI 1.00
[ 1.168152] usb usb1: default language 0x0409
[ 1.168161] usb usb1: udev 1, busnum 1, minor = 0
[ 1.168164] usb usb1: New USB device found, idVendor=1d6b,
idProduct=0002
[ 1.168168] usb usb1: New USB device strings: Mfr=3, Product=2,
SerialNumber=1

[ 1.168171] usb usb1: Product: EHCI Host Controller
[ 1.168173] usb usb1: Manufacturer: Linux 3.3.0+ ehci_hcd
[ 1.168176] usb usb1: SerialNumber: 0000:00:0b.1
[ 1.170962] usb usb1: usb_probe_device
[ 1.170969] usb usb1: configuration #1 chosen from 1 choice
[ 1.170989] usb usb1: adding 1-0:1.0 (config #1, interface 0)
[ 1.171027] hub 1-0:1.0: usb_probe_interface
[ 1.171031] hub 1-0:1.0: usb_probe_interface - got id
[ 1.171035] hub 1-0:1.0: USB hub found
[ 1.171042] hub 1-0:1.0: 8 ports detected
[ 1.171044] hub 1-0:1.0: standalone hub
[ 1.171047] hub 1-0:1.0: no power switching (usb 1.0)
[ 1.171049] hub 1-0:1.0: individual port over-current protection
[ 1.171053] hub 1-0:1.0: power on to power good time: 20ms
[ 1.171058] hub 1-0:1.0: local power source is good
[ 1.171061] hub 1-0:1.0: trying to enable port power on non-switchable
hub

[ 1.171107] drivers/usb/core/inode.c: creating file '001'
[ 1.173776] libata version 3.00 loaded.
[ 1.174296] sata_nv 0000:00:0e.0: version 3.5
[ 1.174532] ACPI: PCI Interrupt Link [APSI] enabled at IRQ 21
[ 1.174549] sata_nv 0000:00:0e.0: Using SWNCQ mode
[ 1.174604] sata_nv 0000:00:0e.0: setting latency timer to 64
[ 1.175782] ACPI: Fan [FAN] (on)
[ 1.176845] scsi0 : sata_nv
[ 1.178890] 8139too: 8139too Fast Ethernet driver 0.9.28
[ 1.179184] ACPI: PCI Interrupt Link [APC2] enabled at IRQ 17
[ 1.180086] scsi1 : sata_nv
[ 1.180256] ata1: SATA max UDMA/133 cmd 0x9f0 ctl 0xbf0 bmdma 0xd400
irq 21
[ 1.180260] ata2: SATA max UDMA/133 cmd 0x970 ctl 0xb70 bmdma 0xd408
irq 21

[ 1.180487] ACPI: PCI Interrupt Link [APSJ] enabled at IRQ 20
[ 1.180506] sata_nv 0000:00:0f.0: Using SWNCQ mode
[ 1.180578] sata_nv 0000:00:0f.0: setting latency timer to 64
[ 1.184032] scsi2 : sata_nv
[ 1.187692] scsi3 : sata_nv
[ 1.187911] ata3: SATA max UDMA/133 cmd 0x9e0 ctl 0xbe0 bmdma 0xe800
irq 20
[ 1.187916] ata4: SATA max UDMA/133 cmd 0x960 ctl 0xb60 bmdma 0xe808
irq 20

[ 1.188568] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 1.188572] ohci_hcd: block sizes: ed 64 td 64
[ 1.188639] ohci_hcd 0000:00:0b.0: setting latency timer to 64
[ 1.188643] ohci_hcd 0000:00:0b.0: OHCI Host Controller
[ 1.188655] drivers/usb/core/inode.c: creating file '002'
[ 1.188661] ohci_hcd 0000:00:0b.0: new USB bus registered, assigned bus
number 2

[ 1.188681] ohci_hcd 0000:00:0b.0: created debug files
[ 1.188684] ohci_hcd 0000:00:0b.0: supports USB remote wakeup
[ 1.188712] ohci_hcd 0000:00:0b.0: irq 23, io mem 0xd5006000
[ 1.194304] thermal LNXTHERM:00: registered as thermal_zone0
[ 1.194308] ACPI: Thermal Zone [THRM] (40 C)
[ 1.204417] 8139too 0000:06:07.0: eth0: RealTek RTL8139 at 0xb000,
00:40:05:00:39:88, IRQ 17

[ 1.204777] ACPI: PCI Interrupt Link [APC3] enabled at IRQ 18
[ 1.204868] skge: 1.14 addr 0xd4000000 irq 18 chip Yukon-Lite rev 9
[ 1.205514] skge 0000:06:0c.0: eth1: addr 00:24:8c:02:b7:12
[ 1.246021] ohci_hcd 0000:00:0b.0: OHCI controller state
[ 1.246027] ohci_hcd 0000:00:0b.0: OHCI 1.0, NO legacy support
registers, rh state running
[ 1.246033] ohci_hcd 0000:00:0b.0: control 0x683 RWE RWC
HCFS=operational CBSR=3

[ 1.246037] ohci_hcd 0000:00:0b.0: cmdstatus 0x00000 SOC=0
[ 1.246041] ohci_hcd 0000:00:0b.0: intrstatus 0x00000004 SF
[ 1.246045] ohci_hcd 0000:00:0b.0: intrenable 0x8000004a MIE RHSC RD WDH
[ 1.246052] ohci_hcd 0000:00:0b.0: hcca frame #0003
[ 1.246057] ohci_hcd 0000:00:0b.0: roothub.a 01000208 POTPGT=1 NPS
NDP=8(8)

[ 1.246062] ohci_hcd 0000:00:0b.0: roothub.b 00000000 PPCM=0000 DR=0000
[ 1.246066] ohci_hcd 0000:00:0b.0: roothub.status 00008000 DRWE
[ 1.246071] ohci_hcd 0000:00:0b.0: roothub.portstatus [0] 0x00000100 PPS
[ 1.246075] ohci_hcd 0000:00:0b.0: roothub.portstatus [1] 0x00000100 PPS
[ 1.246080] ohci_hcd 0000:00:0b.0: roothub.portstatus [2] 0x00000100 PPS
[ 1.246085] ohci_hcd 0000:00:0b.0: roothub.portstatus [3] 0x00000100 PPS
[ 1.246089] ohci_hcd 0000:00:0b.0: roothub.portstatus [4] 0x00000100 PPS
[ 1.246094] ohci_hcd 0000:00:0b.0: roothub.portstatus [5] 0x00000100 PPS
[ 1.246099] ohci_hcd 0000:00:0b.0: roothub.portstatus [6] 0x00000100 PPS
[ 1.246103] ohci_hcd 0000:00:0b.0: roothub.portstatus [7] 0x00000100 PPS
[ 1.246125] usb usb2: default language 0x0409
[ 1.246134] usb usb2: udev 1, busnum 2, minor = 128
[ 1.246137] usb usb2: New USB device found, idVendor=1d6b,
idProduct=0001
[ 1.246140] usb usb2: New USB device strings: Mfr=3, Product=2,
SerialNumber=1

[ 1.246143] usb usb2: Product: OHCI Host Controller
[ 1.246145] usb usb2: Manufacturer: Linux 3.3.0+ ohci_hcd
[ 1.246148] usb usb2: SerialNumber: 0000:00:0b.0
[ 1.246839] usb usb2: usb_probe_device
[ 1.246844] usb usb2: configuration #1 chosen from 1 choice
[ 1.246855] usb usb2: adding 2-0:1.0 (config #1, interface 0)
[ 1.246904] hub 2-0:1.0: usb_probe_interface
[ 1.246907] hub 2-0:1.0: usb_probe_interface - got id
[ 1.246911] hub 2-0:1.0: USB hub found
[ 1.246918] hub 2-0:1.0: 8 ports detected
[ 1.246921] hub 2-0:1.0: standalone hub
[ 1.246923] hub 2-0:1.0: no power switching (usb 1.0)
[ 1.246926] hub 2-0:1.0: global over-current protection
[ 1.246928] hub 2-0:1.0: power on to power good time: 2ms
[ 1.246935] hub 2-0:1.0: local power source is good
[ 1.246937] hub 2-0:1.0: no over-current condition exists
[ 1.246940] hub 2-0:1.0: trying to enable port power on non-switchable
hub

[ 1.247081] drivers/usb/core/inode.c: creating file '001'
[ 1.247145] ehci_hcd 0000:00:0b.1: HS companion for 0000:00:0b.0
[ 1.247215] pata_amd 0000:00:0d.0: version 0.4.1
[ 1.247265] pata_amd 0000:00:0d.0: setting latency timer to 64
[ 1.248067] scsi4 : pata_amd
[ 1.248216] scsi5 : pata_amd
[ 1.248870] ata5: PATA max UDMA/133 cmd 0x1f0 ctl 0x3f6 bmdma 0xf000
irq 14
[ 1.248875] ata6: PATA max UDMA/133 cmd 0x170 ctl 0x376 bmdma 0xf008
irq 15
[ 1.268071] ehci_hcd 0000:00:0b.1: GetStatus port:5 status 001403 0
ACK POWER sig=k CSC CONNECT

[ 1.268079] hub 1-0:1.0: port 5: status 0501 change 0001
[ 1.268092] ehci_hcd 0000:00:0b.1: GetStatus port:6 status 001403 0
ACK POWER sig=k CSC CONNECT

[ 1.268098] hub 1-0:1.0: port 6: status 0501 change 0001
[ 1.344126] hub 2-0:1.0: state 7 ports 8 chg 0000 evt 0000
[ 1.368061] hub 1-0:1.0: state 7 ports 8 chg 0060 evt 0000
[ 1.368074] hub 1-0:1.0: port 5, status 0501, change 0000, 480 Mb/s
[ 1.368086] ehci_hcd 0000:00:0b.1: port 5 low speed --> companion
[ 1.420528] ata5.00: HPA detected: current 321670847, native 321672960
[ 1.420536] ata5.00: ATA-7: HDT722516DLAT80, V43OA96A, max UDMA/133
[ 1.420541] ata5.00: 321670847 sectors, multi 1: LBA48
[ 1.420671] ata5.01: HPA detected: current 156299375, native 156301488
[ 1.420678] ata5.01: ATA-6: WDC WD800BB-00JHC0, 05.01C05, max UDMA/100
[ 1.420683] ata5.01: 156299375 sectors, multi 1: LBA
[ 1.420695] ata5: nv_mode_filter: 0x7f39f&0x7f39f->0x7f39f,
BIOS=0x7f000 (0xc7c60000) ACPI=0x7f01f (15:20:0x1f)
[ 1.420704] ata5: nv_mode_filter: 0x3f39f&0x3f39f->0x3f39f,
BIOS=0x3f000 (0xc7c60000) ACPI=0x3f01f (15:20:0x1f)
[ 1.424067] ehci_hcd 0000:00:0b.1: GetStatus port:5 status 003402 0
ACK POWER OWNER sig=k CSC

[ 1.424092] hub 1-0:1.0: port 6, status 0501, change 0000, 480 Mb/s
[ 1.424102] ehci_hcd 0000:00:0b.1: port 6 low speed --> companion
[ 1.436457] ata5.00: configured for UDMA/133
[ 1.444419] ata5.01: configured for UDMA/100
[ 1.480057] ehci_hcd 0000:00:0b.1: GetStatus port:6 status 003402 0
ACK POWER OWNER sig=k CSC

[ 1.480078] hub 1-0:1.0: state 7 ports 8 chg 0000 evt 0040
[ 1.480090] hub 2-0:1.0: state 7 ports 8 chg 0000 evt 0020
[ 1.480100] ohci_hcd 0000:00:0b.0: GetStatus roothub.portstatus [4] =
0x00010301 CSC LSDA PPS CCS

[ 1.480108] hub 2-0:1.0: port 5, status 0301, change 0001, 1.5 Mb/s
[ 1.492051] ata1: SATA link down (SStatus 0 SControl 300)
[ 1.504052] ata3: SATA link down (SStatus 0 SControl 300)
[ 1.608044] hub 2-0:1.0: debounce: port 5: total 100ms stable 100ms
status 0x301

[ 1.612094] Refined TSC clocksource calibration: 1800.058 MHz.
[ 1.612102] Switching to clocksource tsc
[ 1.728095] ohci_hcd 0000:00:0b.0: GetStatus roothub.portstatus [4] =
0x00100303 PRSC LSDA PPS PES CCS

[ 1.784135] usb 2-5: new low-speed USB device number 2 using ohci_hcd
[ 1.804036] ata2: SATA link down (SStatus 0 SControl 300)
[ 1.904036] ohci_hcd 0000:00:0b.0: GetStatus roothub.portstatus [4] =
0x00100303 PRSC LSDA PPS PES CCS

[ 1.987050] usb 2-5: skipped 1 descriptor after interface
[ 1.990048] usb 2-5: default language 0x0409
[ 1.996050] usb 2-5: udev 2, busnum 2, minor = 129
[ 1.996054] usb 2-5: New USB device found, idVendor=046d, idProduct=c05a
[ 1.996059] usb 2-5: New USB device strings: Mfr=1, Product=2,
SerialNumber=0

[ 1.996063] usb 2-5: Product: USB Optical Mouse
[ 1.996067] usb 2-5: Manufacturer: Logitech
[ 1.996211] usb 2-5: usb_probe_device
[ 1.996217] usb 2-5: configuration #1 chosen from 1 choice
[ 1.999066] usb 2-5: adding 2-5:1.0 (config #1, interface 0)
[ 1.999152] drivers/usb/core/inode.c: creating file '002'
[ 1.999196] ohci_hcd 0000:00:0b.0: GetStatus roothub.portstatus [5] =
0x00010301 CSC LSDA PPS CCS

[ 1.999206] hub 2-0:1.0: port 6, status 0301, change 0001, 1.5 Mb/s
[ 2.116018] ata4: SATA link down (SStatus 0 SControl 300)
[ 2.116255] scsi 4:0:0:0: Direct-Access ATA HDT722516DLAT80
V43O PQ: 0 ANSI: 5
[ 2.116520] scsi 4:0:1:0: Direct-Access ATA WDC WD800BB-00JH
05.0 PQ: 0 ANSI: 5
[ 2.124033] hub 2-0:1.0: debounce: port 6: total 100ms stable 100ms
status 0x301
[ 2.244033] ohci_hcd 0000:00:0b.0: GetStatus roothub.portstatus [5] =
0x00100303 PRSC LSDA PPS PES CCS
[ 2.291595] sd 4:0:0:0: [sda] 321670847 512-byte logical blocks: (164
GB/153 GiB)
[ 2.291622] sd 4:0:1:0: [sdb] 156299375 512-byte logical blocks: (80.0
GB/74.5 GiB)

[ 2.291707] sd 4:0:0:0: [sda] Write Protect is off
[ 2.291711] sd 4:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 2.291756] sd 4:0:1:0: [sdb] Write Protect is off
[ 2.291761] sd 4:0:0:0: [sda] Write cache: enabled, read cache:
enabled, doesn't support DPO or FUA

[ 2.291770] sd 4:0:1:0: [sdb] Mode Sense: 00 3a 00 00
[ 2.291802] sd 4:0:1:0: [sdb] Write cache: enabled, read cache:
enabled, doesn't support DPO or FUA

[ 2.300057] usb 2-6: new low-speed USB device number 3 using ohci_hcd
[ 2.326840] sdb: sdb1 sdb2
[ 2.350967] sda: sda1 sda2 sda4 < sda5 sda6 sda7 >
[ 2.351214] sda: p7 size 37047500 extends beyond EOD, enabling native
capacity

[ 2.351748] ata5: soft resetting link
[ 2.420038] ohci_hcd 0000:00:0b.0: GetStatus roothub.portstatus [5] =
0x00100303 PRSC LSDA PPS PES CCS

[ 2.505052] usb 2-6: skipped 1 descriptor after interface
[ 2.505058] usb 2-6: skipped 1 descriptor after interface
[ 2.508047] usb 2-6: default language 0x0409
[ 2.514051] usb 2-6: udev 3, busnum 2, minor = 130
[ 2.514056] usb 2-6: New USB device found, idVendor=046d, idProduct=c31d
[ 2.514061] usb 2-6: New USB device strings: Mfr=1, Product=2,
SerialNumber=0

[ 2.514065] usb 2-6: Product: USB Keyboard
[ 2.514069] usb 2-6: Manufacturer: Logitech
[ 2.514216] usb 2-6: usb_probe_device
[ 2.514222] usb 2-6: configuration #1 chosen from 1 choice
[ 2.520050] usb 2-6: adding 2-6:1.0 (config #1, interface 0)
[ 2.523104] usb 2-6: adding 2-6:1.1 (config #1, interface 1)
[ 2.526105] drivers/usb/core/inode.c: creating file '003'
[ 2.526151] hub 2-0:1.0: state 7 ports 8 chg 0000 evt 0040
[ 2.529498] ata5.00: n_sectors mismatch 321670847 != 321672960
[ 2.529502] ata5.00: new n_sectors matches native, probably late HPA
unlock, n_sectors updated
[ 2.529514] ata5: nv_mode_filter: 0x7f39f&0x7f39f->0x7f39f,
BIOS=0x7f000 (0xc7c60000) ACPI=0x7f01f (15:20:0x1f)
[ 2.529520] ata5: nv_mode_filter: 0x3f39f&0x3f39f->0x3f39f,
BIOS=0x3f000 (0xc7c60000) ACPI=0x3f01f (15:20:0x1f)

[ 2.533120] usbhid 2-5:1.0: usb_probe_interface
[ 2.533125] usbhid 2-5:1.0: usb_probe_interface - got id
[ 2.539385] input: Logitech USB Optical Mouse as
/devices/pci0000:00/0000:00:0b.0/usb2/2-5/2-5:1.0/input/input2
[ 2.539505] generic-usb 0003:046D:C05A.0001: input,hidraw0: USB HID
v1.11 Mouse [Logitech USB Optical Mouse] on usb-0000:00:0b.0-5/input0

[ 2.539517] usbhid 2-6:1.0: usb_probe_interface
[ 2.539521] usbhid 2-6:1.0: usb_probe_interface - got id
[ 2.544436] input: Logitech USB Keyboard as
/devices/pci0000:00/0000:00:0b.0/usb2/2-6/2-6:1.0/input/input3

[ 2.544477] ata5.00: configured for UDMA/133
[ 2.544518] generic-usb 0003:046D:C31D.0002: input,hidraw1: USB HID
v1.10 Keyboard [Logitech USB Keyboard] on usb-0000:00:0b.0-6/input0

[ 2.544532] usbhid 2-6:1.1: usb_probe_interface
[ 2.544535] usbhid 2-6:1.1: usb_probe_interface - got id
[ 2.552403] ata5.01: configured for UDMA/100
[ 2.552411] ata5: EH complete
[ 2.552535] sd 4:0:0:0: [sda] 321672960 512-byte logical blocks: (164
GB/153 GiB)

[ 2.552570] sd 4:0:1:0: [sdb] Attached SCSI disk
[ 2.553114] sda: detected capacity change from 164695473664 to
164696555520
[ 2.558181] input: Logitech USB Keyboard as
/devices/pci0000:00/0000:00:0b.0/usb2/2-6/2-6:1.1/input/input4
[ 2.558276] generic-usb 0003:046D:C31D.0003: input,hidraw2: USB HID
v1.10 Device [Logitech USB Keyboard] on usb-0000:00:0b.0-6/input1

[ 2.558310] usbcore: registered new interface driver usbhid
[ 2.558312] usbhid: USB HID core driver
[ 2.682848] sda: sda1 sda2 sda4 < sda5 sda6 sda7 >
[ 2.688433] sd 4:0:0:0: [sda] Attached SCSI disk
[ 2.688504] sda: detected capacity change from 0 to 164696555520
[ 3.318799] [drm] Initialized drm 1.1.0 20060810
[ 3.325599] [drm:i915_init] *ERROR* drm/i915 can't work without
intel_agp module!

[ 3.341091] device-mapper: uevent: version 1.0.3
[ 3.341169] device-mapper: ioctl: 4.22.0-ioctl (2011-10-19)
initialised: dm-devel@redhat.com

[ 3.348252] PM: Starting manual resume from disk
[ 3.348256] PM: Hibernation image partition 8:5 present
[ 3.348258] PM: Looking for hibernation image.
[ 3.348448] PM: Image not found (code -22)
[ 3.348452] PM: Hibernation image not present or could not be loaded.
[ 3.352794] PM: Marking nosave pages: 000000000009f000 -
0000000000100000

[ 3.352801] PM: Basic memory bitmaps created
[ 3.382723] PM: Basic memory bitmaps freed
[ 3.465823] kjournald starting. Commit interval 5 seconds
[ 3.465867] EXT3-fs (sda5): mounted filesystem with ordered data mode
[ 3.808772] hub 1-0:1.0: hub_suspend
[ 3.808786] usb usb1: bus auto-suspend, wakeup 1
[ 3.808791] ehci_hcd 0000:00:0b.1: suspend root hub
[ 6.258259] udevd[395]: starting version 175
[ 7.252867] [drm] radeon kernel modesetting enabled.
[ 7.253233] ACPI: PCI Interrupt Link [APC5] enabled at IRQ 16
[ 7.253467] [drm] initializing kernel modesetting (CEDAR 0x1002:0x68E1
0x174B:0x6000).

[ 7.253499] [drm] register mmio base: 0xD1000000
[ 7.253501] [drm] register mmio size: 131072
[ 7.253880] ATOM BIOS: PARK
[ 7.253961] radeon 0000:01:00.0: VRAM: 1024M 0x0000000000000000 -
0x000000003FFFFFFF (1024M used)
[ 7.253965] radeon 0000:01:00.0: GTT: 512M 0x0000000040000000 -
0x000000005FFFFFFF

[ 7.255014] [drm] Detected VRAM RAM=1024M, BAR=256M
[ 7.255019] [drm] RAM width 64bits DDR
[ 7.255112] [TTM] Zone kernel: Available graphics memory: 367472 kiB.
[ 7.255115] [TTM] Zone highmem: Available graphics memory: 1553748 kiB.
[ 7.255118] [TTM] Initializing pool allocator.
[ 7.255124] [TTM] Initializing DMA pool allocator.
[ 7.255157] [drm] radeon: 1024M of VRAM memory ready
[ 7.255159] [drm] radeon: 512M of GTT memory ready.
[ 7.255180] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010).
[ 7.255182] [drm] Driver supports precise vblank timestamp query.
[ 7.255233] radeon 0000:01:00.0: irq 45 for MSI/MSI-X
[ 7.255247] radeon 0000:01:00.0: radeon: using MSI.
[ 7.255283] [drm] radeon: irq initialized.
[ 7.255289] [drm] GART: num cpu pages 131072, num gpu pages 131072
[ 7.256101] [drm] radeon: ib pool ready.
[ 7.256225] [drm] Loading CEDAR Microcode
[ 7.394623] parport_pc 00:09: reported by Plug and Play ACPI
[ 7.394696] parport0: PC-style at 0x378 (0x778), irq 7 [PCSPP,TRISTATE]
[ 7.471634] ACPI Warning: 0x00005000-0x0000503f SystemIO conflicts with
Region \_SB_.PCI0.SM00 1 (20120111/utaddress-251)
[ 7.471643] ACPI: If an ACPI driver is available for this device, you
should use it instead of the native driver

[ 7.471698] i2c i2c-0: nForce2 SMBus adapter at 0x5100
[ 7.498454] NV_TCO: NV TCO WatchDog Timer Driver v0.01
[ 7.498565] NV_TCO: Watchdog reboot not detected.
[ 7.498944] NV_TCO: initialized (0x4440). heartbeat=30 sec (nowayout=0)
[ 7.583354] input: PC Speaker as /devices/platform/pcspkr/input/input5
[ 8.316783] [drm] PCIE GART of 512M enabled (table at
0x0000000000040000).

[ 8.316899] radeon 0000:01:00.0: WB enabled
[ 8.316903] [drm] fence driver on ring 0 use gpu addr 0x40000c00 and
cpu addr 0xffba7c00

[ 8.333103] [drm] ring test on 0 succeeded in 1 usecs
[ 8.333280] [drm] ib test on ring 0 succeeded in 0 usecs
[ 8.333690] [drm] Radeon Display Connectors
[ 8.333692] [drm] Connector 0:
[ 8.333694] [drm] HDMI-A
[ 8.333696] [drm] HPD2
[ 8.333698] [drm] DDC: 0x6440 0x6440 0x6444 0x6444 0x6448 0x6448
0x644c 0x644c

[ 8.333701] [drm] Encoders:
[ 8.333703] [drm] DFP1: INTERNAL_UNIPHY1
[ 8.333705] [drm] Connector 1:
[ 8.333706] [drm] DVI-I
[ 8.333708] [drm] HPD4
[ 8.333710] [drm] DDC: 0x6460 0x6460 0x6464 0x6464 0x6468 0x6468
0x646c 0x646c

[ 8.333712] [drm] Encoders:
[ 8.333714] [drm] DFP2: INTERNAL_UNIPHY
[ 8.333716] [drm] CRT1: INTERNAL_KLDSCP_DAC1
[ 8.333718] [drm] Connector 2:
[ 8.333720] [drm] VGA
[ 8.333722] [drm] DDC: 0x6430 0x6430 0x6434 0x6434 0x6438 0x6438
0x643c 0x643c

[ 8.333725] [drm] Encoders:
[ 8.333726] [drm] CRT2: INTERNAL_KLDSCP_DAC2
[ 8.333766] [drm] Internal thermal controller with fan control
[ 8.333819] [drm] radeon: power management initialized
[ 8.486888] [drm] fb mappable at 0xC0142000
[ 8.486891] [drm] vram apper at 0xC0000000
[ 8.486893] [drm] size 8294400
[ 8.486895] [drm] fb depth is 24
[ 8.486897] [drm] pitch is 7680
[ 8.487001] fbcon: radeondrmfb (fb0) is primary device
[ 8.696036] ACPI: PCI Interrupt Link [AAZA] enabled at IRQ 23
[ 8.696041] hda_intel: Disabling MSI
[ 8.696069] snd_hda_intel 0000:00:10.1: setting latency timer to 64
[ 8.745425] Console: switching to colour frame buffer device 160x64
[ 8.754312] fb0: radeondrmfb frame buffer device
[ 8.754314] drm: registered panic notifier
[ 8.754383] [drm] Initialized radeon 2.13.0 20080528 for 0000:01:00.0
on minor 0
[ 9.410229] input: HDA Digital PCBeep as
/devices/pci0000:00/0000:00:10.1/input/input6

[ 9.560458] ACPI: PCI Interrupt Link [APC6] enabled at IRQ 16
[ 9.560532] snd_hda_intel 0000:01:00.1: irq 46 for MSI/MSI-X
[ 9.820569] HDMI status: Codec=0 Pin=3 Presence_Detect=0 ELD_Valid=0
[ 9.820688] input: HD-Audio Generic HDMI/DP,pcm=3 as
/devices/pci0000:00/0000:00:02.0/0000:01:00.1/sound/card1/input7
[ 11.365474] Adding 1956180k swap on /dev/sda6. Priority:-1 extents:1
across:1956180k

[ 12.045301] EXT3-fs (sda5): using internal journal
[ 18.987361] kjournald starting. Commit interval 5 seconds
[ 18.987627] EXT3-fs (sda2): using internal journal
[ 18.987632] EXT3-fs (sda2): mounted filesystem with ordered data mode
[ 19.062655] FAT-fs (sdb2): utf8 is not a recommended IO charset for FAT
filesystems, filesystem will be case sensitive!

[ 19.131619] kjournald starting. Commit interval 5 seconds
[ 19.131821] EXT3-fs (sda1): using internal journal
[ 19.131827] EXT3-fs (sda1): mounted filesystem with ordered data mode
[ 19.311846] EXT4-fs (sda7): mounted filesystem with ordered data mode.
Opts: (null)

[ 21.292570] fuse init (API version 7.18)
[ 23.372312] ttyS1: LSR safety check engaged!
[ 23.373174] ttyS1: LSR safety check engaged!
[ 24.813744] input: ACPI Virtual Keyboard Device as
/devices/virtual/input/input8

[ 34.024643] lp0: using parport0 (interrupt-driven).
[ 34.407696] ppdev: user-space parallel port driver
[ 37.138306] 8139too 0000:06:07.0: eth0: link down
[ 37.138588] ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 37.152262] skge 0000:06:0c.0: eth1: enabling interface
[ 37.155659] ADDRCONF(NETDEV_UP): eth1: link is not ready
[ 38.839136] skge 0000:06:0c.0: eth1: Link is up at 100 Mbps, full
duplex, flow control both

[ 38.839372] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[ 45.535376] Ebtables v2.0 registered
[ 45.988565] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 46.028436] ip6_tables: (C) 2000-2006 Netfilter Core Team
[ 46.893649] usb usb1: usb auto-resume
[ 46.893655] ehci_hcd 0000:00:0b.1: resume root hub
[ 46.944153] hub 1-0:1.0: hub_resume
[ 46.944199] hub 1-0:1.0: state 7 ports 8 chg 0000 evt 0000
[ 65.804040] hub 1-0:1.0: hub_suspend
[ 65.804053] usb usb1: bus auto-suspend, wakeup 1
[ 65.804058] ehci_hcd 0000:00:0b.1: suspend root hub
[ 81.642706] postgres (4166): /proc/4166/oom_adj is deprecated, please
use /proc/4166/oom_score_adj instead.




> Also, what does the "lspci -vv" output show for the controller if you
> run it with superuser permissions?

[Sat Jul 07 12:50:10 -0700 -- alvarezp@octavio:/sys/kernel/debug/usb]
$ sudo lspci -vv -s 0000:00:0b.1


0b.1 is the EHCI controller. We want to see the OHCI controller, 0b.0.


(Already replied in another message but I repeat it here. Taken without
my pen drive.)

[Sat Jul 07 23:36:02 -0700 -- alvarezp@octavio:~]
$ sudo lspci -vv -s 0000:00:0b.0
00:0b.0 USB controller: NVIDIA Corporation MCP51 USB Controller (rev a2)
(prog-if 10 [OHCI])

Subsystem: ASUSTeK Computer Inc. A8N-VM CSM Mainboard
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr-
Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort-
<MAbort- >SERR- <PERR- INTx-

Latency: 0 (750ns min, 250ns max)
Interrupt: pin A routed to IRQ 23
Region 0: Memory at d5006000 (32-bit, non-prefetchable) [size=4K]
Capabilities: [44] Power Management version 2
Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=0mA
PME(D0+,D1+,D2+,D3hot+,D3cold+)

Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-
Kernel driver in use: ohci_hcd




Can you also post a dmesg log showing a full suspend/immediate-resume
cycle with CONFIG_USB_DEBUG enabled?


This is the continuation of the previous dmesg, starting on second 178,
when I pressed the power button.

(done without my pen drive)

[ 178.165344] skge 0000:06:0c.0: eth1: disabling interface
[ 180.463060] PM: Syncing filesystems ... done.
[ 180.679249] PM: Preparing system for mem sleep
[ 180.700935] Freezing user space processes ... (elapsed 0.01 seconds)
done.
[ 180.716091] Freezing remaining freezable tasks ... (elapsed 0.01
seconds) done.

[ 180.732082] PM: Entering mem sleep
[ 180.732104] Suspending console(s) (use no_console_suspend to debug)
[ 180.732526] sd 4:0:1:0: [sdb] Synchronizing SCSI cache
[ 180.732529] sd 4:0:0:0: [sda] Synchronizing SCSI cache
[ 180.732613] usb 2-5: usb suspend, wakeup 0
[ 180.732715] sd 4:0:1:0: [sdb] Stopping disk
[ 180.732923] usb usb1: usb auto-resume
[ 180.732928] ehci_hcd 0000:00:0b.1: resume root hub
[ 180.767535] sd 4:0:0:0: [sda] Stopping disk
[ 180.767863] parport_pc 00:09: disabled
[ 180.767991] serial 00:08: disabled
[ 180.767998] serial 00:08: wake-up capability disabled by ACPI
[ 180.770361] usb 2-6: usb suspend, wakeup 1
[ 180.784036] hub 2-0:1.0: hub_suspend
[ 180.784042] usb usb2: bus suspend, wakeup 0
[ 180.784045] ohci_hcd 0000:00:0b.0: suspend root hub
[ 180.804023] hub 1-0:1.0: hub_resume
[ 180.804051] hub 1-0:1.0: hub_suspend
[ 180.804058] usb usb1: bus suspend, wakeup 0
[ 180.804062] ehci_hcd 0000:00:0b.1: suspend root hub
[ 180.872152] ACPI handle has no context!
[ 181.140060] PM: suspend of devices complete after 407.683 msecs
[ 181.140302] pci 0000:00:10.0: wake-up capability enabled by ACPI
[ 181.156141] ehci_hcd 0000:00:0b.1: wakeup: 1
[ 181.156202] ehci_hcd 0000:00:0b.1: wake-up capability enabled by ACPI
[ 181.172036] ehci_hcd 0000:00:0b.1: --> PCI D3hot
[ 181.172066] ohci_hcd 0000:00:0b.0: wakeup: 1
[ 181.172079] ohci_hcd 0000:00:0b.0: wake-up capability enabled by ACPI
[ 181.188035] ohci_hcd 0000:00:0b.0: --> PCI D3hot
[ 181.188405] PM: late suspend of devices complete after 48.340 msecs
[ 181.188732] ACPI: Preparing to enter system sleep state S3
[ 181.189094] PM: Saving platform NVS memory
[ 181.189125] Disabling non-boot CPUs ...
[ 181.292034] CPU 1 is now offline
[ 181.292601] Extended CMOS year: 2000
[ 181.292601] ACPI: Low-level resume complete
[ 181.292601] PM: Restoring platform NVS memory
[ 181.292601] Extended CMOS year: 2000
[ 181.292601] Enabling non-boot CPUs ...
[ 181.292601] Booting Node 0 Processor 1 APIC 0x1
[ 181.292601] smpboot cpu 1: start_ip = 9b000
[ 181.190475] Initializing CPU#1
[ 181.190475] Calibrating delay loop (skipped) already calibrated this CPU
[ 181.325670]