Re: [Xen-devel] Lose XHCI Device on HP Ivybridge While Resuming on Battery

[Tom Goetz <tom.goetz@xxxxxxxxxxxxxxxxxxx>]

On Mar 5, 2012, at 12:09 PM, xen-devel-request@xxxxxxxxxxxxx wrote:

> Send Xen-devel mailing list submissions to
>       xen-devel@xxxxxxxxxxxxx
> 
> 
> 
> Today's Topics:
> 
>   2. Lose XHCI Device on HP Ivybridge While Resuming on       Battery
>      (Tom Goetz)
> 
> 
> 
> Message: 2
> Date: Mon, 5 Mar 2012 11:11:10 -0500
> From: Tom Goetz <tom.goetz@xxxxxxxxxxxxxxxxxxx>
> To: xen-devel@xxxxxxxxxxxxx
> Subject: [Xen-devel] Lose XHCI Device on HP Ivybridge While Resuming
>       on      Battery
> Message-ID: <5D6CE9D4-9859-47F7-9885-7A8CAD00AF64@xxxxxxxxxxxxxxxxxxx>
> Content-Type: text/plain; charset=us-ascii
> 
> After resuming more than once on battery these Ivybridge laptop, the XHCI 
> devices stop working. Have anyone seen this before? I wanted to check before 
> diving in deeper. Let me know if you have any ideas. Thanks!
> 

I have more information on this. Here's the sequence of events:

1. XHCI driver removed with SUSPEND_MODULES
2. suspend
3. resume
4. XHCI driver loaded by SUSPEND_MODULES
5. Device is enabled by driver.
6. Device disabled by runtime PM.
7. Plugging in a USB device does not result in the device being enabled.

This works on the same machine in Ubuntu 12.04 Beta 1 (Linux 3.2.0) native 
which is very similar to our Ubuntu 12.04 based (Linux 3.2.5 & 3.2.9) build. So 
either this was broken between 3.2.0 and 3.2.5 or this is Xen related. I've 
included some tracing of resume and runtime pm suspend of the device. A USB 
flash stick was plugged in at the end of the log where nothing happens:


Mar  8 21:57:47 newt kernel: [  407.323444] __pm_runtime_resume[879] 
0000:00:14.0 RPM_GET_PUT 2

Mar  8 21:57:47 newt kernel: [  407.323451] rpm_resume[512] 0000:00:14.0 before

Mar  8 21:57:47 newt kernel: [  407.323461] __pm_runtime_idle[811] 0000:00:14.0 
RPM_GET_PUT 1

Mar  8 21:57:47 newt kernel: [  407.323477] __pm_runtime_resume[879] 
0000:00:14.0 RPM_GET_PUT 2

Mar  8 21:57:47 newt kernel: [  407.323487] rpm_resume[512] 0000:00:14.0 before

Mar  8 21:57:47 newt kernel: [  407.323489] xhci_hcd 0000:00:14.0: remove, 
state 4

Mar  8 21:57:47 newt kernel: [  407.323530] rpm_resume[512] 0000:00:14.0 before

Mar  8 21:57:47 newt kernel: [  407.323532] __pm_runtime_idle[811] 0000:00:14.0 
RPM_GET_PUT 2

Mar  8 21:57:47 newt kernel: [  407.323731] xhci_hcd 0000:00:14.0: USB bus 4 
deregistered

Mar  8 21:57:47 newt kernel: [  407.323820] xhci_hcd 0000:00:14.0: remove, 
state 4

Mar  8 21:57:47 newt kernel: [  407.323862] rpm_resume[512] 0000:00:14.0 before

Mar  8 21:57:47 newt kernel: [  407.323873] __pm_runtime_idle[811] 0000:00:14.0 
RPM_GET_PUT 3

Mar  8 21:57:47 newt kernel: [  407.324123] xhci_hcd 0000:00:14.0: USB bus 3 
deregistered

