RTL8192EU USB Wifi error: rtwn0: r92e_power_off: failed to block Tx queues
Kris G
netsick at gmail.com
Tue Jun 16 23:04:35 UTC 2020
Hi,
Oddly this connected the other day and I had changed nothing. It has been
trying to connect since Feb and not once has it worked accept I noticed
yesterday it just randomly connected for a while..
Jun 16 14:52:13 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
CTRL-EVENT-SSID-REENABLED id=0 ssid="iiNetB3C803"
Jun 16 14:52:13 <daemon.notice> crusty wpa_supplicant[888]: wlan0: Trying
to associate with 10:13:31:b3:c8:03 (SSID='iiNetB3C803' freq=2437 MHz)
Jun 16 14:52:13 <kern.info> crusty kernel: wlan0: link state changed to UP
Jun 16 14:52:13 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
Associated with 10:13:31:b3:c8:03
Jun 16 14:52:13 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA: Key
negotiation completed with 10:13:31:b3:c8:03 [PTK=CCMP GTK=CCMP]
Jun 16 14:52:13 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
CTRL-EVENT-CONNECTED - Connection to 10:13:31:b3:c8:03 completed [id=0
id_str=]
Jun 16 14:52:15 <user.notice> crusty dhclient[3587]: New IP Address
(wlan0): 118.209.4.254
Jun 16 14:52:15 <user.notice> crusty dhclient[3588]: New Subnet Mask
(wlan0): 255.255.224.0
Jun 16 14:52:15 <user.notice> crusty dhclient[3589]: New Broadcast Address
(wlan0): 118.209.31.255
Jun 16 14:52:15 <user.notice> crusty dhclient[3590]: New Routers (wlan0):
118.209.0.1
Jun 16 15:00:30 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA:
Group rekeying completed with 10:13:31:b3:c8:03 [GTK=CCMP]
Jun 16 15:10:30 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA:
Group rekeying completed with 10:13:31:b3:c8:03 [GTK=CCMP]
Jun 16 15:20:30 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA:
Group rekeying completed with 10:13:31:b3:c8:03 [GTK=CCMP]
Jun 16 15:30:30 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA:
Group rekeying completed with 10:13:31:b3:c8:03 [GTK=CCMP]
Jun 16 15:40:30 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA:
Group rekeying completed with 10:13:31:b3:c8:03 [GTK=CCMP]
Jun 16 15:50:30 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA:
Group rekeying completed with 10:13:31:b3:c8:03 [GTK=CCMP]
Jun 16 16:00:30 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA:
Group rekeying completed with 10:13:31:b3:c8:03 [GTK=CCMP]
Jun 16 16:10:30 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA:
Group rekeying completed with 10:13:31:b3:c8:03 [GTK=CCMP]
Jun 16 16:20:30 <daemon.notice> crusty wpa_supplicant[888]: wlan0: WPA:
Group rekeying completed with 10:13:31:b3:c8:03 [GTK=CCMP]
Jun 16 16:33:31 <kern.info> crusty kernel: wlan0: Ethernet address:
a0:f4:59:7f:4b:8c
Jun 16 16:33:35 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
Authentication with 10:13:31:b3:c8:03 timed out.
Jun 16 16:33:35 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
CTRL-EVENT-DISCONNECTED bssid=10:13:31:b3:c8:03 reason=3 locally_generated=1
Jun 16 16:33:35 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="iiNetB3C803" auth_failures=2
duration=31 reason=CONN_FAILED
I can't ofcourse connect again now but why am I missing here that would
make this work when every other time all I received would be below..
Jun 16 14:48:49 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
CTRL-EVENT-SSID-REENABLED id=0 ssid="iiNetB3C803"
Jun 16 14:48:49 <daemon.notice> crusty wpa_supplicant[888]: wlan0: Trying
to associate with 10:13:31:b3:c8:03 (SSID='iiNetB3C803' freq=2437 MHz)
Jun 16 14:48:59 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
Authentication with 10:13:31:b3:c8:03 timed out.
Jun 16 14:48:59 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
CTRL-EVENT-DISCONNECTED bssid=10:13:31:b3:c8:03 reason=3 locally_generated=1
Jun 16 14:48:59 <daemon.notice> crusty wpa_supplicant[888]: wlan0:
CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="iiNetB3C803" auth_failures=28
duration=192 reason=CONN_FAILED
ugen0.4: <Realtek 802.1 NIC> at usbus0 (disconnected)
rtwn0: at uhub0, port 1, addr 11 (disconnected)
rtwn0: r92e_power_off: failed to block Tx queues
rtwn0: detached
usb_alloc_device: set address 4 failed (USB_ERR_IOERROR, ignored)
usbd_setup_device_desc: getting device descriptor at addr 4 failed,
USB_ERR_IOERROR
usbd_req_re_enumerate: addr=4, set address failed! (USB_ERR_IOERROR,
ignored)
usbd_setup_device_desc: getting device descriptor at addr 4 failed,
USB_ERR_IOERROR
usbd_req_re_enumerate: addr=4, set address failed! (USB_ERR_IOERROR,
ignored)
usbd_setup_device_desc: getting device descriptor at addr 4 failed,
USB_ERR_IOERROR
usbd_req_re_enumerate: addr=4, set address failed! (USB_ERR_IOERROR,
ignored)
usbd_setup_device_desc: getting device descriptor at addr 4 failed,
USB_ERR_IOERROR
usbd_req_re_enumerate: addr=4, set address failed! (USB_ERR_IOERROR,
ignored)
usbd_setup_device_desc: getting device descriptor at addr 4 failed,
USB_ERR_IOERROR
ugen0.4: <Unknown > at usbus0 (disconnected)
uhub_reattach_port: could not allocate new device
/etc/wpa_supplicant.conf
network={
ssid="iiNetB3C803"
psk="xxxxxxxxx"
}
/etc/rc.conf
wlans_rtwn0="wlan0"
create_args_wlan0="country AU"
ifconfig_wlan0="WPA inet 192.168.1.1 netmask 255.255.255.255 ssid
iiNetB3C803"
/etc/wpa_supplicant.conf
network={
ssid="iiNetB3C803"
psk="xxxxxxxxx"
}
Kris
On Sat, Feb 22, 2020 at 11:35 AM Kris G <netsick at gmail.com> wrote:
> 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
>>
>
More information about the freebsd-usb
mailing list