* 3.8.2: xhci port is dead until pcieport PME# goes to disabled
@ 2013-03-14 17:30 Martin Mokrejs
2013-03-18 17:01 ` Sarah Sharp
0 siblings, 1 reply; 7+ messages in thread
From: Martin Mokrejs @ 2013-03-14 17:30 UTC (permalink / raw)
To: USB list, Sarah Sharp, Alan Stern, Lan Tianyu,
linux-pci@vger.kernel.org
[resend, no dmesg attached]
Hi,
happened to me again that I plugged in some devcie into my USB3 socket
in my laptop (usually use an external HUB). Don't believe this is a new
issue as a whole, I am facing this time to time since 3.3 time when I bought
the laptop.
But now, I realized the first device works but subsequently plugged in
devices do not show up until until I run 'lsusb -vv'. Simple 'lsusb -v'
or 'lsusb -t' is not enough. The port starts to work after dmesg logs:
[ 1445.597641] pcieport 0000:00:1c.4: PME# disabled
[ 1445.617667] xhci_hcd 0000:0b:00.0: PME# disabled
I believe lsusb -vv while querying for the detailed device info triggers
a fix.
Funny, but maybe this will help us to understand why my express card slot
somehow relates to the USB3 proivided by the TexasInstruments chip. This
is a SandyBridge-based Dell Vostro 3550 laptop. I should add that on the usb
mailing list about a year ago some TexasInstruments developer published that
they had a hardware bug in a "USB redriver", and as my laptop was made in
fall 2011 I am likely having having the buggy HW. If I remember right, the
report was about the problem that once you pluging a USB2 device into the XHCI
slot, subsequently plugged in USB3 devices won't negotiate at SuperSpeed until
you power off computer. So, I think I face a different issue but had to mention
this.
I use pcie_aspm=off on my kernel command line. I tried to catch some usb traffic
on the dead port but the file from usbmon is really empty until I do the
'lsusb -vv'. I tried to turn off the usb powersaving at runtime but I do not
have the file to do:
# echo -n -1 > /sys/bus/usb/devices/3-0\:1.0/power/autosuspend
:(
# ls -la /sys/bus/usb/devices/3-0\:1.0/power/
total 0
drwxr-xr-x 2 root root 0 Mar 14 13:11 .
drwxr-xr-x 6 root root 0 Mar 14 13:58 ..
-rw-r--r-- 1 root root 4096 Mar 14 13:11 async
-r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_active_kids
-r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_enabled
-r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_status
-r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_usage
#
I feel related could be patches under thread: "usb: add usb port auto power off mechanism"
Below is a dmesg since a cold boot. I plugged in a mouse into the USB3 socket.
[ 91.463285] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
[ 91.463377] xhci_hcd 0000:0b:00.0: PME# enabled
[ 447.389700] xhci_hcd 0000:0b:00.0: PME# disabled
[ 447.389711] xhci_hcd 0000:0b:00.0: enabling bus mastering
[ 447.389787] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
[ 447.389819] usb usb3: usb wakeup-resume
Was detected. Unplugged the mouse.
[ 459.600056] usb 3-2: USB disconnect, device number 2
[ 459.600058] usb 3-2: unregistering device
[ 459.600059] usb 3-2: unregistering interface 3-2:1.0
[ 459.600622] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040a641870 ep1in-intr
[ 459.702564] usb 3-2: usb_disable_device nuking all URBs
[ 459.707948] usb 3-2: Successful Endpoint Configure command
[ 459.860892] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
[ 459.860910] hub 3-0:1.0: hub_suspend
[ 459.860917] usb usb3: bus auto-suspend, wakeup 1
[ 459.860981] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
[ 459.861070] xhci_hcd 0000:0b:00.0: PME# enabled
Then I put the mouse back, I think it was not detected after until I did the lsusb
trick. I am not sure, sorry, but is not that important. I was repeating test once
again and just forgot now this detail. So below just that the mouse got detected,
either thanks to lsusb -vv or not.
[ 536.745422] xhci_hcd 0000:0b:00.0: PME# disabled
[ 536.745435] xhci_hcd 0000:0b:00.0: enabling bus mastering
[ 536.745487] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
[ 536.745493] usb usb3: usb auto-resume
[ 536.745509] hub 3-0:1.0: hub_resume
[ 536.745579] hub 3-0:1.0: port 2: status 0301 change 0001
Then a mouse disconnect:
[ 652.232958] usb 3-2: USB disconnect, device number 3
[ 652.232960] usb 3-2: unregistering device
[ 652.232961] usb 3-2: unregistering interface 3-2:1.0
[ 652.233148] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040b1ee288 ep1in-intr
[ 652.333325] usb 3-2: usb_disable_device nuking all URBs
[ 652.337096] usb 3-2: Successful Endpoint Configure command
[ 652.492164] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
[ 652.492183] hub 3-0:1.0: hub_suspend
[ 652.492190] usb usb3: bus auto-suspend, wakeup 1
[ 652.492254] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
[ 652.492341] xhci_hcd 0000:0b:00.0: PME# enabled
[ 652.522157] pcieport 0000:00:1c.4: PME# enabled
[ 688.128198] kmemleak: 1 new suspected memory leaks (see /sys/kernel/debug/kmemleak)
Note above two things pcieport started to mess in. I you look what xhci_hcd printed
in previous attempts the was NO pcieport involved around, at all. Now, provided
that I reported the kmemleak multiple times this year:
unreferenced object 0xffff88040b4a0690 (size 256):
comm "swapper/0", pid 1, jiffies 4294937581 (age 17428.760s)
hex dump (first 32 bytes):
00 00 00 00 ad 4e ad de ff ff ff ff 00 00 00 00 .....N..........
ff ff ff ff ff ff ff ff d8 9f 57 82 ff ff ff ff ..........W.....
backtrace:
[<ffffffff815fcec7>] kmemleak_alloc+0x21/0x3e
[<ffffffff81117ac4>] slab_post_alloc_hook+0x28/0x2a
[<ffffffff8111a18f>] __kmalloc+0xf3/0x105
[<ffffffff8132b20c>] kzalloc.constprop.14+0xe/0x10
[<ffffffff8132b66d>] device_private_init+0x14/0x63
[<ffffffff8132d790>] dev_set_drvdata+0x19/0x2f
[<ffffffff813f19d1>] i801_probe+0x5e/0x41a
[<ffffffff812924d8>] local_pci_probe+0x39/0x61
[<ffffffff812936fb>] pci_device_probe+0xc6/0xf3
[<ffffffff8132dd59>] driver_probe_device+0xa9/0x1c1
[<ffffffff8132decb>] __driver_attach+0x5a/0x7e
[<ffffffff8132c5f3>] bus_for_each_dev+0x5c/0x88
[<ffffffff8132d8f6>] driver_attach+0x19/0x1b
[<ffffffff8132d4c6>] bus_add_driver+0xa8/0x1fa
[<ffffffff8132e33d>] driver_register+0x8c/0x106
[<ffffffff812932f6>] __pci_register_driver+0x5c/0x60
I believe this is related to the issue, somehow.
Now I started usbmon, so lets follow another attempt to repeat the bug. For clarity,
I will plug into the socket a kayboard, and it won't work until I do 'lsusb -vv'.
I pressed then space on the keyboard and that worked immediately (keyboard was dead
until lsusb -vv and also dmesg did not log anything).
So, lsusb -vv:
[ 802.986036] pcieport 0000:00:1c.4: PME# disabled
[ 803.016008] xhci_hcd 0000:0b:00.0: PME# disabled
[ 803.016021] xhci_hcd 0000:0b:00.0: enabling bus mastering
[ 803.016078] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
[ 803.016085] usb usb3: usb auto-resume
[ 803.016103] hub 3-0:1.0: hub_resume
[ 803.016172] hub 3-0:1.0: port 2: status 0301 change 0001
unplug of the keyboard:
[ 821.621331] usb usb4: usb auto-resume
[ 821.621347] hub 4-0:1.0: hub_resume
[ 821.621405] hub 4-0:1.0: state 7 ports 2 chg 0000 evt 0000
[ 821.622047] hub 4-0:1.0: hub_suspend
[ 821.622053] usb usb4: bus auto-suspend, wakeup 1
[ 967.433533] hub 3-0:1.0: state 7 ports 2 chg 0000 evt 0004
[ 967.433768] hub 3-0:1.0: port 2, status 0100, change 0001, 12 Mb/s
[ 967.433771] usb 3-2: USB disconnect, device number 4
[ 967.433772] usb 3-2: unregistering device
[ 967.433775] usb 3-2: unregistering interface 3-2:1.0
[ 967.433946] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040a641948 ep1in-intr
[ 967.579130] usb 3-2: usb_disable_device nuking all URBs
[ 967.584494] usb 3-2: Successful Endpoint Configure command
[ 967.798027] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
[ 967.798046] hub 3-0:1.0: hub_suspend
[ 967.798075] usb usb3: bus auto-suspend, wakeup 1
[ 967.798155] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
[ 967.798242] xhci_hcd 0000:0b:00.0: PME# enabled
[ 967.828015] pcieport 0000:00:1c.4: PME# enabled
You see? pcieport is messing with
00:1c.4 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI Express Root Port 5 (rev b5) (prog-if 00 [Normal decode])
I unplugged and replugged the keyboard multiple times and it did not work, so
I connected the mouse used in the very beginning right after cold boot, still
not working. But these were likely logged due to that.
[ 1063.263014] pcieport 0000:00:1c.4: PME# disabled
[ 1063.272918] pcieport 0000:00:1c.4: PME# enabled
[ 1063.312915] pcieport 0000:00:1c.4: PME# disabled
[ 1063.322906] pcieport 0000:00:1c.4: PME# enabled
[ 1070.132666] pcieport 0000:00:1c.4: PME# disabled
[ 1070.142559] pcieport 0000:00:1c.4: PME# enabled
[ 1070.182601] pcieport 0000:00:1c.4: PME# disabled
[ 1070.192644] pcieport 0000:00:1c.4: PME# enabled
After doing 'lsusb -vv' I rescued the USB3 socket and it detected the mouse.
[ 1108.504953] pcieport 0000:00:1c.4: PME# disabled
[ 1108.525010] xhci_hcd 0000:0b:00.0: PME# disabled
[ 1108.525023] xhci_hcd 0000:0b:00.0: enabling bus mastering
[ 1108.525069] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
[ 1108.525075] usb usb3: usb auto-resume
[ 1108.525094] hub 3-0:1.0: hub_resume
[ 1108.525196] hub 3-0:1.0: port 2: status 0301 change 0001
I unplugged the mouse:
[ 1230.618019] usb 3-2: unregistering device
[ 1230.618020] usb 3-2: unregistering interface 3-2:1.0
[ 1230.618188] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040b1ee288 ep1in-intr
[ 1230.711764] usb 3-2: usb_disable_device nuking all URBs
[ 1230.715101] usb 3-2: Successful Endpoint Configure command
[ 1230.870763] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
[ 1230.870782] hub 3-0:1.0: hub_suspend
[ 1230.870797] usb usb3: bus auto-suspend, wakeup 1
[ 1230.870862] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
[ 1230.870950] xhci_hcd 0000:0b:00.0: PME# enabled
[ 1230.900730] pcieport 0000:00:1c.4: PME# enabled
In summary, note that *immediately before the line* "xhci_hcd 0000:0b:00.0: PME# disabled"
must appear "pcieport 0000:00:1c.4: PME# disabled". Don't know who is the blocker.
But in reverse order the PME# are being enabled after a device is unplugged from the USB3
socket.
During the tests I had an eSATA in the express card slot since cold boot but don't think
it is relevant here. The express slot is 01.c7 device and the card would be 11.0.
I will send to Alan and Sarah two files directly (thank you for their inspection): ;)
The first part of testing is in usbmon_0u.bin (repeatedly connecting a mouse).
The latter in usbmon_0u_mouse_unplug_keyboard_insert.bin. Both files should demonstrate
the same issue although in the latter file it might be easier to see because the keyboard
was plugged in after a mouse and was not detected until 'lsusb -vv'.
Below is when a keyboard is connected to the dead USB3 socket:
# diff -u -w lspci_vvvxxx_mouse_unplugged.txt lspci_vvvxxx_mouse_unplugged_keyboard_inserted.txt
--- lspci_vvvxxx_mouse_unplugged.txt 2013-03-14 12:22:34.000000000 +0100
+++ lspci_vvvxxx_mouse_unplugged_keyboard_inserted.txt 2013-03-14 12:25:06.000000000 +0100
@@ -747,7 +747,7 @@
Region 2: Memory at f7d10000 (64-bit, non-prefetchable) [size=8K]
Capabilities: [40] Power Management version 3
Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=100mA PME(D0+,D1+,D2+,D3hot+,D3cold+)
- Status: D3 NoSoftRst+ PME-Enable+ DSel=0 DScale=0 PME-
+ Status: D3 NoSoftRst+ PME-Enable+ DSel=0 DScale=0 PME+
Capabilities: [48] MSI: Enable- Count=1/8 Maskable- 64bit+
Address: 0000000000000000 Data: 0000
Capabilities: [70] Express (v2) Endpoint, MSI 00
@@ -785,7 +785,7 @@
10: 04 00 d0 f7 00 00 00 00 04 00 d1 f7 00 00 00 00
20: 00 00 00 00 00 00 00 00 00 00 00 00 28 10 b3 04
30: 00 00 00 00 40 00 00 00 00 00 00 00 0b 01 00 00
-40: 01 48 83 fe 0b 01 00 00 05 70 86 00 00 00 00 00
+40: 01 48 83 fe 0b 81 00 00 05 70 86 00 00 00 00 00
50: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
60: 30 20 00 00 00 00 00 00 00 00 00 00 00 00 00 00
70: 10 c0 02 00 c3 8f 90 05 00 20 19 00 12 3c 07 00
Now, when the port is rescued by lsusb -vv, the following can be seen:
# diff -u -w lspci_vv_mouse_unplugged_keyboard_inserted.txt lspci_vv_mouse_unplugged_keyboard_inserted_kb_alive.txt
--- lspci_vv_mouse_unplugged_keyboard_inserted.txt 2013-03-14 12:25:13.000000000 +0100
+++ lspci_vv_mouse_unplugged_keyboard_inserted_kb_alive.txt 2013-03-14 12:26:42.000000000 +0100
@@ -276,7 +276,7 @@
Capabilities: [90] Subsystem: Dell Device 04b3
Capabilities: [a0] Power Management version 2
Flags: PMEClk- DSI- D1- D2- AuxCurrent=0mA PME(D0+,D1-,D2-,D3hot+,D3cold+)
- Status: D3 NoSoftRst- PME-Enable+ DSel=0 DScale=0 PME-
+ Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-
Kernel driver in use: pcieport
00:1c.7 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI Express Root Port 8 (rev b5) (prog-if 00 [Normal decode])
@@ -476,14 +476,15 @@
0b:00.0 USB controller: Texas Instruments TUSB73x0 SuperSpeed USB 3.0 xHCI Host Controller (rev 02) (prog-if 30 [XHCI])
Subsystem: Dell Device 04b3
- Control: I/O- Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+
+ 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, Cache Line Size: 64 bytes
Interrupt: pin A routed to IRQ 16
Region 0: Memory at f7d00000 (64-bit, non-prefetchable) [size=64K]
Region 2: Memory at f7d10000 (64-bit, non-prefetchable) [size=8K]
Capabilities: [40] Power Management version 3
Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=100mA PME(D0+,D1+,D2+,D3hot+,D3cold+)
- Status: D3 NoSoftRst+ PME-Enable+ DSel=0 DScale=0 PME+
+ Status: D0 NoSoftRst+ PME-Enable- DSel=0 DScale=0 PME-
Capabilities: [48] MSI: Enable- Count=1/8 Maskable- 64bit+
Address: 0000000000000000 Data: 0000
Capabilities: [70] Express (v2) Endpoint, MSI 00
#
The difference in lsusb output is probably useless as it just shows that once the socket
was working the keyboard was picked up by a driver. Looks like yet another PCI issue.
At least no diffs in iomem. ;)
Thanks,
Martin
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: 3.8.2: xhci port is dead until pcieport PME# goes to disabled
2013-03-14 17:30 3.8.2: xhci port is dead until pcieport PME# goes to disabled Martin Mokrejs
@ 2013-03-18 17:01 ` Sarah Sharp
2013-03-18 17:20 ` Martin Mokrejs
0 siblings, 1 reply; 7+ messages in thread
From: Sarah Sharp @ 2013-03-18 17:01 UTC (permalink / raw)
To: Martin Mokrejs
Cc: USB list, Alan Stern, Lan Tianyu, linux-pci@vger.kernel.org
Hi Martin,
I'm really having trouble following you here. Please don't try to tell
me what you think the root cause of the issue is. Just tell me exactly
(in a paragraph or less) what your symptoms are.
Sending lots of log files and lsusb output at me isn't helpful if I
don't know what your issue is.
Sarah Sharp
On Thu, Mar 14, 2013 at 06:30:53PM +0100, Martin Mokrejs wrote:
> [resend, no dmesg attached]
>
> Hi,
> happened to me again that I plugged in some devcie into my USB3 socket
> in my laptop (usually use an external HUB). Don't believe this is a new
> issue as a whole, I am facing this time to time since 3.3 time when I bought
> the laptop.
> But now, I realized the first device works but subsequently plugged in
> devices do not show up until until I run 'lsusb -vv'. Simple 'lsusb -v'
> or 'lsusb -t' is not enough. The port starts to work after dmesg logs:
>
>
> [ 1445.597641] pcieport 0000:00:1c.4: PME# disabled
> [ 1445.617667] xhci_hcd 0000:0b:00.0: PME# disabled
>
> I believe lsusb -vv while querying for the detailed device info triggers
> a fix.
>
> Funny, but maybe this will help us to understand why my express card slot
> somehow relates to the USB3 proivided by the TexasInstruments chip. This
> is a SandyBridge-based Dell Vostro 3550 laptop. I should add that on the usb
> mailing list about a year ago some TexasInstruments developer published that
> they had a hardware bug in a "USB redriver", and as my laptop was made in
> fall 2011 I am likely having having the buggy HW. If I remember right, the
> report was about the problem that once you pluging a USB2 device into the XHCI
> slot, subsequently plugged in USB3 devices won't negotiate at SuperSpeed until
> you power off computer. So, I think I face a different issue but had to mention
> this.
>
> I use pcie_aspm=off on my kernel command line. I tried to catch some usb traffic
> on the dead port but the file from usbmon is really empty until I do the
> 'lsusb -vv'. I tried to turn off the usb powersaving at runtime but I do not
> have the file to do:
>
> # echo -n -1 > /sys/bus/usb/devices/3-0\:1.0/power/autosuspend
>
> :(
>
> # ls -la /sys/bus/usb/devices/3-0\:1.0/power/
> total 0
> drwxr-xr-x 2 root root 0 Mar 14 13:11 .
> drwxr-xr-x 6 root root 0 Mar 14 13:58 ..
> -rw-r--r-- 1 root root 4096 Mar 14 13:11 async
> -r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_active_kids
> -r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_enabled
> -r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_status
> -r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_usage
> #
>
> I feel related could be patches under thread: "usb: add usb port auto power off mechanism"
>
>
> Below is a dmesg since a cold boot. I plugged in a mouse into the USB3 socket.
>
> [ 91.463285] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
> [ 91.463377] xhci_hcd 0000:0b:00.0: PME# enabled
> [ 447.389700] xhci_hcd 0000:0b:00.0: PME# disabled
> [ 447.389711] xhci_hcd 0000:0b:00.0: enabling bus mastering
> [ 447.389787] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
> [ 447.389819] usb usb3: usb wakeup-resume
>
> Was detected. Unplugged the mouse.
>
> [ 459.600056] usb 3-2: USB disconnect, device number 2
> [ 459.600058] usb 3-2: unregistering device
> [ 459.600059] usb 3-2: unregistering interface 3-2:1.0
> [ 459.600622] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040a641870 ep1in-intr
> [ 459.702564] usb 3-2: usb_disable_device nuking all URBs
> [ 459.707948] usb 3-2: Successful Endpoint Configure command
> [ 459.860892] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
> [ 459.860910] hub 3-0:1.0: hub_suspend
> [ 459.860917] usb usb3: bus auto-suspend, wakeup 1
> [ 459.860981] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
> [ 459.861070] xhci_hcd 0000:0b:00.0: PME# enabled
>
> Then I put the mouse back, I think it was not detected after until I did the lsusb
> trick. I am not sure, sorry, but is not that important. I was repeating test once
> again and just forgot now this detail. So below just that the mouse got detected,
> either thanks to lsusb -vv or not.
>
> [ 536.745422] xhci_hcd 0000:0b:00.0: PME# disabled
> [ 536.745435] xhci_hcd 0000:0b:00.0: enabling bus mastering
> [ 536.745487] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
> [ 536.745493] usb usb3: usb auto-resume
> [ 536.745509] hub 3-0:1.0: hub_resume
> [ 536.745579] hub 3-0:1.0: port 2: status 0301 change 0001
>
> Then a mouse disconnect:
>
> [ 652.232958] usb 3-2: USB disconnect, device number 3
> [ 652.232960] usb 3-2: unregistering device
> [ 652.232961] usb 3-2: unregistering interface 3-2:1.0
> [ 652.233148] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040b1ee288 ep1in-intr
> [ 652.333325] usb 3-2: usb_disable_device nuking all URBs
> [ 652.337096] usb 3-2: Successful Endpoint Configure command
> [ 652.492164] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
> [ 652.492183] hub 3-0:1.0: hub_suspend
> [ 652.492190] usb usb3: bus auto-suspend, wakeup 1
> [ 652.492254] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
> [ 652.492341] xhci_hcd 0000:0b:00.0: PME# enabled
> [ 652.522157] pcieport 0000:00:1c.4: PME# enabled
>
> [ 688.128198] kmemleak: 1 new suspected memory leaks (see /sys/kernel/debug/kmemleak)
>
> Note above two things pcieport started to mess in. I you look what xhci_hcd printed
> in previous attempts the was NO pcieport involved around, at all. Now, provided
> that I reported the kmemleak multiple times this year:
>
> unreferenced object 0xffff88040b4a0690 (size 256):
> comm "swapper/0", pid 1, jiffies 4294937581 (age 17428.760s)
> hex dump (first 32 bytes):
> 00 00 00 00 ad 4e ad de ff ff ff ff 00 00 00 00 .....N..........
> ff ff ff ff ff ff ff ff d8 9f 57 82 ff ff ff ff ..........W.....
> backtrace:
> [<ffffffff815fcec7>] kmemleak_alloc+0x21/0x3e
> [<ffffffff81117ac4>] slab_post_alloc_hook+0x28/0x2a
> [<ffffffff8111a18f>] __kmalloc+0xf3/0x105
> [<ffffffff8132b20c>] kzalloc.constprop.14+0xe/0x10
> [<ffffffff8132b66d>] device_private_init+0x14/0x63
> [<ffffffff8132d790>] dev_set_drvdata+0x19/0x2f
> [<ffffffff813f19d1>] i801_probe+0x5e/0x41a
> [<ffffffff812924d8>] local_pci_probe+0x39/0x61
> [<ffffffff812936fb>] pci_device_probe+0xc6/0xf3
> [<ffffffff8132dd59>] driver_probe_device+0xa9/0x1c1
> [<ffffffff8132decb>] __driver_attach+0x5a/0x7e
> [<ffffffff8132c5f3>] bus_for_each_dev+0x5c/0x88
> [<ffffffff8132d8f6>] driver_attach+0x19/0x1b
> [<ffffffff8132d4c6>] bus_add_driver+0xa8/0x1fa
> [<ffffffff8132e33d>] driver_register+0x8c/0x106
> [<ffffffff812932f6>] __pci_register_driver+0x5c/0x60
>
> I believe this is related to the issue, somehow.
>
> Now I started usbmon, so lets follow another attempt to repeat the bug. For clarity,
> I will plug into the socket a kayboard, and it won't work until I do 'lsusb -vv'.
> I pressed then space on the keyboard and that worked immediately (keyboard was dead
> until lsusb -vv and also dmesg did not log anything).
>
> So, lsusb -vv:
>
> [ 802.986036] pcieport 0000:00:1c.4: PME# disabled
> [ 803.016008] xhci_hcd 0000:0b:00.0: PME# disabled
> [ 803.016021] xhci_hcd 0000:0b:00.0: enabling bus mastering
> [ 803.016078] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
> [ 803.016085] usb usb3: usb auto-resume
> [ 803.016103] hub 3-0:1.0: hub_resume
> [ 803.016172] hub 3-0:1.0: port 2: status 0301 change 0001
>
> unplug of the keyboard:
>
> [ 821.621331] usb usb4: usb auto-resume
> [ 821.621347] hub 4-0:1.0: hub_resume
> [ 821.621405] hub 4-0:1.0: state 7 ports 2 chg 0000 evt 0000
> [ 821.622047] hub 4-0:1.0: hub_suspend
> [ 821.622053] usb usb4: bus auto-suspend, wakeup 1
> [ 967.433533] hub 3-0:1.0: state 7 ports 2 chg 0000 evt 0004
> [ 967.433768] hub 3-0:1.0: port 2, status 0100, change 0001, 12 Mb/s
> [ 967.433771] usb 3-2: USB disconnect, device number 4
> [ 967.433772] usb 3-2: unregistering device
> [ 967.433775] usb 3-2: unregistering interface 3-2:1.0
> [ 967.433946] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040a641948 ep1in-intr
> [ 967.579130] usb 3-2: usb_disable_device nuking all URBs
> [ 967.584494] usb 3-2: Successful Endpoint Configure command
> [ 967.798027] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
> [ 967.798046] hub 3-0:1.0: hub_suspend
> [ 967.798075] usb usb3: bus auto-suspend, wakeup 1
> [ 967.798155] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
> [ 967.798242] xhci_hcd 0000:0b:00.0: PME# enabled
> [ 967.828015] pcieport 0000:00:1c.4: PME# enabled
>
>
> You see? pcieport is messing with
> 00:1c.4 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI Express Root Port 5 (rev b5) (prog-if 00 [Normal decode])
>
> I unplugged and replugged the keyboard multiple times and it did not work, so
> I connected the mouse used in the very beginning right after cold boot, still
> not working. But these were likely logged due to that.
>
> [ 1063.263014] pcieport 0000:00:1c.4: PME# disabled
> [ 1063.272918] pcieport 0000:00:1c.4: PME# enabled
> [ 1063.312915] pcieport 0000:00:1c.4: PME# disabled
> [ 1063.322906] pcieport 0000:00:1c.4: PME# enabled
> [ 1070.132666] pcieport 0000:00:1c.4: PME# disabled
> [ 1070.142559] pcieport 0000:00:1c.4: PME# enabled
> [ 1070.182601] pcieport 0000:00:1c.4: PME# disabled
> [ 1070.192644] pcieport 0000:00:1c.4: PME# enabled
>
> After doing 'lsusb -vv' I rescued the USB3 socket and it detected the mouse.
>
> [ 1108.504953] pcieport 0000:00:1c.4: PME# disabled
> [ 1108.525010] xhci_hcd 0000:0b:00.0: PME# disabled
> [ 1108.525023] xhci_hcd 0000:0b:00.0: enabling bus mastering
> [ 1108.525069] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
> [ 1108.525075] usb usb3: usb auto-resume
> [ 1108.525094] hub 3-0:1.0: hub_resume
> [ 1108.525196] hub 3-0:1.0: port 2: status 0301 change 0001
>
> I unplugged the mouse:
>
> [ 1230.618019] usb 3-2: unregistering device
> [ 1230.618020] usb 3-2: unregistering interface 3-2:1.0
> [ 1230.618188] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040b1ee288 ep1in-intr
> [ 1230.711764] usb 3-2: usb_disable_device nuking all URBs
> [ 1230.715101] usb 3-2: Successful Endpoint Configure command
> [ 1230.870763] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
> [ 1230.870782] hub 3-0:1.0: hub_suspend
> [ 1230.870797] usb usb3: bus auto-suspend, wakeup 1
> [ 1230.870862] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
> [ 1230.870950] xhci_hcd 0000:0b:00.0: PME# enabled
> [ 1230.900730] pcieport 0000:00:1c.4: PME# enabled
>
>
> In summary, note that *immediately before the line* "xhci_hcd 0000:0b:00.0: PME# disabled"
> must appear "pcieport 0000:00:1c.4: PME# disabled". Don't know who is the blocker.
> But in reverse order the PME# are being enabled after a device is unplugged from the USB3
> socket.
>
> During the tests I had an eSATA in the express card slot since cold boot but don't think
> it is relevant here. The express slot is 01.c7 device and the card would be 11.0.
>
>
>
>
> I will send to Alan and Sarah two files directly (thank you for their inspection): ;)
>
> The first part of testing is in usbmon_0u.bin (repeatedly connecting a mouse).
>
> The latter in usbmon_0u_mouse_unplug_keyboard_insert.bin. Both files should demonstrate
> the same issue although in the latter file it might be easier to see because the keyboard
> was plugged in after a mouse and was not detected until 'lsusb -vv'.
>
>
>
>
>
> Below is when a keyboard is connected to the dead USB3 socket:
>
> # diff -u -w lspci_vvvxxx_mouse_unplugged.txt lspci_vvvxxx_mouse_unplugged_keyboard_inserted.txt
> --- lspci_vvvxxx_mouse_unplugged.txt 2013-03-14 12:22:34.000000000 +0100
> +++ lspci_vvvxxx_mouse_unplugged_keyboard_inserted.txt 2013-03-14 12:25:06.000000000 +0100
> @@ -747,7 +747,7 @@
> Region 2: Memory at f7d10000 (64-bit, non-prefetchable) [size=8K]
> Capabilities: [40] Power Management version 3
> Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=100mA PME(D0+,D1+,D2+,D3hot+,D3cold+)
> - Status: D3 NoSoftRst+ PME-Enable+ DSel=0 DScale=0 PME-
> + Status: D3 NoSoftRst+ PME-Enable+ DSel=0 DScale=0 PME+
> Capabilities: [48] MSI: Enable- Count=1/8 Maskable- 64bit+
> Address: 0000000000000000 Data: 0000
> Capabilities: [70] Express (v2) Endpoint, MSI 00
> @@ -785,7 +785,7 @@
> 10: 04 00 d0 f7 00 00 00 00 04 00 d1 f7 00 00 00 00
> 20: 00 00 00 00 00 00 00 00 00 00 00 00 28 10 b3 04
> 30: 00 00 00 00 40 00 00 00 00 00 00 00 0b 01 00 00
> -40: 01 48 83 fe 0b 01 00 00 05 70 86 00 00 00 00 00
> +40: 01 48 83 fe 0b 81 00 00 05 70 86 00 00 00 00 00
> 50: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 60: 30 20 00 00 00 00 00 00 00 00 00 00 00 00 00 00
> 70: 10 c0 02 00 c3 8f 90 05 00 20 19 00 12 3c 07 00
>
>
> Now, when the port is rescued by lsusb -vv, the following can be seen:
>
> # diff -u -w lspci_vv_mouse_unplugged_keyboard_inserted.txt lspci_vv_mouse_unplugged_keyboard_inserted_kb_alive.txt
> --- lspci_vv_mouse_unplugged_keyboard_inserted.txt 2013-03-14 12:25:13.000000000 +0100
> +++ lspci_vv_mouse_unplugged_keyboard_inserted_kb_alive.txt 2013-03-14 12:26:42.000000000 +0100
> @@ -276,7 +276,7 @@
> Capabilities: [90] Subsystem: Dell Device 04b3
> Capabilities: [a0] Power Management version 2
> Flags: PMEClk- DSI- D1- D2- AuxCurrent=0mA PME(D0+,D1-,D2-,D3hot+,D3cold+)
> - Status: D3 NoSoftRst- PME-Enable+ DSel=0 DScale=0 PME-
> + Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-
> Kernel driver in use: pcieport
>
> 00:1c.7 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI Express Root Port 8 (rev b5) (prog-if 00 [Normal decode])
> @@ -476,14 +476,15 @@
>
> 0b:00.0 USB controller: Texas Instruments TUSB73x0 SuperSpeed USB 3.0 xHCI Host Controller (rev 02) (prog-if 30 [XHCI])
> Subsystem: Dell Device 04b3
> - Control: I/O- Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+
> + 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, Cache Line Size: 64 bytes
> Interrupt: pin A routed to IRQ 16
> Region 0: Memory at f7d00000 (64-bit, non-prefetchable) [size=64K]
> Region 2: Memory at f7d10000 (64-bit, non-prefetchable) [size=8K]
> Capabilities: [40] Power Management version 3
> Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=100mA PME(D0+,D1+,D2+,D3hot+,D3cold+)
> - Status: D3 NoSoftRst+ PME-Enable+ DSel=0 DScale=0 PME+
> + Status: D0 NoSoftRst+ PME-Enable- DSel=0 DScale=0 PME-
> Capabilities: [48] MSI: Enable- Count=1/8 Maskable- 64bit+
> Address: 0000000000000000 Data: 0000
> Capabilities: [70] Express (v2) Endpoint, MSI 00
> #
>
>
>
> The difference in lsusb output is probably useless as it just shows that once the socket
> was working the keyboard was picked up by a driver. Looks like yet another PCI issue.
> At least no diffs in iomem. ;)
>
>
> Thanks,
> Martin
>
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: 3.8.2: xhci port is dead until pcieport PME# goes to disabled
2013-03-18 17:01 ` Sarah Sharp
@ 2013-03-18 17:20 ` Martin Mokrejs
2013-03-18 18:26 ` Sarah Sharp
0 siblings, 1 reply; 7+ messages in thread
From: Martin Mokrejs @ 2013-03-18 17:20 UTC (permalink / raw)
To: Sarah Sharp; +Cc: USB list, Alan Stern, Lan Tianyu, linux-pci@vger.kernel.org
Hi Sarah,
in this particular thread, the USB3 socket of the laptop works once
I plugin a device. When I unplug it and insert same or another device it appears
to be dead until I use 'lsusb -vvv'. After that, I see in dmesg the two lines:
[ 1445.597641] pcieport 0000:00:1c.4: PME# disabled
[ 1445.617667] xhci_hcd 0000:0b:00.0: PME# disabled
That's the whole story.
Thank you,
Martin
Sarah Sharp wrote:
> Hi Martin,
>
> I'm really having trouble following you here. Please don't try to tell
> me what you think the root cause of the issue is. Just tell me exactly
> (in a paragraph or less) what your symptoms are.
>
> Sending lots of log files and lsusb output at me isn't helpful if I
> don't know what your issue is.
>
> Sarah Sharp
>
> On Thu, Mar 14, 2013 at 06:30:53PM +0100, Martin Mokrejs wrote:
>> [resend, no dmesg attached]
>>
>> Hi,
>> happened to me again that I plugged in some devcie into my USB3 socket
>> in my laptop (usually use an external HUB). Don't believe this is a new
>> issue as a whole, I am facing this time to time since 3.3 time when I bought
>> the laptop.
>> But now, I realized the first device works but subsequently plugged in
>> devices do not show up until until I run 'lsusb -vv'. Simple 'lsusb -v'
>> or 'lsusb -t' is not enough. The port starts to work after dmesg logs:
>>
>>
>> [ 1445.597641] pcieport 0000:00:1c.4: PME# disabled
>> [ 1445.617667] xhci_hcd 0000:0b:00.0: PME# disabled
>>
>> I believe lsusb -vv while querying for the detailed device info triggers
>> a fix.
>>
>> Funny, but maybe this will help us to understand why my express card slot
>> somehow relates to the USB3 proivided by the TexasInstruments chip. This
>> is a SandyBridge-based Dell Vostro 3550 laptop. I should add that on the usb
>> mailing list about a year ago some TexasInstruments developer published that
>> they had a hardware bug in a "USB redriver", and as my laptop was made in
>> fall 2011 I am likely having having the buggy HW. If I remember right, the
>> report was about the problem that once you pluging a USB2 device into the XHCI
>> slot, subsequently plugged in USB3 devices won't negotiate at SuperSpeed until
>> you power off computer. So, I think I face a different issue but had to mention
>> this.
>>
>> I use pcie_aspm=off on my kernel command line. I tried to catch some usb traffic
>> on the dead port but the file from usbmon is really empty until I do the
>> 'lsusb -vv'. I tried to turn off the usb powersaving at runtime but I do not
>> have the file to do:
>>
>> # echo -n -1 > /sys/bus/usb/devices/3-0\:1.0/power/autosuspend
>>
>> :(
>>
>> # ls -la /sys/bus/usb/devices/3-0\:1.0/power/
>> total 0
>> drwxr-xr-x 2 root root 0 Mar 14 13:11 .
>> drwxr-xr-x 6 root root 0 Mar 14 13:58 ..
>> -rw-r--r-- 1 root root 4096 Mar 14 13:11 async
>> -r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_active_kids
>> -r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_enabled
>> -r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_status
>> -r--r--r-- 1 root root 4096 Mar 14 13:11 runtime_usage
>> #
>>
>> I feel related could be patches under thread: "usb: add usb port auto power off mechanism"
>>
>>
>> Below is a dmesg since a cold boot. I plugged in a mouse into the USB3 socket.
>>
>> [ 91.463285] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
>> [ 91.463377] xhci_hcd 0000:0b:00.0: PME# enabled
>> [ 447.389700] xhci_hcd 0000:0b:00.0: PME# disabled
>> [ 447.389711] xhci_hcd 0000:0b:00.0: enabling bus mastering
>> [ 447.389787] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
>> [ 447.389819] usb usb3: usb wakeup-resume
>>
>> Was detected. Unplugged the mouse.
>>
>> [ 459.600056] usb 3-2: USB disconnect, device number 2
>> [ 459.600058] usb 3-2: unregistering device
>> [ 459.600059] usb 3-2: unregistering interface 3-2:1.0
>> [ 459.600622] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040a641870 ep1in-intr
>> [ 459.702564] usb 3-2: usb_disable_device nuking all URBs
>> [ 459.707948] usb 3-2: Successful Endpoint Configure command
>> [ 459.860892] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
>> [ 459.860910] hub 3-0:1.0: hub_suspend
>> [ 459.860917] usb usb3: bus auto-suspend, wakeup 1
>> [ 459.860981] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
>> [ 459.861070] xhci_hcd 0000:0b:00.0: PME# enabled
>>
>> Then I put the mouse back, I think it was not detected after until I did the lsusb
>> trick. I am not sure, sorry, but is not that important. I was repeating test once
>> again and just forgot now this detail. So below just that the mouse got detected,
>> either thanks to lsusb -vv or not.
>>
>> [ 536.745422] xhci_hcd 0000:0b:00.0: PME# disabled
>> [ 536.745435] xhci_hcd 0000:0b:00.0: enabling bus mastering
>> [ 536.745487] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
>> [ 536.745493] usb usb3: usb auto-resume
>> [ 536.745509] hub 3-0:1.0: hub_resume
>> [ 536.745579] hub 3-0:1.0: port 2: status 0301 change 0001
>>
>> Then a mouse disconnect:
>>
>> [ 652.232958] usb 3-2: USB disconnect, device number 3
>> [ 652.232960] usb 3-2: unregistering device
>> [ 652.232961] usb 3-2: unregistering interface 3-2:1.0
>> [ 652.233148] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040b1ee288 ep1in-intr
>> [ 652.333325] usb 3-2: usb_disable_device nuking all URBs
>> [ 652.337096] usb 3-2: Successful Endpoint Configure command
>> [ 652.492164] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
>> [ 652.492183] hub 3-0:1.0: hub_suspend
>> [ 652.492190] usb usb3: bus auto-suspend, wakeup 1
>> [ 652.492254] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
>> [ 652.492341] xhci_hcd 0000:0b:00.0: PME# enabled
>> [ 652.522157] pcieport 0000:00:1c.4: PME# enabled
>>
>> [ 688.128198] kmemleak: 1 new suspected memory leaks (see /sys/kernel/debug/kmemleak)
>>
>> Note above two things pcieport started to mess in. I you look what xhci_hcd printed
>> in previous attempts the was NO pcieport involved around, at all. Now, provided
>> that I reported the kmemleak multiple times this year:
>>
>> unreferenced object 0xffff88040b4a0690 (size 256):
>> comm "swapper/0", pid 1, jiffies 4294937581 (age 17428.760s)
>> hex dump (first 32 bytes):
>> 00 00 00 00 ad 4e ad de ff ff ff ff 00 00 00 00 .....N..........
>> ff ff ff ff ff ff ff ff d8 9f 57 82 ff ff ff ff ..........W.....
>> backtrace:
>> [<ffffffff815fcec7>] kmemleak_alloc+0x21/0x3e
>> [<ffffffff81117ac4>] slab_post_alloc_hook+0x28/0x2a
>> [<ffffffff8111a18f>] __kmalloc+0xf3/0x105
>> [<ffffffff8132b20c>] kzalloc.constprop.14+0xe/0x10
>> [<ffffffff8132b66d>] device_private_init+0x14/0x63
>> [<ffffffff8132d790>] dev_set_drvdata+0x19/0x2f
>> [<ffffffff813f19d1>] i801_probe+0x5e/0x41a
>> [<ffffffff812924d8>] local_pci_probe+0x39/0x61
>> [<ffffffff812936fb>] pci_device_probe+0xc6/0xf3
>> [<ffffffff8132dd59>] driver_probe_device+0xa9/0x1c1
>> [<ffffffff8132decb>] __driver_attach+0x5a/0x7e
>> [<ffffffff8132c5f3>] bus_for_each_dev+0x5c/0x88
>> [<ffffffff8132d8f6>] driver_attach+0x19/0x1b
>> [<ffffffff8132d4c6>] bus_add_driver+0xa8/0x1fa
>> [<ffffffff8132e33d>] driver_register+0x8c/0x106
>> [<ffffffff812932f6>] __pci_register_driver+0x5c/0x60
>>
>> I believe this is related to the issue, somehow.
>>
>> Now I started usbmon, so lets follow another attempt to repeat the bug. For clarity,
>> I will plug into the socket a kayboard, and it won't work until I do 'lsusb -vv'.
>> I pressed then space on the keyboard and that worked immediately (keyboard was dead
>> until lsusb -vv and also dmesg did not log anything).
>>
>> So, lsusb -vv:
>>
>> [ 802.986036] pcieport 0000:00:1c.4: PME# disabled
>> [ 803.016008] xhci_hcd 0000:0b:00.0: PME# disabled
>> [ 803.016021] xhci_hcd 0000:0b:00.0: enabling bus mastering
>> [ 803.016078] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
>> [ 803.016085] usb usb3: usb auto-resume
>> [ 803.016103] hub 3-0:1.0: hub_resume
>> [ 803.016172] hub 3-0:1.0: port 2: status 0301 change 0001
>>
>> unplug of the keyboard:
>>
>> [ 821.621331] usb usb4: usb auto-resume
>> [ 821.621347] hub 4-0:1.0: hub_resume
>> [ 821.621405] hub 4-0:1.0: state 7 ports 2 chg 0000 evt 0000
>> [ 821.622047] hub 4-0:1.0: hub_suspend
>> [ 821.622053] usb usb4: bus auto-suspend, wakeup 1
>> [ 967.433533] hub 3-0:1.0: state 7 ports 2 chg 0000 evt 0004
>> [ 967.433768] hub 3-0:1.0: port 2, status 0100, change 0001, 12 Mb/s
>> [ 967.433771] usb 3-2: USB disconnect, device number 4
>> [ 967.433772] usb 3-2: unregistering device
>> [ 967.433775] usb 3-2: unregistering interface 3-2:1.0
>> [ 967.433946] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040a641948 ep1in-intr
>> [ 967.579130] usb 3-2: usb_disable_device nuking all URBs
>> [ 967.584494] usb 3-2: Successful Endpoint Configure command
>> [ 967.798027] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
>> [ 967.798046] hub 3-0:1.0: hub_suspend
>> [ 967.798075] usb usb3: bus auto-suspend, wakeup 1
>> [ 967.798155] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
>> [ 967.798242] xhci_hcd 0000:0b:00.0: PME# enabled
>> [ 967.828015] pcieport 0000:00:1c.4: PME# enabled
>>
>>
>> You see? pcieport is messing with
>> 00:1c.4 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI Express Root Port 5 (rev b5) (prog-if 00 [Normal decode])
>>
>> I unplugged and replugged the keyboard multiple times and it did not work, so
>> I connected the mouse used in the very beginning right after cold boot, still
>> not working. But these were likely logged due to that.
>>
>> [ 1063.263014] pcieport 0000:00:1c.4: PME# disabled
>> [ 1063.272918] pcieport 0000:00:1c.4: PME# enabled
>> [ 1063.312915] pcieport 0000:00:1c.4: PME# disabled
>> [ 1063.322906] pcieport 0000:00:1c.4: PME# enabled
>> [ 1070.132666] pcieport 0000:00:1c.4: PME# disabled
>> [ 1070.142559] pcieport 0000:00:1c.4: PME# enabled
>> [ 1070.182601] pcieport 0000:00:1c.4: PME# disabled
>> [ 1070.192644] pcieport 0000:00:1c.4: PME# enabled
>>
>> After doing 'lsusb -vv' I rescued the USB3 socket and it detected the mouse.
>>
>> [ 1108.504953] pcieport 0000:00:1c.4: PME# disabled
>> [ 1108.525010] xhci_hcd 0000:0b:00.0: PME# disabled
>> [ 1108.525023] xhci_hcd 0000:0b:00.0: enabling bus mastering
>> [ 1108.525069] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
>> [ 1108.525075] usb usb3: usb auto-resume
>> [ 1108.525094] hub 3-0:1.0: hub_resume
>> [ 1108.525196] hub 3-0:1.0: port 2: status 0301 change 0001
>>
>> I unplugged the mouse:
>>
>> [ 1230.618019] usb 3-2: unregistering device
>> [ 1230.618020] usb 3-2: unregistering interface 3-2:1.0
>> [ 1230.618188] xhci_hcd 0000:0b:00.0: shutdown urb ffff88040b1ee288 ep1in-intr
>> [ 1230.711764] usb 3-2: usb_disable_device nuking all URBs
>> [ 1230.715101] usb 3-2: Successful Endpoint Configure command
>> [ 1230.870763] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
>> [ 1230.870782] hub 3-0:1.0: hub_suspend
>> [ 1230.870797] usb usb3: bus auto-suspend, wakeup 1
>> [ 1230.870862] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
>> [ 1230.870950] xhci_hcd 0000:0b:00.0: PME# enabled
>> [ 1230.900730] pcieport 0000:00:1c.4: PME# enabled
>>
>>
>> In summary, note that *immediately before the line* "xhci_hcd 0000:0b:00.0: PME# disabled"
>> must appear "pcieport 0000:00:1c.4: PME# disabled". Don't know who is the blocker.
>> But in reverse order the PME# are being enabled after a device is unplugged from the USB3
>> socket.
>>
>> During the tests I had an eSATA in the express card slot since cold boot but don't think
>> it is relevant here. The express slot is 01.c7 device and the card would be 11.0.
>>
>>
>>
>>
>> I will send to Alan and Sarah two files directly (thank you for their inspection): ;)
>>
>> The first part of testing is in usbmon_0u.bin (repeatedly connecting a mouse).
>>
>> The latter in usbmon_0u_mouse_unplug_keyboard_insert.bin. Both files should demonstrate
>> the same issue although in the latter file it might be easier to see because the keyboard
>> was plugged in after a mouse and was not detected until 'lsusb -vv'.
>>
>>
>>
>>
>>
>> Below is when a keyboard is connected to the dead USB3 socket:
>>
>> # diff -u -w lspci_vvvxxx_mouse_unplugged.txt lspci_vvvxxx_mouse_unplugged_keyboard_inserted.txt
>> --- lspci_vvvxxx_mouse_unplugged.txt 2013-03-14 12:22:34.000000000 +0100
>> +++ lspci_vvvxxx_mouse_unplugged_keyboard_inserted.txt 2013-03-14 12:25:06.000000000 +0100
>> @@ -747,7 +747,7 @@
>> Region 2: Memory at f7d10000 (64-bit, non-prefetchable) [size=8K]
>> Capabilities: [40] Power Management version 3
>> Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=100mA PME(D0+,D1+,D2+,D3hot+,D3cold+)
>> - Status: D3 NoSoftRst+ PME-Enable+ DSel=0 DScale=0 PME-
>> + Status: D3 NoSoftRst+ PME-Enable+ DSel=0 DScale=0 PME+
>> Capabilities: [48] MSI: Enable- Count=1/8 Maskable- 64bit+
>> Address: 0000000000000000 Data: 0000
>> Capabilities: [70] Express (v2) Endpoint, MSI 00
>> @@ -785,7 +785,7 @@
>> 10: 04 00 d0 f7 00 00 00 00 04 00 d1 f7 00 00 00 00
>> 20: 00 00 00 00 00 00 00 00 00 00 00 00 28 10 b3 04
>> 30: 00 00 00 00 40 00 00 00 00 00 00 00 0b 01 00 00
>> -40: 01 48 83 fe 0b 01 00 00 05 70 86 00 00 00 00 00
>> +40: 01 48 83 fe 0b 81 00 00 05 70 86 00 00 00 00 00
>> 50: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 60: 30 20 00 00 00 00 00 00 00 00 00 00 00 00 00 00
>> 70: 10 c0 02 00 c3 8f 90 05 00 20 19 00 12 3c 07 00
>>
>>
>> Now, when the port is rescued by lsusb -vv, the following can be seen:
>>
>> # diff -u -w lspci_vv_mouse_unplugged_keyboard_inserted.txt lspci_vv_mouse_unplugged_keyboard_inserted_kb_alive.txt
>> --- lspci_vv_mouse_unplugged_keyboard_inserted.txt 2013-03-14 12:25:13.000000000 +0100
>> +++ lspci_vv_mouse_unplugged_keyboard_inserted_kb_alive.txt 2013-03-14 12:26:42.000000000 +0100
>> @@ -276,7 +276,7 @@
>> Capabilities: [90] Subsystem: Dell Device 04b3
>> Capabilities: [a0] Power Management version 2
>> Flags: PMEClk- DSI- D1- D2- AuxCurrent=0mA PME(D0+,D1-,D2-,D3hot+,D3cold+)
>> - Status: D3 NoSoftRst- PME-Enable+ DSel=0 DScale=0 PME-
>> + Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-
>> Kernel driver in use: pcieport
>>
>> 00:1c.7 PCI bridge: Intel Corporation 6 Series/C200 Series Chipset Family PCI Express Root Port 8 (rev b5) (prog-if 00 [Normal decode])
>> @@ -476,14 +476,15 @@
>>
>> 0b:00.0 USB controller: Texas Instruments TUSB73x0 SuperSpeed USB 3.0 xHCI Host Controller (rev 02) (prog-if 30 [XHCI])
>> Subsystem: Dell Device 04b3
>> - Control: I/O- Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+
>> + 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, Cache Line Size: 64 bytes
>> Interrupt: pin A routed to IRQ 16
>> Region 0: Memory at f7d00000 (64-bit, non-prefetchable) [size=64K]
>> Region 2: Memory at f7d10000 (64-bit, non-prefetchable) [size=8K]
>> Capabilities: [40] Power Management version 3
>> Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=100mA PME(D0+,D1+,D2+,D3hot+,D3cold+)
>> - Status: D3 NoSoftRst+ PME-Enable+ DSel=0 DScale=0 PME+
>> + Status: D0 NoSoftRst+ PME-Enable- DSel=0 DScale=0 PME-
>> Capabilities: [48] MSI: Enable- Count=1/8 Maskable- 64bit+
>> Address: 0000000000000000 Data: 0000
>> Capabilities: [70] Express (v2) Endpoint, MSI 00
>> #
>>
>>
>>
>> The difference in lsusb output is probably useless as it just shows that once the socket
>> was working the keyboard was picked up by a driver. Looks like yet another PCI issue.
>> At least no diffs in iomem. ;)
>>
>>
>> Thanks,
>> Martin
>>
> --
> To unsubscribe from this list: send the line "unsubscribe linux-pci" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at http://vger.kernel.org/majordomo-info.html
>
>
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: 3.8.2: xhci port is dead until pcieport PME# goes to disabled
2013-03-18 17:20 ` Martin Mokrejs
@ 2013-03-18 18:26 ` Sarah Sharp
2013-03-18 18:52 ` Martin Mokrejs
0 siblings, 1 reply; 7+ messages in thread
From: Sarah Sharp @ 2013-03-18 18:26 UTC (permalink / raw)
To: Martin Mokrejs
Cc: USB list, Alan Stern, Lan Tianyu, linux-pci@vger.kernel.org
On Mon, Mar 18, 2013 at 06:20:12PM +0100, Martin Mokrejs wrote:
> Hi Sarah,
> in this particular thread, the USB3 socket of the laptop works once
> I plugin a device. When I unplug it and insert same or another device it appears
> to be dead until I use 'lsusb -vvv'. After that, I see in dmesg the two lines:
Which kernel are you running on? We had a regression that involved dead
ports after a USB disconnect in 3.8. This was was fixed in 3.8.3. Can
you please retest with that kernel version?
Sarah Sharp
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: 3.8.2: xhci port is dead until pcieport PME# goes to disabled
2013-03-18 18:26 ` Sarah Sharp
@ 2013-03-18 18:52 ` Martin Mokrejs
2013-03-18 19:58 ` Sarah Sharp
0 siblings, 1 reply; 7+ messages in thread
From: Martin Mokrejs @ 2013-03-18 18:52 UTC (permalink / raw)
To: Sarah Sharp; +Cc: USB list, Alan Stern, Lan Tianyu, linux-pci@vger.kernel.org
Sarah Sharp wrote:
> On Mon, Mar 18, 2013 at 06:20:12PM +0100, Martin Mokrejs wrote:
>> Hi Sarah,
>> in this particular thread, the USB3 socket of the laptop works once
>> I plugin a device. When I unplug it and insert same or another device it appears
>> to be dead until I use 'lsusb -vvv'. After that, I see in dmesg the two lines:
>
> Which kernel are you running on? We had a regression that involved dead
As the subject says, 3.8.2. I really started last week several different email
threads, each independent.
> ports after a USB disconnect in 3.8. This was was fixed in 3.8.3. Can
> you please retest with that kernel version?
Hmm, will do. What change do you mean exactly?
BTW, how about this bugfix which just appeared at linux-pci?
[PATCH] PCI: Remove not needed check in disable aspm link
Thank you,
Martin
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: 3.8.2: xhci port is dead until pcieport PME# goes to disabled
2013-03-18 18:52 ` Martin Mokrejs
@ 2013-03-18 19:58 ` Sarah Sharp
2013-03-20 10:00 ` Martin Mokrejs
0 siblings, 1 reply; 7+ messages in thread
From: Sarah Sharp @ 2013-03-18 19:58 UTC (permalink / raw)
To: Martin Mokrejs
Cc: USB list, Alan Stern, Lan Tianyu, linux-pci@vger.kernel.org
On Mon, Mar 18, 2013 at 07:52:10PM +0100, Martin Mokrejs wrote:
> Sarah Sharp wrote:
> > On Mon, Mar 18, 2013 at 06:20:12PM +0100, Martin Mokrejs wrote:
> >> Hi Sarah,
> >> in this particular thread, the USB3 socket of the laptop works once
> >> I plugin a device. When I unplug it and insert same or another device it appears
> >> to be dead until I use 'lsusb -vvv'. After that, I see in dmesg the two lines:
> >
> > Which kernel are you running on? We had a regression that involved dead
>
> As the subject says, 3.8.2. I really started last week several different email
> threads, each independent.
>
> > ports after a USB disconnect in 3.8. This was was fixed in 3.8.3. Can
> > you please retest with that kernel version?
>
> Hmm, will do. What change do you mean exactly?
Commit d79303047f25957a3e00d25cc1eddb82b3ee0f89 "USB: Fix connected
device switch to Inactive state." along with three others.
> BTW, how about this bugfix which just appeared at linux-pci?
> [PATCH] PCI: Remove not needed check in disable aspm link
I understand that you want to try and figure out on your own what bug
you're hitting. I'm really happy you're enthusiastic about reporting
and testing bug fixes. However, looking at random bug fix patches when
we don't know the root cause is not very productive. I would suggest
you read http://www.chiark.greenend.org.uk/~sgtatham/bugs.html,
especially the "I think the tachyon modulation must be wrongly
polarised" section.
We have no hard proof that this is even a PCI bug. lsusb does cause the
PCI host to wakeup out of D3, which triggers the PME disable messages
you mentioned. However, lsusb also triggers the USB core to look at the
roothub status registers. Without further debugging, we can't be sure
if we lost a PCI wakeup from the port status change, or if the xHCI
driver mishandled the USB device disconnect and running lsusb causes the
USB core to notice the connect change. Let's rule out the most common
case, the xHCI driver, first.
Please, try 3.8.3 first, and if that doesn't help, turn on
CONFIG_USB_DEBUG and CONFIG_USB_XHCI_HCD_DEBUGGING, and send me the
dmesg from the time you first unplug the USB 3.0 device to when you
replug it in and have to run `sudo lsusb -v` to get the device to
appear.
Sarah Sharp
^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: 3.8.2: xhci port is dead until pcieport PME# goes to disabled
2013-03-18 19:58 ` Sarah Sharp
@ 2013-03-20 10:00 ` Martin Mokrejs
0 siblings, 0 replies; 7+ messages in thread
From: Martin Mokrejs @ 2013-03-20 10:00 UTC (permalink / raw)
To: Sarah Sharp; +Cc: USB list, Alan Stern, Lan Tianyu, linux-pci@vger.kernel.org
[-- Attachment #1: Type: text/plain, Size: 3145 bytes --]
Sarah Sharp wrote:
> On Mon, Mar 18, 2013 at 07:52:10PM +0100, Martin Mokrejs wrote:
>> Sarah Sharp wrote:
>>> On Mon, Mar 18, 2013 at 06:20:12PM +0100, Martin Mokrejs wrote:
>>>> Hi Sarah,
>>>> in this particular thread, the USB3 socket of the laptop works once
>>>> I plugin a device. When I unplug it and insert same or another device it appears
>>>> to be dead until I use 'lsusb -vvv'. After that, I see in dmesg the two lines:
>>>
>>> Which kernel are you running on? We had a regression that involved dead
>>
>> As the subject says, 3.8.2. I really started last week several different email
>> threads, each independent.
>>
>>> ports after a USB disconnect in 3.8. This was was fixed in 3.8.3. Can
>>> you please retest with that kernel version?
Sadly, 3.8.3 is still broken.
>>
>> Hmm, will do. What change do you mean exactly?
>
> Commit d79303047f25957a3e00d25cc1eddb82b3ee0f89 "USB: Fix connected
> device switch to Inactive state." along with three others.
>
>> BTW, how about this bugfix which just appeared at linux-pci?
>> [PATCH] PCI: Remove not needed check in disable aspm link
>
> I understand that you want to try and figure out on your own what bug
> you're hitting. I'm really happy you're enthusiastic about reporting
> and testing bug fixes. However, looking at random bug fix patches when
> we don't know the root cause is not very productive. I would suggest
> you read http://www.chiark.greenend.org.uk/~sgtatham/bugs.html,
> especially the "I think the tachyon modulation must be wrongly
> polarised" section.
Unfortunately I got not much feedback on the issues I am facing. I tried
several threads, each showing a different issue. It is a year since I reported
that PCI hotplug does not work, and with every kernel version different broken
behavior must be described, and we are just getting more and more data to look
into. No, there is nothing to bisect to, sadly I don't know of any kernel version
which is working fine. Provided there is now the ongoing discussion at linux-pci
("[PATCH] udevadm-info: Don't access sysfs 'resource<N>' files") about
lspci -xxx being able to reprogram PCI devices just by reading their registers
I wonder how much of my reports were screwed due to me using this command.
>
> We have no hard proof that this is even a PCI bug. lsusb does cause the
> PCI host to wakeup out of D3, which triggers the PME disable messages
> you mentioned. However, lsusb also triggers the USB core to look at the
> roothub status registers. Without further debugging, we can't be sure
> if we lost a PCI wakeup from the port status change, or if the xHCI
> driver mishandled the USB device disconnect and running lsusb causes the
> USB core to notice the connect change. Let's rule out the most common
> case, the xHCI driver, first.
Thank you, this is what I wanted to hear.
>
> Please, try 3.8.3 first, and if that doesn't help, turn on
> CONFIG_USB_DEBUG and CONFIG_USB_XHCI_HCD_DEBUGGING, and send me the
> dmesg from the time you first unplug the USB 3.0 device to when you
> replug it in and have to run `sudo lsusb -v` to get the device to
> appear.
Attached.
Martin
[-- Attachment #2: mouse_unplug_and_reinsert_and_lsusb_v.txt --]
[-- Type: text/plain, Size: 45559 bytes --]
--- dmesg_after_mouse_insert.txt 2013-03-20 10:41:51.000000000 +0100
+++ dmesg_after_mouse_insert_and_unplug_and_reinsert_and_after_lsusb_v.txt 2013-03-20 10:42:57.000000000 +0100
@@ -1737,3 +1737,505 @@
[ 65.785393] input: Genius NetScroll + Mini Traveler as /devices/pci0000:00/0000:00:1c.4/0000:0b:00.0/usb3/3-2/3-2:1.0/input/input15
[ 65.786637] hid-generic 0003:0458:0036.0003: input,hidraw2: USB HID v1.10 Mouse [Genius NetScroll + Mini Traveler] on usb-0000:0b:00.0-2/input0
[ 65.943638] xhci_hcd 0000:0b:00.0: xhci_hub_status_data: stopping port polling.
+[ 84.501520] xhci_hcd 0000:0b:00.0: Port Status Change Event for port 2
+[ 84.501536] xhci_hcd 0000:0b:00.0: handle_port_status: starting port polling.
+[ 84.501742] hub 3-0:1.0: state 7 ports 2 chg 0000 evt 0004
+[ 84.501779] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x202a0
+[ 84.501781] xhci_hcd 0000:0b:00.0: Get port status returned 0x10100
+[ 84.501822] xhci_hcd 0000:0b:00.0: clear port connect change, actual port 1 status = 0x2a0
+[ 84.501830] hub 3-0:1.0: port 2, status 0100, change 0001, 12 Mb/s
+[ 84.501833] usb 3-2: USB disconnect, device number 2
+[ 84.501835] usb 3-2: unregistering device
+[ 84.501836] usb 3-2: unregistering interface 3-2:1.0
+[ 84.502392] xhci_hcd 0000:0b:00.0: Cancel URB ffff880403339288, dev 2, ep 0x81, starting at offset 0xd601b400
+[ 84.502401] xhci_hcd 0000:0b:00.0: // Ding dong!
+[ 84.502407] xhci_hcd 0000:0b:00.0: shutdown urb ffff880403339288 ep1in-intr
+[ 84.502428] xhci_hcd 0000:0b:00.0: Stopped on Transfer TRB
+[ 84.502430] xhci_hcd 0000:0b:00.0: Removing canceled TD starting at 0xd601b400 (dma).
+[ 84.502432] xhci_hcd 0000:0b:00.0: Finding segment containing stopped TRB.
+[ 84.502433] xhci_hcd 0000:0b:00.0: Finding endpoint context
+[ 84.502434] xhci_hcd 0000:0b:00.0: Finding segment containing last TRB in TD.
+[ 84.502435] xhci_hcd 0000:0b:00.0: Cycle state = 0x1
+[ 84.502436] xhci_hcd 0000:0b:00.0: New dequeue segment = ffff88040530dea8 (virtual)
+[ 84.502437] xhci_hcd 0000:0b:00.0: New dequeue pointer = 0xd601b410 (DMA)
+[ 84.502442] xhci_hcd 0000:0b:00.0: Set TR Deq Ptr cmd, new deq seg = ffff88040530dea8 (0xd601b400 dma), new deq ptr = ffff8800d601b410 (0xd601b410 dma), new cycle = 1
+[ 84.502443] xhci_hcd 0000:0b:00.0: // Ding dong!
+[ 84.502499] xhci_hcd 0000:0b:00.0: Successful Set TR Deq Ptr cmd, deq = @d601b411
+[ 84.607132] usb 3-2: usb_disable_device nuking all URBs
+[ 84.607140] xhci_hcd 0000:0b:00.0: xhci_drop_endpoint called for udev ffff88040a49e938
+[ 84.607143] xhci_hcd 0000:0b:00.0: drop ep 0x81, slot id 1, new drop flags = 0x8, new add flags = 0x0, new slot info = 0x8200000
+[ 84.607145] xhci_hcd 0000:0b:00.0: xhci_check_bandwidth called for udev ffff88040a49e938
+[ 84.607146] xhci_hcd 0000:0b:00.0: New Input Control Context:
+[ 84.607148] xhci_hcd 0000:0b:00.0: @ffff8800d601a000 (virt) @d601a000 (dma) 0x000008 - drop flags
+[ 84.607149] xhci_hcd 0000:0b:00.0: @ffff8800d601a004 (virt) @d601a004 (dma) 0x000001 - add flags
+[ 84.607150] xhci_hcd 0000:0b:00.0: @ffff8800d601a008 (virt) @d601a008 (dma) 0x000000 - rsvd2[0]
+[ 84.607152] xhci_hcd 0000:0b:00.0: @ffff8800d601a00c (virt) @d601a00c (dma) 0x000000 - rsvd2[1]
+[ 84.607153] xhci_hcd 0000:0b:00.0: @ffff8800d601a010 (virt) @d601a010 (dma) 0x000000 - rsvd2[2]
+[ 84.607155] xhci_hcd 0000:0b:00.0: @ffff8800d601a014 (virt) @d601a014 (dma) 0x000000 - rsvd2[3]
+[ 84.607156] xhci_hcd 0000:0b:00.0: @ffff8800d601a018 (virt) @d601a018 (dma) 0x000000 - rsvd2[4]
+[ 84.607157] xhci_hcd 0000:0b:00.0: @ffff8800d601a01c (virt) @d601a01c (dma) 0x000000 - rsvd2[5]
+[ 84.607159] xhci_hcd 0000:0b:00.0: @ffff8800d601a020 (virt) @d601a020 (dma) 0x000000 - rsvd64[0]
+[ 84.607160] xhci_hcd 0000:0b:00.0: @ffff8800d601a028 (virt) @d601a028 (dma) 0x000000 - rsvd64[1]
+[ 84.607162] xhci_hcd 0000:0b:00.0: @ffff8800d601a030 (virt) @d601a030 (dma) 0x000000 - rsvd64[2]
+[ 84.607163] xhci_hcd 0000:0b:00.0: @ffff8800d601a038 (virt) @d601a038 (dma) 0x000000 - rsvd64[3]
+[ 84.607164] xhci_hcd 0000:0b:00.0: Slot Context:
+[ 84.607166] xhci_hcd 0000:0b:00.0: @ffff8800d601a040 (virt) @d601a040 (dma) 0x8200000 - dev_info
+[ 84.607167] xhci_hcd 0000:0b:00.0: @ffff8800d601a044 (virt) @d601a044 (dma) 0x020000 - dev_info2
+[ 84.607168] xhci_hcd 0000:0b:00.0: @ffff8800d601a048 (virt) @d601a048 (dma) 0x000000 - tt_info
+[ 84.607170] xhci_hcd 0000:0b:00.0: @ffff8800d601a04c (virt) @d601a04c (dma) 0x000000 - dev_state
+[ 84.607171] xhci_hcd 0000:0b:00.0: @ffff8800d601a050 (virt) @d601a050 (dma) 0x000000 - rsvd[0]
+[ 84.607173] xhci_hcd 0000:0b:00.0: @ffff8800d601a054 (virt) @d601a054 (dma) 0x000000 - rsvd[1]
+[ 84.607174] xhci_hcd 0000:0b:00.0: @ffff8800d601a058 (virt) @d601a058 (dma) 0x000000 - rsvd[2]
+[ 84.607175] xhci_hcd 0000:0b:00.0: @ffff8800d601a05c (virt) @d601a05c (dma) 0x000000 - rsvd[3]
+[ 84.607177] xhci_hcd 0000:0b:00.0: @ffff8800d601a060 (virt) @d601a060 (dma) 0x000000 - rsvd64[0]
+[ 84.607178] xhci_hcd 0000:0b:00.0: @ffff8800d601a068 (virt) @d601a068 (dma) 0x000000 - rsvd64[1]
+[ 84.607179] xhci_hcd 0000:0b:00.0: @ffff8800d601a070 (virt) @d601a070 (dma) 0x000000 - rsvd64[2]
+[ 84.607181] xhci_hcd 0000:0b:00.0: @ffff8800d601a078 (virt) @d601a078 (dma) 0x000000 - rsvd64[3]
+[ 84.607182] xhci_hcd 0000:0b:00.0: Endpoint 00 Context:
+[ 84.607183] xhci_hcd 0000:0b:00.0: @ffff8800d601a080 (virt) @d601a080 (dma) 0x000000 - ep_info
+[ 84.607185] xhci_hcd 0000:0b:00.0: @ffff8800d601a084 (virt) @d601a084 (dma) 0x080026 - ep_info2
+[ 84.607186] xhci_hcd 0000:0b:00.0: @ffff8800d601a088 (virt) @d601a088 (dma) 0xd6007801 - deq
+[ 84.607188] xhci_hcd 0000:0b:00.0: @ffff8800d601a090 (virt) @d601a090 (dma) 0x000000 - tx_info
+[ 84.607189] xhci_hcd 0000:0b:00.0: @ffff8800d601a094 (virt) @d601a094 (dma) 0x000000 - rsvd[0]
+[ 84.607190] xhci_hcd 0000:0b:00.0: @ffff8800d601a098 (virt) @d601a098 (dma) 0x000000 - rsvd[1]
+[ 84.607192] xhci_hcd 0000:0b:00.0: @ffff8800d601a09c (virt) @d601a09c (dma) 0x000000 - rsvd[2]
+[ 84.607193] xhci_hcd 0000:0b:00.0: @ffff8800d601a0a0 (virt) @d601a0a0 (dma) 0x000000 - rsvd64[0]
+[ 84.607195] xhci_hcd 0000:0b:00.0: @ffff8800d601a0a8 (virt) @d601a0a8 (dma) 0x000000 - rsvd64[1]
+[ 84.607196] xhci_hcd 0000:0b:00.0: @ffff8800d601a0b0 (virt) @d601a0b0 (dma) 0x000000 - rsvd64[2]
+[ 84.607197] xhci_hcd 0000:0b:00.0: @ffff8800d601a0b8 (virt) @d601a0b8 (dma) 0x000000 - rsvd64[3]
+[ 84.607200] xhci_hcd 0000:0b:00.0: // Ding dong!
+[ 84.616695] xhci_hcd 0000:0b:00.0: Completed config ep cmd
+[ 84.616892] usb 3-2: Successful Endpoint Configure command
+[ 84.616893] xhci_hcd 0000:0b:00.0: Output context after successful config ep cmd:
+[ 84.616895] xhci_hcd 0000:0b:00.0: Slot Context:
+[ 84.616897] xhci_hcd 0000:0b:00.0: @ffff8800d6019000 (virt) @d6019000 (dma) 0x8200000 - dev_info
+[ 84.616898] xhci_hcd 0000:0b:00.0: @ffff8800d6019004 (virt) @d6019004 (dma) 0x020000 - dev_info2
+[ 84.616899] xhci_hcd 0000:0b:00.0: @ffff8800d6019008 (virt) @d6019008 (dma) 0x000000 - tt_info
+[ 84.616901] xhci_hcd 0000:0b:00.0: @ffff8800d601900c (virt) @d601900c (dma) 0x10000001 - dev_state
+[ 84.616902] xhci_hcd 0000:0b:00.0: @ffff8800d6019010 (virt) @d6019010 (dma) 0x000000 - rsvd[0]
+[ 84.616904] xhci_hcd 0000:0b:00.0: @ffff8800d6019014 (virt) @d6019014 (dma) 0x000000 - rsvd[1]
+[ 84.616905] xhci_hcd 0000:0b:00.0: @ffff8800d6019018 (virt) @d6019018 (dma) 0x000000 - rsvd[2]
+[ 84.616906] xhci_hcd 0000:0b:00.0: @ffff8800d601901c (virt) @d601901c (dma) 0x000000 - rsvd[3]
+[ 84.616908] xhci_hcd 0000:0b:00.0: @ffff8800d6019020 (virt) @d6019020 (dma) 0x000000 - rsvd64[0]
+[ 84.616909] xhci_hcd 0000:0b:00.0: @ffff8800d6019028 (virt) @d6019028 (dma) 0x000000 - rsvd64[1]
+[ 84.616910] xhci_hcd 0000:0b:00.0: @ffff8800d6019030 (virt) @d6019030 (dma) 0x000000 - rsvd64[2]
+[ 84.616912] xhci_hcd 0000:0b:00.0: @ffff8800d6019038 (virt) @d6019038 (dma) 0x000000 - rsvd64[3]
+[ 84.616913] xhci_hcd 0000:0b:00.0: Endpoint 00 Context:
+[ 84.616914] xhci_hcd 0000:0b:00.0: @ffff8800d6019040 (virt) @d6019040 (dma) 0x000001 - ep_info
+[ 84.616915] xhci_hcd 0000:0b:00.0: @ffff8800d6019044 (virt) @d6019044 (dma) 0x080026 - ep_info2
+[ 84.616917] xhci_hcd 0000:0b:00.0: @ffff8800d6019048 (virt) @d6019048 (dma) 0xd60079c1 - deq
+[ 84.616918] xhci_hcd 0000:0b:00.0: @ffff8800d6019050 (virt) @d6019050 (dma) 0x000000 - tx_info
+[ 84.616919] xhci_hcd 0000:0b:00.0: @ffff8800d6019054 (virt) @d6019054 (dma) 0x8000000 - rsvd[0]
+[ 84.616920] xhci_hcd 0000:0b:00.0: @ffff8800d6019058 (virt) @d6019058 (dma) 0x000000 - rsvd[1]
+[ 84.616922] xhci_hcd 0000:0b:00.0: @ffff8800d601905c (virt) @d601905c (dma) 0x00012c - rsvd[2]
+[ 84.616923] xhci_hcd 0000:0b:00.0: @ffff8800d6019060 (virt) @d6019060 (dma) 0x000000 - rsvd64[0]
+[ 84.616924] xhci_hcd 0000:0b:00.0: @ffff8800d6019068 (virt) @d6019068 (dma) 0x000000 - rsvd64[1]
+[ 84.616926] xhci_hcd 0000:0b:00.0: @ffff8800d6019070 (virt) @d6019070 (dma) 0x000000 - rsvd64[2]
+[ 84.616927] xhci_hcd 0000:0b:00.0: @ffff8800d6019078 (virt) @d6019078 (dma) 0x000000 - rsvd64[3]
+[ 84.616929] xhci_hcd 0000:0b:00.0: Cached old ring, 1 ring cached
+[ 84.618319] xhci_hcd 0000:0b:00.0: // Ding dong!
+[ 84.618449] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x2a0
+[ 84.618450] xhci_hcd 0000:0b:00.0: Get port status returned 0x100
+[ 84.655708] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x2a0
+[ 84.655710] xhci_hcd 0000:0b:00.0: Get port status returned 0x100
+[ 84.665590] xhci_hcd 0000:0b:00.0: xhci_hub_status_data: stopping port polling.
+[ 84.695656] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x2a0
+[ 84.695659] xhci_hcd 0000:0b:00.0: Get port status returned 0x100
+[ 84.735651] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x2a0
+[ 84.735653] xhci_hcd 0000:0b:00.0: Get port status returned 0x100
+[ 84.775538] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x2a0
+[ 84.775541] xhci_hcd 0000:0b:00.0: Get port status returned 0x100
+[ 84.775555] hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
+[ 84.775573] hub 3-0:1.0: hub_suspend
+[ 84.775579] usb usb3: bus auto-suspend, wakeup 1
+[ 84.775594] xhci_hcd 0000:0b:00.0: xhci_hub_status_data: stopping port polling.
+[ 84.775613] xhci_hcd 0000:0b:00.0: xhci_suspend: stopping port polling.
+[ 84.775639] xhci_hcd 0000:0b:00.0: // Setting command ring address to 0xd6007001
+[ 84.775692] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_suspend: 0
+[ 84.775782] xhci_hcd 0000:0b:00.0: PME# enabled
+[ 129.408592] xhci_hcd 0000:0b:00.0: PME# disabled
+[ 129.408603] xhci_hcd 0000:0b:00.0: enabling bus mastering
+[ 129.408613] xhci_hcd 0000:0b:00.0: // Setting command ring address to 0xd6007001
+[ 129.408687] xhci_hcd 0000:0b:00.0: Port Status Change Event for port 2
+[ 129.408690] xhci_hcd 0000:0b:00.0: resume root hub
+[ 129.408693] xhci_hcd 0000:0b:00.0: handle_port_status: starting port polling.
+[ 129.408704] xhci_hcd 0000:0b:00.0: xhci_resume: starting port polling.
+[ 129.408709] xhci_hcd 0000:0b:00.0: hcd_pci_runtime_resume: 0
+[ 129.408712] usb usb3: usb auto-resume
+[ 129.408730] hub 3-0:1.0: hub_resume
+[ 129.408752] xhci_hcd 0000:0b:00.0: get port status, actual port 0 status = 0x2a0
+[ 129.408753] xhci_hcd 0000:0b:00.0: Get port status returned 0x100
+[ 129.408777] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x20ae1
+[ 129.408778] xhci_hcd 0000:0b:00.0: Get port status returned 0x10301
+[ 129.408784] hub 3-0:1.0: port 2: status 0301 change 0001
+[ 129.408804] xhci_hcd 0000:0b:00.0: clear port connect change, actual port 1 status = 0xae1
+[ 129.408818] usb usb4: usb wakeup-resume
+[ 129.408820] usb usb4: usb auto-resume
+[ 129.408834] hub 4-0:1.0: hub_resume
+[ 129.408853] xhci_hcd 0000:0b:00.0: get port status, actual port 0 status = 0x2a0
+[ 129.408854] xhci_hcd 0000:0b:00.0: Get port status returned 0x2a0
+[ 129.408877] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x2a0
+[ 129.408878] xhci_hcd 0000:0b:00.0: Get port status returned 0x2a0
+[ 129.408893] hub 4-0:1.0: state 7 ports 2 chg 0000 evt 0000
+[ 129.408919] xhci_hcd 0000:0b:00.0: set port remote wake mask, actual port 0 status = 0xe0002a0
+[ 129.408945] xhci_hcd 0000:0b:00.0: set port remote wake mask, actual port 1 status = 0xe0002a0
+[ 129.408952] hub 4-0:1.0: hub_suspend
+[ 129.408956] usb usb4: bus auto-suspend, wakeup 1
+[ 129.408967] xhci_hcd 0000:0b:00.0: xhci_hub_status_data: stopping port polling.
+[ 129.528128] xhci_hcd 0000:0b:00.0: xhci_hub_status_data: stopping port polling.
+[ 129.528138] hub 3-0:1.0: state 7 ports 2 chg 0004 evt 0000
+[ 129.528159] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0xae1
+[ 129.528160] xhci_hcd 0000:0b:00.0: Get port status returned 0x301
+[ 129.528168] hub 3-0:1.0: port 2, status 0301, change 0000, 1.5 Mb/s
+[ 129.528184] xhci_hcd 0000:0b:00.0: // Ding dong!
+[ 129.528223] xhci_hcd 0000:0b:00.0: Slot 1 output ctx = 0xd601a000 (dma)
+[ 129.528233] xhci_hcd 0000:0b:00.0: Slot 1 input ctx = 0xd6019000 (dma)
+[ 129.528260] xhci_hcd 0000:0b:00.0: Set slot id 1 dcbaa entry ffff8800d6006008 to 0xd601a000
+[ 129.528289] xhci_hcd 0000:0b:00.0: set port reset, actual port 1 status = 0xaf1
+[ 129.578302] xhci_hcd 0000:0b:00.0: Port Status Change Event for port 2
+[ 129.578318] xhci_hcd 0000:0b:00.0: handle_port_status: starting port polling.
+[ 129.588263] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x200a03
+[ 129.588266] xhci_hcd 0000:0b:00.0: Get port status returned 0x100303
+[ 129.648125] xhci_hcd 0000:0b:00.0: clear port reset change, actual port 1 status = 0xa03
+[ 129.648140] usb 3-2: new low-speed USB device number 3 using xhci_hcd
+[ 129.648142] xhci_hcd 0000:0b:00.0: Set root hub portnum to 2
+[ 129.648143] xhci_hcd 0000:0b:00.0: Set fake root hub portnum to 2
+[ 129.648144] xhci_hcd 0000:0b:00.0: udev->tt = ffff88040b7bd990
+[ 129.648146] xhci_hcd 0000:0b:00.0: udev->ttport = 0x2
+[ 129.648147] xhci_hcd 0000:0b:00.0: Slot ID 1 Input Context:
+[ 129.648149] xhci_hcd 0000:0b:00.0: @ffff8800d6019000 (virt) @d6019000 (dma) 0x000000 - drop flags
+[ 129.648150] xhci_hcd 0000:0b:00.0: @ffff8800d6019004 (virt) @d6019004 (dma) 0x000003 - add flags
+[ 129.648152] xhci_hcd 0000:0b:00.0: @ffff8800d6019008 (virt) @d6019008 (dma) 0x000000 - rsvd2[0]
+[ 129.648153] xhci_hcd 0000:0b:00.0: @ffff8800d601900c (virt) @d601900c (dma) 0x000000 - rsvd2[1]
+[ 129.648154] xhci_hcd 0000:0b:00.0: @ffff8800d6019010 (virt) @d6019010 (dma) 0x000000 - rsvd2[2]
+[ 129.648156] xhci_hcd 0000:0b:00.0: @ffff8800d6019014 (virt) @d6019014 (dma) 0x000000 - rsvd2[3]
+[ 129.648157] xhci_hcd 0000:0b:00.0: @ffff8800d6019018 (virt) @d6019018 (dma) 0x000000 - rsvd2[4]
+[ 129.648158] xhci_hcd 0000:0b:00.0: @ffff8800d601901c (virt) @d601901c (dma) 0x000000 - rsvd2[5]
+[ 129.648160] xhci_hcd 0000:0b:00.0: @ffff8800d6019020 (virt) @d6019020 (dma) 0x000000 - rsvd64[0]
+[ 129.648161] xhci_hcd 0000:0b:00.0: @ffff8800d6019028 (virt) @d6019028 (dma) 0x000000 - rsvd64[1]
+[ 129.648162] xhci_hcd 0000:0b:00.0: @ffff8800d6019030 (virt) @d6019030 (dma) 0x000000 - rsvd64[2]
+[ 129.648164] xhci_hcd 0000:0b:00.0: @ffff8800d6019038 (virt) @d6019038 (dma) 0x000000 - rsvd64[3]
+[ 129.648165] xhci_hcd 0000:0b:00.0: Slot Context:
+[ 129.648166] xhci_hcd 0000:0b:00.0: @ffff8800d6019040 (virt) @d6019040 (dma) 0x8200000 - dev_info
+[ 129.648167] xhci_hcd 0000:0b:00.0: @ffff8800d6019044 (virt) @d6019044 (dma) 0x020000 - dev_info2
+[ 129.648168] xhci_hcd 0000:0b:00.0: @ffff8800d6019048 (virt) @d6019048 (dma) 0x000000 - tt_info
+[ 129.648170] xhci_hcd 0000:0b:00.0: @ffff8800d601904c (virt) @d601904c (dma) 0x000000 - dev_state
+[ 129.648171] xhci_hcd 0000:0b:00.0: @ffff8800d6019050 (virt) @d6019050 (dma) 0x000000 - rsvd[0]
+[ 129.648172] xhci_hcd 0000:0b:00.0: @ffff8800d6019054 (virt) @d6019054 (dma) 0x000000 - rsvd[1]
+[ 129.648174] xhci_hcd 0000:0b:00.0: @ffff8800d6019058 (virt) @d6019058 (dma) 0x000000 - rsvd[2]
+[ 129.648175] xhci_hcd 0000:0b:00.0: @ffff8800d601905c (virt) @d601905c (dma) 0x000000 - rsvd[3]
+[ 129.648176] xhci_hcd 0000:0b:00.0: @ffff8800d6019060 (virt) @d6019060 (dma) 0x000000 - rsvd64[0]
+[ 129.648177] xhci_hcd 0000:0b:00.0: @ffff8800d6019068 (virt) @d6019068 (dma) 0x000000 - rsvd64[1]
+[ 129.648179] xhci_hcd 0000:0b:00.0: @ffff8800d6019070 (virt) @d6019070 (dma) 0x000000 - rsvd64[2]
+[ 129.648180] xhci_hcd 0000:0b:00.0: @ffff8800d6019078 (virt) @d6019078 (dma) 0x000000 - rsvd64[3]
+[ 129.648181] xhci_hcd 0000:0b:00.0: Endpoint 00 Context:
+[ 129.648182] xhci_hcd 0000:0b:00.0: @ffff8800d6019080 (virt) @d6019080 (dma) 0x000000 - ep_info
+[ 129.648184] xhci_hcd 0000:0b:00.0: @ffff8800d6019084 (virt) @d6019084 (dma) 0x080026 - ep_info2
+[ 129.648185] xhci_hcd 0000:0b:00.0: @ffff8800d6019088 (virt) @d6019088 (dma) 0xd601b401 - deq
+[ 129.648186] xhci_hcd 0000:0b:00.0: @ffff8800d6019090 (virt) @d6019090 (dma) 0x000000 - tx_info
+[ 129.648187] xhci_hcd 0000:0b:00.0: @ffff8800d6019094 (virt) @d6019094 (dma) 0x000000 - rsvd[0]
+[ 129.648189] xhci_hcd 0000:0b:00.0: @ffff8800d6019098 (virt) @d6019098 (dma) 0x000000 - rsvd[1]
+[ 129.648190] xhci_hcd 0000:0b:00.0: @ffff8800d601909c (virt) @d601909c (dma) 0x000000 - rsvd[2]
+[ 129.648191] xhci_hcd 0000:0b:00.0: @ffff8800d60190a0 (virt) @d60190a0 (dma) 0x000000 - rsvd64[0]
+[ 129.648193] xhci_hcd 0000:0b:00.0: @ffff8800d60190a8 (virt) @d60190a8 (dma) 0x000000 - rsvd64[1]
+[ 129.648194] xhci_hcd 0000:0b:00.0: @ffff8800d60190b0 (virt) @d60190b0 (dma) 0x000000 - rsvd64[2]
+[ 129.648195] xhci_hcd 0000:0b:00.0: @ffff8800d60190b8 (virt) @d60190b8 (dma) 0x000000 - rsvd64[3]
+[ 129.648196] xhci_hcd 0000:0b:00.0: Endpoint 01 Context:
+[ 129.648197] xhci_hcd 0000:0b:00.0: @ffff8800d60190c0 (virt) @d60190c0 (dma) 0x000000 - ep_info
+[ 129.648199] xhci_hcd 0000:0b:00.0: @ffff8800d60190c4 (virt) @d60190c4 (dma) 0x000000 - ep_info2
+[ 129.648200] xhci_hcd 0000:0b:00.0: @ffff8800d60190c8 (virt) @d60190c8 (dma) 0x000000 - deq
+[ 129.648201] xhci_hcd 0000:0b:00.0: @ffff8800d60190d0 (virt) @d60190d0 (dma) 0x000000 - tx_info
+[ 129.648202] xhci_hcd 0000:0b:00.0: @ffff8800d60190d4 (virt) @d60190d4 (dma) 0x000000 - rsvd[0]
+[ 129.648204] xhci_hcd 0000:0b:00.0: @ffff8800d60190d8 (virt) @d60190d8 (dma) 0x000000 - rsvd[1]
+[ 129.648205] xhci_hcd 0000:0b:00.0: @ffff8800d60190dc (virt) @d60190dc (dma) 0x000000 - rsvd[2]
+[ 129.648206] xhci_hcd 0000:0b:00.0: @ffff8800d60190e0 (virt) @d60190e0 (dma) 0x000000 - rsvd64[0]
+[ 129.648208] xhci_hcd 0000:0b:00.0: @ffff8800d60190e8 (virt) @d60190e8 (dma) 0x000000 - rsvd64[1]
+[ 129.648209] xhci_hcd 0000:0b:00.0: @ffff8800d60190f0 (virt) @d60190f0 (dma) 0x000000 - rsvd64[2]
+[ 129.648210] xhci_hcd 0000:0b:00.0: @ffff8800d60190f8 (virt) @d60190f8 (dma) 0x000000 - rsvd64[3]
+[ 129.648211] xhci_hcd 0000:0b:00.0: Endpoint 02 Context:
+[ 129.648213] xhci_hcd 0000:0b:00.0: @ffff8800d6019100 (virt) @d6019100 (dma) 0x000000 - ep_info
+[ 129.648214] xhci_hcd 0000:0b:00.0: @ffff8800d6019104 (virt) @d6019104 (dma) 0x000000 - ep_info2
+[ 129.648215] xhci_hcd 0000:0b:00.0: @ffff8800d6019108 (virt) @d6019108 (dma) 0x000000 - deq
+[ 129.648216] xhci_hcd 0000:0b:00.0: @ffff8800d6019110 (virt) @d6019110 (dma) 0x000000 - tx_info
+[ 129.648218] xhci_hcd 0000:0b:00.0: @ffff8800d6019114 (virt) @d6019114 (dma) 0x000000 - rsvd[0]
+[ 129.648219] xhci_hcd 0000:0b:00.0: @ffff8800d6019118 (virt) @d6019118 (dma) 0x000000 - rsvd[1]
+[ 129.648220] xhci_hcd 0000:0b:00.0: @ffff8800d601911c (virt) @d601911c (dma) 0x000000 - rsvd[2]
+[ 129.648221] xhci_hcd 0000:0b:00.0: @ffff8800d6019120 (virt) @d6019120 (dma) 0x000000 - rsvd64[0]
+[ 129.648223] xhci_hcd 0000:0b:00.0: @ffff8800d6019128 (virt) @d6019128 (dma) 0x000000 - rsvd64[1]
+[ 129.648224] xhci_hcd 0000:0b:00.0: @ffff8800d6019130 (virt) @d6019130 (dma) 0x000000 - rsvd64[2]
+[ 129.648225] xhci_hcd 0000:0b:00.0: @ffff8800d6019138 (virt) @d6019138 (dma) 0x000000 - rsvd64[3]
+[ 129.648228] xhci_hcd 0000:0b:00.0: // Ding dong!
+[ 129.649498] xhci_hcd 0000:0b:00.0: Successful Address Device command
+[ 129.649514] xhci_hcd 0000:0b:00.0: Op regs DCBAA ptr = 0x000000d6006000
+[ 129.649522] xhci_hcd 0000:0b:00.0: Slot ID 1 dcbaa entry @ffff8800d6006008 = 0x000000d601a000
+[ 129.649527] xhci_hcd 0000:0b:00.0: Output Context DMA address = 0xd601a000
+[ 129.649532] xhci_hcd 0000:0b:00.0: Slot ID 1 Input Context:
+[ 129.649539] xhci_hcd 0000:0b:00.0: @ffff8800d6019000 (virt) @d6019000 (dma) 0x000000 - drop flags
+[ 129.649545] xhci_hcd 0000:0b:00.0: @ffff8800d6019004 (virt) @d6019004 (dma) 0x000003 - add flags
+[ 129.649551] xhci_hcd 0000:0b:00.0: @ffff8800d6019008 (virt) @d6019008 (dma) 0x000000 - rsvd2[0]
+[ 129.649556] xhci_hcd 0000:0b:00.0: @ffff8800d601900c (virt) @d601900c (dma) 0x000000 - rsvd2[1]
+[ 129.649562] xhci_hcd 0000:0b:00.0: @ffff8800d6019010 (virt) @d6019010 (dma) 0x000000 - rsvd2[2]
+[ 129.649568] xhci_hcd 0000:0b:00.0: @ffff8800d6019014 (virt) @d6019014 (dma) 0x000000 - rsvd2[3]
+[ 129.649580] xhci_hcd 0000:0b:00.0: @ffff8800d6019018 (virt) @d6019018 (dma) 0x000000 - rsvd2[4]
+[ 129.649582] xhci_hcd 0000:0b:00.0: @ffff8800d601901c (virt) @d601901c (dma) 0x000000 - rsvd2[5]
+[ 129.649583] xhci_hcd 0000:0b:00.0: @ffff8800d6019020 (virt) @d6019020 (dma) 0x000000 - rsvd64[0]
+[ 129.649584] xhci_hcd 0000:0b:00.0: @ffff8800d6019028 (virt) @d6019028 (dma) 0x000000 - rsvd64[1]
+[ 129.649586] xhci_hcd 0000:0b:00.0: @ffff8800d6019030 (virt) @d6019030 (dma) 0x000000 - rsvd64[2]
+[ 129.649587] xhci_hcd 0000:0b:00.0: @ffff8800d6019038 (virt) @d6019038 (dma) 0x000000 - rsvd64[3]
+[ 129.649588] xhci_hcd 0000:0b:00.0: Slot Context:
+[ 129.649589] xhci_hcd 0000:0b:00.0: @ffff8800d6019040 (virt) @d6019040 (dma) 0x8200000 - dev_info
+[ 129.649591] xhci_hcd 0000:0b:00.0: @ffff8800d6019044 (virt) @d6019044 (dma) 0x020000 - dev_info2
+[ 129.649592] xhci_hcd 0000:0b:00.0: @ffff8800d6019048 (virt) @d6019048 (dma) 0x000000 - tt_info
+[ 129.649593] xhci_hcd 0000:0b:00.0: @ffff8800d601904c (virt) @d601904c (dma) 0x000000 - dev_state
+[ 129.649594] xhci_hcd 0000:0b:00.0: @ffff8800d6019050 (virt) @d6019050 (dma) 0x000000 - rsvd[0]
+[ 129.649596] xhci_hcd 0000:0b:00.0: @ffff8800d6019054 (virt) @d6019054 (dma) 0x000000 - rsvd[1]
+[ 129.649597] xhci_hcd 0000:0b:00.0: @ffff8800d6019058 (virt) @d6019058 (dma) 0x000000 - rsvd[2]
+[ 129.649598] xhci_hcd 0000:0b:00.0: @ffff8800d601905c (virt) @d601905c (dma) 0x000000 - rsvd[3]
+[ 129.649600] xhci_hcd 0000:0b:00.0: @ffff8800d6019060 (virt) @d6019060 (dma) 0x000000 - rsvd64[0]
+[ 129.649601] xhci_hcd 0000:0b:00.0: @ffff8800d6019068 (virt) @d6019068 (dma) 0x000000 - rsvd64[1]
+[ 129.649602] xhci_hcd 0000:0b:00.0: @ffff8800d6019070 (virt) @d6019070 (dma) 0x000000 - rsvd64[2]
+[ 129.649603] xhci_hcd 0000:0b:00.0: @ffff8800d6019078 (virt) @d6019078 (dma) 0x000000 - rsvd64[3]
+[ 129.649605] xhci_hcd 0000:0b:00.0: Endpoint 00 Context:
+[ 129.649606] xhci_hcd 0000:0b:00.0: @ffff8800d6019080 (virt) @d6019080 (dma) 0x000000 - ep_info
+[ 129.649607] xhci_hcd 0000:0b:00.0: @ffff8800d6019084 (virt) @d6019084 (dma) 0x080026 - ep_info2
+[ 129.649608] xhci_hcd 0000:0b:00.0: @ffff8800d6019088 (virt) @d6019088 (dma) 0xd601b401 - deq
+[ 129.649610] xhci_hcd 0000:0b:00.0: @ffff8800d6019090 (virt) @d6019090 (dma) 0x000000 - tx_info
+[ 129.649611] xhci_hcd 0000:0b:00.0: @ffff8800d6019094 (virt) @d6019094 (dma) 0x000000 - rsvd[0]
+[ 129.649612] xhci_hcd 0000:0b:00.0: @ffff8800d6019098 (virt) @d6019098 (dma) 0x000000 - rsvd[1]
+[ 129.649613] xhci_hcd 0000:0b:00.0: @ffff8800d601909c (virt) @d601909c (dma) 0x000000 - rsvd[2]
+[ 129.649615] xhci_hcd 0000:0b:00.0: @ffff8800d60190a0 (virt) @d60190a0 (dma) 0x000000 - rsvd64[0]
+[ 129.649616] xhci_hcd 0000:0b:00.0: @ffff8800d60190a8 (virt) @d60190a8 (dma) 0x000000 - rsvd64[1]
+[ 129.649617] xhci_hcd 0000:0b:00.0: @ffff8800d60190b0 (virt) @d60190b0 (dma) 0x000000 - rsvd64[2]
+[ 129.649619] xhci_hcd 0000:0b:00.0: @ffff8800d60190b8 (virt) @d60190b8 (dma) 0x000000 - rsvd64[3]
+[ 129.649620] xhci_hcd 0000:0b:00.0: Endpoint 01 Context:
+[ 129.649621] xhci_hcd 0000:0b:00.0: @ffff8800d60190c0 (virt) @d60190c0 (dma) 0x000000 - ep_info
+[ 129.649622] xhci_hcd 0000:0b:00.0: @ffff8800d60190c4 (virt) @d60190c4 (dma) 0x000000 - ep_info2
+[ 129.649623] xhci_hcd 0000:0b:00.0: @ffff8800d60190c8 (virt) @d60190c8 (dma) 0x000000 - deq
+[ 129.649625] xhci_hcd 0000:0b:00.0: @ffff8800d60190d0 (virt) @d60190d0 (dma) 0x000000 - tx_info
+[ 129.649626] xhci_hcd 0000:0b:00.0: @ffff8800d60190d4 (virt) @d60190d4 (dma) 0x000000 - rsvd[0]
+[ 129.649627] xhci_hcd 0000:0b:00.0: @ffff8800d60190d8 (virt) @d60190d8 (dma) 0x000000 - rsvd[1]
+[ 129.649628] xhci_hcd 0000:0b:00.0: @ffff8800d60190dc (virt) @d60190dc (dma) 0x000000 - rsvd[2]
+[ 129.649630] xhci_hcd 0000:0b:00.0: @ffff8800d60190e0 (virt) @d60190e0 (dma) 0x000000 - rsvd64[0]
+[ 129.649631] xhci_hcd 0000:0b:00.0: @ffff8800d60190e8 (virt) @d60190e8 (dma) 0x000000 - rsvd64[1]
+[ 129.649632] xhci_hcd 0000:0b:00.0: @ffff8800d60190f0 (virt) @d60190f0 (dma) 0x000000 - rsvd64[2]
+[ 129.649634] xhci_hcd 0000:0b:00.0: @ffff8800d60190f8 (virt) @d60190f8 (dma) 0x000000 - rsvd64[3]
+[ 129.649635] xhci_hcd 0000:0b:00.0: Endpoint 02 Context:
+[ 129.649636] xhci_hcd 0000:0b:00.0: @ffff8800d6019100 (virt) @d6019100 (dma) 0x000000 - ep_info
+[ 129.649637] xhci_hcd 0000:0b:00.0: @ffff8800d6019104 (virt) @d6019104 (dma) 0x000000 - ep_info2
+[ 129.649638] xhci_hcd 0000:0b:00.0: @ffff8800d6019108 (virt) @d6019108 (dma) 0x000000 - deq
+[ 129.649639] xhci_hcd 0000:0b:00.0: @ffff8800d6019110 (virt) @d6019110 (dma) 0x000000 - tx_info
+[ 129.649641] xhci_hcd 0000:0b:00.0: @ffff8800d6019114 (virt) @d6019114 (dma) 0x000000 - rsvd[0]
+[ 129.649642] xhci_hcd 0000:0b:00.0: @ffff8800d6019118 (virt) @d6019118 (dma) 0x000000 - rsvd[1]
+[ 129.649643] xhci_hcd 0000:0b:00.0: @ffff8800d601911c (virt) @d601911c (dma) 0x000000 - rsvd[2]
+[ 129.649645] xhci_hcd 0000:0b:00.0: @ffff8800d6019120 (virt) @d6019120 (dma) 0x000000 - rsvd64[0]
+[ 129.649646] xhci_hcd 0000:0b:00.0: @ffff8800d6019128 (virt) @d6019128 (dma) 0x000000 - rsvd64[1]
+[ 129.649647] xhci_hcd 0000:0b:00.0: @ffff8800d6019130 (virt) @d6019130 (dma) 0x000000 - rsvd64[2]
+[ 129.649648] xhci_hcd 0000:0b:00.0: @ffff8800d6019138 (virt) @d6019138 (dma) 0x000000 - rsvd64[3]
+[ 129.649650] xhci_hcd 0000:0b:00.0: Slot ID 1 Output Context:
+[ 129.649651] xhci_hcd 0000:0b:00.0: Slot Context:
+[ 129.649652] xhci_hcd 0000:0b:00.0: @ffff8800d601a000 (virt) @d601a000 (dma) 0x8200000 - dev_info
+[ 129.649653] xhci_hcd 0000:0b:00.0: @ffff8800d601a004 (virt) @d601a004 (dma) 0x020000 - dev_info2
+[ 129.649655] xhci_hcd 0000:0b:00.0: @ffff8800d601a008 (virt) @d601a008 (dma) 0x000000 - tt_info
+[ 129.649657] xhci_hcd 0000:0b:00.0: @ffff8800d601a00c (virt) @d601a00c (dma) 0x10000001 - dev_state
+[ 129.649658] xhci_hcd 0000:0b:00.0: @ffff8800d601a010 (virt) @d601a010 (dma) 0x000000 - rsvd[0]
+[ 129.649660] xhci_hcd 0000:0b:00.0: @ffff8800d601a014 (virt) @d601a014 (dma) 0x000000 - rsvd[1]
+[ 129.649661] xhci_hcd 0000:0b:00.0: @ffff8800d601a018 (virt) @d601a018 (dma) 0x000000 - rsvd[2]
+[ 129.649663] xhci_hcd 0000:0b:00.0: @ffff8800d601a01c (virt) @d601a01c (dma) 0x000000 - rsvd[3]
+[ 129.649665] xhci_hcd 0000:0b:00.0: @ffff8800d601a020 (virt) @d601a020 (dma) 0x000000 - rsvd64[0]
+[ 129.649666] xhci_hcd 0000:0b:00.0: @ffff8800d601a028 (virt) @d601a028 (dma) 0x000000 - rsvd64[1]
+[ 129.649668] xhci_hcd 0000:0b:00.0: @ffff8800d601a030 (virt) @d601a030 (dma) 0x000000 - rsvd64[2]
+[ 129.649669] xhci_hcd 0000:0b:00.0: @ffff8800d601a038 (virt) @d601a038 (dma) 0x000000 - rsvd64[3]
+[ 129.649671] xhci_hcd 0000:0b:00.0: Endpoint 00 Context:
+[ 129.649672] xhci_hcd 0000:0b:00.0: @ffff8800d601a040 (virt) @d601a040 (dma) 0x000001 - ep_info
+[ 129.649674] xhci_hcd 0000:0b:00.0: @ffff8800d601a044 (virt) @d601a044 (dma) 0x080026 - ep_info2
+[ 129.649676] xhci_hcd 0000:0b:00.0: @ffff8800d601a048 (virt) @d601a048 (dma) 0xd601b401 - deq
+[ 129.649677] xhci_hcd 0000:0b:00.0: @ffff8800d601a050 (virt) @d601a050 (dma) 0x000000 - tx_info
+[ 129.649679] xhci_hcd 0000:0b:00.0: @ffff8800d601a054 (virt) @d601a054 (dma) 0x000000 - rsvd[0]
+[ 129.649680] xhci_hcd 0000:0b:00.0: @ffff8800d601a058 (virt) @d601a058 (dma) 0x000000 - rsvd[1]
+[ 129.649682] xhci_hcd 0000:0b:00.0: @ffff8800d601a05c (virt) @d601a05c (dma) 0x000000 - rsvd[2]
+[ 129.649684] xhci_hcd 0000:0b:00.0: @ffff8800d601a060 (virt) @d601a060 (dma) 0x000000 - rsvd64[0]
+[ 129.649685] xhci_hcd 0000:0b:00.0: @ffff8800d601a068 (virt) @d601a068 (dma) 0x000000 - rsvd64[1]
+[ 129.649687] xhci_hcd 0000:0b:00.0: @ffff8800d601a070 (virt) @d601a070 (dma) 0x000000 - rsvd64[2]
+[ 129.649688] xhci_hcd 0000:0b:00.0: @ffff8800d601a078 (virt) @d601a078 (dma) 0x000000 - rsvd64[3]
+[ 129.649689] xhci_hcd 0000:0b:00.0: Endpoint 01 Context:
+[ 129.649691] xhci_hcd 0000:0b:00.0: @ffff8800d601a080 (virt) @d601a080 (dma) 0x000000 - ep_info
+[ 129.649692] xhci_hcd 0000:0b:00.0: @ffff8800d601a084 (virt) @d601a084 (dma) 0x000000 - ep_info2
+[ 129.649693] xhci_hcd 0000:0b:00.0: @ffff8800d601a088 (virt) @d601a088 (dma) 0x000000 - deq
+[ 129.649694] xhci_hcd 0000:0b:00.0: @ffff8800d601a090 (virt) @d601a090 (dma) 0x000000 - tx_info
+[ 129.649696] xhci_hcd 0000:0b:00.0: @ffff8800d601a094 (virt) @d601a094 (dma) 0x000000 - rsvd[0]
+[ 129.649697] xhci_hcd 0000:0b:00.0: @ffff8800d601a098 (virt) @d601a098 (dma) 0x000000 - rsvd[1]
+[ 129.649698] xhci_hcd 0000:0b:00.0: @ffff8800d601a09c (virt) @d601a09c (dma) 0x000000 - rsvd[2]
+[ 129.649699] xhci_hcd 0000:0b:00.0: @ffff8800d601a0a0 (virt) @d601a0a0 (dma) 0x000000 - rsvd64[0]
+[ 129.649701] xhci_hcd 0000:0b:00.0: @ffff8800d601a0a8 (virt) @d601a0a8 (dma) 0x000000 - rsvd64[1]
+[ 129.649702] xhci_hcd 0000:0b:00.0: @ffff8800d601a0b0 (virt) @d601a0b0 (dma) 0x000000 - rsvd64[2]
+[ 129.649703] xhci_hcd 0000:0b:00.0: @ffff8800d601a0b8 (virt) @d601a0b8 (dma) 0x000000 - rsvd64[3]
+[ 129.649704] xhci_hcd 0000:0b:00.0: Endpoint 02 Context:
+[ 129.649706] xhci_hcd 0000:0b:00.0: @ffff8800d601a0c0 (virt) @d601a0c0 (dma) 0x000000 - ep_info
+[ 129.649707] xhci_hcd 0000:0b:00.0: @ffff8800d601a0c4 (virt) @d601a0c4 (dma) 0x000000 - ep_info2
+[ 129.649708] xhci_hcd 0000:0b:00.0: @ffff8800d601a0c8 (virt) @d601a0c8 (dma) 0x000000 - deq
+[ 129.649709] xhci_hcd 0000:0b:00.0: @ffff8800d601a0d0 (virt) @d601a0d0 (dma) 0x000000 - tx_info
+[ 129.649711] xhci_hcd 0000:0b:00.0: @ffff8800d601a0d4 (virt) @d601a0d4 (dma) 0x000000 - rsvd[0]
+[ 129.649712] xhci_hcd 0000:0b:00.0: @ffff8800d601a0d8 (virt) @d601a0d8 (dma) 0x000000 - rsvd[1]
+[ 129.649713] xhci_hcd 0000:0b:00.0: @ffff8800d601a0dc (virt) @d601a0dc (dma) 0x000000 - rsvd[2]
+[ 129.649715] xhci_hcd 0000:0b:00.0: @ffff8800d601a0e0 (virt) @d601a0e0 (dma) 0x000000 - rsvd64[0]
+[ 129.649716] xhci_hcd 0000:0b:00.0: @ffff8800d601a0e8 (virt) @d601a0e8 (dma) 0x000000 - rsvd64[1]
+[ 129.649717] xhci_hcd 0000:0b:00.0: @ffff8800d601a0f0 (virt) @d601a0f0 (dma) 0x000000 - rsvd64[2]
+[ 129.649718] xhci_hcd 0000:0b:00.0: @ffff8800d601a0f8 (virt) @d601a0f8 (dma) 0x000000 - rsvd64[3]
+[ 129.649720] xhci_hcd 0000:0b:00.0: Internal device address = 2
+[ 129.686495] usb 3-2: skipped 1 descriptor after interface
+[ 129.688362] xhci_hcd 0000:0b:00.0: Waiting for status stage event
+[ 129.689551] usb 3-2: default language 0x0409
+[ 129.697438] xhci_hcd 0000:0b:00.0: Waiting for status stage event
+[ 129.701439] xhci_hcd 0000:0b:00.0: Waiting for status stage event
+[ 129.702553] usb 3-2: udev 3, busnum 3, minor = 258
+[ 129.702557] usb 3-2: New USB device found, idVendor=0458, idProduct=0036
+[ 129.702558] usb 3-2: New USB device strings: Mfr=2, Product=1, SerialNumber=0
+[ 129.702559] usb 3-2: Product: NetScroll + Mini Traveler
+[ 129.702560] usb 3-2: Manufacturer: Genius
+[ 129.703038] usb 3-2: usb_probe_device
+[ 129.703041] usb 3-2: configuration #1 chosen from 1 choice
+[ 129.703090] usb 3-2: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes
+[ 129.703093] xhci_hcd 0000:0b:00.0: add ep 0x81, slot id 1, new drop flags = 0x0, new add flags = 0x8, new slot info = 0x18200000
+[ 129.703095] xhci_hcd 0000:0b:00.0: xhci_check_bandwidth called for udev ffff880406fdb090
+[ 129.703096] xhci_hcd 0000:0b:00.0: New Input Control Context:
+[ 129.703098] xhci_hcd 0000:0b:00.0: @ffff8800d6019000 (virt) @d6019000 (dma) 0x000000 - drop flags
+[ 129.703099] xhci_hcd 0000:0b:00.0: @ffff8800d6019004 (virt) @d6019004 (dma) 0x000009 - add flags
+[ 129.703100] xhci_hcd 0000:0b:00.0: @ffff8800d6019008 (virt) @d6019008 (dma) 0x000000 - rsvd2[0]
+[ 129.703102] xhci_hcd 0000:0b:00.0: @ffff8800d601900c (virt) @d601900c (dma) 0x000000 - rsvd2[1]
+[ 129.703103] xhci_hcd 0000:0b:00.0: @ffff8800d6019010 (virt) @d6019010 (dma) 0x000000 - rsvd2[2]
+[ 129.703105] xhci_hcd 0000:0b:00.0: @ffff8800d6019014 (virt) @d6019014 (dma) 0x000000 - rsvd2[3]
+[ 129.703106] xhci_hcd 0000:0b:00.0: @ffff8800d6019018 (virt) @d6019018 (dma) 0x000000 - rsvd2[4]
+[ 129.703107] xhci_hcd 0000:0b:00.0: @ffff8800d601901c (virt) @d601901c (dma) 0x000000 - rsvd2[5]
+[ 129.703109] xhci_hcd 0000:0b:00.0: @ffff8800d6019020 (virt) @d6019020 (dma) 0x000000 - rsvd64[0]
+[ 129.703111] xhci_hcd 0000:0b:00.0: @ffff8800d6019028 (virt) @d6019028 (dma) 0x000000 - rsvd64[1]
+[ 129.703112] xhci_hcd 0000:0b:00.0: @ffff8800d6019030 (virt) @d6019030 (dma) 0x000000 - rsvd64[2]
+[ 129.703113] xhci_hcd 0000:0b:00.0: @ffff8800d6019038 (virt) @d6019038 (dma) 0x000000 - rsvd64[3]
+[ 129.703114] xhci_hcd 0000:0b:00.0: Slot Context:
+[ 129.703116] xhci_hcd 0000:0b:00.0: @ffff8800d6019040 (virt) @d6019040 (dma) 0x18200000 - dev_info
+[ 129.703117] xhci_hcd 0000:0b:00.0: @ffff8800d6019044 (virt) @d6019044 (dma) 0x020000 - dev_info2
+[ 129.703119] xhci_hcd 0000:0b:00.0: @ffff8800d6019048 (virt) @d6019048 (dma) 0x000000 - tt_info
+[ 129.703120] xhci_hcd 0000:0b:00.0: @ffff8800d601904c (virt) @d601904c (dma) 0x000000 - dev_state
+[ 129.703121] xhci_hcd 0000:0b:00.0: @ffff8800d6019050 (virt) @d6019050 (dma) 0x000000 - rsvd[0]
+[ 129.703123] xhci_hcd 0000:0b:00.0: @ffff8800d6019054 (virt) @d6019054 (dma) 0x000000 - rsvd[1]
+[ 129.703124] xhci_hcd 0000:0b:00.0: @ffff8800d6019058 (virt) @d6019058 (dma) 0x000000 - rsvd[2]
+[ 129.703125] xhci_hcd 0000:0b:00.0: @ffff8800d601905c (virt) @d601905c (dma) 0x000000 - rsvd[3]
+[ 129.703127] xhci_hcd 0000:0b:00.0: @ffff8800d6019060 (virt) @d6019060 (dma) 0x000000 - rsvd64[0]
+[ 129.703128] xhci_hcd 0000:0b:00.0: @ffff8800d6019068 (virt) @d6019068 (dma) 0x000000 - rsvd64[1]
+[ 129.703130] xhci_hcd 0000:0b:00.0: @ffff8800d6019070 (virt) @d6019070 (dma) 0x000000 - rsvd64[2]
+[ 129.703131] xhci_hcd 0000:0b:00.0: @ffff8800d6019078 (virt) @d6019078 (dma) 0x000000 - rsvd64[3]
+[ 129.703132] xhci_hcd 0000:0b:00.0: Endpoint 00 Context:
+[ 129.703134] xhci_hcd 0000:0b:00.0: @ffff8800d6019080 (virt) @d6019080 (dma) 0x000000 - ep_info
+[ 129.703135] xhci_hcd 0000:0b:00.0: @ffff8800d6019084 (virt) @d6019084 (dma) 0x080026 - ep_info2
+[ 129.703136] xhci_hcd 0000:0b:00.0: @ffff8800d6019088 (virt) @d6019088 (dma) 0xd601b401 - deq
+[ 129.703138] xhci_hcd 0000:0b:00.0: @ffff8800d6019090 (virt) @d6019090 (dma) 0x000000 - tx_info
+[ 129.703139] xhci_hcd 0000:0b:00.0: @ffff8800d6019094 (virt) @d6019094 (dma) 0x000000 - rsvd[0]
+[ 129.703140] xhci_hcd 0000:0b:00.0: @ffff8800d6019098 (virt) @d6019098 (dma) 0x000000 - rsvd[1]
+[ 129.703142] xhci_hcd 0000:0b:00.0: @ffff8800d601909c (virt) @d601909c (dma) 0x000000 - rsvd[2]
+[ 129.703143] xhci_hcd 0000:0b:00.0: @ffff8800d60190a0 (virt) @d60190a0 (dma) 0x000000 - rsvd64[0]
+[ 129.703145] xhci_hcd 0000:0b:00.0: @ffff8800d60190a8 (virt) @d60190a8 (dma) 0x000000 - rsvd64[1]
+[ 129.703146] xhci_hcd 0000:0b:00.0: @ffff8800d60190b0 (virt) @d60190b0 (dma) 0x000000 - rsvd64[2]
+[ 129.703147] xhci_hcd 0000:0b:00.0: @ffff8800d60190b8 (virt) @d60190b8 (dma) 0x000000 - rsvd64[3]
+[ 129.703149] xhci_hcd 0000:0b:00.0: Endpoint 01 Context:
+[ 129.703150] xhci_hcd 0000:0b:00.0: @ffff8800d60190c0 (virt) @d60190c0 (dma) 0x000000 - ep_info
+[ 129.703151] xhci_hcd 0000:0b:00.0: @ffff8800d60190c4 (virt) @d60190c4 (dma) 0x000000 - ep_info2
+[ 129.703153] xhci_hcd 0000:0b:00.0: @ffff8800d60190c8 (virt) @d60190c8 (dma) 0x000000 - deq
+[ 129.703154] xhci_hcd 0000:0b:00.0: @ffff8800d60190d0 (virt) @d60190d0 (dma) 0x000000 - tx_info
+[ 129.703155] xhci_hcd 0000:0b:00.0: @ffff8800d60190d4 (virt) @d60190d4 (dma) 0x000000 - rsvd[0]
+[ 129.703157] xhci_hcd 0000:0b:00.0: @ffff8800d60190d8 (virt) @d60190d8 (dma) 0x000000 - rsvd[1]
+[ 129.703158] xhci_hcd 0000:0b:00.0: @ffff8800d60190dc (virt) @d60190dc (dma) 0x000000 - rsvd[2]
+[ 129.703159] xhci_hcd 0000:0b:00.0: @ffff8800d60190e0 (virt) @d60190e0 (dma) 0x000000 - rsvd64[0]
+[ 129.703161] xhci_hcd 0000:0b:00.0: @ffff8800d60190e8 (virt) @d60190e8 (dma) 0x000000 - rsvd64[1]
+[ 129.703162] xhci_hcd 0000:0b:00.0: @ffff8800d60190f0 (virt) @d60190f0 (dma) 0x000000 - rsvd64[2]
+[ 129.703163] xhci_hcd 0000:0b:00.0: @ffff8800d60190f8 (virt) @d60190f8 (dma) 0x000000 - rsvd64[3]
+[ 129.703165] xhci_hcd 0000:0b:00.0: Endpoint 02 Context:
+[ 129.703166] xhci_hcd 0000:0b:00.0: @ffff8800d6019100 (virt) @d6019100 (dma) 0x060000 - ep_info
+[ 129.703167] xhci_hcd 0000:0b:00.0: @ffff8800d6019104 (virt) @d6019104 (dma) 0x04003e - ep_info2
+[ 129.703169] xhci_hcd 0000:0b:00.0: @ffff8800d6019108 (virt) @d6019108 (dma) 0xd601b001 - deq
+[ 129.703170] xhci_hcd 0000:0b:00.0: @ffff8800d6019110 (virt) @d6019110 (dma) 0x040004 - tx_info
+[ 129.703171] xhci_hcd 0000:0b:00.0: @ffff8800d6019114 (virt) @d6019114 (dma) 0x000000 - rsvd[0]
+[ 129.703173] xhci_hcd 0000:0b:00.0: @ffff8800d6019118 (virt) @d6019118 (dma) 0x000000 - rsvd[1]
+[ 129.703174] xhci_hcd 0000:0b:00.0: @ffff8800d601911c (virt) @d601911c (dma) 0x000000 - rsvd[2]
+[ 129.703175] xhci_hcd 0000:0b:00.0: @ffff8800d6019120 (virt) @d6019120 (dma) 0x000000 - rsvd64[0]
+[ 129.703177] xhci_hcd 0000:0b:00.0: @ffff8800d6019128 (virt) @d6019128 (dma) 0x000000 - rsvd64[1]
+[ 129.703178] xhci_hcd 0000:0b:00.0: @ffff8800d6019130 (virt) @d6019130 (dma) 0x000000 - rsvd64[2]
+[ 129.703180] xhci_hcd 0000:0b:00.0: @ffff8800d6019138 (virt) @d6019138 (dma) 0x000000 - rsvd64[3]
+[ 129.703182] xhci_hcd 0000:0b:00.0: // Ding dong!
+[ 129.712248] xhci_hcd 0000:0b:00.0: Completed config ep cmd
+[ 129.712291] usb 3-2: Successful Endpoint Configure command
+[ 129.712297] xhci_hcd 0000:0b:00.0: Output context after successful config ep cmd:
+[ 129.712302] xhci_hcd 0000:0b:00.0: Slot Context:
+[ 129.712309] xhci_hcd 0000:0b:00.0: @ffff8800d601a000 (virt) @d601a000 (dma) 0x18200000 - dev_info
+[ 129.712315] xhci_hcd 0000:0b:00.0: @ffff8800d601a004 (virt) @d601a004 (dma) 0x020000 - dev_info2
+[ 129.712321] xhci_hcd 0000:0b:00.0: @ffff8800d601a008 (virt) @d601a008 (dma) 0x000000 - tt_info
+[ 129.712327] xhci_hcd 0000:0b:00.0: @ffff8800d601a00c (virt) @d601a00c (dma) 0x18000001 - dev_state
+[ 129.712333] xhci_hcd 0000:0b:00.0: @ffff8800d601a010 (virt) @d601a010 (dma) 0x000000 - rsvd[0]
+[ 129.712339] xhci_hcd 0000:0b:00.0: @ffff8800d601a014 (virt) @d601a014 (dma) 0x0001f4 - rsvd[1]
+[ 129.712345] xhci_hcd 0000:0b:00.0: @ffff8800d601a018 (virt) @d601a018 (dma) 0x000000 - rsvd[2]
+[ 129.712350] xhci_hcd 0000:0b:00.0: @ffff8800d601a01c (virt) @d601a01c (dma) 0x000000 - rsvd[3]
+[ 129.712356] xhci_hcd 0000:0b:00.0: @ffff8800d601a020 (virt) @d601a020 (dma) 0x000000 - rsvd64[0]
+[ 129.712362] xhci_hcd 0000:0b:00.0: @ffff8800d601a028 (virt) @d601a028 (dma) 0x000000 - rsvd64[1]
+[ 129.712368] xhci_hcd 0000:0b:00.0: @ffff8800d601a030 (virt) @d601a030 (dma) 0x000000 - rsvd64[2]
+[ 129.712374] xhci_hcd 0000:0b:00.0: @ffff8800d601a038 (virt) @d601a038 (dma) 0x000000 - rsvd64[3]
+[ 129.712379] xhci_hcd 0000:0b:00.0: Endpoint 00 Context:
+[ 129.712391] xhci_hcd 0000:0b:00.0: @ffff8800d601a040 (virt) @d601a040 (dma) 0x000001 - ep_info
+[ 129.712393] xhci_hcd 0000:0b:00.0: @ffff8800d601a044 (virt) @d601a044 (dma) 0x080026 - ep_info2
+[ 129.712394] xhci_hcd 0000:0b:00.0: @ffff8800d601a048 (virt) @d601a048 (dma) 0xd601b551 - deq
+[ 129.712395] xhci_hcd 0000:0b:00.0: @ffff8800d601a050 (virt) @d601a050 (dma) 0x000000 - tx_info
+[ 129.712397] xhci_hcd 0000:0b:00.0: @ffff8800d601a054 (virt) @d601a054 (dma) 0x8000000 - rsvd[0]
+[ 129.712398] xhci_hcd 0000:0b:00.0: @ffff8800d601a058 (virt) @d601a058 (dma) 0x2000000 - rsvd[1]
+[ 129.712400] xhci_hcd 0000:0b:00.0: @ffff8800d601a05c (virt) @d601a05c (dma) 0x000126 - rsvd[2]
+[ 129.712401] xhci_hcd 0000:0b:00.0: @ffff8800d601a060 (virt) @d601a060 (dma) 0x000000 - rsvd64[0]
+[ 129.712403] xhci_hcd 0000:0b:00.0: @ffff8800d601a068 (virt) @d601a068 (dma) 0x000000 - rsvd64[1]
+[ 129.712404] xhci_hcd 0000:0b:00.0: @ffff8800d601a070 (virt) @d601a070 (dma) 0x000000 - rsvd64[2]
+[ 129.712405] xhci_hcd 0000:0b:00.0: @ffff8800d601a078 (virt) @d601a078 (dma) 0x000000 - rsvd64[3]
+[ 129.712407] xhci_hcd 0000:0b:00.0: Endpoint 01 Context:
+[ 129.712408] xhci_hcd 0000:0b:00.0: @ffff8800d601a080 (virt) @d601a080 (dma) 0x000000 - ep_info
+[ 129.712409] xhci_hcd 0000:0b:00.0: @ffff8800d601a084 (virt) @d601a084 (dma) 0x000000 - ep_info2
+[ 129.712411] xhci_hcd 0000:0b:00.0: @ffff8800d601a088 (virt) @d601a088 (dma) 0x000000 - deq
+[ 129.712412] xhci_hcd 0000:0b:00.0: @ffff8800d601a090 (virt) @d601a090 (dma) 0x000000 - tx_info
+[ 129.712413] xhci_hcd 0000:0b:00.0: @ffff8800d601a094 (virt) @d601a094 (dma) 0x000000 - rsvd[0]
+[ 129.712415] xhci_hcd 0000:0b:00.0: @ffff8800d601a098 (virt) @d601a098 (dma) 0x000000 - rsvd[1]
+[ 129.712416] xhci_hcd 0000:0b:00.0: @ffff8800d601a09c (virt) @d601a09c (dma) 0x000000 - rsvd[2]
+[ 129.712418] xhci_hcd 0000:0b:00.0: @ffff8800d601a0a0 (virt) @d601a0a0 (dma) 0x000000 - rsvd64[0]
+[ 129.712419] xhci_hcd 0000:0b:00.0: @ffff8800d601a0a8 (virt) @d601a0a8 (dma) 0x000000 - rsvd64[1]
+[ 129.712420] xhci_hcd 0000:0b:00.0: @ffff8800d601a0b0 (virt) @d601a0b0 (dma) 0x000000 - rsvd64[2]
+[ 129.712422] xhci_hcd 0000:0b:00.0: @ffff8800d601a0b8 (virt) @d601a0b8 (dma) 0x000000 - rsvd64[3]
+[ 129.712423] xhci_hcd 0000:0b:00.0: Endpoint 02 Context:
+[ 129.712424] xhci_hcd 0000:0b:00.0: @ffff8800d601a0c0 (virt) @d601a0c0 (dma) 0x060001 - ep_info
+[ 129.712426] xhci_hcd 0000:0b:00.0: @ffff8800d601a0c4 (virt) @d601a0c4 (dma) 0x04003e - ep_info2
+[ 129.712427] xhci_hcd 0000:0b:00.0: @ffff8800d601a0c8 (virt) @d601a0c8 (dma) 0xd601b001 - deq
+[ 129.712428] xhci_hcd 0000:0b:00.0: @ffff8800d601a0d0 (virt) @d601a0d0 (dma) 0x040004 - tx_info
+[ 129.712430] xhci_hcd 0000:0b:00.0: @ffff8800d601a0d4 (virt) @d601a0d4 (dma) 0x000000 - rsvd[0]
+[ 129.712431] xhci_hcd 0000:0b:00.0: @ffff8800d601a0d8 (virt) @d601a0d8 (dma) 0x000000 - rsvd[1]
+[ 129.712432] xhci_hcd 0000:0b:00.0: @ffff8800d601a0dc (virt) @d601a0dc (dma) 0x1000000 - rsvd[2]
+[ 129.712434] xhci_hcd 0000:0b:00.0: @ffff8800d601a0e0 (virt) @d601a0e0 (dma) 0x000000 - rsvd64[0]
+[ 129.712435] xhci_hcd 0000:0b:00.0: @ffff8800d601a0e8 (virt) @d601a0e8 (dma) 0x000000 - rsvd64[1]
+[ 129.712437] xhci_hcd 0000:0b:00.0: @ffff8800d601a0f0 (virt) @d601a0f0 (dma) 0x000000 - rsvd64[2]
+[ 129.712438] xhci_hcd 0000:0b:00.0: @ffff8800d601a0f8 (virt) @d601a0f8 (dma) 0x000000 - rsvd64[3]
+[ 129.712441] xhci_hcd 0000:0b:00.0: Endpoint 0x81 not halted, refusing to reset.
+[ 129.713510] usb 3-2: adding 3-2:1.0 (config #1, interface 0)
+[ 129.713741] usbhid 3-2:1.0: usb_probe_interface
+[ 129.713743] usbhid 3-2:1.0: usb_probe_interface - got id
+[ 129.725562] input: Genius NetScroll + Mini Traveler as /devices/pci0000:00/0000:00:1c.4/0000:0b:00.0/usb3/3-2/3-2:1.0/input/input16
+[ 129.726671] hid-generic 0003:0458:0036.0004: input,hidraw2: USB HID v1.10 Mouse [Genius NetScroll + Mini Traveler] on usb-0000:0b:00.0-2/input0
+[ 129.733807] xhci_hcd 0000:0b:00.0: get port status, actual port 0 status = 0x2a0
+[ 129.733809] xhci_hcd 0000:0b:00.0: Get port status returned 0x100
+[ 129.734354] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0xa03
+[ 129.734355] xhci_hcd 0000:0b:00.0: Get port status returned 0x303
+[ 129.737102] usb usb4: usb auto-resume
+[ 129.737118] hub 4-0:1.0: hub_resume
+[ 129.737142] xhci_hcd 0000:0b:00.0: get port status, actual port 0 status = 0x2a0
+[ 129.737144] xhci_hcd 0000:0b:00.0: Get port status returned 0x2a0
+[ 129.737172] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x2a0
+[ 129.737173] xhci_hcd 0000:0b:00.0: Get port status returned 0x2a0
+[ 129.737231] hub 4-0:1.0: state 7 ports 2 chg 0000 evt 0000
+[ 129.775455] xhci_hcd 0000:0b:00.0: get port status, actual port 0 status = 0x2a0
+[ 129.775458] xhci_hcd 0000:0b:00.0: Get port status returned 0x2a0
+[ 129.776009] xhci_hcd 0000:0b:00.0: get port status, actual port 1 status = 0x2a0
+[ 129.776010] xhci_hcd 0000:0b:00.0: Get port status returned 0x2a0
+[ 129.796668] xhci_hcd 0000:0b:00.0: set port remote wake mask, actual port 0 status = 0xe0002a0
+[ 129.796699] xhci_hcd 0000:0b:00.0: set port remote wake mask, actual port 1 status = 0xe0002a0
+[ 129.796709] hub 4-0:1.0: hub_suspend
+[ 129.796740] usb usb4: bus auto-suspend, wakeup 1
+[ 129.796752] xhci_hcd 0000:0b:00.0: xhci_hub_status_data: stopping port polling.
+[ 129.847651] xhci_hcd 0000:0b:00.0: xhci_hub_status_data: stopping port polling.
^ permalink raw reply [flat|nested] 7+ messages in thread
end of thread, other threads:[~2013-03-20 10:00 UTC | newest]
Thread overview: 7+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2013-03-14 17:30 3.8.2: xhci port is dead until pcieport PME# goes to disabled Martin Mokrejs
2013-03-18 17:01 ` Sarah Sharp
2013-03-18 17:20 ` Martin Mokrejs
2013-03-18 18:26 ` Sarah Sharp
2013-03-18 18:52 ` Martin Mokrejs
2013-03-18 19:58 ` Sarah Sharp
2013-03-20 10:00 ` Martin Mokrejs
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).