From owner-freebsd-wireless@freebsd.org Fri Feb 9 00:53:33 2018 Return-Path: Delivered-To: freebsd-wireless@mailman.ysv.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mailman.ysv.freebsd.org (Postfix) with ESMTP id 14348F15246 for ; Fri, 9 Feb 2018 00:53:33 +0000 (UTC) (envelope-from freebsd-wifi@sentry.org) Received: from shadow.sentry.org (shadow.sentry.org [103.78.157.218]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (Client CN "shadow.sentry.org", Issuer "COMODO RSA Domain Validation Secure Server CA" (verified OK)) by mx1.freebsd.org (Postfix) with ESMTPS id 6E87768B42 for ; Fri, 9 Feb 2018 00:53:30 +0000 (UTC) (envelope-from freebsd-wifi@sentry.org) Received: from shadow.sentry.org (localhost [127.0.0.1]) by shadow.sentry.org (8.15.2/8.15.2) with ESMTP id w190jqvq047298 for ; Fri, 9 Feb 2018 11:45:52 +1100 (AEDT) (envelope-from freebsd-wifi@sentry.org) Subject: Re: Mac Mini late 2009 BCM4321 issues :) From: Trev To: freebsd-wireless@freebsd.org References: <6e188362-fad7-4dee-967b-1280ebe8978e@sentry.org> Message-ID: <52d64b1e-d9c4-ccca-426a-f36be3412e8b@sentry.org> Date: Fri, 9 Feb 2018 11:45:52 +1100 User-Agent: Mozilla/5.0 (X11; FreeBSD amd64; rv:52.0) Gecko/20100101 Firefox/52.0 SeaMonkey/2.49.2 MIME-Version: 1.0 In-Reply-To: <6e188362-fad7-4dee-967b-1280ebe8978e@sentry.org> Content-Type: text/plain; charset=UTF-8; format=flowed Content-Transfer-Encoding: quoted-printable X-Greylist: Sender IP whitelisted, not delayed by milter-greylist-4.6.2 (shadow.sentry.org [0.0.0.0]); Fri, 09 Feb 2018 11:45:52 +1100 (AEDT) X-BeenThere: freebsd-wireless@freebsd.org X-Mailman-Version: 2.1.25 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: Fri, 09 Feb 2018 00:53:33 -0000 Trev wrote on 09/03/2017 17:55: > 1A, uname -a shows: >=20 > FreeBSD citadel.sentry.org 11.0-STABLE FreeBSD 11.0-STABLE #0 \ > r314907M: Thu Mar=C2=A0 9 04:26:47 AEDT 2017 \ > root@citadel.sentry.org:/usr/obj/usr/src/sys/GENERIC=C2=A0 amd64 >=20 >=20 > 1B. dmesg shows: >=20 > siba_bwn0: \ > mem 0xd3200000-0xd3203fff at device 0.0 on pci3 > siba_bwn0: warn: multiple PCI(E) cores > siba_bwn0: unsupported coreid (USB 1.1 Host) > bwn0 on siba_bwn0 > bwn0: WLAN (chipid 0x4321 rev 12) PHY (analog 5 type 4 rev 2) \ > RADIO (manuf 0x17f ver 0x2055 rev 4) > bwn0: DMA (64 bits) > bwn0: Note: compiled with BWN_GPL_PHY; includes GPLv2 code > bwn0: Using 1 MSI messages >=20 >=20 > 2. wpa_supplicant.conf contains: >=20 > =C2=A0=C2=A0 network=3D{ > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 ssid=3D"WLAN24G" > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 psk=3D"my_password" > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 } >=20 >=20 > 3, wlan0 is created with: >=20 > ifconfig wlan0 create wlandev bwn0 wlanaddr 48:02:2a:63:4e:c1 \ > inet 192.168.1.99 channel 3 >=20 > Note: Unless I specify a wlanaddr then wlan0 is created with a mac=20 > address of ff:ff:ff:ff:ff:ff >=20 >=20 > 4. ifconfig wlan0 shows: >=20 > wlan0: flags=3D8c02 metric 0 mtu 1= 500 > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 ether 48:02:2a:63:4e:c1 > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 inet 192.168.1.99 netmask 0= xffffff00 broadcast 192.168.1.255 > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 inet6 fe80::4a02:2aff:fe63:= 4ec1%wlan0 prefixlen 64 tentative=20 > scopeid 0x3 > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 nd6 options=3D21 > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 media: IEEE 802.11 Wireless= Ethernet autoselect (autoselect) > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 status: no carrier > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 ssid "" channel 3 (2422 MHz= 11g) > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 regdomain FCC country US au= thmode OPEN privacy OFF txpower 30=20 > bmiss 7 > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 scanvalid 60 protmode CTS w= me > =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 groups: wlan >=20 >=20 > 5. ifconfig wlan0 scan shows (among others): > SSID/MESH ID=C2=A0=C2=A0=C2=A0 BSSID=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0= =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 CHAN RATE=C2=A0=C2=A0=C2=A0 S:= N=C2=A0=C2=A0=C2=A0=C2=A0 INT CAPS > WLAN24G=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 34:31:c4:bc:d0:= 21=C2=A0=C2=A0=C2=A0 3=C2=A0=C2=A0 54M=C2=A0 -77:-95=C2=A0=C2=A0 100 EPS=C2= =A0 HTCAP=20 > WME ATH RSN >=20 >=20 > 5. wpa_supplicant -dd -i wlan0 -c /etc/wpa_supplicant.conf produces: >=20 > wpa_supplicant v2.5 > Successfully initialized wpa_supplicant > Initializing interface 'wlan0' conf '/etc/wpa_supplicant.conf' driver=20 > 'default' ctrl_interface 'N/A' bridge 'N/A' > Configuration file '/etc/wpa_supplicant.conf' -> '/etc/wpa_supplicant.c= onf' > Reading configuration file '/etc/wpa_supplicant.conf' > Line: 5 - start of a new network block > ssid - hexdump_ascii(len=3D7): > =C2=A0=C2=A0=C2=A0=C2=A0 57 4c 41 4e 32 34 47=C2=A0=C2=A0=C2=A0=C2=A0=C2= =A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0= =C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0=C2=A0 = WLAN24G > PSK (ASCII passphrase) - hexdump_ascii(len=3D16): [REMOVED] > PSK (from passphrase) - hexdump(len=3D32): [REMOVED] > Priority group 0 > =C2=A0=C2=A0 id=3D0 ssid=3D'WLAN24G' > wpa_driver_bsd_capa: drivercaps=3D0x0581e801,cryptocaps=3D0x00000000 > Add interface wlan0 to a new radio N/A > wlan0: Own MAC address: 48:02:2a:63:4e:c1 > bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D0 set_tx=3D0 seq_len=3D0 key_= len=3D0 > bsd_del_key: key_idx=3D0 > bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D1 set_tx=3D0 seq_len=3D0 key_= len=3D0 > bsd_del_key: key_idx=3D1 > bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D2 set_tx=3D0 seq_len=3D0 key_= len=3D0 > bsd_del_key: key_idx=3D2 > bsd_set_key: alg=3D0 addr=3D0x0 key_idx=3D3 set_tx=3D0 seq_len=3D0 key_= len=3D0 > bsd_del_key: key_idx=3D3 > wpa_driver_bsd_set_countermeasures: enabled=3D0 > wlan0: RSN: flushing PMKID list in the driver > wlan0: Setting scan request: 0.100000 sec > wlan0: WPS: UUID based on MAC address: dbcf9172-a2c4-5074-bfc2-05bfaecc= 535f > ENGINE: Loading dynamic engine > ENGINE: Loading dynamic engine > EAPOL: SUPP_PAE entering state DISCONNECTED > EAPOL: Supplicant port status: Unauthorized > EAPOL: KEY_RX entering state NO_KEY_RECEIVE > EAPOL: SUPP_BE entering state INITIALIZE > EAP: EAP entering state DISABLED > wlan0: Added interface wlan0 > wlan0: State: DISCONNECTED -> DISCONNECTED > wlan0: State: DISCONNECTED -> SCANNING > wlan0: Starting AP scan for wildcard SSID > wlan0: Add radio work 'scan'@0x801ce4000 > wlan0: First radio work item in the queue - schedule start immediately > wlan0: Starting radio work 'scan'@0x801ce4000 after 0.000012 second wai= t > wpa_driver_bsd_set_wpa: enabled=3D1 > wpa_driver_bsd_set_wpa_internal: wpa=3D3 privacy=3D1 > wlan0: Event SCAN_RESULTS (3) received > Received 868 bytes of scan results (4 BSSes) > wlan0: BSS: Start scan result update 1 > wlan0: BSS: Add new id 0 BSSID 48:02:2a:61:6a:24 SSID 'router-2' freq 2= 422 > wlan0: BSS: Add new id 1 BSSID 48:02:2a:63:4e:c0 SSID 'router-3' freq 2= 422 > wlan0: BSS: Add new id 2 BSSID 34:31:c4:bc:d0:21 SSID 'WLAN24G' freq 24= 22 > wlan0: BSS: Add new id 3 BSSID 44:33:4c:b5:d3:68 SSID 'router-4' freq 2= 422 > BSS: last_scan_res_used=3D4/32 > wlan0: New scan results available (own=3D0 ext=3D0) > wlan0: Radio work 'scan'@0x801ce4000 done in 0.212149 seconds > wlan0: Selecting BSS from priority group 0 > wlan0: 0: 48:02:2a:61:6a:24 ssid=3D'router-2' wpa_ie_len=3D0 rsn_ie_len= =3D20=20 > caps=3D0x11 level=3D-66 freq=3D2422 > wlan0:=C2=A0=C2=A0=C2=A0 skip - SSID mismatch > wlan0: 1: 48:02:2a:63:4e:c0 ssid=3D'router-3' wpa_ie_len=3D0 rsn_ie_len= =3D20=20 > caps=3D0x11 level=3D-69 freq=3D2422 > wlan0:=C2=A0=C2=A0=C2=A0 skip - SSID mismatch > wlan0: 2: 34:31:c4:bc:d0:21 ssid=3D'WLAN24G' wpa_ie_len=3D0 rsn_ie_len=3D= 20=20 > caps=3D0x31 level=3D-77 freq=3D2422 > wlan0:=C2=A0=C2=A0=C2=A0 selected based on RSN IE > wlan0:=C2=A0=C2=A0=C2=A0 selected BSS 34:31:c4:bc:d0:21 ssid=3D'WLAN24G= ' > wlan0: Considering connect request: reassociate: 0=C2=A0 selected:=20 > 34:31:c4:bc:d0:21=C2=A0 bssid: 00:00:00:00:00:00=C2=A0 pending: 00:00:0= 0:00:00:00=20 > wpa_state: SCANNING=C2=A0 ssid=3D0x801c45000=C2=A0 current_ssid=3D0x0 > wlan0: Request association with 34:31:c4:bc:d0:21 > wlan0: Add radio work 'connect'@0x801ce4000 > wlan0: First radio work item in the queue - schedule start immediately > wlan0: Starting radio work 'connect'@0x801ce4000 after 0.000009 second = wait > wlan0: Trying to associate with 34:31:c4:bc:d0:21 (SSID=3D'WLAN24G'=20 > freq=3D2422 MHz) > FT: Stored MDIE and FTIE from (Re)Association Response - hexdump(len=3D= 0): > wlan0: Cancelling scan request > wlan0: WPA: clearing own WPA/RSN IE > wlan0: Automatic auth_alg selection: 0x1 > RSN: PMKSA cache search - network_ctx=3D0x801c45000 try_opportunistic=3D= 0 > RSN: Search for BSSID 34:31:c4:bc:d0:21 > RSN: No PMKSA cache entry found > wlan0: RSN: using IEEE 802.11i/D9.0 > wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 pro= to 2 > wlan0: WPA: clearing AP WPA IE > WPA: set AP RSN IE - hexdump(len=3D22): 30 14 01 00 00 0f ac 04 01 00 0= 0=20 > 0f ac 04 01 00 00 0f ac 02 00 00 > wlan0: WPA: using GTK CCMP > wlan0: WPA: using PTK CCMP > wlan0: WPA: using KEY_MGMT WPA-PSK > WPA: Set own WPA IE default - hexdump(len=3D22): 30 14 01 00 00 0f ac 0= 4=20 > 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 > wlan0: State: SCANNING -> ASSOCIATING > Limit connection to BSSID 34:31:c4:bc:d0:21 freq=3D2422 MHz based on sc= an=20 > results (bssid_set=3D0) > wpa_driver_bsd_associate: ssid 'WLAN24G' wpa ie len 22 pairwise 16 grou= p=20 > 16 key mgmt 2 > wpa_driver_bsd_set_drop_unencrypted: enabled=3D1 > bsd_set_opt_ie: set WPA+RSN ie (len 22) > wpa_driver_bsd_associate: set PRIVACY 1 > wlan0: Setting authentication timeout: 10 sec 0 usec > EAPOL: External notification - EAP success=3D0 > EAPOL: External notification - EAP fail=3D0 > EAPOL: External notification - portControl=3DAuto > EAPOL: disable timer tick > wlan0: Authentication with 34:31:c4:bc:d0:21 timed out. > Added BSSID 34:31:c4:bc:d0:21 into blacklist > wlan0: WPA: Clear old PMK and PTK > wlan0: Request to deauthenticate - bssid=3D00:00:00:00:00:00=20 > pending_bssid=3D34:31:c4:bc:d0:21 reason=3D3 state=3DASSOCIATING > wlan0: Event DEAUTH (12) received > wlan0: Deauthentication notification > wlan0:=C2=A0 * reason 3 (locally generated) > Deauthentication frame IE(s) - hexdump(len=3D0): [NULL] > wlan0: CTRL-EVENT-DISCONNECTED bssid=3D34:31:c4:bc:d0:21 reason=3D3=20 > locally_generated=3D1 > wlan0: Auto connect enabled: try to reconnect (wps=3D0/0 wpa_state=3D5)= > wlan0: Setting scan request: 0.100000 sec > wlan0: Radio work 'connect'@0x801ce4000 done in 10.022229 seconds > [etc] >=20 > and never manages to associate successfully. >=20 > Any ideas? As per Adrian's advice I updated to head via source (12.0-CURRENT #0=20 r315191M: Tue Mar 14 02:02:24 AEDT 2017) However, the result was the same. No successful association. Today I upgraded head via source to 12.0-CURRENT #0 r329009: Thu Feb 8=20 21:13:07 AEDT 2018 and I seem to be going backwards. The log file now sho= ws: Feb 9 11:32:40 citadel kernel: wlan0: Ethernet address: 00:26:b0:f7:a1:e= c Feb 9 11:32:40 citadel kernel: bwn0: ucode fw: ucode11 Feb 9 11:32:40 citadel kernel: bwn0: firmware version (rev 410 patch=20 2160 date 0x751a time 0x7c0a) Feb 9 11:32:40 citadel kernel: bwn0: NVRAM variable txpid5ga0 unreadable= : 2 Feb 9 11:32:40 citadel kernel: bwn0: PHY init failed Feb 9 11:32:40 citadel kernel: bwn0: need multicast update callback Feb 9 11:32:44 citadel kernel: bwn0: ucode fw: ucode11 Feb 9 11:32:45 citadel kernel: bwn0: firmware version (rev 410 patch=20 2160 date 0x751a time 0x7c0a) Feb 9 11:32:45 citadel kernel: bwn0: NVRAM variable txpid5ga0 unreadable= : 2 Feb 9 11:32:45 citadel kernel: bwn0: PHY init failed Feb 9 11:33:32 citadel kernel: bwn0: ucode fw: ucode11 Feb 9 11:33:32 citadel kernel: bwn0: firmware version (rev 410 patch=20 2160 date 0x751a time 0x7c0a) Feb 9 11:33:32 citadel kernel: bwn0: NVRAM variable txpid5ga0 unreadable= : 2 Feb 9 11:33:32 citadel kernel: bwn0: PHY init failed On the plus side, I no longer have to specify a MAC address as it is no=20 longer ff:ff:ff:ff:ff:ff :-) Any further ideas? Thanks, TREV.