I'm having issues using a Linksys EA8300 (ath10k) running recent snapshot builds as a WDS Client. It sounds similar to what [some others users have described] (5GHz channel problems) using other hardware.
I've tried connecting to WDS APs on two different devices running 18.06.2 (Archer C7v2 & TP-Link C2600). The connection authenticates, associates, and then deauthenticates ~4s later with "(Reason: 2=PREV_AUTH_NOT_VALID)." This repeats multiple times, then the supplicant temporarily disables the interfaces (CTRL-EVENT-SSID-TEMP-DISABLED), and then reenables it ~10s later and the cycle starts again.
The WDS Client interface is the only device on the physical interface.
The wireless security setting is:
- encryption 'psk2+ccmp'
I've tried this with the following options both on & off on both Client & AP:
- legacy_rates
- option disassoc_low_ack
- option wpa_disable_eapol_key_retries
I've used snapshot builds for the WDS Client device I downloaded on the evenings (GMT-7) of May 31 and June 2.
Log from WDS-client device (EA8300)
This shows the full cycle through CTRL-EVENT-SSID-TEMP-DISABLED, and then the start of a new cycle again.
Mon Jun 3 06:21:48 2019 daemon.notice wpa_supplicant[4566]: wlan2: SME: Trying to authenticate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:21:48 2019 kern.info kernel: [ 782.889940] wlan2: authenticate with XX:XX:XX:XX:X:XX
Mon Jun 3 06:21:48 2019 daemon.notice wpa_supplicant[4566]: wlan2: Trying to associate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:21:48 2019 kern.info kernel: [ 783.089315] wlan2: send auth to XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:21:48 2019 kern.info kernel: [ 783.097105] wlan2: authenticated
Mon Jun 3 06:21:48 2019 kern.info kernel: [ 783.098820] wlan2: associate with XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:21:48 2019 kern.info kernel: [ 783.118624] wlan2: RX AssocResp from XX:XX:XX:XX:X:XX (capab=0x11 status=0 aid=1)
Mon Jun 3 06:21:48 2019 kern.info kernel: [ 783.137024] wlan2: associated
Mon Jun 3 06:21:48 2019 kern.warn kernel: [ 783.137584] ath10k_ahb a800000.wifi: Invalid peer id 0 or peer stats buffer, peer: (null) sta: (null)
Mon Jun 3 06:21:48 2019 daemon.notice netifd: Network device 'wlan2' link is up
Mon Jun 3 06:21:48 2019 daemon.notice wpa_supplicant[4566]: wlan2: Associated with XX:XX:XX:XX:X:XX
Mon Jun 3 06:21:48 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Mon Jun 3 06:21:52 2019 kern.info kernel: [ 787.124749] wlan2: deauthenticated from XX:XX:XX:XX:X:XX (Reason: 2=PREV_AUTH_NOT_VALID)
Mon Jun 3 06:21:52 2019 daemon.notice netifd: Network device 'wlan2' link is down
Mon Jun 3 06:21:53 2019 kern.warn kernel: [ 787.219641] ath10k_ahb a800000.wifi: peer-unmap-event: unknown peer id 1
Mon Jun 3 06:21:53 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-DISCONNECTED bssid=XX:XX:XX:XX:X:XX reason=2
Mon Jun 3 06:21:54 2019 daemon.notice wpa_supplicant[4566]: wlan2: SME: Trying to authenticate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:21:54 2019 kern.info kernel: [ 788.216672] wlan2: authenticate with XX:XX:XX:XX:X:XX
Mon Jun 3 06:21:54 2019 kern.info kernel: [ 788.224842] wlan2: send auth to XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:21:54 2019 kern.info kernel: [ 788.227595] wlan2: authenticated
Mon Jun 3 06:21:54 2019 daemon.notice wpa_supplicant[4566]: wlan2: Trying to associate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:21:54 2019 kern.info kernel: [ 788.238622] wlan2: associate with XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:21:54 2019 kern.info kernel: [ 788.240434] wlan2: RX AssocResp from XX:XX:XX:XX:X:XX (capab=0x11 status=0 aid=1)
Mon Jun 3 06:21:54 2019 kern.info kernel: [ 788.245542] wlan2: associated
Mon Jun 3 06:21:54 2019 daemon.notice netifd: Network device 'wlan2' link is up
Mon Jun 3 06:21:54 2019 daemon.notice wpa_supplicant[4566]: wlan2: Associated with XX:XX:XX:XX:X:XX
Mon Jun 3 06:21:54 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Mon Jun 3 06:21:58 2019 kern.info kernel: [ 792.255909] wlan2: deauthenticated from XX:XX:XX:XX:X:XX (Reason: 2=PREV_AUTH_NOT_VALID)
Mon Jun 3 06:21:58 2019 daemon.notice netifd: Network device 'wlan2' link is down
Mon Jun 3 06:21:58 2019 kern.warn kernel: [ 792.340032] ath10k_ahb a800000.wifi: peer-unmap-event: unknown peer id 1
Mon Jun 3 06:21:58 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-DISCONNECTED bssid=XX:XX:XX:XX:X:XX reason=2
Mon Jun 3 06:21:59 2019 daemon.notice wpa_supplicant[4566]: wlan2: SME: Trying to authenticate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:21:59 2019 kern.info kernel: [ 793.327297] wlan2: authenticate with XX:XX:XX:XX:X:XX
Mon Jun 3 06:21:59 2019 kern.info kernel: [ 793.336113] wlan2: send auth to XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:21:59 2019 daemon.notice wpa_supplicant[4566]: wlan2: Trying to associate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:21:59 2019 kern.info kernel: [ 793.339350] wlan2: authenticated
Mon Jun 3 06:21:59 2019 kern.info kernel: [ 793.348685] wlan2: associate with XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:21:59 2019 kern.info kernel: [ 793.350449] wlan2: RX AssocResp from XX:XX:XX:XX:X:XX (capab=0x11 status=0 aid=1)
Mon Jun 3 06:21:59 2019 daemon.notice netifd: Network device 'wlan2' link is up
Mon Jun 3 06:21:59 2019 kern.info kernel: [ 793.355874] wlan2: associated
Mon Jun 3 06:21:59 2019 daemon.notice wpa_supplicant[4566]: wlan2: Associated with XX:XX:XX:XX:X:XX
Mon Jun 3 06:21:59 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Mon Jun 3 06:22:03 2019 kern.info kernel: [ 797.358213] wlan2: deauthenticated from XX:XX:XX:XX:X:XX (Reason: 2=PREV_AUTH_NOT_VALID)
Mon Jun 3 06:22:03 2019 daemon.notice netifd: Network device 'wlan2' link is down
Mon Jun 3 06:22:03 2019 kern.warn kernel: [ 797.439604] ath10k_ahb a800000.wifi: peer-unmap-event: unknown peer id 1
Mon Jun 3 06:22:03 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-DISCONNECTED bssid=XX:XX:XX:XX:X:XX reason=2
Mon Jun 3 06:22:04 2019 daemon.notice wpa_supplicant[4566]: wlan2: SME: Trying to authenticate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:22:04 2019 kern.info kernel: [ 798.420433] wlan2: authenticate with XX:XX:XX:XX:X:XX
Mon Jun 3 06:22:04 2019 kern.info kernel: [ 798.427806] wlan2: send auth to XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:22:04 2019 daemon.notice wpa_supplicant[4566]: wlan2: Trying to associate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:22:04 2019 kern.info kernel: [ 798.430601] wlan2: authenticated
Mon Jun 3 06:22:04 2019 kern.info kernel: [ 798.438630] wlan2: associate with XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:22:04 2019 kern.info kernel: [ 798.440490] wlan2: RX AssocResp from XX:XX:XX:XX:X:XX (capab=0x11 status=0 aid=1)
Mon Jun 3 06:22:04 2019 kern.info kernel: [ 798.449361] wlan2: associated
Mon Jun 3 06:22:04 2019 daemon.notice wpa_supplicant[4566]: wlan2: Associated with XX:XX:XX:XX:X:XX
Mon Jun 3 06:22:04 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Mon Jun 3 06:22:04 2019 daemon.notice netifd: Network device 'wlan2' link is up
Mon Jun 3 06:22:08 2019 daemon.notice netifd: Network device 'wlan2' link is down
Mon Jun 3 06:22:08 2019 kern.info kernel: [ 802.446693] wlan2: deauthenticated from XX:XX:XX:XX:X:XX (Reason: 2=PREV_AUTH_NOT_VALID)
Mon Jun 3 06:22:08 2019 kern.warn kernel: [ 802.529615] ath10k_ahb a800000.wifi: peer-unmap-event: unknown peer id 1
Mon Jun 3 06:22:08 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-DISCONNECTED bssid=XX:XX:XX:XX:X:XX reason=2
Mon Jun 3 06:22:08 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="my-openwrt-ssid" auth_failures=1 duration=10 reason=CONN_FAILED
Mon Jun 3 06:22:19 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-SSID-REENABLED id=0 ssid="my-openwrt-ssid"
Mon Jun 3 06:22:19 2019 daemon.notice wpa_supplicant[4566]: wlan2: SME: Trying to authenticate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:22:19 2019 kern.info kernel: [ 813.386666] wlan2: authenticate with XX:XX:XX:XX:X:XX
Mon Jun 3 06:22:19 2019 kern.info kernel: [ 813.393911] wlan2: send auth to XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:22:19 2019 kern.info kernel: [ 813.396052] wlan2: authenticated
Mon Jun 3 06:22:19 2019 daemon.notice wpa_supplicant[4566]: wlan2: Trying to associate with XX:XX:XX:XX:X:XX (SSID='my-openwrt-ssid' freq=5180 MHz)
Mon Jun 3 06:22:19 2019 kern.info kernel: [ 813.408589] wlan2: associate with XX:XX:XX:XX:X:XX (try 1/3)
Mon Jun 3 06:22:19 2019 kern.info kernel: [ 813.410495] wlan2: RX AssocResp from XX:XX:XX:XX:X:XX (capab=0x11 status=0 aid=1)
Mon Jun 3 06:22:19 2019 kern.info kernel: [ 813.416570] wlan2: associated
Mon Jun 3 06:22:19 2019 daemon.notice netifd: Network device 'wlan2' link is up
Mon Jun 3 06:22:19 2019 daemon.notice wpa_supplicant[4566]: wlan2: Associated with XX:XX:XX:XX:X:XX
Mon Jun 3 06:22:19 2019 daemon.notice wpa_supplicant[4566]: wlan2: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Log from WDS-AP device (C2600)
Mon Jun 3 06:21:48 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: authenticated
Mon Jun 3 06:21:48 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: associated (aid 1)
Mon Jun 3 06:21:48 2019 kern.info kernel: [116875.381902] br-lan: port 8(wlan0-1.sta1) entered blocking state
Mon Jun 3 06:21:48 2019 kern.info kernel: [116875.381943] br-lan: port 8(wlan0-1.sta1) entered disabled state
Mon Jun 3 06:21:48 2019 kern.info kernel: [116875.387429] device wlan0-1.sta1 entered promiscuous mode
Mon Jun 3 06:21:49 2019 daemon.notice hostapd: wlan0: WDS-STA-INTERFACE-ADDED ifname=wlan0-1.sta1 sta_addr=YY:YY:YY:YY:YY:YY
Mon Jun 3 06:21:49 2019 kern.info kernel: [116875.397502] br-lan: port 8(wlan0-1.sta1) entered blocking state
Mon Jun 3 06:21:49 2019 kern.info kernel: [116875.398349] br-lan: port 8(wlan0-1.sta1) entered forwarding state
Mon Jun 3 06:21:54 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: authenticated
Mon Jun 3 06:21:54 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: associated (aid 1)
Mon Jun 3 06:21:59 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: authenticated
Mon Jun 3 06:21:59 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: associated (aid 1)
Mon Jun 3 06:22:04 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: authenticated
Mon Jun 3 06:22:04 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: associated (aid 1)
Mon Jun 3 06:22:13 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: deauthenticated due to local deauth request
Mon Jun 3 06:22:13 2019 daemon.err hostapd: nl80211: NL80211_ATTR_STA_VLAN (addr=YY:YY:YY:YY:YY:YY ifname=wlan0-1 vlan_id=0) failed: -2 (No such file or directory)
Mon Jun 3 06:22:13 2019 kern.info kernel: [116899.662550] device wlan0-1.sta1 left promiscuous mode
Mon Jun 3 06:22:13 2019 kern.info kernel: [116899.662721] br-lan: port 8(wlan0-1.sta1) entered disabled state
Mon Jun 3 06:22:13 2019 daemon.notice hostapd: wlan0: WDS-STA-INTERFACE-REMOVED ifname=wlan0-1.sta1 sta_addr=YY:YY:YY:YY:YY:YY
Mon Jun 3 06:22:19 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: authenticated
Mon Jun 3 06:22:19 2019 daemon.info hostapd: wlan0-1: STA YY:YY:YY:YY:YY:YY IEEE 802.11: associated (aid 1)
Mon Jun 3 06:22:19 2019 kern.info kernel: [116905.662619] br-lan: port 8(wlan0-1.sta1) entered blocking state
Mon Jun 3 06:22:19 2019 kern.info kernel: [116905.662648] br-lan: port 8(wlan0-1.sta1) entered disabled state
Mon Jun 3 06:22:19 2019 kern.info kernel: [116905.667850] device wlan0-1.sta1 entered promiscuous mode
Mon Jun 3 06:22:19 2019 daemon.notice hostapd: wlan0: WDS-STA-INTERFACE-ADDED ifname=wlan0-1.sta1 sta_addr=YY:YY:YY:YY:YY:YY
Mon Jun 3 06:22:19 2019 kern.info kernel: [116905.674625] br-lan: port 8(wlan0-1.sta1) entered blocking state
Mon Jun 3 06:22:19 2019 kern.info kernel: [116905.679063] br-lan: port 8(wlan0-1.sta1) entered forwarding state
Is anyone else seeing this behavior? Has anyone found any work arounds?