VoIP phone is disconnecting somtimes

michael_hoffman — Fri, 13 May 2022 10:57:43 GMT

Hello Community,

there is an ASCOM VoIP phone Mac=00:01:3E:18:89:53 which is disconnecting sometimes from WLAN SSID with simple WPA2-PSK.
Does anyone have an idea why this happens? Have a look at part of show tech:

2022-05-13 08:42:39 info kernel: [wifi]: [CLT_CAPS]Remove scb<00:01:3e:18:89:53> in tailq
2022-05-13 08:42:38 info ah_auth: aaa: update RT sta: MAC=0001:3e18:8953, IPv6 address number =0, username= on wifi1.4
2022-05-13 08:42:38 info ah_auth: aaa: Station 0001:3e18:8953 ip 0.0.0.0 username n/a hostname I62-18-89-53 OS VoIP Device
2022-05-13 08:42:38 notice ah_auth: The IP address of station 0001:3e18:8953 changed from 0.0.0.0 to 10.189.209.140.
2022-05-13 08:42:38 info ah_auth: aaa: detect station(0001:3e18:8953) os(VoIP Device) via DHCP fingerprint
2022-05-13 08:42:38 info ah_auth: aaa: detect station(0001:3e18:8953) os(VoIP Device) via DHCP fingerprint
2022-05-13 08:42:37 info ah_auth: aaa: update RT sta: MAC=0001:3e18:8953, IPv6 address number =0, username= on wifi1.4
2022-05-13 08:42:37 info ah_auth: aaa: Station 0001:3e18:8953 ip 0.0.0.0 username n/a hostname n/a OS VoIP Device, flag = DHCP
2022-05-13 08:42:37 notice ah_auth: Station 0001:3e18:8953 was authenticated on 3485:844e:f5e7 through SSID GfI-VoIP vid 4.
2022-05-13 08:42:37 info ah_auth: aaa: detect station(0001:3e18:8953) os(VoIP Device) via DHCP fingerprint
2022-05-13 08:42:36 info kernel: [qos]: add qos user 0001:3e18:8953 idx 137 uppid 6
2022-05-13 08:42:36 info ah_auth: aaa: detect station(0001:3e18:8953) os(VoIP Device) via DHCP fingerprint
2022-05-13 08:42:35 info kernel: [mesh]: set proxy : 0001:3e18:8953 3485:844e:f5c0 wifi1.4 flag 0x1c03
2022-05-13 08:42:35 info amrp2: misc: set VLAN ID 4 for station 0001:3e18:8953
2022-05-13 08:42:35 info amrp2: l2routing: set proxy route: 0001:3e18:8953 -> 3485:844e:f5c0 ifp wifi1.4 upid 6 flag 0x1c03 monitor(0/0) pkt/sec ok
2022-05-13 08:42:35 info ah_auth: aaa: add new RT sta: MAC=0001:3e18:8953, IP=10.189.209.140, hostname=I62-18-89-53, username= on wifi1.4
2022-05-13 08:42:35 info ah_auth: aaa: [Auth]STA(0001:3e18:8953) login to SSID(wifi1.4) by user_name=
2022-05-13 08:42:35 info ah_auth: aaa: [Auth]: receive driver notification[0x8, WLC_E_ASSOC_IND] for Sta[0001:3e18:8953] at Hapd[3485:844e:f5e7, wifi1.4]
2022-05-13 08:42:35 info ah_bot: wifi: wifi1.4 alarm sta_join:0001:3e18:8953 sta
2022-05-13 08:42:35 warn ah_dcd: wifi: [CLT_CAPS]Invalid frame 1: Unknown IE(70)
2022-05-13 08:42:35 info kernel: [wifi]: [CLT_CAPS]wifi1.4: Add scb<00:01:3e:18:89:53>, waiting its probe req frame 2
2022-05-13 08:42:22 info ah_auth: aaa: [Auth]: receive driver notification[0x5, WLC_E_DEAUTH] for Sta[0001:3e18:8953] at Hapd[3485:844e:f5e4, wifi1.1]
2022-05-13 08:42:22 info ah_auth: aaa: sta 0001:3e18:8953 is disassociated from 3485:844e:f5e7(wifi1.4) in driver
2022-05-13 08:42:22 info ah_auth: aaa: [Auth]: receive driver notification[0x5, WLC_E_DEAUTH] for Sta[0001:3e18:8953] at Hapd[3485:844e:f5e7, wifi1.4]
2022-05-13 08:42:22 info kernel: [wifi]: wl1: wlc_txbf_delete_link_serve_ge128 STA 00:01:3e:18:89:53 doesn't have TxBF cap 0
2022-05-13 08:42:22 info ah_bot: wifi: wifi1.4 alarm sta_leave:0001:3e18:8953 info-0105 sta
2022-05-13 08:42:22 info ah_auth: aaa: sta 0001:3e18:8953 is disassociated from 3485:844e:f5e7(wifi1.4) in driver
2022-05-13 08:42:22 info ah_auth: aaa: [Auth]: receive driver notification[0x5, WLC_E_DEAUTH] for Sta[0001:3e18:8953] at Hapd[3485:844e:f5e7, wifi1.4]
2022-05-13 08:42:22 info ah_bot: wifi: wifi1.4 alarm sta_leave:0001:3e18:8953 info-0105 sta
2022-05-13 08:42:22 info kernel: [wifi]: wl1: wlc_txbf_delete_link_serve_ge128 STA 00:01:3e:18:89:53 doesn't have TxBF cap 0
2022-05-13 08:42:22 info kernel: [wifi]: wl1: wlc_txbf_delete_link_serve_ge128 STA 00:01:3e:18:89:53 doesn't have TxBF cap 0
2022-05-13 08:42:21 info kernel: [wifi]: [CLT_CAPS]Remove scb<00:01:3e:18:89:53> in tailq
2022-05-13 08:42:21 info ah_dcd: wifi: [CLT_CAPS]Send 3485:844e:f5e7[0001:3e18:8953] <PROBE REQ> trap successfully
2022-05-13 08:42:20 info ah_auth: aaa: Notify driver to disassoc 0001:3e18:8953 from wifi1.4
2022-05-13 08:42:20 info ah_auth: aaa: Try to disassoc 0001:3e18:8953 from 3485:844e:f5e7(wifi1.4) for ssid GfI-VoIP because of 4way handshake failure
2022-05-13 08:42:20 info ah_auth: aaa: STA(0001:3e18:8953) at 3485:844e:f5e7(wifi1.4, GfI-VoIP) EAPOL-Key timeout, wpa_ptk_state=7[WPA_PTK_PTKSTART(1/4)]
2022-05-13 08:42:16 info ah_auth: aaa: STA(0001:3e18:8953) at 3485:844e:f5e7(wifi1.4, GfI-VoIP) EAPOL-Key timeout, wpa_ptk_state=7[WPA_PTK_PTKSTART(1/4)]
2022-05-13 08:42:08 info ah_auth: aaa: [Auth]: receive driver notification[0x8, WLC_E_ASSOC_IND] for Sta[0001:3e18:8953] at Hapd[3485:844e:f5e7, wifi1.4]
2022-05-13 08:42:08 info ah_bot: wifi: wifi1.4 alarm sta_join:0001:3e18:8953 sta
2022-05-13 08:42:08 info kernel: [wifi]: [CLT_CAPS]wifi1.4: Add scb<00:01:3e:18:89:53>, waiting its probe req frame 2
2022-05-13 08:42:08 warn ah_dcd: wifi: [CLT_CAPS]Invalid frame 1: Unknown IE(70)
2022-05-13 08:42:07 info kernel: [wifi]: awe_wlc_scbfind for sta 0001:3e18:8953 failed
2022-05-13 08:42:07 info ah_auth: aaa: sta 0001:3e18:8953 is disassociated from 3485:844e:f5e7(wifi1.4) in driver
2022-05-13 08:42:07 info ah_auth: aaa: [Auth]: receive driver notification[0xc, WLC_E_DISASSOC_IND] for Sta[0001:3e18:8953] at Hapd[3485:844e:f5e7, wifi1.4]
2022-05-13 08:42:07 info ah_auth: aaa: station_new_status_trap_force: ah_dcd_get_radio_one_sta(0001:3e18:8953) failed
2022-05-13 08:42:07 info ah_auth: aaa: ah_rt_sta_del: 0001:3e18:8953
2022-05-13 08:42:07 info ah_auth: aaa: sta 0001:3e18:8953 is disassociated from 3485:844e:f5e7(wifi1.4) in driver
2022-05-13 08:42:07 info ah_auth: aaa: [Auth]: receive driver notification[0x6, WLC_E_DEAUTH_IND] for Sta[0001:3e18:8953] at Hapd[3485:844e:f5e7, wifi1.4]
2022-05-13 08:42:07 info kernel: [wifi]: wl1: wlc_txbf_delete_link_serve failed for 00:01:3e:18:89:53
2022-05-13 08:42:07 info kernel: [wifi]: wl1: wlc_txbf_delete_link_serve_ge128 STA 00:01:3e:18:89:53 doesn't have TxBF cap 0
2022-05-13 08:42:07 info kernel: [wifi]: wl1: random key value: 96C9A3A3316228214C66028541DFAE61F56237B06F220834981D58B6B0248A1A

