Hi Hal,
On 4/19/2019 11:41 PM, Hal Emmerich wrote:
Hello all,
I have a ar9271 wireless dongle which is known to not work well with dwc2.
When inserted, it may initialize properly if the system was just
rebooted but after that will error out.
With dwc2 verbose debug enabled, I see a bulk transfer followed by a
frame overrun interrupt.
The log of this is below. I attempted to get a verbose log of it
functioning, but was unable to.
Not sure if that might indicate a race condition since it does sometimes
work when verbose debug
is disabled?
Is the frame overrun error indicating the message was received after the
frame? Or is it saying
one of the dma buffers is full? I can't find a reference to frame
overrun errors in the usb error list.
I also recorded the transactions with wireshark and can provide those if
it would help.
Description for frame overrun from databook:
-----------------------
3.7 Selecting the Queue Depth
Choose the Periodic and Non-periodic Request Queue depths carefully to
match the number of periodic or
non-periodic endpoints accessed.
The Non-periodic Request Queue depth affects the performance of
non-periodic transfers. The deeper the
queue (along with sufficient FIFO size), the more often the controller
is able to pipeline non-periodic
transfers. If the queue size is small, the controller is able to put in
new requests only when the queue space is
freed up.
The controller’s Periodic Request Queue depth is critical to performing
periodic transfers as scheduled.
Select the periodic queue depth, based on the number of periodic
transfers scheduled in a microframe. In
Slave mode, however, the application must also take into account the
disable entry that must be put into the
queue. So, if there are two non-high-bandwidth periodic endpoints, the
Periodic Request Queue depth must
be at least 4. If at least one high-bandwidth endpoint supported, the
queue depth must be 8. If the Periodic
Request Queue depth is smaller than the periodic transfers scheduled in
a microframe, a frame overrun
condition results.
-----------------------
Actually, this interrupt should not be asserted because in your case
started just one periodic (Interrupt IN) transfer in microframe and
before starting queue depth checked - there are enough space to start
transfer:
P Tx Req Queue Space Avail (before queue): 8
Thanks,
Minas
[ 93.104662] usb 2-1: ath9k_htc: Transferred FW:
ath9k_htc/htc_9271-1.4.0.fw, size: 51008
[ 93.113803] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.119568] dwc2 ff580000.usb: urb_enqueue, urb 1032f3a7
[ 93.125510] dwc2 ff580000.usb: Device address: 2
[ 93.130917] dwc2 ff580000.usb: Endpoint: 2, IN
[ 93.136088] dwc2 ff580000.usb: Endpoint type: BULK IN (IN)
[ 93.142429] dwc2 ff580000.usb: Speed: HIGH
[ 93.147209] dwc2 ff580000.usb: Max packet size: 512
[ 93.152865] dwc2 ff580000.usb: Data buffer length: 16384
[ 93.159005] dwc2 ff580000.usb: Transfer buffer: 56fd7fba, Transfer
DMA: 2beb0000
[ 93.167476] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 93.175362] dwc2 ff580000.usb: Interval: 0
[ 93.180145] dwc2 ff580000.usb: addr=2, ep_num=2, ep_dir=80,
ep_type=2, mps=512
[ 93.188228] dwc2 ff580000.usb: dwc2_hcd_qh_add()
[ 93.193384] dwc2 ff580000.usb: dwc2_assign_and_init_hc(bd1a24cd,024930b4)
[ 93.200966] dwc2 ff580000.usb: dwc2_hc_init()
[ 93.205819] dwc2 ff580000.usb: DMA enabled
[ 93.210389] dwc2 ff580000.usb: desc DMA disabled
[ 93.215542] dwc2 ff580000.usb: set HCINTMSK to 00000006
[ 93.221374] dwc2 ff580000.usb: set HAINTMSK to 00000040
[ 93.227206] dwc2 ff580000.usb: set GINTMSK to f3000806
[ 93.232941] dwc2 ff580000.usb: set HCCHAR(6) to 00889200
[ 93.238871] dwc2 ff580000.usb: dwc2_hc_init: Channel 6
[ 93.244606] dwc2 ff580000.usb: Dev Addr: 2
[ 93.249369] dwc2 ff580000.usb: Ep Num: 2
[ 93.253940] dwc2 ff580000.usb: Is In: 1
[ 93.258412] dwc2 ff580000.usb: Is Low Speed: 0
[ 93.263565] dwc2 ff580000.usb: Ep Type: 2
[ 93.268231] dwc2 ff580000.usb: Max Pkt: 512
[ 93.273093] dwc2 ff580000.usb: Queue non-periodic transactions
[ 93.279606] dwc2 ff580000.usb: NP Tx Req Queue Space Avail (before
queue): 8
[ 93.287672] dwc2 ff580000.usb: NP Tx FIFO Space Avail (before
queue): 128
[ 93.295447] dwc2 ff580000.usb: dwc2_hc_start_transfer()
[ 93.301280] dwc2 ff580000.usb: no split
[ 93.305559] dwc2 ff580000.usb: Wrote 01004000 to HCTSIZ(6)
[ 93.311683] dwc2 ff580000.usb: dwc2_hc_start_transfer: Channel 6
[ 93.318389] dwc2 ff580000.usb: Xfer Size: 16384
[ 93.323639] dwc2 ff580000.usb: Num Pkts: 32
[ 93.328501] dwc2 ff580000.usb: Start PID: 0
[ 93.333362] dwc2 ff580000.usb: Wrote 2beb0000 to HCDMA(6)
[ 93.339390] dwc2 ff580000.usb: Multi Cnt: 1
[ 93.344251] dwc2 ff580000.usb: Wrote 80989200 to HCCHAR(6)
[ 93.350434] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.356189] dwc2 ff580000.usb: urb_enqueue, urb 92464acd
[ 93.362146] dwc2 ff580000.usb: Device address: 2
[ 93.367514] dwc2 ff580000.usb: Endpoint: 2, IN
[ 93.372725] dwc2 ff580000.usb: Endpoint type: BULK IN (IN)
[ 93.379118] dwc2 ff580000.usb: Speed: HIGH
[ 93.383910] dwc2 ff580000.usb: Max packet size: 512
[ 93.389581] dwc2 ff580000.usb: Data buffer length: 16384
[ 93.395730] dwc2 ff580000.usb: Transfer buffer: 4231e933, Transfer
DMA: 2beb8000
[ 93.404201] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 93.412090] dwc2 ff580000.usb: Interval: 0
[ 93.416871] dwc2 ff580000.usb: addr=2, ep_num=2, ep_dir=80,
ep_type=2, mps=512
[ 93.424954] dwc2 ff580000.usb: dwc2_hcd_qh_add()
[ 93.430146] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.435896] dwc2 ff580000.usb: urb_enqueue, urb 2cf45f79
[ 93.441840] dwc2 ff580000.usb: Device address: 2
[ 93.447222] dwc2 ff580000.usb: Endpoint: 2, IN
[ 93.452408] dwc2 ff580000.usb: Endpoint type: BULK IN (IN)
[ 93.458758] dwc2 ff580000.usb: Speed: HIGH
[ 93.463544] dwc2 ff580000.usb: Max packet size: 512
[ 93.469199] dwc2 ff580000.usb: Data buffer length: 16384
[ 93.475339] dwc2 ff580000.usb: Transfer buffer: ae4d9b47, Transfer
DMA: 2bec0000
[ 93.483810] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 93.491695] dwc2 ff580000.usb: Interval: 0
[ 93.496476] dwc2 ff580000.usb: addr=2, ep_num=2, ep_dir=80,
ep_type=2, mps=512
[ 93.504568] dwc2 ff580000.usb: dwc2_hcd_qh_add()
[ 93.509777] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.515530] dwc2 ff580000.usb: urb_enqueue, urb e322725e
[ 93.521479] dwc2 ff580000.usb: Device address: 2
[ 93.526842] dwc2 ff580000.usb: Endpoint: 2, IN
[ 93.532011] dwc2 ff580000.usb: Endpoint type: BULK IN (IN)
[ 93.538342] dwc2 ff580000.usb: Speed: HIGH
[ 93.543123] dwc2 ff580000.usb: Max packet size: 512
[ 93.548774] dwc2 ff580000.usb: Data buffer length: 16384
[ 93.554914] dwc2 ff580000.usb: Transfer buffer: 8e9d9e9b, Transfer
DMA: 2bec8000
[ 93.563382] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 93.571270] dwc2 ff580000.usb: Interval: 0
[ 93.576048] dwc2 ff580000.usb: addr=2, ep_num=2, ep_dir=80,
ep_type=2, mps=512
[ 93.584130] dwc2 ff580000.usb: dwc2_hcd_qh_add()
[ 93.589316] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.595067] dwc2 ff580000.usb: urb_enqueue, urb 2533b990
[ 93.601011] dwc2 ff580000.usb: Device address: 2
[ 93.606378] dwc2 ff580000.usb: Endpoint: 2, IN
[ 93.611545] dwc2 ff580000.usb: Endpoint type: BULK IN (IN)
[ 93.617877] dwc2 ff580000.usb: Speed: HIGH
[ 93.622654] dwc2 ff580000.usb: Max packet size: 512
[ 93.628306] dwc2 ff580000.usb: Data buffer length: 16384
[ 93.634443] dwc2 ff580000.usb: Transfer buffer: b4fb1405, Transfer
DMA: 2bed0000
[ 93.642914] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 93.650797] dwc2 ff580000.usb: Interval: 0
[ 93.655578] dwc2 ff580000.usb: addr=2, ep_num=2, ep_dir=80,
ep_type=2, mps=512
[ 93.663658] dwc2 ff580000.usb: dwc2_hcd_qh_add()
[ 93.668842] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.674588] dwc2 ff5800005.usb: urb_enqueue, urb ccee38dd
[ 93.680533] dwc2 ff580000.usb: Device address: 2
[ 93.685893] dwc2 ff580000.usb: Endpoint: 2, IN
[ 93.691064] dwc2 ff580000.usb: Endpoint type: BULK IN (IN)
[ 93.697386] dwc2 ff580000.usb: Speed: HIGH
[ 93.702165] dwc2 ff580000.usb: Max packet size: 512
[ 93.707814] dwc2 ff580000.usb: Data buffer length: 16384
[ 93.713953] dwc2 ff580000.usb: Transfer buffer: 9aedefe1, Transfer
DMA: 2bed8000
[ 93.722420] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 93.730314] dwc2 ff580000.usb: Interval: 0
[ 93.735096] dwc2 ff580000.usb: addr=2, ep_num=2, ep_dir=80,
ep_type=2, mps=512
[ 93.743178] dwc2 ff580000.usb: dwc2_hcd_qh_add()
[ 93.748360] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.754108] dwc2 ff580000.usb: urb_enqueue, urb 319ee6c4
[ 93.760052] dwc2 ff580000.usb: Device address: 2
[ 93.765415] dwc2 ff580000.usb: Endpoint: 2, IN
[ 93.770580] dwc2 ff580000.usb: Endpoint type: BULK IN (IN)
[ 93.776912] dwc2 ff580000.usb: Speed: HIGH
[ 93.781689] dwc2 ff580000.usb: Max packet size: 512
[ 93.787341] dwc2 ff580000.usb: Data buffer length: 16384
[ 93.793477] dwc2 ff580000.usb: Transfer buffer: a4787cb2, Transfer
DMA: 2bee0000
[ 93.801946] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 93.809829] dwc2 ff580000.usb: Interval: 0
[ 93.814609] dwc2 ff580000.usb: addr=2, ep_num=2, ep_dir=80,
ep_type=2, mps=512
[ 93.822688] dwc2 ff580000.usb: dwc2_hcd_qh_add()
[ 93.827871] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.833615] dwc2 ff580000.usb: urb_enqueue, urb 5cd74369
[ 93.839560] dwc2 ff580000.usb: Device address: 2
[ 93.844919] dwc2 ff580000.usb: Endpoint: 2, IN
[ 93.850086] dwc2 ff580000.usb: Endpoint type: BULK IN (IN)
[ 93.856416] dwc2 ff580000.usb: Speed: HIGH
[ 93.861194] dwc2 ff580000.usb: Max packet size: 512
[ 93.866848] dwc2 ff580000.usb: Data buffer length: 16384
[ 93.872988] dwc2 ff580000.usb: Transfer buffer: 146bc214, Transfer
DMA: 2bee8000
[ 93.881456] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 93.889341] dwc2 ff580000.usb: Interval: 0
[ 93.894119] dwc2 ff580000.usb: addr=2, ep_num=2, ep_dir=80,
ep_type=2, mps=512
[ 93.902200] dwc2 ff580000.usb: dwc2_hcd_qh_add()
[ 93.907377] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.913139] dwc2 ff580000.usb: urb_enqueue, urb 0386947f
[ 93.919118] dwc2 ff580000.usb: Device address: 2
[ 93.924503] dwc2 ff580000.usb: Endpoint: 3, IN
[ 93.929711] dwc2 ff580000.usb: Endpoint type: INTERRUPT IN (IN)
[ 93.936579] dwc2 ff580000.usb: Speed: HIGH
[ 93.941377] dwc2 ff580000.usb: Max packet size: 64
[ 93.946961] dwc2 ff580000.usb: Data buffer length: 64
[ 93.952868] dwc2 ff580000.usb: Transfer buffer: 9cf61093, Transfer
DMA: 2b82f500
[ 93.961351] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 93.969240] dwc2 ff580000.usb: Interval: 1
[ 93.974023] dwc2 ff580000.usb: addr=2, ep_num=3, ep_dir=80,
ep_type=3, mps=64
[ 93.982010] dwc2 ff580000.usb: dwc2_hcd_qh_add()
[ 93.987198] dwc2 ff580000.usb: DWC OTG HCD URB Enqueue
[ 93.987285] dwc2 ff580000.usb: dwc2_assign_and_init_hc(bd1a24cd,38ec32c7)
[ 93.992945] dwc2 ff580000.usb: urb_enqueue, urb c069ee03
[ 94.000517] dwc2 ff580000.usb: dwc2_hc_init()
[ 94.000520] dwc2 ff580000.usb: DMA enabled
[ 94.000522] dwc2 ff580000.usb: desc DMA disabled
[ 94.000527] dwc2 ff580000.usb: set HCINTMSK to 00000006
[ 94.006464] dwc2 ff580000.usb: Device address: 2
[ 94.011315] dwc2 ff580000.usb: set HAINTMSK to 000000c0
[ 94.011318] dwc2 ff580000.usb: set GINTMSK to f300080e
[ 94.011321] dwc2 ff580000.usb: set HCCHAR(7) to 008c9840
[ 94.011325] dwc2 ff580000.usb: dwc2_hc_init: Channel 7
[ 94.015900] dwc2 ff580000.usb: Endpoint: 3, IN
[ 94.021044] dwc2 ff580000.usb: Dev Addr: 2
[ 94.021046] dwc2 ff580000.usb: Ep Num: 3
[ 94.021049] dwc2 ff580000.usb: Is In: 1
[ 94.021053] dwc2 ff580000.usb: Is Low Speed: 0
[ 94.026889] dwc2 ff580000.usb: Endpoint type: INTERRUPT IN (IN)
[ 94.032227] dwc2 ff580000.usb: Ep Type: 3
[ 94.032229] dwc2 ff580000.usb: Max Pkt: 64
[ 94.032233] dwc2 ff580000.usb: Queue periodic transactions
[ 94.032236] dwc2 ff580000.usb: P Tx Req Queue Space Avail (before
queue): 8
[ 94.038071] dwc2 ff580000.usb: Speed: HIGH
[ 94.043798] dwc2 ff580000.usb: P Tx FIFO Space Avail (before
queue): 256
[ 94.043801] dwc2 ff580000.usb: dwc2_hc_start_transfer()
[ 94.043804] dwc2 ff580000.usb: no split
[ 94.043807] dwc2 ff580000.usb: Wrote 00080040 to HCTSIZ(7)
[ 94.049739] dwc2 ff580000.usb: Max packet size: 64
[ 94.055467] dwc2 ff580000.usb: dwc2_hc_start_transfer: Channel 7
[ 94.055469] dwc2 ff580000.usb: Xfer Size: 64
[ 94.055472] dwc2 ff580000.usb: Num Pkts: 1
[ 94.055476] dwc2 ff580000.usb: Start PID: 0
[ 94.060631] dwc2 ff580000.usb: Data buffer length: 64
[ 94.065386] dwc2 ff580000.usb: Wrote 2b82f500 to HCDMA(7)
[ 94.065391] dwc2 ff580000.usb: Multi Cnt: 1
[ 94.065395] dwc2 ff580000.usb: Wrote 809c9840 to HCCHAR(7)
[ 94.069969] dwc2 ff580000.usb: Transfer buffer: c30c74ed, Transfer
DMA: 2b82fb00
[ 94.074473] dwc2 ff580000.usb: DWC OTG HCD Interrupt Detected
gintsts&gintmsk=0x02000008
[ 94.079601] dwc2 ff580000.usb: Setup buffer: (null), Setup DMA:
00000000
[ 94.086398] dwc2 ff580000.usb: dwc2_hc_intr()
[ 94.086400] dwc2 ff580000.usb: HAINT=00000080
[ 94.086404] dwc2 ff580000.usb: --Host Channel Interrupt--, Channel 7
[ 94.086408] dwc2 ff580000.usb: hcint 0x00000202, hcintmsk
0x00000006, hcint&hcintmsk 0x00000002
[ 94.091069] dwc2 ff580000.usb: Interval: 1
[ 94.095827] dwc2 ff580000.usb: --Host Channel 7 Interrupt: Channel
Halted--
[ 94.095830] dwc2 ff580000.usb: --Host Channel 7 Interrupt: DMA
Channel Halted--
[ 94.095836] dwc2 ff580000.usb: --Host Channel 7 Interrupt: Frame
Overrun--
[ 94.268674] dwc2 ff580000.usb: dwc2_halt_channel()
[ 94.274021] dwc2 ff580000.usb: DMA enabled
[ 94.278593] dwc2 ff580000.usb: dwc2_release_channel: channel 7,
halt_status 8
[ 94.286750] dwc2 ff580000.usb: dwc2_deactivate_qh(bd1a24cd,38ec32c7,0)
[ 94.294234] dwc2 ff580000.usb: dwc2_hcd_qh_deactivate()
