RTL8192EU USB Wifi error: rtwn0: r92e_power_off: failed to block Tx queues

[Kris G]

Part of the usbdump below around the point it detaches and starts logging
ERR=IOERROR rather than ERR=0

11:23:21.831625 usbus0.5
DONE-CTRL-EP=00000080,SPD=HIGH,NFR=2,SLEN=4,IVAL=0,ERR=0
 frame[0] WRITE 8 bytes
 frame[1] READ 1 bytes
 0000  00 -- -- -- -- -- -- --  -- -- -- -- -- -- -- --  |.               |
 flags 0 <0>
 status 0xca1a1
<OPEN|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.831629 usbus0.5 SUBM-CTRL-EP=00000000,SPD=HIGH,NFR=2,SLEN=12,IVAL=0
 frame[0] WRITE 8 bytes
 0000  40 05 28 00 00 00 01 00  -- -- -- -- -- -- -- --  |@.(.....        |
 frame[1] WRITE 1 bytes
 0000  00 -- -- -- -- -- -- --  -- -- -- -- -- -- -- --  |.               |
 flags 0 <0>
 status 0xea1a3
<OPEN|TRANSFERRING|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CURR_DMA_SET|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.831751 usbus0.5
DONE-CTRL-EP=00000000,SPD=HIGH,NFR=2,SLEN=0,IVAL=0,ERR=0
 frame[0] WRITE 8 bytes
 frame[1] WRITE 1 bytes
 flags 0 <0>
 status 0xea1a1
<OPEN|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CURR_DMA_SET|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.831755 usbus0.5 SUBM-CTRL-EP=00000080,SPD=HIGH,NFR=2,SLEN=8,IVAL=0
 frame[0] WRITE 8 bytes
 0000  C0 05 78 00 00 00 04 00  -- -- -- -- -- -- -- --  |..x.....        |
 frame[1] READ 4 bytes
 flags 0 <0>
 status 0xca1a3
<OPEN|TRANSFERRING|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.831875 usbus0.5
DONE-CTRL-EP=00000080,SPD=HIGH,NFR=2,SLEN=4,IVAL=0,ERR=0
 frame[0] WRITE 8 bytes
 frame[1] READ 4 bytes
 0000  2A 00 1E 0A -- -- -- --  -- -- -- -- -- -- -- --  |*...            |
 flags 0 <0>
 status 0xca1a1
<OPEN|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.831879 usbus0.5 SUBM-CTRL-EP=00000000,SPD=HIGH,NFR=2,SLEN=12,IVAL=0
 frame[0] WRITE 8 bytes
 0000  40 05 78 00 00 00 04 00  -- -- -- -- -- -- -- --  |@.x.....        |
 frame[1] WRITE 4 bytes
 0000  2A 00 1E 0A -- -- -- --  -- -- -- -- -- -- -- --  |*...            |
 flags 0 <0>
 status 0xea1a3
<OPEN|TRANSFERRING|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CURR_DMA_SET|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.832000 usbus0.5
DONE-CTRL-EP=00000000,SPD=HIGH,NFR=2,SLEN=0,IVAL=0,ERR=0
 frame[0] WRITE 8 bytes
 frame[1] WRITE 4 bytes
 flags 0 <0>
 status 0xea1a1
<OPEN|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CURR_DMA_SET|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.832004 usbus0.5 SUBM-CTRL-EP=00000080,SPD=HIGH,NFR=2,SLEN=8,IVAL=0
 frame[0] WRITE 8 bytes
 0000  C0 05 05 00 00 00 01 00  -- -- -- -- -- -- -- --  |........        |
 frame[1] READ 1 bytes
 flags 0 <0>
 status 0xca1a3
<OPEN|TRANSFERRING|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.832126 usbus0.5
DONE-CTRL-EP=00000080,SPD=HIGH,NFR=2,SLEN=4,IVAL=0,ERR=0
 frame[0] WRITE 8 bytes
 frame[1] READ 1 bytes
 0000  0C -- -- -- -- -- -- --  -- -- -- -- -- -- -- --  |.               |
 flags 0 <0>
 status 0xca1a1
<OPEN|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.832130 usbus0.5 SUBM-CTRL-EP=00000000,SPD=HIGH,NFR=2,SLEN=12,IVAL=0
 frame[0] WRITE 8 bytes
 0000  40 05 05 00 00 00 01 00  -- -- -- -- -- -- -- --  |@.......        |
 frame[1] WRITE 1 bytes
 0000  04 -- -- -- -- -- -- --  -- -- -- -- -- -- -- --  |.               |
 flags 0 <0>
 status 0xea1a3
<OPEN|TRANSFERRING|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CURR_DMA_SET|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.832751 usbus0.5
DONE-CTRL-EP=00000000,SPD=HIGH,NFR=2,SLEN=0,IVAL=0,ERR=IOERROR
 frame[0] WRITE 8 bytes
 frame[1] WRITE 1 bytes
 flags 0 <0>
 status 0xaa1a5
<OPEN|DID_DMA_DELAY|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CURR_DMA_SET|DOING_CALLBACK|0>
11:23:21.908976 usbus0.5 SUBM-CTRL-EP=00000000,SPD=HIGH,NFR=2,SLEN=12,IVAL=0
 frame[0] WRITE 8 bytes
 0000  40 05 05 00 00 00 01 00  -- -- -- -- -- -- -- --  |@.......        |
 frame[1] WRITE 1 bytes
 0000  04 -- -- -- -- -- -- --  -- -- -- -- -- -- -- --  |.               |
 flags 0 <0>
 status 0xca1a3
<OPEN|TRANSFERRING|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|CAN_CANCEL_IMMED|DOING_CALLBACK|0>
11:23:21.909533 usbus0.5
DONE-CTRL-EP=00000000,SPD=HIGH,NFR=1,SLEN=0,IVAL=0,ERR=IOERROR
 frame[0] WRITE 0 bytes
 flags 0 <0>
 status 0x8a1a5
<OPEN|DID_DMA_DELAY|STARTED|CONTROL_XFR|CONTROL_HDR|BDMA_ENABLE|BDMA_SETUP|DOING_CALLBACK|0>




On Fri, Feb 21, 2020 at 7:13 PM Hans Petter Selasky <hps at selasky.org> wrote:

> On 2020-02-21 06:47, Kris G wrote:
> > rtwn0: detached
>
> Hi,
>
> Likely the device's firmware crashes/restarts and the device detaches.
>
> Maybe you can do a usbdump of all traffic, to isolate exactly which
> command causes this.
>
> usbdump -i usbusX -f Y -s 65536 -vvv
>
> X and Y are numbers after ugen.
>
> Run this before starting wpa supplicant.
>
> --HPS4
>