WiFi Dongle Generates tons of syslog messages

Post Reply
turgu1
Posts: 22
Joined: Fri May 31, 2019 6:57 am
languages_spoken: english
ODROIDs: odroid-n2 odroid-c2
Has thanked: 3 times
Been thanked: 0
Contact:

WiFi Dongle Generates tons of syslog messages

Unread post by turgu1 » Thu Jul 11, 2019 7:54 am

Hello,

Since I purchased my Odroid-N2, I've got a lot of trouble with my WiFi Dongle. It worked for some time (half an hour) and then disconnect from the network. I've a router into which I then plug the Ethernet port to get access to the board. The /var/log/syslog receives tons of similar messages like the following (around 100 messages per second):
Jul 10 21:19:35 odroid kernel: [12456.638805@4] ieee80211 phy1: rt2x00usb_vendor_request: Error - Vendor Request 0x07 failed for offset 0x101c with error -12
Jul 10 21:19:35 odroid kernel: [12456.649931@4] xhci-hcd xhci-hcd.0.auto: Ring expansion failed
As soon as I disconnect the WiFi Dongle, the syslog messages stop. The following lines are then sent to syslog:
Jul 10 21:20:44 odroid kernel: [12526.191655@0] usb 1-1.1: USB disconnect, device number 4
Jul 10 21:20:44 odroid NetworkManager[2122]: <info> [1562793644.9511] device (wlan0): state change: disconnected -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Jul 10 21:20:44 odroid NetworkManager[2122]: <info> [1562793644.9602] devices removed (path: /sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.1/1-1.1:1.0/net/wlan0, iface: wlan0)
Jul 10 21:20:44 odroid wpa_supplicant[2117]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Jul 10 21:20:44 odroid NetworkManager[2122]: <info> [1562793644.9858] radio killswitch /sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.1/1-1.1:1.0/ieee80211/phy1/rfkill1 disappeared
Jul 10 21:20:44 odroid systemd[1]: Starting Load/Save RF Kill Switch Status...
Jul 10 21:20:44 odroid systemd-rfkill[5526]: Failed to open device rfkill1: No such device
Jul 10 21:20:45 odroid systemd[1]: Started Load/Save RF Kill Switch Status.
After that I reconnected the Dongle:
Jul 10 21:41:35 odroid kernel: [13777.192551@0] usb 1-1.1: new high-speed USB device number 8 using xhci-hcd
Jul 10 21:41:35 odroid kernel: [13777.333871@0] usb 1-1.1: New USB device found, idVendor=148f, idProduct=5370
Jul 10 21:41:35 odroid kernel: [13777.333884@0] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jul 10 21:41:35 odroid kernel: [13777.333892@0] usb 1-1.1: Product: 802.11 n WLAN
Jul 10 21:41:35 odroid kernel: [13777.333899@0] usb 1-1.1: Manufacturer: Ralink
Jul 10 21:41:35 odroid kernel: [13777.333906@0] usb 1-1.1: SerialNumber: 1.0
Jul 10 21:41:36 odroid kernel: [13777.440626@0] usb 1-1.1: reset high-speed USB device number 8 using xhci-hcd
Jul 10 21:41:36 odroid kernel: [13777.575239@0] ieee80211 phy2: rt2x00_set_rt: Info - RT chipset 5390, rev 0502 detected
Jul 10 21:41:36 odroid kernel: [13777.589862@0] ieee80211 phy2: rt2x00_set_rf: Info - RF chipset 5370 detected
Jul 10 21:41:36 odroid kernel: [13777.590745@0] ieee80211 phy2: Selected rate control algorithm 'minstrel_ht'
Jul 10 21:41:36 odroid NetworkManager[2122]: <info> [1562794896.2573] wifi-nl80211: (wlan0): using nl80211 for WiFi device control
Jul 10 21:41:36 odroid NetworkManager[2122]: <info> [1562794896.2578] device (wlan0): driver supports Access Point (AP) mode
Jul 10 21:41:36 odroid systemd[1]: Starting Load/Save RF Kill Switch Status...
Jul 10 21:41:36 odroid NetworkManager[2122]: <info> [1562794896.2712] manager: (wlan0): new 802.11 WiFi device (/org/freedesktop/NetworkManager/Devices/5)
Jul 10 21:41:36 odroid systemd-udevd[5594]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jul 10 21:41:36 odroid NetworkManager[2122]: <info> [1562794896.3195] rfkill2: found WiFi radio killswitch (at /sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.1/1-1.1:1.0/ieee80211/phy2/rfkill2) (driver rt2800usb)
Jul 10 21:41:36 odroid systemd[1]: Started Load/Save RF Kill Switch Status.
Jul 10 21:41:36 odroid NetworkManager[2122]: <info> [1562794896.3346] devices added (path: /sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.1/1-1.1:1.0/net/wlan0, iface: wlan0)
Jul 10 21:41:36 odroid NetworkManager[2122]: <info> [1562794896.3347] device added (path: /sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.1/1-1.1:1.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
Jul 10 21:41:36 odroid NetworkManager[2122]: <info> [1562794896.3350] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Jul 10 21:41:36 odroid kernel: [13777.676208@0] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 10 21:41:36 odroid kernel: [13777.676403@0] ieee80211 phy2: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin'
Jul 10 21:41:36 odroid kernel: [13777.676474@0] ieee80211 phy2: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.36
Jul 10 21:41:36 odroid kernel: [13777.940741@0] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 10 21:41:36 odroid wpa_supplicant[2117]: dbus: fill_dict_with_properties dbus_interface=fi.w1.wpa_supplicant1.Interface dbus_property=Stations getter failed
Jul 10 21:41:36 odroid wpa_supplicant[2117]: dbus: wpa_dbus_get_object_properties: failed to get object properties: (none) none
Jul 10 21:41:36 odroid wpa_supplicant[2117]: dbus: Failed to construct signal
Jul 10 21:41:36 odroid wpa_supplicant[2117]: dbus: fill_dict_with_properties dbus_interface=fi.w1.wpa_supplicant1.Interface dbus_property=Stations getter failed
Jul 10 21:41:36 odroid NetworkManager[2122]: <info> [1562794896.6745] device (wlan0): supplicant interface state: starting -> ready
Jul 10 21:41:36 odroid NetworkManager[2122]: <info> [1562794896.6758] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Jul 10 21:41:36 odroid kernel: [13778.018382@0] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1334] policy: auto-activating connection 'Genevrier E37'
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1380] device (wlan0): Activation: starting connection 'Genevrier E37' (30a66cdc-87a7-40d4-9f8b-b1832e4756fb)
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1396] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1421] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1431] device (wlan0): Activation: (wifi) access point 'Genevrier E37' has security, but secrets are required.
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1432] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1553] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1569] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1577] device (wlan0): Activation: (wifi) connection 'Genevrier E37' has security, and secrets exist. No new secrets needed.
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1580] Config: added 'ssid' value 'Genevrier E37'
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1581] Config: added 'scan_ssid' value '1'
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1581] Config: added 'bgscan' value 'simple:30:-80:86400'
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1582] Config: added 'key_mgmt' value 'WPA-PSK'
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1582] Config: added 'auth_alg' value 'OPEN'
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.1583] Config: added 'psk' value '<hidden>'
Jul 10 21:41:38 odroid wpa_supplicant[2117]: wlan0: SME: Trying to authenticate with 68:72:51:00:d4:5a (SSID='Genevrier E37' freq=2462 MHz)
Jul 10 21:41:38 odroid kernel: [13779.518970@1] wlan0: authenticate with 68:72:51:00:d4:5a
Jul 10 21:41:38 odroid kernel: [13779.543955@1] wlan0: send auth to 68:72:51:00:d4:5a (try 1/3)
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.2052] device (wlan0): supplicant interface state: ready -> authenticating
Jul 10 21:41:38 odroid wpa_supplicant[2117]: wlan0: Trying to associate with 68:72:51:00:d4:5a (SSID='Genevrier E37' freq=2462 MHz)
Jul 10 21:41:38 odroid kernel: [13779.569849@1] wlan0: authenticated
Jul 10 21:41:38 odroid kernel: [13779.572740@0] wlan0: associate with 68:72:51:00:d4:5a (try 1/3)
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.2363] device (wlan0): supplicant interface state: authenticating -> associating
Jul 10 21:41:38 odroid kernel: [13779.637562@1] wlan0: RX AssocResp from 68:72:51:00:d4:5a (capab=0x431 status=0 aid=10)
Jul 10 21:41:38 odroid kernel: [13779.644206@1] wlan0: associated
Jul 10 21:41:38 odroid wpa_supplicant[2117]: wlan0: Associated with 68:72:51:00:d4:5a
Jul 10 21:41:38 odroid wpa_supplicant[2117]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jul 10 21:41:38 odroid NetworkManager[2122]: <info> [1562794898.3126] device (wlan0): supplicant interface state: associating -> 4-way handshake
Jul 10 21:41:38 odroid ModemManager[2116]: <info> Couldn't check support for device '/sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.1': not supported by any plugin
Jul 10 21:41:39 odroid wpa_supplicant[2117]: wlan0: WPA: Key negotiation completed with 68:72:51:00:d4:5a [PTK=CCMP GTK=CCMP]
Jul 10 21:41:39 odroid wpa_supplicant[2117]: wlan0: CTRL-EVENT-CONNECTED - Connection to 68:72:51:00:d4:5a completed [id=0 id_str=]
Jul 10 21:41:39 odroid kernel: [13780.694266@0] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.3674] device (wlan0): supplicant interface state: 4-way handshake -> completed
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.3809] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'Genevrier E37'.
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.3924] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.3960] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.4022] dhcp4 (wlan0): dhclient started with pid 5603
Jul 10 21:41:39 odroid wpa_supplicant[2117]: wlan0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-55 noise=9999 txrate=1000
Jul 10 21:41:39 odroid dhclient[5603]: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3 (xid=0x2c270036)
Jul 10 21:41:39 odroid dhclient[5603]: DHCPREQUEST of 192.168.7.174 on wlan0 to 255.255.255.255 port 67 (xid=0x3600272c)
Jul 10 21:41:39 odroid dhclient[5603]: DHCPOFFER of 192.168.7.174 from 192.168.7.254
Jul 10 21:41:39 odroid dhclient[5603]: DHCPACK of 192.168.7.174 from 192.168.7.254
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5434] dhcp4 (wlan0): address 192.168.7.174
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5435] dhcp4 (wlan0): plen 24 (255.255.255.0)
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5436] dhcp4 (wlan0): gateway 192.168.7.254
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5437] dhcp4 (wlan0): lease time 600
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5438] dhcp4 (wlan0): hostname 'odroid'
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5439] dhcp4 (wlan0): nameserver '192.168.7.254'
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5439] dhcp4 (wlan0): state changed unknown -> bound
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5538] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5587] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Jul 10 21:41:39 odroid NetworkManager[2122]: <info> [1562794899.5608] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Jul 10 21:41:40 odroid dhclient[5603]: bound to 192.168.7.174 -- renewal in 254 seconds.
Jul 10 21:41:40 odroid NetworkManager[2122]: <info> [1562794900.2744] device (wlan0): Activation: successful, device activated.
Jul 10 21:41:40 odroid dbus-daemon[2111]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.6' (uid=0 pid=2122 comm="/usr/sbin/NetworkManager --no-daemon " label="kernel")
Jul 10 21:41:40 odroid systemd[1]: Starting Network Manager Script Dispatcher Service...
Jul 10 21:41:40 odroid dbus-daemon[2111]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jul 10 21:41:40 odroid nm-dispatcher: req:1 'up' [wlan0]: new request (1 scripts)
Jul 10 21:41:40 odroid nm-dispatcher: req:1 'up' [wlan0]: start running ordered scripts...
Jul 10 21:41:40 odroid systemd[1]: Started Network Manager Script Dispatcher Service.
Jul 10 21:41:40 odroid systemd[1]: Reloading OpenBSD Secure Shell server.
Jul 10 21:41:40 odroid systemd[1]: Reloaded OpenBSD Secure Shell server.
Jul 10 21:41:40 odroid systemd[1]: Reloading OpenBSD Secure Shell server.
Jul 10 21:41:40 odroid systemd[1]: Reloaded OpenBSD Secure Shell server.
Here is the current linux kernel information:
Linux odroid 4.9.184-37 #1 SMP PREEMPT Wed Jul 10 02:50:15 -03 2019 aarch64 aarch64 aarch64 GNU/Linux
I've seen similar problems with past kernel versions, so I don't think it is specific to 4.9-184-37.

