Bug 1956992 - Wifi association took too long, failing activation after upgrade from NM 1.26.2-2 to 1.26.8-1
Summary: Wifi association took too long, failing activation after upgrade from NM 1.26...
Keywords:
Status: NEW
Alias: None
Product: Fedora
Classification: Fedora
Component: NetworkManager
Version: 33
Hardware: x86_64
OS: Linux
unspecified
unspecified
Target Milestone: ---
Assignee: Lubomir Rintel
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2021-05-04 19:53 UTC by Erwin
Modified: 2021-05-12 20:29 UTC (History)
11 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed:
Type: Bug


Attachments (Terms of Use)

Description Erwin 2021-05-04 19:53:07 UTC
Description of problem:

F33 was installed from Fedora-WS-Live-33-1-2 on Dell Latitude E6410 with Intel Centrino Advanced-N 6200 Wifi adapter.

After installation Wifi worked fine.

rpm -qa | grep NetworkManager-wifi
NetworkManager-wifi-1.26.2-2.fc33.x86_64

After first upgrade Wifi stopped working. It seems to be scanning all the time for wifi networks but never connects.

systemctl status wpa_supplicant.service reports

CTRL-EVENT-SCAN-FAILED ret=-22 retry=1

Downgrading NetworkManager-wifi solved the problem

sudo dnf downgrade NetworkManager-wifi-1.26.8-1.fc33.x86_64

Tried to install F34, but in this case wifi never worked.


systemctl status NetworkManager.service

    ● NetworkManager.service - Network Manager
         Loaded: loaded (/usr/lib/systemd/system/NetworkManager.service; enabled; vendor preset: enabled)
         Active: active (running) since Wed 2021-05-05 04:01:57 EDT; 1 day 23h ago
           Docs: man:NetworkManager(8)
       Main PID: 813 (NetworkManager)
          Tasks: 3 (limit: 6833)
         Memory: 10.6M
            CPU: 1min 44.054s
         CGroup: /system.slice/NetworkManager.service
                 └─813 /usr/sbin/NetworkManager --no-daemon

    Mai 07 03:42:30 fedora NetworkManager[813]: <info>  [1620373350.3754] Config: added 'auth_alg' value 'OPEN'
    Mai 07 03:42:30 fedora NetworkManager[813]: <info>  [1620373350.3754] Config: added 'psk' value '<hidden>'
    Mai 07 03:42:55 fedora NetworkManager[813]: <warn>  [1620373375.7243] device (wlp2s0): Activation: (wifi) association took too long, failing activation
    Mai 07 03:42:55 fedora NetworkManager[813]: <info>  [1620373375.7244] device (wlp2s0): state change: config -> failed (reason 'ssid-not-found', sys-iface-state: 'managed>
    Mai 07 03:42:55 fedora NetworkManager[813]: <info>  [1620373375.7450] device (wlp2s0): set-hw-addr: set MAC address to 02:E2:FE:7E:22:01 (scanning)
    Mai 07 03:42:56 fedora NetworkManager[813]: <warn>  [1620373376.0584] device (wlp2s0): Activation: failed for connection 'erwin-public'
    Mai 07 03:42:56 fedora NetworkManager[813]: <info>  [1620373376.0586] device (wlp2s0): supplicant interface state: inactive -> disconnected
    Mai 07 03:42:56 fedora NetworkManager[813]: <info>  [1620373376.0587] device (wlp2s0): supplicant interface state: disconnected -> interface_disabled
    Mai 07 03:42:56 fedora NetworkManager[813]: <info>  [1620373376.0592] device (wlp2s0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
    Mai 07 03:42:56 fedora NetworkManager[813]: <info>  [1620373376.0714] device (wlp2s0): supplicant interface state: interface_disabled -> inactive

Comment 1 Erwin 2021-05-05 08:41:48 UTC
https://ask.fedoraproject.org/t/f33-wifi-stopped-working-after-networkmanager-upgrade/13920/9

I just reverted downgrade of NM and System is back in failure state.

I could do some diagnostics if someone advises me.

Comment 2 Beniamino Galvani 2021-05-07 15:39:38 UTC
The only changes related to Wi-Fi between 1.26.2 and 1.26.8 are:

  $ git log --oneline --grep=wifi 1.26.2...1.26.8
  8df18958ed05 libnm: fix tracking object state in NMClient cache
  3cff6f294f84 wifi: fix caching ap-flags for supplicant-interface
  5454681ab76e wifi: auto-activate devices as soon as the first scan finishes
  2bec392e563b wifi: fix evaluating the scanning state for wpa-supplicant
  def7533c64d6 core: recognize "wifi.scan-generate-mac-address-mask" as valid "NetworkManager.conf" option
  9b8a60a9a315 shared: fix NM_CAST_STRV_CC() for "char **const" pointers
  09c4fa5a6cae iwd: Fix a use after free
  42d28ae2e8dd device/wifi: don't reset the SSID of a NMWifiAP to unknown
  b5310115d1b9 wifi: re-add code for tracking a peers groups
  bd5da809caee device/wifi: fix leak of NMSupplicantPeerInfo.peer_path in NMSupplicantInterface
  ed9f63724a16 supplicant: fix crash in _scan_notify_allowed() when supplicant disconnects

If you know how to rebuild packages, it would be worth to try
reverting 5454681ab76e and 2bec392e563b (or even better, a git
bisect).

Otherwise, please provide debug logs of NM and wpa_supplicant:

  - set "level=TRACE" in the [logging] section of /etc/NetworkManager.conf
  - set "RateLimitIntervalSec=0" in /etc/systemd/journald.conf to disable journal rate limiting
  - add "-ddd" to OTHERARGS in /etc/sysconfig/wpa_supplicant
  - reboot
  - reproduce the issue
  - attach the output of "journalctl -u wpa_supplicant -u NetworkManager -b"

Note that the journal might contain sensitive information like MAC
addresses, IP addresses, SSIDs. Redact them before attaching the
file. If you prefer to not attach it here, you can send the file to me
at bgalvani@redhat.com. Thanks.

Comment 3 q8311692 2021-05-12 20:29:52 UTC
This bug also affects Fedora 34:

NetworkManager[943]: <warn>  [xxx.xxx] device (wlpXsX): Activation: (wifi) association took too long, failing activation


This bug makes WiFi connections on Intel Centrino Advanced-N 6200 devices unusable. 

This adapter type is quite widespread and widely used, for example, in the Thinkpad T and P series. 

Please set an appropriate priority and severity.


Device: "Intel Centrino Advanced-N 6200"

$ NetworkManager --version
1.30.4-1.fc34

Linux 5.11.18-300.fc34.x86_64


Note You need to log in before you can comment on or make changes to this bug.