FS#63480 - WiFi fails to associate after upgrading to NetworkManager 1.20

Attached to Project: Arch Linux
Opened by Eric Blau (eblau) - Friday, 16 August 2019, 18:53 GMT
Last edited by freswa (frederik) - Saturday, 22 February 2020, 20:37 GMT
Task Type Bug Report
Category Packages: Extra
Status Closed
Assigned To No-one
Architecture All
Severity High
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 6
Private No

Details

Description:
After upgrading from NetworkManager 1.18.2-1 to 1.20.0-1, I can no longer connect to my corporate wifi network which uses WPA2, EAP-PEAP, MSCHAPV2 authentication. Authentication succeeds and the connection transitions to associated, but it immediately drops with an error. I use wpa_supplicant 2:2.8-1 and that version has not changed for the last 3+ months. Downgrading to 1.18.2-1 fixes the issue.


Here are logs emitted when a connection is dropped. These logs repeat in a loop, never reaching a stable connection:

Aug 16 12:43:37 eric-macbookpro NetworkManager[1680]: <info> [1565973817.7375] device (wlp3s0): supplicant interface state: disconnected -> scanning
Aug 16 12:43:37 eric-macbookpro NetworkManager[1680]: <info> [1565973817.7376] device (p2p-dev-wlp3s0): supplicant management interface state: disconnected -> scanning
Aug 16 12:43:38 eric-macbookpro wpa_supplicant[1834]: wlp3s0: Trying to associate with SSID 'NTNX_corp'
Aug 16 12:43:38 eric-macbookpro NetworkManager[1680]: <info> [1565973818.0477] device (wlp3s0): supplicant interface state: scanning -> associating
Aug 16 12:43:38 eric-macbookpro NetworkManager[1680]: <info> [1565973818.0480] device (p2p-dev-wlp3s0): supplicant management interface state: scanning -> associating
Aug 16 12:43:39 eric-macbookpro wpa_supplicant[1834]: wlp3s0: Associated with 4c:77:6d:8f:51:ef
Aug 16 12:43:39 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-EAP-STARTED EAP authentication started
Aug 16 12:43:39 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Aug 16 12:43:39 eric-macbookpro NetworkManager[1680]: <info> [1565973819.1691] device (wlp3s0): supplicant interface state: associating -> associated
Aug 16 12:43:39 eric-macbookpro NetworkManager[1680]: <info> [1565973819.1692] device (p2p-dev-wlp3s0): supplicant management interface state: associating -> associated
Aug 16 12:43:39 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-EAP-PROPOSED-METHOD vendor=0 method=25
Aug 16 12:43:39 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-EAP-METHOD EAP vendor 0 method 25 (PEAP) selected
Aug 16 12:43:39 eric-macbookpro wpa_supplicant[1834]: EAP-TLV: TLV Result - Success - EAP-TLV/Phase2 Completed
Aug 16 12:43:39 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-EAP-SUCCESS EAP authentication completed successfully
Aug 16 12:43:39 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to 4c:77:6d:8f:51:ef completed [id=0 id_str=]
Aug 16 12:43:39 eric-macbookpro NetworkManager[1680]: <info> [1565973819.4737] device (wlp3s0): supplicant interface state: associated -> completed
Aug 16 12:43:39 eric-macbookpro NetworkManager[1680]: <info> [1565973819.4738] device (p2p-dev-wlp3s0): supplicant management interface state: associated -> completed
Aug 16 12:43:42 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=4c:77:6d:8f:51:ef reason=15 locally_generated=1
Aug 16 12:43:42 eric-macbookpro wpa_supplicant[1834]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/0
Aug 16 12:43:42 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Aug 16 12:43:42 eric-macbookpro NetworkManager[1680]: <warn> [1565973822.5904] sup-iface[0x5629b97f30f0,wlp3s0]: connection disconnected (reason -15)
Aug 16 12:43:42 eric-macbookpro NetworkManager[1680]: <info> [1565973822.5955] device (wlp3s0): supplicant interface state: completed -> disconnected
Aug 16 12:43:42 eric-macbookpro NetworkManager[1680]: <info> [1565973822.5955] device (p2p-dev-wlp3s0): supplicant management interface state: completed -> disconnected
Aug 16 12:43:42 eric-macbookpro NetworkManager[1680]: <info> [1565973822.6959] device (wlp3s0): supplicant interface state: disconnected -> scanning
Aug 16 12:43:42 eric-macbookpro NetworkManager[1680]: <info> [1565973822.6960] device (p2p-dev-wlp3s0): supplicant management interface state: disconnected -> scanning
Aug 16 12:43:43 eric-macbookpro wpa_supplicant[1834]: wlp3s0: Trying to associate with SSID 'NTNX_corp'
Aug 16 12:43:43 eric-macbookpro NetworkManager[1680]: <info> [1565973823.0663] device (wlp3s0): supplicant interface state: scanning -> associating
Aug 16 12:43:43 eric-macbookpro NetworkManager[1680]: <info> [1565973823.0664] device (p2p-dev-wlp3s0): supplicant management interface state: scanning -> associating
Aug 16 12:43:44 eric-macbookpro wpa_supplicant[1834]: wlp3s0: Associated with 4c:77:6d:8f:51:ef
Aug 16 12:43:44 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-EAP-STARTED EAP authentication started
Aug 16 12:43:44 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Aug 16 12:43:44 eric-macbookpro NetworkManager[1680]: <info> [1565973824.1866] device (wlp3s0): supplicant interface state: associating -> associated
Aug 16 12:43:44 eric-macbookpro NetworkManager[1680]: <info> [1565973824.1867] device (p2p-dev-wlp3s0): supplicant management interface state: associating -> associated
Aug 16 12:43:44 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-EAP-PROPOSED-METHOD vendor=0 method=25
Aug 16 12:43:44 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-EAP-METHOD EAP vendor 0 method 25 (PEAP) selected
Aug 16 12:43:44 eric-macbookpro wpa_supplicant[1834]: EAP-TLV: TLV Result - Success - EAP-TLV/Phase2 Completed
Aug 16 12:43:44 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-EAP-SUCCESS EAP authentication completed successfully
Aug 16 12:43:44 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-CONNECTED - Connection to 4c:77:6d:8f:51:ef completed [id=0 id_str=]
Aug 16 12:43:44 eric-macbookpro NetworkManager[1680]: <info> [1565973824.4899] device (wlp3s0): supplicant interface state: associated -> completed
Aug 16 12:43:44 eric-macbookpro NetworkManager[1680]: <info> [1565973824.4900] device (p2p-dev-wlp3s0): supplicant management interface state: associated -> completed
Aug 16 12:43:47 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=4c:77:6d:8f:51:ef reason=15 locally_generated=1
Aug 16 12:43:47 eric-macbookpro wpa_supplicant[1834]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/0
Aug 16 12:43:47 eric-macbookpro wpa_supplicant[1834]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Aug 16 12:43:47 eric-macbookpro NetworkManager[1680]: <warn> [1565973827.5490] sup-iface[0x5629b97f30f0,wlp3s0]: connection disconnected (reason -15)
Aug 16 12:43:47 eric-macbookpro NetworkManager[1680]: <info> [1565973827.5537] device (wlp3s0): supplicant interface state: completed -> disconnected
Aug 16 12:43:47 eric-macbookpro NetworkManager[1680]: <info> [1565973827.5538] device (p2p-dev-wlp3s0): supplicant management interface state: completed -> disconnected


