xhci_hcd: unstable communication with Opella-XD JTAG probe

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

 



Hello,

I'm seeing a problem when attaching USB device (in my case Ashling
Opella-XD JTAG probe) in Dell e7440 laptop if USB 3.0 is enable in BIOS.

If I disable USB 3.0 in BIOS the same device works perfectly fine.
What's also interesting on my previous laptop (it was HP Elitebook
something) I saw the same device working fine even if plugged in USB 3.0
port (essentially USB 3.0 was enabled in BIOS as well).

And essentially that same device works on the same Laptop in Windows 7.

I run very simple utility that just reads some basic information
from my JTAG probe like serial number, firmware version etc. And I see it
succeeds every second run.

----------------------------+AD4-8---------------------------
+ACQ- ./opxddiag --list
Opella-XD Diagnostic Utility (OPXDDIAG).
v1.0.4, 17-Aug-2012, (c)Ashling Microsystems Ltd 2012.

Detecting connected Opella-XD(s) ... 1 found

Opella-XD serial number: 880475
           Manufactured: 16-Jul-2008, R1-M0-E0
               Firmware: v1.1.1, 20-Dec-2012
----------------------------+AD4-8---------------------------

And for that successful run I see the following in system log:
----------------------------+AD4-8---------------------------
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Waiting for status stage event
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Waiting for status stage event
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x1 ep reset callback called
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x82 ep reset callback called
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x3 ep reset callback called
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x84 ep reset callback called
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Waiting for status stage event
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Waiting for status stage event
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x1 ep reset callback called
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x82 ep reset callback called
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x3 ep reset callback called
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x84 ep reset callback called
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Cancel URB ffff88037ae293c0, dev 2, ep 0x82, starting at offset
0x3cd8fd500
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: // Ding dong+ACE-
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Stopped on Transfer TRB
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Removing canceled TD starting at 0x3cd8fd500 (dma).
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Finding endpoint context
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Cycle state +AD0- 0x1
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: New dequeue segment +AD0- ffff8803cd8932c0 (virtual)
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: New dequeue pointer +AD0- 0x3cd8fd550 (DMA)
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Set TR Deq Ptr cmd, new deq seg +AD0- ffff8803cd8932c0 (0x3cd8fd000 dma), new
deq ptr +AD0- ffff8803cd8fd550 (0x3cd8fd550 dma), new cycle +AD0- 1
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: // Ding dong+ACE-
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Successful Set TR Deq Ptr cmd, deq +AD0- +AEA-3cd8fd550
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: ep 0x82 - asked for 65664 bytes, 16311 bytes untransferred
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: WARN Event TRB for slot 8 ep 4 with no TDs queued?
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Event TRB with TRB type ID 32
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x0 +AD0- 0xcd8fd590
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x4 +AD0- 0x3
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x8 +AD0- 0xd000080
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0xc +AD0- 0x8058001
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: ep 0x82 - asked for 65664 bytes, 16363 bytes untransferred
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: WARN Event TRB for slot 8 ep 4 with no TDs queued?
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Event TRB with TRB type ID 32
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x0 +AD0- 0xcd8fd5e0
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x4 +AD0- 0x3
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x8 +AD0- 0xd000080
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0xc +AD0- 0x8058001
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: ep 0x82 - asked for 65664 bytes, 16347 bytes untransferred
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: WARN Event TRB for slot 8 ep 4 with no TDs queued?
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Event TRB with TRB type ID 32
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x0 +AD0- 0xcd8fd630
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x4 +AD0- 0x3
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x8 +AD0- 0xd000080
Sep 10 14:11:22 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0xc +AD0- 0x8058001
----------------------------+AD4-8---------------------------

Now on the next run test utility is executed significantly longer and
finishes with failure:
----------------------------+AD4-8---------------------------
+ACQ- ./opxddiag --list
Opella-XD Diagnostic Utility (OPXDDIAG).
v1.0.4, 17-Aug-2012, (c)Ashling Microsystems Ltd 2012.

Detecting connected Opella-XD(s) ... 1 found

Opella-XD serial number: 880475
  Cannot obtain detailed information (possibly in-use by another application)
----------------------------+AD4-8---------------------------

