Bug 1199616 - [1425666129.427383] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface
Summary: [1425666129.427383] [supplicant-manager/nm-supplicant-interface.c:857] interf...
Keywords:
Status: CLOSED NOTABUG
Alias: None
Product: Fedora
Classification: Fedora
Component: NetworkManager
Version: rawhide
Hardware: Unspecified
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: Dan Williams
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2015-03-06 18:31 UTC by Adam Miller
Modified: 2015-03-06 22:25 UTC (History)
3 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2015-03-06 22:25:03 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)

Description Adam Miller 2015-03-06 18:31:47 UTC
Description of problem:
I updated to the latest in rawhide and found an issue with NetworkManager unable to "grab" my wifi interface.

Version-Release number of selected component (if applicable):
NetworkManager-1.0.1-1.git20150305.2a72527c.fc23.x86_64

How reproducible:
Always

Steps to Reproduce:
1. Run rawhide
2. dnf update
3. Reboot

Actual results:
Wifi interface unable to be brought "up" in NetworkManger

Expected results:


Additional info:
nmcli lists the device as "unavailable":
$ nmcli device show wlp3s0 
GENERAL.DEVICE:                         wlp3s0
GENERAL.TYPE:                           wifi
GENERAL.HWADDR:                         28:B2:BD:DF:34:CD
GENERAL.MTU:                            1500
GENERAL.STATE:                          20 (unavailable)
GENERAL.CONNECTION:                     --
GENERAL.CON-PATH:                       --


There are a lot of these messages in my logs:
Mar 06 12:22:08 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:08 pseudogen NetworkManager[15674]: <error> [1425666128.983385] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:08 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.013439] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.037336] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.067309] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.100383] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.124310] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.154197] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.187318] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.217218] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.241326] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.280437] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.307269] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.334415] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.376389] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <error> [1425666129.427383] [supplicant-manager/nm-supplicant-interface.c:857] interface_add_cb(): (wlp3s0): error adding interface: wpa_supplicant couldn't grab this interface.
Mar 06 12:22:09 pseudogen NetworkManager[15674]: <info>  (wlp3s0): supplicant interface state: starting -> down

However I am able to enable the interface with 'iw' and scan just fine:
$ ip link set wlp3s0 up

$ iw dev wlp3s0 scan
BSS f8:e4:fb:4e:07:b7(on wlp3s0)
        TSF: 268622442084 usec (3d, 02:37:02)
        freq: 2462
        beacon interval: 100 TUs
        capability: ESS Privacy ShortPreamble ShortSlotTime (0x0431)
        signal: -61.00 dBm
        last seen: 1 ms ago
        Information elements from Probe Response frame:
        SSID: Tomato
        Supported rates: 1.0* 2.0* 5.5* 11.0* 6.0 9.0 12.0 18.0 
        DS Parameter set: channel 11
        Power constraint: 0 dB
        RSN:     * Version: 1
                 * Group cipher: TKIP
                 * Pairwise ciphers: CCMP TKIP
                 * Authentication suites: PSK
                 * Capabilities: 1-PTKSA-RC 1-GTKSA-RC (0x0000)
        WPA:     * Version: 1
                 * Group cipher: TKIP
                 * Pairwise ciphers: CCMP TKIP
                 * Authentication suites: PSK
        ERP: <no flags>
        Extended supported rates: 24.0 36.0 48.0 54.0 
        WMM:     * Parameter version 1
                 * BE: CW 15-1023, AIFSN 3
                 * BK: CW 15-1023, AIFSN 7
                 * VI: CW 7-15, AIFSN 2, TXOP 3008 usec
                 * VO: CW 3-7, AIFSN 2, TXOP 1504 usec
        HT capabilities:
                Capabilities: 0x138c
                        HT20
                        SM Power Save disabled
                        TX STBC
                        RX STBC 3-streams
                        Max AMSDU length: 3839 bytes
                        DSSS/CCK HT40
                Maximum RX AMPDU length 65535 bytes (exponent: 0x003)
                Minimum RX AMPDU time spacing: 8 usec (0x06)
                HT RX MCS rate indexes supported: 0-15
                HT TX MCS rate indexes are undefined
        HT operation:
                 * primary channel: 11
                 * secondary channel offset: no secondary
                 * STA channel width: 20 MHz
                 * RIFS: 1
                 * HT protection: no
                 * non-GF present: 0
                 * OBSS non-GF present: 0
                 * dual beacon: 0
                 * dual CTS protection: 0
                 * STBC beacon: 0
                 * L-SIG TXOP Prot: 0
                 * PCO active: 0
                 * PCO phase: 0
        Country: US     Environment: Indoor/Outdoor
                Channels [1 - 11] @ 27 dBm

Comment 1 Jirka Klimes 2015-03-06 20:39:13 UTC
Does anything show up in 'dmesg'? What are your kernel and wpa_supplicant versions.

# journalctl -b -u wpa_supplicant

Comment 2 Adam Miller 2015-03-06 22:10:22 UTC
There doesn't appear to be an actual rawhide build of this since the F22 branch, here's my current version:
wpa_supplicant-2.3-2.fc22.x86_64

Sadly nothing in dmesg that appears relevant, but /var/log/wpa_supplicant is just scrolling these messages like crazy so it's highly possible this isn't a NetworkManager issue:

Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.
SSL: Failed to initialize TLS context.
Failed to initialize EAPOL state machines.

Comment 3 Adam Miller 2015-03-06 22:12:33 UTC
Errr.... /var/log/wpa_supplicant.log *

Comment 4 Adam Miller 2015-03-06 22:25:03 UTC
Nevermind, this turned out to be an issue with the crypto-policies package, the crypto-policies-20150305-1.git098a8a6.fc23.noarch broke everything but has been updated to crypto-policies-20150305-1.git098a8a6.fc23.noarch and after restarting wpa_supplicant.service everything is happy again.

Closing: NOTABUG


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