From owner-freebsd-wireless@freebsd.org Wed Sep 14 15:27:46 2016 Return-Path: Delivered-To: freebsd-wireless@mailman.ysv.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) by mailman.ysv.freebsd.org (Postfix) with ESMTP id 9A628BDAFF5 for ; Wed, 14 Sep 2016 15:27:46 +0000 (UTC) (envelope-from mike@reifenberger.com) Received: from mail.eeeit.de (mail.eeeit.de [37.120.160.187]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (Client did not present a certificate) by mx1.freebsd.org (Postfix) with ESMTPS id 0F68A11E7; Wed, 14 Sep 2016 15:27:45 +0000 (UTC) (envelope-from mike@reifenberger.com) Received: from localhost (localhost [127.0.0.1]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (No client certificate requested) (Authenticated sender: mike@reifenberger.com) by mail.eeeit.de (Postfix) with ESMTPSA id EDAE85E82; Wed, 14 Sep 2016 17:27:42 +0200 (CEST) Received: from ppp-62-216-197-106.dynamic.mnet-online.de (ppp-62-216-197-106.dynamic.mnet-online.de [62.216.197.106]) by mail.eeeit.de (Horde Framework) with HTTPS; Wed, 14 Sep 2016 17:27:42 +0200 Date: Wed, 14 Sep 2016 17:27:42 +0200 Message-ID: <20160914172742.Horde.ZL9ZWdGKhIB9Qt9HS2O7rhE@mail.eeeit.de> From: Michael Reifenberger To: adrian@freebsd.org Cc: freebsd-wireless@freebsd.org Subject: More than one SSID doesn't fully work with AR9280 (ath or hostapd bug?) User-Agent: Horde Application Framework 5 Content-Type: text/plain; charset=utf-8; format=flowed; DelSp=Yes MIME-Version: 1.0 Content-Disposition: inline Content-Transfer-Encoding: 8bit X-BeenThere: freebsd-wireless@freebsd.org X-Mailman-Version: 2.1.23 Precedence: list List-Id: "Discussions of 802.11 stack, tools device driver development." List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Wed, 14 Sep 2016 15:27:46 -0000 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@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 metric 0 mtu 2290 ether 00:15:6d:85:e3:00 nd6 options=21 media: IEEE 802.11 Wireless Ethernet autoselect mode 11ng status: running ath0_wlan1: flags=8843 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 media: IEEE 802.11 Wireless Ethernet autoselect mode 11ng 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 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 media: IEEE 802.11 Wireless Ethernet autoselect mode 11ng 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@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@freebsd.org Gruß --- Michael Reifenberger