Bug 35212

Summary:	USB 3.0 controller on Clevo P150HM dies on reconnect
Product:	Drivers	Reporter:	Julian Sikorski (belegdol)
Component:	USB	Assignee:	Greg Kroah-Hartman (greg)
Status:	RESOLVED INVALID
Severity:	normal	CC:	yelena_volynets
Priority:	P1
Hardware:	All
OS:	Linux
Kernel Version:	2.6.38.6-26.rc1.fc15.x86_64	Subsystem:
Regression:	No	Bisected commit-id:
Attachments:	Test case for Bug 35212: USB 3.0 controller: re-connection problem

Description Julian Sikorski 2011-05-16 18:58:26 UTC

lspci says it is the following device:
02:00.0 USB Controller: NEC Corporation uPD720200 USB 3.0 Host Controller (rev 03)

I can only connect something to it once per reboot, the second attempt will fail. dmesg output with both successful and failed attempt is the following:

[34425.213419] usb 3-4: new full speed USB device using xhci_hcd and address 2
[34425.213813] usb 3-4: Device not responding to set address.
[34425.414475] usb 3-4: Device not responding to set address.
[34425.614842] usb 3-4: device not accepting address 2, error -71
[34425.767811] usb 3-4: new full speed USB device using xhci_hcd and address 3
[34425.768121] usb 3-4: Device not responding to set address.
[34425.968879] usb 3-4: Device not responding to set address.
[34426.169276] usb 3-4: device not accepting address 3, error -71
[34426.220220] hub 3-0:1.0: unable to enumerate USB device on port 4
[34427.336304] usb 3-2: new SuperSpeed USB device using xhci_hcd and address 5
[34427.349872] xhci_hcd 0000:02:00.0: WARN: short transfer on control ep
[34427.350489] xhci_hcd 0000:02:00.0: WARN: short transfer on control ep
[34427.351117] xhci_hcd 0000:02:00.0: WARN: short transfer on control ep
[34427.351738] xhci_hcd 0000:02:00.0: WARN: short transfer on control ep
[34427.352019] usb 3-2: New USB device found, idVendor=059f, idProduct=103e
[34427.352031] usb 3-2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[34427.352040] usb 3-2: Product: Rugged USB 3
[34427.352046] usb 3-2: Manufacturer: LaCie
[34427.352050] usb 3-2: SerialNumber: ce0238914a4c0000000
[34427.362678] scsi7 : usb-storage 3-2:1.0
[34429.212045] scsi 7:0:0:0: Direct-Access     ST950032 5AS              0002 PQ: 0 ANSI: 0
[34429.213752] sd 7:0:0:0: Attached scsi generic sg2 type 0
[34429.213939] sd 7:0:0:0: [sdb] 976773168 512-byte logical blocks: (500 GB/465 GiB)
[34429.214099] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.214665] sd 7:0:0:0: [sdb] Write Protect is off
[34429.214673] sd 7:0:0:0: [sdb] Mode Sense: 23 00 00 00
[34429.215015] sd 7:0:0:0: [sdb] No Caching mode page present
[34429.215022] sd 7:0:0:0: [sdb] Assuming drive cache: write through
[34429.217307] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.218228] sd 7:0:0:0: [sdb] No Caching mode page present
[34429.218235] sd 7:0:0:0: [sdb] Assuming drive cache: write through
[34429.260564]  sdb: sdb1 sdb2
[34429.261857] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.262954] sd 7:0:0:0: [sdb] No Caching mode page present
[34429.262966] sd 7:0:0:0: [sdb] Assuming drive cache: write through
[34429.262974] sd 7:0:0:0: [sdb] Attached SCSI disk
[34429.271422] sd 7:0:0:0: [sdb]  Sense Key : Recovered Error [current] [descriptor]
[34429.271437] Descriptor sense data with sense descriptors (in hex):
[34429.271442]         72 01 04 1d 00 00 00 0a 09 0c 00 00 00 00 00 07 
[34429.271462]         00 00 
[34429.271469] sd 7:0:0:0: [sdb]  ASC=0x4 ASCQ=0x1d
[34429.276821] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.277570] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.592731] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.593418] sd 7:0:0:0: [sdb]  Sense Key : Recovered Error [current] [descriptor]
[34429.593460] Descriptor sense data with sense descriptors (in hex):
[34429.593464]         72 01 04 1d 00 00 00 0e 09 0c 00 00 00 00 00 07 
[34429.593480]         00 00 00 00 40 50 
[34429.593489] sd 7:0:0:0: [sdb]  ASC=0x4 ASCQ=0x1d
[34429.749329] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.749943] sd 7:0:0:0: [sdb]  Sense Key : Recovered Error [current] [descriptor]
[34429.749955] Descriptor sense data with sense descriptors (in hex):
[34429.749960]         72 01 04 1d 00 00 00 0e 09 0c 00 00 00 00 00 c6 
[34429.749980]         00 08 00 0a 40 50 
[34429.749990] sd 7:0:0:0: [sdb]  ASC=0x4 ASCQ=0x1d
[34429.760285] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.798146] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.798775] sd 7:0:0:0: [sdb]  Sense Key : Recovered Error [current] [descriptor]
[34429.798788] Descriptor sense data with sense descriptors (in hex):
[34429.798793]         72 01 04 1d 00 00 00 0e 09 0c 00 00 00 00 00 46 
[34429.798812]         00 00 00 00 40 50 
[34429.798823] sd 7:0:0:0: [sdb]  ASC=0x4 ASCQ=0x1d
[34429.920832] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.921402] sd 7:0:0:0: [sdb]  Sense Key : Recovered Error [current] [descriptor]
[34429.921416] Descriptor sense data with sense descriptors (in hex):
[34429.921422]         72 01 04 1d 00 00 00 0e 09 0c 00 00 00 00 00 4e 
[34429.921443]         00 00 00 00 40 50 
[34429.921454] sd 7:0:0:0: [sdb]  ASC=0x4 ASCQ=0x1d
[34429.962981] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34429.963596] sd 7:0:0:0: [sdb]  Sense Key : Recovered Error [current] [descriptor]
[34429.963609] Descriptor sense data with sense descriptors (in hex):
[34429.963614]         72 01 04 1d 00 00 00 0e 09 0c 00 00 00 00 00 00 
[34429.963634]         00 4f 00 c2 40 50 
[34429.963645] sd 7:0:0:0: [sdb]  ASC=0x4 ASCQ=0x1d
[34430.963935] SELinux: initialized (dev sdb1, type fuseblk), uses genfs_contexts
[34442.290273] usb 3-2: USB disconnect, address 5
[34447.814631] usb 3-4: new full speed USB device using xhci_hcd and address 6
[34447.814980] usb 3-4: Device not responding to set address.
[34448.015489] usb 3-4: Device not responding to set address.
[34448.216049] usb 3-4: device not accepting address 6, error -71
[34448.369014] usb 3-4: new full speed USB device using xhci_hcd and address 7
[34448.369367] usb 3-4: Device not responding to set address.
[34448.569797] usb 3-4: Device not responding to set address.
[34448.770462] usb 3-4: device not accepting address 7, error -71
[34448.821390] hub 3-0:1.0: unable to enumerate USB device on port 4
[34449.854539] usb 3-2: new SuperSpeed USB device using xhci_hcd and address 9
[34449.868696] xhci_hcd 0000:02:00.0: WARN: short transfer on control ep
[34449.869254] xhci_hcd 0000:02:00.0: WARN: short transfer on control ep
[34449.870002] xhci_hcd 0000:02:00.0: WARN: short transfer on control ep
[34449.870669] xhci_hcd 0000:02:00.0: WARN: short transfer on control ep
[34449.870891] usb 3-2: New USB device found, idVendor=059f, idProduct=103e
[34449.870898] usb 3-2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[34449.870905] usb 3-2: Product: Rugged USB 3
[34449.870909] usb 3-2: Manufacturer: LaCie
[34449.870914] usb 3-2: SerialNumber: ce0238914a4c0000000
[34449.872420] scsi8 : usb-storage 3-2:1.0
[34451.814990] scsi 8:0:0:0: Direct-Access     ST950032 5AS              0002 PQ: 0 ANSI: 0
[34451.816791] sd 8:0:0:0: Attached scsi generic sg2 type 0
[34451.816867] sd 8:0:0:0: [sdb] 976773168 512-byte logical blocks: (500 GB/465 GiB)
[34451.817000] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34451.817617] sd 8:0:0:0: [sdb] Write Protect is off
[34451.817626] sd 8:0:0:0: [sdb] Mode Sense: 23 00 00 00
[34451.817955] sd 8:0:0:0: [sdb] No Caching mode page present
[34451.817961] sd 8:0:0:0: [sdb] Assuming drive cache: write through
[34451.819693] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34451.820663] sd 8:0:0:0: [sdb] No Caching mode page present
[34451.820674] sd 8:0:0:0: [sdb] Assuming drive cache: write through
[34451.863302]  sdb: sdb1 sdb2
[34451.864441] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34451.865405] sd 8:0:0:0: [sdb] No Caching mode page present
[34451.865414] sd 8:0:0:0: [sdb] Assuming drive cache: write through
[34451.865421] sd 8:0:0:0: [sdb] Attached SCSI disk
[34451.871178] sd 8:0:0:0: [sdb]  Sense Key : Recovered Error [current] [descriptor]
[34451.871193] Descriptor sense data with sense descriptors (in hex):
[34451.871198]         72 01 04 1d 00 00 00 0a 09 0c 00 00 00 00 00 07 
[34451.871217]         00 00 
[34451.871224] sd 8:0:0:0: [sdb]  ASC=0x4 ASCQ=0x1d
[34451.874734] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34451.875598] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34452.047787] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
[34464.284418] xhci_hcd 0000:02:00.0: xHCI host not responding to stop endpoint command.
[34464.284428] xhci_hcd 0000:02:00.0: Assuming host is dying, halting host.
[34464.290033] xhci_hcd 0000:02:00.0: Non-responsive xHCI host is not halting.
[34464.290040] xhci_hcd 0000:02:00.0: Completing active URBs anyway.
[34464.290057] xhci_hcd 0000:02:00.0: HC died; cleaning up
[34464.290092] usb 3-2: USB disconnect, address 9
[34464.290215] sd 8:0:0:0: Device offlined - not ready after error recovery