Hope that this can help you find the problem. If you need more info, please let me know. I can also do some tests for you.

Cheers!

Guy

User avatar
odroid
Site Admin
Posts: 31363
Joined: Fri Feb 22, 2013 11:14 pm
languages_spoken: English
ODROIDs: ODROID
Has thanked: 50 times
Been thanked: 184 times
Contact:

Re: WiFi Dongle Generates tons of syslog messages

Unread post by odroid » Thu Jul 11, 2019 10:05 am

Do you use this WiFi module 4? I could not reproduce the issue.
https://www.hardkernel.com/shop/wifi-module-4/

turgu1
Posts: 22
Joined: Fri May 31, 2019 6:57 am
languages_spoken: english
ODROIDs: odroid-n2 odroid-c2
Has thanked: 3 times
Been thanked: 0
Contact:

Re: WiFi Dongle Generates tons of syslog messages

Unread post by turgu1 » Thu Jul 11, 2019 10:24 am

odroid wrote:
Thu Jul 11, 2019 10:05 am
Do you use this WiFi module 4? I could not reproduce the issue.
https://www.hardkernel.com/shop/wifi-module-4/
No it’s not. Can’t tell the brand as there is no external indication on the device. Best info is from the syslog output I sent in my first message. I was used to use it on a Raspberry Pi (USB2 connexion).