Mar  8 21:57:47 newt kernel: [  407.324232] pci_disable_device[1345] 
0000:00:14.0

Mar  8 21:57:47 newt kernel: [  407.324244] xhci_hcd 0000:00:14.0: can't derive 
routing for PCI INT A

Mar  8 21:57:47 newt kernel: [  407.324246] pci_disable_device[1353] 
0000:00:14.0 busmaster off

Mar  8 21:57:57 newt kernel: [  409.053506] rpm_resume[512] 0000:00:14.0 before

Mar  8 21:57:57 newt kernel: [  413.422310] pci 0000:00:14.0: restoring config 
space at offset 0xf (was 0x100, writing 0x10a)

Mar  8 21:57:57 newt kernel: [  413.422354] pci 0000:00:14.0: restoring config 
space at offset 0x4 (was 0x4, writing 0x94720004)

Mar  8 21:57:57 newt kernel: [  413.422368] pci 0000:00:14.0: restoring config 
space at offset 0x1 (was 0x2900000, writing 0x2900002)

Mar  8 21:57:57 newt kernel: [  413.424862] __pm_runtime_idle[811] 0000:00:14.0 
RPM_GET_PUT 2

Mar  8 21:57:58 newt kernel: [  414.122464] __pm_runtime_idle[811] 0000:00:14.0 
RPM_GET_PUT 1

Mar  8 21:57:59 newt kernel: [  415.758316] __pci_enable_device_flags[1129] 
0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.758323] __pci_enable_device_flags[1133] 
0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.758342] xhci_hcd 0000:00:14.0: can't derive 
routing for PCI INT A

Mar  8 21:57:59 newt kernel: [  415.758348] xhci_hcd 0000:00:14.0: PCI INT A: 
no GSI - using ISA IRQ 10

Mar  8 21:57:59 newt kernel: [  415.758378] __pci_enable_device_flags[1145] 
0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.758481] pci_set_master[2620] 0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.758485] __pci_set_master[2593] 0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.758510] __pci_set_master[2606] 0000:00:14.0 
enabling pci_write_config_word ret 0

Mar  8 21:57:59 newt kernel: [  415.758519] xhci_hcd 0000:00:14.0: setting 
latency timer to 64

Mar  8 21:57:59 newt kernel: [  415.758529] xhci_hcd 0000:00:14.0: xHCI Host 
Controller

Mar  8 21:57:59 newt kernel: [  415.758771] xhci_hcd 0000:00:14.0: new USB bus 
registered, assigned bus number 3

Mar  8 21:57:59 newt kernel: [  415.758924] xhci_hcd 0000:00:14.0: cache line 
size of 64 is not supported

Mar  8 21:57:59 newt kernel: [  415.758957] xhci_hcd 0000:00:14.0: irq 10, io 
mem 0x94720000

Mar  8 21:57:59 newt kernel: [  415.759002] pci_enable_msi_block[699] 
0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.759027] pci_enable_msi_block[703] 
0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.759036] pci_enable_msi_block[709] 
0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.759062] pci_enable_msi_block[716] 
0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.759066] pci_enable_msi_block[724] 
0000:00:14.0

Mar  8 21:57:59 newt kernel: [  415.821636] xhci_hcd 0000:00:14.0: xHCI Host 
Controller

Mar  8 21:57:59 newt kernel: [  415.821879] xhci_hcd 0000:00:14.0: new USB bus 
registered, assigned bus number 4

Mar  8 21:57:59 newt kernel: [  415.971575] __pm_runtime_idle[811] 0000:00:14.0 
RPM_GET_PUT 1

Mar  8 21:57:59 newt kernel: [  416.403188] pm_runtime_allow[1156] 0000:00:14.0 
1

Mar  8 21:57:59 newt kernel: [  416.403195] pm_runtime_allow[1159] 0000:00:14.0 
IDLE