Comment 1 Julian Sikorski 2011-05-16 19:24:53 UTC

Upon resume the controller seems to be completely dead, not producing any logs upon device plugged in.

Comment 2 Julian Sikorski 2011-08-10 20:00:22 UTC

It is still a problem with 3.0. Moreover, the ports would sometimes die with machine on:

Aug 10 21:52:03 snowball2 kernel: [10822.659308] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
Aug 10 21:52:03 snowball2 kernel: [10822.660149] sd 6:0:0:0: [sdb]  Sense Key : Recovered Error [current] [descriptor]
Aug 10 21:52:03 snowball2 kernel: [10822.660153] Descriptor sense data with sense descriptors (in hex):
Aug 10 21:52:03 snowball2 kernel: [10822.660154]         72 01 04 1d 00 00 00 0e 09 0c 00 00 00 00 09 9d 
Aug 10 21:52:03 snowball2 kernel: [10822.660159]         00 0c 00 7d 40 50 
Aug 10 21:52:03 snowball2 kernel: [10822.660162] sd 6:0:0:0: [sdb]  ASC=0x4 ASCQ=0x1d
Aug 10 21:52:03 snowball2 kernel: [10822.660499] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
Aug 10 21:52:03 snowball2 kernel: [10822.745535] xhci_hcd 0000:02:00.0: WARN: Stalled endpoint
Aug 10 21:52:15 snowball2 kernel: [10835.221438] xhci_hcd 0000:02:00.0: xHCI host not responding to stop endpoint command.
Aug 10 21:52:15 snowball2 kernel: [10835.221451] xhci_hcd 0000:02:00.0: Assuming host is dying, halting host.
Aug 10 21:52:15 snowball2 kernel: [10835.228925] xhci_hcd 0000:02:00.0: HC died; cleaning up
Aug 10 21:52:15 snowball2 kernel: [10835.228965] usb 4-2: USB disconnect, device number 2
Aug 10 21:52:15 snowball2 kernel: [10835.229049] sd 6:0:0:0: Device offlined - not ready after error recovery
Aug 10 21:52:15 snowball2 kernel: [10835.229089] sd 6:0:0:0: rejecting I/O to offline device
Aug 10 21:52:15 snowball2 kernel: [10835.229121] sd 6:0:0:0: rejecting I/O to offline device
Aug 10 21:52:15 snowball2 kernel: [10835.229146] Buffer I/O error on device dm-3, logical block 19816543
Aug 10 21:52:15 snowball2 kernel: [10835.229151] Buffer I/O error on device dm-3, logical block 19816544
Aug 10 21:52:15 snowball2 kernel: [10835.229156] Buffer I/O error on device dm-3, logical block 19816545
Aug 10 21:52:15 snowball2 kernel: [10835.229160] Buffer I/O error on device dm-3, logical block 19816546
Aug 10 21:52:15 snowball2 kernel: [10835.229163] Buffer I/O error on device dm-3, logical block 19816547
Aug 10 21:52:15 snowball2 kernel: [10835.229167] Buffer I/O error on device dm-3, logical block 19816548
Aug 10 21:52:15 snowball2 kernel: [10835.229171] Buffer I/O error on device dm-3, logical block 19816549
Aug 10 21:52:15 snowball2 kernel: [10835.229174] Buffer I/O error on device dm-3, logical block 19816550
Aug 10 21:52:15 snowball2 kernel: [10835.229178] Buffer I/O error on device dm-3, logical block 19816551
Aug 10 21:52:15 snowball2 kernel: [10835.229249] sd 6:0:0:0: [sdb] Unhandled error code
Aug 10 21:52:15 snowball2 kernel: [10835.229251] sd 6:0:0:0: [sdb]  Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Aug 10 21:52:15 snowball2 kernel: [10835.229254] sd 6:0:0:0: [sdb] CDB: Read(10): 28 00 09 7d 0c 9e 00 00 f0 00
Aug 10 21:52:15 snowball2 kernel: [10835.229262] end_request: I/O error, dev sdb, sector 159190174