Best regards
Michael

Re: VoIP phone is disconnecting somtimes

ZakariahP — Tue, 24 May 2022 14:53:53 GMT

Hi Michael,

Looks like Transmit Beamforming is enabled and seem to be the reason clients are disassociating, I would try disabling this. This is found in any 802.11ac or 802.11ax mode radio profiles under "Advanced Settings">"Radio Settings".

The article below also has further info regarding Transmit Beamforming if you'd like to know more about this setting.

https://extremeportal.force.com/ExtrArticleDetail?an=000099149

Zak Protz
Associate Technical Support Engineer / Extreme Networks
zaprotz@extremenetworks.com

ExtremeNetworks.com
Advance with Us™

Re: VoIP phone is disconnecting somtimes

michael_hoffman — Mon, 30 May 2022 13:47:06 GMT

Hi Zak,

thx for your information, but ASCOM VoIP Phones are connecting via Radio 802.11na 5GHz.
So disabling "transmit beamforming" shouldn't make a difference, am I right?
Do you have another advice?

Best regards
Michael

Re: VoIP phone is disconnecting somtimes

ZakariahP — Tue, 31 May 2022 15:05:27 GMT

Michael,

Based on the logs, I do not see any other issues other than Tx Beamforming, so I unfortunately do not have any other ideas as to why this is occurring.

Transmit Beamforming does have the ability to improve the signal for legacy clients, this is called "implicit beamforming". So, even though the VoIP phones are 802.11na, our APs will still try to use "Implicit Beamforming" to improve the signal for those devices when Transmit Beamforming is enabled.

Zak Protz
Associate Technical Support Engineer / Extreme Networks
zaprotz@extremenetworks.com

ExtremeNetworks.com
Advance with Us™

topic Re: VoIP phone is disconnecting somtimes in ExtremeCloud IQ

VoIP phone is disconnecting somtimes

Re: VoIP phone is disconnecting somtimes

Re: VoIP phone is disconnecting somtimes

Re: VoIP phone is disconnecting somtimes