From the Device Hunt Site: https://devicehunt.com/search/type/usb/ ... evice/5370

User avatar
odroid
Site Admin
Posts: 31363
Joined: Fri Feb 22, 2013 11:14 pm
languages_spoken: English
ODROIDs: ODROID
Has thanked: 50 times
Been thanked: 184 times
Contact:

Re: WiFi Dongle Generates tons of syslog messages

Unread post by odroid » Thu Jul 11, 2019 10:39 am

We will check the issue with the same WiFi chipset on the Mate image.

User avatar
odroid
Site Admin
Posts: 31363
Joined: Fri Feb 22, 2013 11:14 pm
languages_spoken: English
ODROIDs: ODROID
Has thanked: 50 times
Been thanked: 184 times
Contact:

Re: WiFi Dongle Generates tons of syslog messages

Unread post by odroid » Mon Jul 15, 2019 4:17 pm

We couldn't reproduced the issue.
We connected our WiFi module 0 to ODROID-N2 and downloaded a 1TB dummy file for one hour.
There was no similar error message in "dmesg" output.

Code: Select all

oot@odroid:~# uname -a
Linux odroid 4.9.184-37 #1 SMP PREEMPT Wed Jul 10 02:50:15 -03 2019 aarch64 aarch64 aarch64 GNU/Linux
root@odroid:~# lsb_release -a
No LSB modules are available.
Distributor ID:	Ubuntu
Description:	Ubuntu 18.04.2 LTS
Release:	18.04
Codename:	bionic
WiFi Module 0 : https://www.hardkernel.com/shop/wifi-module-0/

Post Reply

Return to “Ubuntu”

Who is online

Users browsing this forum: No registered users and 1 guest