And system log shows:
----------------------------+AD4-8---------------------------
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Waiting for status stage event
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Waiting for status stage event
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x1 ep reset callback called
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x82 ep reset callback called
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x3 ep reset callback called
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x84 ep reset callback called
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Waiting for status stage event
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Waiting for status stage event
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x1 ep reset callback called
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x82 ep reset callback called
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x3 ep reset callback called
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Endpoint 0x84 ep reset callback called
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Cancel URB ffff8803ec79c3c0, dev 2, ep 0x82, starting at offset
0x3cd8fd640
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: // Ding dong+ACE-
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Stopped on Transfer TRB
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Removing canceled TD starting at 0x3cd8fd640 (dma).
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Finding endpoint context
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Cycle state +AD0- 0x1
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: New dequeue segment +AD0- ffff8803cd8932c0 (virtual)
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: New dequeue pointer +AD0- 0x3cd8fd690 (DMA)
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Set TR Deq Ptr cmd, new deq seg +AD0- ffff8803cd8932c0 (0x3cd8fd000 dma), new
deq ptr +AD0- ffff8803cd8fd690 (0x3cd8fd690 dma), new cycle +AD0- 1
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: // Ding dong+ACE-
Sep 10 14:11:28 kernel: xhci+AF8-hcd 0000:00:14.0: Successful Set TR Deq Ptr cmd, deq +AD0- +AEA-3cd8fd690

+ADw- 5 seconds delay +AD4-
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: Cancel URB ffff8803ec79c3c0, dev 2, ep 0x82, starting at offset
0x3cd8fd690
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: // Ding dong+ACE-
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: Stopped on Transfer TRB
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: Removing canceled TD starting at 0x3cd8fd690 (dma).
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: Finding endpoint context
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: Cycle state +AD0- 0x1
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: New dequeue segment +AD0- ffff8803cd8932c0 (virtual)
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: New dequeue pointer +AD0- 0x3cd8fd6e0 (DMA)
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: Set TR Deq Ptr cmd, new deq seg +AD0- ffff8803cd8932c0 (0x3cd8fd000 dma), new
deq ptr +AD0- ffff8803cd8fd6e0 (0x3cd8fd6e0 dma), new cycle +AD0- 1
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: // Ding dong+ACE-
Sep 10 14:11:33 kernel: xhci+AF8-hcd 0000:00:14.0: Successful Set TR Deq Ptr cmd, deq +AD0- +AEA-3cd8fd6e0

+ADw- 5 seconds delay +AD4-
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Cancel URB ffff8803de24f000, dev 2, ep 0x82, starting at offset
0x3cd8fd6e0
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: // Ding dong+ACE-
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Stopped on Transfer TRB
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Removing canceled TD starting at 0x3cd8fd6e0 (dma).
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Finding endpoint context
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Cycle state +AD0- 0x1
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: New dequeue segment +AD0- ffff8803cd8932c0 (virtual)
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: New dequeue pointer +AD0- 0x3cd8fd730 (DMA)
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Set TR Deq Ptr cmd, new deq seg +AD0- ffff8803cd8932c0 (0x3cd8fd000 dma), new
deq ptr +AD0- ffff8803cd8fd730 (0x3cd8fd730 dma), new cycle +AD0- 1
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: // Ding dong+ACE-
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Successful Set TR Deq Ptr cmd, deq +AD0- +AEA-3cd8fd730
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: ep 0x82 - asked for 65664 bytes, 16347 bytes untransferred
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: WARN Event TRB for slot 8 ep 4 with no TDs queued?
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Event TRB with TRB type ID 32
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x0 +AD0- 0xcd8fd770
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x4 +AD0- 0x3
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0x8 +AD0- 0xd000080
Sep 10 14:11:38 kernel: xhci+AF8-hcd 0000:00:14.0: Offset 0xc +AD0- 0x8058001
----------------------------+AD4-8---------------------------

Notice 2 delays in between of that last log.

I'm on 4.1.6-200.fc22.x86+AF8-64 kernel. But I remember seeing that problem
when I first got that laptop at times of kernel 3.18.

I'll be happy to provide more details if required.

Regards,
Alexey--
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



[Index of Archives]     [Linux Media]     [Linux Input]     [Linux Audio Users]     [Yosemite News]     [Linux Kernel]     [Linux SCSI]     [Old Linux USB Devel Archive]

  Powered by Linux