From nobody Sun Jul 09 11:35:31 2023 X-Original-To: freebsd-wireless@mlmmj.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mlmmj.nyi.freebsd.org (Postfix) with ESMTP id 4QzQ7T599Xz4lw9L for ; Sun, 9 Jul 2023 11:36:05 +0000 (UTC) (envelope-from freebsd@walstatt-de.de) Received: from smtp6.goneo.de (smtp6.goneo.de [IPv6:2001:1640:5::8:31]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits) server-digest SHA256) (Client did not present a certificate) by mx1.freebsd.org (Postfix) with ESMTPS id 4QzQ7S5GlFz3vjJ for ; Sun, 9 Jul 2023 11:36:04 +0000 (UTC) (envelope-from freebsd@walstatt-de.de) Authentication-Results: mx1.freebsd.org; dkim=pass header.d=walstatt-de.de header.s=DKIM001 header.b="V63//lOD"; spf=none (mx1.freebsd.org: domain of freebsd@walstatt-de.de has no SPF policy when checking 2001:1640:5::8:31) smtp.mailfrom=freebsd@walstatt-de.de; dmarc=none Received: from hub1.goneo.de (hub1.goneo.de [85.220.129.52]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits)) (No client certificate requested) by smtp6.goneo.de (Postfix) with ESMTPS id C85CE10A330B for ; Sun, 9 Jul 2023 13:36:00 +0200 (CEST) Received: from hub1.goneo.de (localhost [127.0.0.1]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits)) (No client certificate requested) by hub1.goneo.de (Postfix) with ESMTPS id 37B6A105C581 for ; Sun, 9 Jul 2023 13:35:59 +0200 (CEST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=walstatt-de.de; s=DKIM001; t=1688902559; h=from:from:reply-to:subject:subject:date:date:message-id:message-id: to:to:cc:mime-version:mime-version:content-type:content-type: content-transfer-encoding:content-transfer-encoding; bh=/36UcYFazGyiUkxhOKQnJSPBVmK5qTm+eQOWaoQpQm4=; b=V63//lODM4T7aq6OoPwx023I8EPgfOH+TOpd0wHWqoiRtT7xrO8rzMwJx3dT+aswqDCMAP 9E7FCfn8VpMng+kNXNbSnyPsXgyepzz699ic+cRi+HmX/vIvLOoYv9VbrmM4yUYlvTgmHd qDz0zOsg9YGpGzeTuYJSBFfmPn1xlj9slg7DnrgSrFxWEIIGUqbziyJzli09Us1d9WcYtG wFRT5cLk23QBleKpb3FMBPDDX1U4tiy0adY6GbgHAhudAnid7vC3Fr/qUYmLOcsTSoLE+j yab09Iw4vDYeQPra/2zcH/lvFF6Idtg8966w+0jM8LhBjHj/SBkIWB12rIV9+A== Received: from thor.intern.walstatt.dynvpn.de (dynamic-089-014-141-078.89.14.pool.telefonica.de [89.14.141.78]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange ECDHE (P-256) server-signature RSA-PSS (4096 bits) server-digest SHA256) (No client certificate requested) by hub1.goneo.de (Postfix) with ESMTPSA id 08EC4105C5C4 for ; Sun, 9 Jul 2023 13:35:58 +0200 (CEST) Date: Sun, 9 Jul 2023 13:35:31 +0200 From: FreeBSD User To: freebsd-wireless Subject: wpa_supplicant: lagg0 not working anymore (if_iwm/if_iwlwifi) Message-ID: <20230709133558.45d1a39b@thor.intern.walstatt.dynvpn.de> Organization: walstatt-de.de List-Id: Discussions List-Archive: https://lists.freebsd.org/archives/freebsd-wireless List-Help: List-Post: List-Subscribe: List-Unsubscribe: Sender: owner-freebsd-wireless@freebsd.org X-BeenThere: freebsd-wireless@freebsd.org MIME-Version: 1.0 Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: 7bit X-Rspamd-UID: 24053a X-Rspamd-UID: c66cdc X-Spamd-Result: default: False [-3.30 / 15.00]; NEURAL_HAM_LONG(-1.00)[-1.000]; NEURAL_HAM_MEDIUM(-1.00)[-1.000]; NEURAL_HAM_SHORT(-1.00)[-0.999]; R_DKIM_ALLOW(-0.20)[walstatt-de.de:s=DKIM001]; MIME_GOOD(-0.10)[text/plain]; MLMMJ_DEST(0.00)[freebsd-wireless@freebsd.org]; DKIM_TRACE(0.00)[walstatt-de.de:+]; RCVD_VIA_SMTP_AUTH(0.00)[]; R_SPF_NA(0.00)[no SPF record]; TO_DN_ALL(0.00)[]; MIME_TRACE(0.00)[0:+]; FROM_EQ_ENVFROM(0.00)[]; RCVD_COUNT_THREE(0.00)[4]; RCPT_COUNT_ONE(0.00)[1]; ASN(0.00)[asn:25394, ipnet:2001:1640::/32, country:DE]; FROM_HAS_DN(0.00)[]; ARC_NA(0.00)[]; HAS_ORG_HEADER(0.00)[]; TO_MATCH_ENVRCPT_ALL(0.00)[]; DMARC_NA(0.00)[walstatt-de.de]; PREVIOUSLY_DELIVERED(0.00)[freebsd-wireless@freebsd.org]; RCVD_TLS_ALL(0.00)[] X-Rspamd-Queue-Id: 4QzQ7S5GlFz3vjJ X-Spamd-Bar: --- X-ThisMailContainsUnwantedMimeParts: N Hello, running 13-STABLE (13.2-STABLE #29 stable/13-n255792-d295e418ae7e: Sun Jul 9 10:08:25 CEST 2023 amd64) on a Lenovo T560 with Intel i8260 WiFi chipset, combining the LAN (if_em) and WiFi (if_iwm) via if_lagg for convenient switching between LAN and WiFi APs of our departments, worked fine until recently. Althoug running wlan0 with several debug options, I can't fathom what is going wrong, there seems to be an authentication problem. Switching to either solitair if_iwm or if_iwlwifi using WiFi only works perfectly without changing anything but several line in /etc/rc.conf telling ifconfig_iwlwifi being configured with tag WPA and DHCP. With lagg0- config, I simply ommit DHCP and configure that accordingly with lagg0. Last time I used if_iwlwifi was a year ago and then the mutual more modern driver was supposed to have issues also with if_lagg configuration. But the problem also occurs with the mutual legacy if_iwm driver, which worked so far perfectly. I'm out of ideas here, maybe someone is aware of the problem so I do not need to file a PR or, just in case, can give some hints what to log further to make a PR useful (I already set in rc.conf: wlandebug_wlan0="+state+crypto+node+auth+assoc+dot1xsm+wpa+power") In the console logging for wlan0 messages, I can see this: [...] Jul 9 13:23:15 <0.6> hermann kernel: wlan0: sta_newstate: AUTH -> SCAN (1) Jul 9 13:23:25 <0.6> hermann kernel: wlan0: [ETHER_of_ACCESSPOINT] station deauth via MLME (reason: 3 (sending STA is leaving/has left IBSS or ESS)) Jul 9 13:23:25 <0.6> hermann kernel: wlan0: ieee80211_new_state_locked: SCAN -> INIT (arg 3) (nrunning 0 nscanning 0) Jul 9 13:23:25 <0.6> hermann kernel: wlan0: ieee80211_newstate_cb: SCAN -> INIT arg 3 Jul 9 13:23:25 <0.6> hermann kernel: wlan0: sta_newstate: SCAN -> INIT (3) Jul 9 13:23:25 <0.6> hermann kernel: wlan0: node_reclaim: remove 0xfffffe01148eb000 from station table, refcnt 1 Jul 9 13:23:25 <0.6> hermann kernel: wlan0: ieee80211_alloc_node 0xfffffe01148f3000 in station table Jul 9 13:23:25 <0.6> hermann kernel: wlan0: ieee80211_new_state_locked: INIT -> SCAN (arg 0) (nrunning 0 nscanning 0) Jul 9 13:23:25 <0.6> hermann kernel: wlan0: ieee80211_newstate_cb: INIT -> SCAN arg 0 Jul 9 13:23:25 <0.6> hermann kernel: wlan0: sta_newstate: INIT -> SCAN (0) Jul 9 13:28:03 <0.6> hermann kernel: wlan0: [ETHER_of_ACCESSPOINT] station assoc via MLME Jul 9 13:28:03 <0.6> hermann kernel: wlan0: ieee80211_alloc_node 0xfffffe011497b000 in station table Jul 9 13:28:03 <0.6> hermann kernel: wlan0: node_reclaim: remove 0xfffffe01148f3000 from station table, refcnt 1 Jul 9 13:28:03 <0.6> hermann kernel: wlan0: ieee80211_sta_join1 0xfffffe011497b000 SCAN -> AUTH, FC0_SUBTYPE_DEAUTH Jul 9 13:28:03 <0.6> hermann kernel: wlan0: ieee80211_new_state_locked: SCAN -> AUTH (arg 192) (nrunning 0 nscanning 0) Jul 9 13:28:03 <0.6> hermann kernel: wlan0: ieee80211_newstate_cb: SCAN -> AUTH arg 192 Jul 9 13:28:03 <0.6> hermann kernel: wlan0: sta_newstate: SCAN -> AUTH (192) Jul 9 13:28:03 <0.6> hermann kernel: wlan0: ieee80211_ref_node (ieee80211_send_mgmt:2708) 0xfffffe011497b000 refcnt 4 Jul 9 13:28:03 <0.6> hermann kernel: wlan0: ni 0xfffffe011497b000 vap 0xfffffe01117fd010 mode STA state AUTH m 0xfffff801f39f4e00 status 1 Jul 9 13:28:03 <0.6> hermann kernel: wlan0: ni 0xfffffe011497b000 mode STA state AUTH arg 0x2 status 1 Jul 9 13:28:03 <0.6> hermann kernel: wlan0: vap 0xfffffe01117fd010 mode STA state AUTH flags 0x2000 & 0x80 Jul 9 13:28:03 <0.6> hermann kernel: wlan0: ieee80211_new_state_locked: AUTH -> SCAN (arg 1) (nrunning 0 nscanning 0) Jul 9 13:28:03 <0.6> hermann kernel: wlan0: ieee80211_newstate_cb: AUTH -> SCAN arg 1 Jul 9 13:28:03 <0.6> hermann kernel: wlan0: sta_newstate: AUTH -> SCAN (1) Jul 9 13:28:13 <0.6> hermann kernel: wlan0: [ETHER_of_ACCESSPOINT] station deauth via MLME (reason: 3 (sending STA is leaving/has left IBSS or ESS)) Jul 9 13:28:13 <0.6> hermann kernel: wlan0: ieee80211_new_state_locked: SCAN -> INIT (arg 3) (nrunning 0 nscanning 0) Jul 9 13:28:13 <0.6> hermann kernel: wlan0: ieee80211_newstate_cb: SCAN -> INIT arg 3 Jul 9 13:28:13 <0.6> hermann kernel: wlan0: sta_newstate: SCAN -> INIT (3) Jul 9 13:28:13 <0.6> hermann kernel: wlan0: node_reclaim: remove 0xfffffe011497b000 from station table, refcnt 1 Jul 9 13:28:13 <0.6> hermann kernel: wlan0: ieee80211_alloc_node 0xfffffe01148eb000 in station table Jul 9 13:28:13 <0.6> hermann kernel: wlan0: ieee80211_new_state_locked: INIT -> SCAN (arg 0) (nrunning 0 nscanning 0) Jul 9 13:28:13 <0.6> hermann kernel: wlan0: ieee80211_newstate_cb: INIT -> SCAN arg 0 Jul 9 13:28:13 <0.6> hermann kernel: wlan0: sta_newstate: INIT -> SCAN (0) [...] And logging for wpa_supplicant, one can see this messages: [...] Jul 9 13:28:13 <3.5> hermann wpa_supplicant[401]: BSSID ETHER_OF_ACCESSPOINT ignore list count incremented to 2, ignoring for 10 seconds Jul 9 13:28:13 <3.5> hermann wpa_supplicant[401]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="My-AP-OpenWRT" auth_failures=6 duration=110 reason=CONN_FAILED Jul 9 13:28:13 <3.5> hermann wpa_supplicant[401]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jul 9 13:30:03 <3.5> hermann wpa_supplicant[401]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="My-AP-OpenWRT" Jul 9 13:30:03 <3.5> hermann wpa_supplicant[401]: wlan0: Trying to associate with ETHER_OF_ACCESSPOINT (SSID='My-AP-OpenWRT' freq=2462 MHz) Jul 9 13:30:13 <3.5> hermann wpa_supplicant[401]: wlan0: Authentication with ETHER_OF_ACCESSPOINT timed out. Jul 9 13:30:13 <3.5> hermann wpa_supplicant[401]: wlan0: CTRL-EVENT-DISCONNECTED bssid=ETHER_OF_ACCESSPOINT reason=3 locally_generated=1 Jul 9 13:30:13 <3.5> hermann wpa_supplicant[401]: BSSID ETHER_OF_ACCESSPOINT ignore list count incremented to 2, ignoring for 10 seconds Jul 9 13:30:13 <3.5> hermann wpa_supplicant[401]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="My-AP-OpenWRT" auth_failures=7 duration=144 reason=CONN_FAILED Jul 9 13:30:13 <3.5> hermann wpa_supplicant[401]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all -- O. Hartmann