More than one SSID doesn't fully work with AR9280 (ath or hostapd bug?)

Adrian Chadd adrian at freebsd.org
Wed Sep 14 16:02:18 UTC 2016 

hi,

I'll have to try it on -head. I tohught we fixed these issues!


-a


On 14 September 2016 at 08:27, Michael Reifenberger
<mike at reifenberger.com> wrote:
> Hi,
>
> I'm using pfsense (FreeBSD fw.rm-i.net 10.3-RELEASE-p7 FreeBSD
> 10.3-RELEASE-p7 #0 a0a18f4(stable/16.7): Mon Aug 15 06:35:28 CEST 2016
> root at sensey64:/usr/obj/usr/src/sys/SMP  amd64) and a Ubiquiti SR71-E on a
> APU.2C4 to get the following:
>
> I want two interfaces ath0_wlan1 and ath0_wlan2 with different SSID's and
> PSK's in order to implement
> two different access filters.
>
> The configuration works. I see:
> ath0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 2290
>         ether 00:15:6d:85:e3:00
>         nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
>         media: IEEE 802.11 Wireless Ethernet autoselect mode 11ng <hostap>
>         status: running
> ath0_wlan1: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu
> 1500
>         ether 00:15:6d:85:e3:00
>         inet6 fe80::215:6dff:fe85:e300%ath0_wlan1 prefixlen 64 scopeid 0x9
>         inet 10.0.4.1 netmask 0xffffff00 broadcast 10.0.4.255
>         nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
>         media: IEEE 802.11 Wireless Ethernet autoselect mode 11ng <hostap>
>         status: running
>         ssid WL1 channel 2 (2417 MHz 11g ht/40+) bssid 00:15:6d:85:e3:00
>         country US ecm authmode WPA2/802.11i privacy MIXED deftxkey 2
>         AES-CCM 2:128-bit AES-CCM 3:128-bit txpower 27 scanvalid 60
>         protmode RTSCTS ampdulimit 64k ampdudensity 8 shortgi wme burst
>         -apbridge dtimperiod 1 -dfs
> ath0_wlan2: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu
> 1500
>         ether 06:15:6d:85:e3:00
>         inet6 fe80::415:6dff:fe85:e300%ath0_wlan2 prefixlen 64 scopeid 0xa
>         inet 10.0.5.1 netmask 0xffffff00 broadcast 10.0.5.255
>         nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
>         media: IEEE 802.11 Wireless Ethernet autoselect mode 11ng <hostap>
>         status: running
>         ssid WL2 channel 2 (2417 MHz 11g ht/40+) bssid 06:15:6d:85:e3:00
>         country US ecm authmode WPA2/802.11i privacy MIXED deftxkey 2
>         AES-CCM 2:128-bit AES-CCM 3:128-bit txpower 27 scanvalid 60
>         protmode RTSCTS ampdulimit 64k ampdudensity 8 shortgi wme burst
>         -apbridge dtimperiod 1 -dfs
>
> Both hostapd.conf's seem reasonable:
> root at fw:/var/etc # diff -u2 hostapd_ath0_wlan1.conf hostapd_ath0_wlan2.conf
> --- hostapd_ath0_wlan1.conf     2016-03-07 02:00:58.528705000 +0100
> +++ hostapd_ath0_wlan2.conf     2016-03-07 02:01:00.348875000 +0100
> @@ -1,3 +1,3 @@
> -interface=ath0_wlan1
> +interface=ath0_wlan2
>  driver=bsd
>  logger_syslog=-1
> @@ -5,11 +5,11 @@
>  logger_stdout=-1
>  logger_stdout_level=0
> -dump_file=/tmp/hostapd_ath0_wlan1.dump
> +dump_file=/tmp/hostapd_ath0_wlan2.dump
>  ctrl_interface=/var/run/hostapd
>  ctrl_interface_group=wheel
> -#accept_mac_file=/tmp/hostapd_ath0_wlan1.accept
> -#deny_mac_file=/tmp/hostapd_ath0_wlan1.deny
> +#accept_mac_file=/tmp/hostapd_ath0_wlan2.accept
> +#deny_mac_file=/tmp/hostapd_ath0_wlan2.deny
>  #macaddr_acl=
> -ssid=WL1
> +ssid=WL2
>  debug=
>  auth_algs=1
>
> The problem:
> Only one of the interfaces works fully at the same time
> Either the first one or the second one, but not both together.
>
> If enabling both at the same time, I see both SSID's on the WLAN,
> can authenticate to one of the interfaces (probably the first configured
> one) but not
> to the other. The other allways gives an authentification failure.
>
> How can I debug this issue further?
>
> BTW (if it's related):
> Over time I see some of the following errors in dmesg:
> ...
> ath0: stuck beacon; resetting (bmiss count 4)
> ath0: stuck beacon; resetting (bmiss count 4)
> ath0: stuck beacon; resetting (bmiss count 4)
> ath0: stuck beacon; resetting (bmiss count 4)
> ath0: stuck beacon; resetting (bmiss count 4)
> ....
>
>
> In the wireless logfile I see during an unsuccessfull authentification:
> ...
> Sep 14 17:19:53 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:19:53 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:19:53 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 1
> notification
> Sep 14 17:19:53 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: start
> authentication
> Sep 14 17:19:53 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:19:53 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:19:54 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:19:54 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:19:55 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:19:55 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:19:56 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:19:56 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: PTKSTART:
> Retry limit 4 reached
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 3
> notification
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DEAUTHENTICATE.indication(c4:85:08:3a:cf:f0, 2)
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DELETEKEYS.request(c4:85:08:3a:cf:f0)
> Sep 14 17:19:57 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 2
> notification
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:19:57 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 1
> notification
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: start
> authentication
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:19:57 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:19:58 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:19:58 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:19:59 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:19:59 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:00 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:00 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: PTKSTART:
> Retry limit 4 reached
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 3
> notification
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DEAUTHENTICATE.indication(c4:85:08:3a:cf:f0, 2)
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DELETEKEYS.request(c4:85:08:3a:cf:f0)
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:20:01 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 2
> notification
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:01 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 1
> notification
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: start
> authentication
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:01 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:02 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:02 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:03 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:03 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:04 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:04 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: PTKSTART:
> Retry limit 4 reached
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 3
> notification
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DEAUTHENTICATE.indication(c4:85:08:3a:cf:f0, 2)
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DELETEKEYS.request(c4:85:08:3a:cf:f0)
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 2
> notification
> Sep 14 17:20:05 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:05 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 1
> notification
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: start
> authentication
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:05 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:06 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:06 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:07 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:07 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:08 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:08 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: PTKSTART:
> Retry limit 4 reached
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 3
> notification
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:09 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DEAUTHENTICATE.indication(c4:85:08:3a:cf:f0, 2)
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DELETEKEYS.request(c4:85:08:3a:cf:f0)
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 2
> notification
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:20:09 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> associated
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 1
> notification
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: start
> authentication
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:09 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:10 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:10 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:11 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:11 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:12 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:12 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: sending
> 1/4 msg of 4-Way Handshake
> Sep 14 17:20:13 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: EAPOL-Key
> timeout
> Sep 14 17:20:13 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: PTKSTART:
> Retry limit 4 reached
> Sep 14 17:20:13 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 3
> notification
> Sep 14 17:20:13 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> Sep 14 17:20:13 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DEAUTHENTICATE.indication(c4:85:08:3a:cf:f0, 2)
> Sep 14 17:20:13 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 MLME:
> MLME-DELETEKEYS.request(c4:85:08:3a:cf:f0)
> Sep 14 17:20:13 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:20:13 fw hostapd: ath0_wlan1: STA c4:85:08:3a:cf:f0 IEEE 802.11:
> disassociated
> Sep 14 17:20:13 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 WPA: event 2
> notification
> Sep 14 17:20:13 fw hostapd: ath0_wlan2: STA c4:85:08:3a:cf:f0 IEEE 802.1X:
> unauthorizing port
> ...
>
> Any clues?
>
> Thanks in advance!
> ---
> mike mr at freebsd.org
>
>
> Gruß
> ---
> Michael Reifenberger
>

More information about the freebsd-wireless mailing list