Mar  8 21:57:59 newt kernel: [  416.403202] rpm_idle[206] 0000:00:14.0 before

Mar  8 21:57:59 newt kernel: [  416.403208] rpm_check_suspend_allowed[145] 
0000:00:14.0 children

Mar  8 21:57:59 newt kernel: [  416.403214] rpm_idle[210] 0000:00:14.0 
!conditions

Mar  8 21:58:01 newt kernel: [  418.441612] rpm_suspend[474] usb3 parent 
0000:00:14.0

Mar  8 21:58:01 newt kernel: [  418.441613] rpm_idle[206] 0000:00:14.0 before

Mar  8 21:58:01 newt kernel: [  418.441615] rpm_check_suspend_allowed[145] 
0000:00:14.0 children

Mar  8 21:58:01 newt kernel: [  418.441616] rpm_idle[210] 0000:00:14.0 
!conditions

Mar  8 21:58:01 newt kernel: [  418.441630] rpm_suspend[474] usb4 parent 
0000:00:14.0

Mar  8 21:58:01 newt kernel: [  418.441631] rpm_idle[206] 0000:00:14.0 before

Mar  8 21:58:01 newt kernel: [  418.441635] pm_runtime_work[708] 0000:00:14.0 
RPM_REQ_IDLE

Mar  8 21:58:01 newt kernel: [  418.441636] rpm_idle[206] 0000:00:14.0 before

Mar  8 21:58:01 newt kernel: [  418.441639] pci_pm_runtime_idle[1064] 
0000:00:14.0 before

Mar  8 21:58:01 newt kernel: [  418.441640] pci_pm_runtime_idle[1074] 
0000:00:14.0 pm_runtime_suspend

Mar  8 21:58:01 newt kernel: [  418.441642] __pm_runtime_suspend[849] 
0000:00:14.0 before

Mar  8 21:58:01 newt kernel: [  418.441643] rpm_suspend[323] 0000:00:14.0 before

Mar  8 21:58:01 newt kernel: [  418.441644] rpm_suspend[419] 0000:00:14.0 
RPM_SUSPENDING

Mar  8 21:58:01 newt kernel: [  418.441646] pci_pm_runtime_suspend[1016] 
0000:00:14.0 before

Mar  8 21:58:01 newt kernel: [  418.441648] hcd_pci_runtime_suspend[564] 
0000:00:14.0 before

Mar  8 21:58:01 newt kernel: [  418.441649] suspend_common[389] 0000:00:14.0 
before

Mar  8 21:58:01 newt kernel: [  418.441688] pci_disable_device[1345] 
0000:00:14.0

Mar  8 21:58:01 newt kernel: [  418.441697] pci_disable_device[1353] 
0000:00:14.0 busmaster off

Mar  8 21:58:01 newt kernel: [  418.441850] suspend_common[436] 0000:00:14.0 
after

Mar  8 21:58:01 newt kernel: [  418.441852] hcd_pci_runtime_suspend[569] 
0000:00:14.0 afyer ret 0

Mar  8 21:58:01 newt kernel: [  418.441853] pci_pm_runtime_suspend[1018] 
0000:00:14.0 after

Mar  8 21:58:01 newt kernel: [  418.441917] xhci_hcd 0000:00:14.0: PME# enabled

Mar  8 21:58:01 newt kernel: [  418.461530] pci_pm_runtime_suspend[1037] 
0000:00:14.0 done

Mar  8 21:58:01 newt kernel: [  418.461544] rpm_suspend[474] 0000:00:14.0 
parent pci0000:00

Mar  8 21:58:01 newt kernel: [  418.461553] __pm_runtime_suspend[853] 
0000:00:14.0 after

Mar  8 21:58:01 newt kernel: [  418.461555] pci_pm_runtime_idle[1076] 
0000:00:14.0 after



_______________________________________________
Xen-devel mailing list
Xen-devel@xxxxxxxxxxxxx
http://lists.xen.org/xen-devel