Google
  Web www.spinics.net

Re: ehci-hang on 2.6.32

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]


On Wed, 31 Mar 2010, matthieu castet wrote:

> Hi,
> 
> 
> since some kernels, high speed usb hang after some random time.
> 
> Here [1] is a trace of the hang with debug enabled for ehci.
> 
> When the hang occurs a device is disconnected, and then enumerated again.
> The enumeration fails and fallback to full-speed.
> If new device are plugged, high speed enumeration fails and fallback to full-speed.
> 
> 
> Any idea what could cause the failure ?
> Failing hardware ?

That's what it looks like.

If you leave the DVB tuner unplugged and use only the USB key, does the 
same thing still happen?

> [  229.180997] ehci_hcd 0000:00:10.3: detected XactErr len 7168/16356 retry 1
> [  286.293111] ehci_hcd 0000:00:10.3: detected XactErr len 2048/16356 retry 1
> [  288.944202] ehci_hcd 0000:00:10.3: detected XactErr len 3072/16356 retry 1
> [  388.887337] ehci_hcd 0000:00:10.3: detected XactErr len 512/16356 retry 1
> [  389.472001] ehci_hcd 0000:00:10.3: detected XactErr len 3072/16356 retry 1
> [  394.203943] ehci_hcd 0000:00:10.3: detected XactErr len 10752/16356 retry 1
> [  485.167470] ehci_hcd 0000:00:10.3: detected XactErr len 8704/16356 retry 1
> [  489.608760] ehci_hcd 0000:00:10.3: detected XactErr len 14848/16356 retry 1
> [  523.204270] ehci_hcd 0000:00:10.3: detected XactErr len 5120/16356 retry 1
> [  588.801557] ehci_hcd 0000:00:10.3: detected XactErr len 15872/16356 retry 1
> [  711.706667] ehci_hcd 0000:00:10.3: detected XactErr len 14848/16356 retry 1

These errors indicate some sort of hardware problem, although not
necessarily in the EHCI controller.  They could be caused by a bad
cable connection, electromagnetic interference, or something wrong with
the DVB tuner.

> [  787.775636] ehci_hcd 0000:00:10.3: GetStatus port 4 status 00100a POWER sig=se0 PEC CSC
> [  787.775654] usb 1-4: USB disconnect, address 2
> [  787.784508] af9015: bulk message failed:-22 (8/-1019085672)
> [  787.784515] af9013: I2C read failed reg:d730

Did you manually unplug the tuner here?  If you didn't then this is 
clearly a hardware error.

> [  791.966298] dvb-usb: AverMedia AVerTV Volar Black HD (A850) successfully deinitialized and disconnected.
> [  791.966505] ehci_hcd 0000:00:10.3: GetStatus port 4 status 001803 POWER sig=j CSC CONNECT
> [  792.148129] ehci_hcd 0000:00:10.3: port 4 high speed
> [  792.148140] ehci_hcd 0000:00:10.3: GetStatus port 4 status 001005 POWER sig=se0 PE CONNECT
> [  792.204022] usb 1-4: new high speed USB device using ehci_hcd and address 3
> [  807.296122] ehci_hcd 0000:00:10.3: port 4 high speed
> [  807.296133] ehci_hcd 0000:00:10.3: GetStatus port 4 status 00100f POWER sig=se0 PEC PE CSC CONNECT

That PEC is a bad symptom.  Apparently something caused the port to be
disabled before it was reset.

> [  807.296191] hub 1-0:1.0: unable to enumerate USB device on port 4
> [  807.299179] ehci_hcd 0000:00:10.3: GetStatus port 4 status 001803 POWER sig=j CSC CONNECT
> [  807.480122] ehci_hcd 0000:00:10.3: port 4 high speed
> [  807.480132] ehci_hcd 0000:00:10.3: GetStatus port 4 status 001005 POWER sig=se0 PE CONNECT
> [  807.536033] usb 1-4: new high speed USB device using ehci_hcd and address 4
> [  822.628121] ehci_hcd 0000:00:10.3: port 4 high speed
> [  822.628132] ehci_hcd 0000:00:10.3: GetStatus port 4 status 00100f POWER sig=se0 PEC PE CSC CONNECT
> [  822.628158] hub 1-0:1.0: unable to enumerate USB device on port 4
> [  822.640024] ehci_hcd 0000:00:10.3: GetStatus port 4 status 003802 POWER OWNER sig=j CSC
> [  822.884016] usb 3-2: new full speed USB device using uhci_hcd and address 2
> [  823.031034] usb 3-2: not running at top speed; connect to a high speed hub
> [  823.071031] usb 3-2: New USB device found, idVendor=07ca, idProduct=850a
> [  823.071036] usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
> [  823.071041] usb 3-2: Product: A850 DVBT
> [  823.071044] usb 3-2: Manufacturer: AVerMedia
> [  823.071048] usb 3-2: SerialNumber: 302471600900000
> [  823.071250] usb 3-2: configuration #1 chosen from 1 choice
> <-- full speed dvb tuner
> 
> [  825.000034] ehci_hcd 0000:00:10.3: suspend root hub
> [  825.004005] ehci_hcd 0000:00:10.3: force halt; handhake f7c5ed14 0000c000 00000000 -> -110

This is very bad indeed.  It means the EHCI controller is misbehaving,
not turning off its async schedule when told to do so.  The driver
resets it, but from this point on it won't work at all.

Alan Stern

--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html

B and H Foto and Electronics Corp.

[Video for Linux]     [Linux Audio Users]     [Photo]     [Yosemite News]    [Yosemite Photos]    [Free Online Dating]     [Linux Kernel]     [Linux SCSI]     [XFree86]     [Devices]     [Old Linux USB Devel Archive]

Add to Google Powered by Linux