Comment 3 yelena_volynets 2011-08-10 22:48:06 UTC

Created attachment 68452 [details]
Test case for Bug 35212: USB 3.0 controller: re-connection problem

Test case for Bug 35212: USB 3.0 controller: re-connection problem.

Comment 4 yelena_volynets 2011-08-10 22:49:41 UTC

I had a problem similar to this Bug 35212 using kernel 2.6.39 and Seagate 2.5” USB3 external drives. They cannot re-connect.
 I installed a new kernel 3.0. The behavior is somewhat better: I can reconnect 2.5” USB 3 drives now, but I still can break it, if I disconnect USB3 drive very quickly, before it was recognizes by the system, then it cannot connect the second time.
Please note: if I use 3.5” USB3 external drive with its own power supply, the problem does not happen, even on a very fast disconnect.
I did try it with ACPI Support ON and OFF, but it did not affect 2.5” USB3 reconnection problem.
It is very easy to create the problem by simply wiggling the connector during the insertion, and then not seeing the device at all, so it is important to fix.
Please note: after breaking it with 2.5” USB3 drive, I cannot connect 3.5” USB3 drive either.
USB2 does not have this problem on any of the above units no matter how fast I connect and disconnect them.
I tested with different USB3 cards with the same result.

Comment 5 yelena_volynets 2011-08-19 19:00:57 UTC

I installed Kernel 3.0.3, but my quick re-connection problem with Seagate USB3 unit (PID=2332) is still the same

Aug 19 11:53:03 JblUsbEr70 kernel: [83105.812037] xhci_hcd 0000:34:00.0: Timeout while waiting for a slot
Aug 19 11:53:23 JblUsbEr70 kernel: [83126.016046] xhci_hcd 0000:34:00.0: Timeout while waiting for a slot
Aug 19 11:53:23 JblUsbEr70 kernel: [83126.220033] usb 17-1: device not accepting address 6, error -62
Aug 19 11:53:43 JblUsbEr70 kernel: [83146.220035] xhci_hcd 0000:34:00.0: Timeout while waiting for a slot
Aug 19 11:53:43 JblUsbEr70 kernel: [83146.220044] hub 17-0:1.0: couldn't allocate port 1 usb_device

Comment 6 Greg Kroah-Hartman 2012-02-22 21:02:26 UTC

All USB bugs should be sent to the linux-usb@vger.kernel.org mailing 
list, and not entered into bugzilla.  Please bring this issue up there,
if it is still a problem in the latest kernel release.