Steps to reproduce:

Update to NetworkManager 1.20 and attempt to connect to an AP using WPA2 EAP-PEAP MSCHAPV2.
This task depends upon

Closed by  freswa (frederik)
Saturday, 22 February 2020, 20:37 GMT
Reason for closing:  None
Additional comments about closing:  This seems pretty stalled to me. If it's still an issue, please fill a re-open request. Thank you :)
Comment by Eric Blau (eblau) - Saturday, 17 August 2019, 19:24 GMT Comment by Faissal Traore (trafalguard) - Tuesday, 17 September 2019, 08:29 GMT
Same problem here.

Upgraded from NetworkManager 1.18.0-1 to 1.20.0-1 since August and can't connect to wifi, having the same logs.
More details, the connection works for known networks, only after new session, then I always have to reboot or logout every time to be reconnected.

Hope it will be fixed soon.
Comment by Eric Blau (eblau) - Thursday, 03 October 2019, 12:03 GMT
This issue is still occurring with NetworkManager 1.20.4-1. 1.18.2-1 still works fine for me.
Comment by Daniel Shchur (docquantum) - Monday, 14 October 2019, 23:27 GMT
Same here. Looks like https://bugs.archlinux.org/task/63397 is related.
Comment by Daniel Shchur (docquantum) - Wednesday, 06 November 2019, 21:06 GMT
This still exists in 1.20.0-6
Comment by Michael (mod24) - Monday, 30 December 2019, 21:57 GMT
This issue unfortunately still exists in 1.22.2-1

As far as I understood from the bug-reports for Networkmanager the root cause is wpa_supplicant. Still i don't see any bugreports over there (can't find an issue tracking to be honest)

Loading...