Bug 1369161 - NetworkManager sees not all network
Summary: NetworkManager sees not all network
Keywords:
Status: CLOSED EOL
Alias: None
Product: Fedora
Classification: Fedora
Component: NetworkManager
Version: 24
Hardware: Unspecified
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: Lubomir Rintel
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2016-08-22 14:43 UTC by Pavel Alexeev
Modified: 2017-08-08 16:39 UTC (History)
4 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed: 2017-08-08 16:39:59 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)

Description Pavel Alexeev 2016-08-22 14:43:39 UTC
Description of problem:

# iwlist wlo1 scan | grep ESSID
                    ESSID:"307"
                    ESSID:"dr.House"
                    ESSID:"Keenetic-2261"
                    ESSID:"TP-LINK_7F58"


# LANG=en_US.utf8 nmcli dev wifi list
*  SSID           MODE   CHAN  RATE       SIGNAL  BARS  SECURITY  
   307            Infra  1     54 Mbit/s  77      ▂▄▆_  WPA1 WPA2 
   TP-LINK_7F58   Infra  1     54 Mbit/s  54      ▂▄__  WPA1 WPA2 
   Keenetic-2261  Infra  11    54 Mbit/s  40      ▂▄__  WPA1 WPA2 
   Home           Infra  6     54 Mbit/s  27      ▂___  WPA1 WPA2

As you see there is not present WiFi network with name "dr.House".

Log after enabling WiFi:
# nmcli g log level debug domains PLATFORM,RFKILL,ETHER,WIFI,BT,MB,DHCP4,DHCP6,PPP,IP4,IP6,AUTOIP4,DNS,VPN,SHARING,SUPPLICANT,AGENTS,SETTINGS,SUSPEND,CORE,DEVICE,OLPC,INFINIBAND,FIREWALL,ADSL,BOND,VLAN,BRIDGE,TEAM,CONCHECK,DCB,DISPATCH,AUDIT,SYSTEMD
# nmcli r wifi off
# nmcli r wifi on

In log:

Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5282] sup-iface[0x5648fbe16f90,wlo1]: interface added to supplicant
Aug 22 17:38:17 localhost NetworkManager[896]: <info>  [1471876697.5325] sup-iface[0x5648fbe16f90,wlo1]: supports 4 scan SSIDs
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5341] sup-iface[0x5648fbe16f90,wlo1]: supplicant supports network credentials requests
Aug 22 17:38:17 localhost NetworkManager[896]: <info>  [1471876697.5349] device (wlo1): supplicant interface state: starting -> ready
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5349] device[0x5648fbdd7460] (wlo1): remove_pending_action (0): 'waiting for supplicant'
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5351] device[0x5648fbdd7460] (wlo1): add_pending_action (1): 'queued state change to disconnected'
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5352] device[0x5648fbdd7460] (wlo1): queued state change to disconnected due to supplicant-available (id 35595)
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5352] device[0x5648fbdd7460] (wlo1): is available, will transition to disconnected
Aug 22 17:38:17 localhost kernel: IPv6: ADDRCONF(NETDEV_UP): wlo1: link is not ready
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5353] device[0x5648fbdd7460] (wlo1): running queued state change to disconnected (id 35595)
Aug 22 17:38:17 localhost NetworkManager[896]: <info>  [1471876697.5353] device (wlo1): state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5355] device[0x5648fbdd7460] (wlo1): will enable userland IPv6LL
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5355] platform-linux: link: change 5: user-ipv6ll: set IPv6 address generation mode to none
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5357] platform-linux: do-request-link: 5
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5359] platform-linux: do-change-link[5]: success changing link: success
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5359] platform-linux: sysctl: reading '/proc/sys/net/ipv6/conf/wlo1/disable_ipv6': '0'
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5360] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlo1/disable_ipv6' to '1' (current value is '0')
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5361] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlo1/disable_ipv6' to '0' (current value is '1')
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5363] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlo1/accept_ra_defrtr' to '0' (current value is identical)
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5364] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlo1/accept_ra_pinfo' to '0' (current value is identical)
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5365] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlo1/accept_ra_rtr_pref' to '0' (current value is identical)
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5366] platform-linux: sysctl: setting '/proc/sys/net/ipv6/conf/wlo1/use_tempaddr' to '0' (current value is identical)
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5373] device[0x5648fbdd7460] (wlo1): add_pending_action (2): 'scan'
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5374] device[0x5648fbdd7460] (wlo1): add_pending_action (3): 'autoactivate'
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5375] device[0x5648fbdd7460] (wlo1): remove_pending_action (2): 'queued state change to disconnected'
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5384] device[0x5648fbdd7460] (wlo1): remove_pending_action (1): 'autoactivate'
Aug 22 17:38:17 localhost NetworkManager[896]: <debug> [1471876697.5492] device[0x5648fbdd7460] (wlo1): remove_pending_action (0): 'scan'
Aug 22 17:38:18 localhost NetworkManager[896]: <debug> [1471876698.4498] platform: signal: link changed: 5: wlo1 <UP;broadcast,multicast,up> mtu 0 arp 1 wifi? init addr 44:1C:A8:0F:B8:E7 driver rtl8188ee
Aug 22 17:38:18 localhost wpa_supplicant[16626]: dbus: fill_dict_with_properties dbus_interface=fi.w1.wpa_supplicant1.BSS dbus_property=RSN getter failed
Aug 22 17:38:18 localhost NetworkManager[896]: <debug> [1471876698.4499] device[0x5648fbdd7460] (wlo1): queued link change for ifindex 5
Aug 22 17:38:18 localhost wpa_supplicant[16626]: dbus: wpa_dbus_get_object_properties: failed to get object properties: (org.freedesktop.DBus.Error.Failed) failed to parse RSN IE
Aug 22 17:38:18 localhost wpa_supplicant[16626]: dbus: Failed to construct signal
Aug 22 17:38:18 localhost NetworkManager[896]: <info>  [1471876698.4598] device (wlo1): supplicant interface state: ready -> inactive
Aug 22 17:38:18 localhost NetworkManager[896]: <debug> [1471876698.4601] device[0x5648fbdd7460] (wlo1): add_pending_action (1): 'scan'
Aug 22 17:38:18 localhost NetworkManager[896]: <debug> [1471876698.4621] device[0x5648fbdd7460] (wlo1): remove_pending_action (0): 'scan'
Aug 22 17:38:18 localhost NetworkManager[896]: <debug> [1471876698.4643] device[0x5648fbdd7460] (wlo1): add_pending_action (1): 'autoactivate'
Aug 22 17:38:18 localhost NetworkManager[896]: <debug> [1471876698.4704] device[0x5648fbdd7460] (wlo1): remove_pending_action (0): 'autoactivate'
Aug 22 17:38:18 localhost wpa_supplicant[16626]: dbus: fill_dict_with_properties dbus_interface=fi.w1.wpa_supplicant1.BSS dbus_property=RSN getter failed
Aug 22 17:38:19 localhost NetworkManager[896]: <debug> [1471876699.3927] device[0x5648fbdd7460] (wlo1): add_pending_action (1): 'autoactivate'
Aug 22 17:38:19 localhost NetworkManager[896]: <debug> [1471876699.3933] device[0x5648fbdd7460] (wlo1): remove_pending_action (0): 'autoactivate'
Aug 22 17:38:22 localhost audit: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 22 17:38:41 localhost NetworkManager[896]: <debug> [1471876721.7062] device[0x5648fbdd7460] (wlo1): add_pending_action (1): 'scan'
Aug 22 17:38:41 localhost NetworkManager[896]: <debug> [1471876721.7075] device[0x5648fbdd7460] (wlo1): remove_pending_action (0): 'scan'


I think part:
Aug 22 17:38:18 localhost wpa_supplicant[16626]: dbus: wpa_dbus_get_object_properties: failed to get object properties: (org.freedesktop.DBus.Error.Failed) failed to parse RSN IE
Aug 22 17:38:18 localhost wpa_supplicant[16626]: dbus: Failed to construct signal
is relevant (according to https://mail.gnome.org/archives/networkmanager-list/2016-June/msg00071.html).

Target net according to iwlist scan:
          Cell 02 - Address: 00:11:D8:97:DF:A6
                    Channel:2
                    Frequency:2.417 GHz (Channel 2)
                    Quality=70/70  Signal level=-34 dBm
                    Encryption key:on
                    ESSID:"dr.House"
                    Bit Rates:1 Mb/s; 2 Mb/s; 5.5 Mb/s; 11 Mb/s; 18 Mb/s
                              24 Mb/s; 36 Mb/s; 54 Mb/s
                    Bit Rates:6 Mb/s; 9 Mb/s; 12 Mb/s; 48 Mb/s
                    Mode:Master
                    Extra:tsf=0000001166076252
                    Extra: Last beacon: 32ms ago
                    IE: Unknown: 000864722E486F757365
                    IE: Unknown: 010882848B96A4B0C8EC
                    IE: Unknown: 030102
                    IE: Unknown: 0706444520010D14
                    IE: Unknown: 200100
                    IE: Unknown: 23021000
                    IE: Unknown: 2A0100
                    IE: Unknown: 2F0100
                    IE: IEEE 802.11i/WPA2 Version 1
                        Group Cipher : WEP-40
                        Pairwise Ciphers (1) : TKIP
                        Authentication Suites (1) : PSK
                    IE: Unknown: 32048C9298E0
                    IE: Unknown: DD09001018020013000000

Version-Release number of selected component (if applicable):
# rpm -qa wpa_supplicant 'NetworkManager*'
NetworkManager-openvpn-1.2.4-2.fc24.x86_64
NetworkManager-libnm-1.2.4-2.fc24.x86_64
wpa_supplicant-2.5-5.fc24.x86_64
NetworkManager-wifi-1.2.4-2.fc24.x86_64
NetworkManager-pptp-1.2.2-1.fc24.x86_64
NetworkManager-libreswan-1.2.4-1.fc24.x86_64
NetworkManager-bluetooth-1.2.4-2.fc24.x86_64
NetworkManager-team-1.2.4-2.fc24.x86_64
NetworkManager-glib-1.2.4-2.fc24.x86_64
NetworkManager-wwan-1.2.4-2.fc24.x86_64
NetworkManager-1.2.4-2.fc24.x86_64
NetworkManager-adsl-1.2.4-2.fc24.x86_64
NetworkManager-vpnc-1.2.2-1.fc24.x86_64
NetworkManager-openconnect-1.2.2-1.fc24.x86_64
NetworkManager-l2tp-1.2.2-1.fc24.x86_64
NetworkManager-config-connectivity-fedora-1.2.4-2.fc24.x86_64

How reproducible:
Always

Comment 1 Blueowl 2016-12-06 16:18:43 UTC
As discussed in the thread you mentioned, the problem is in wpa_supplicant. Dan mentioned the commit [1] there that removed the legacy support for WEP cipher suites in group cipher announced in RSN IE. Even though those setup may not be common, the Wi-Fi standard allows it [2] and AirPort Extreme router uses that in "WEP (Transitional Security Network)" option [3].

> Aug 22 17:38:18 localhost wpa_supplicant[16626]: dbus: wpa_dbus_get_object_properties: failed to get object properties: (org.freedesktop.DBus.Error.Failed) failed to parse RSN IE

this is from here https://w1.fi/cgit/hostap/tree/wpa_supplicant/dbus/dbus_new_handlers.c#n4004

[1] https://w1.fi/cgit/hostap/commit/?id=ce8963fc9f197771cd51ba2834fbdf711189641a
[2] https://www.cwnp.com/forums/posts?postNum=298734
[3] http://apple.stackexchange.com/questions/35030/what-is-the-difference-between-the-wpa2-personal-wpa-and-wpa2-wifi-security-pas

Comment 2 Fedora End Of Life 2017-07-25 22:33:59 UTC
This message is a reminder that Fedora 24 is nearing its end of life.
Approximately 2 (two) weeks from now Fedora will stop maintaining
and issuing updates for Fedora 24. It is Fedora's policy to close all
bug reports from releases that are no longer maintained. At that time
this bug will be closed as EOL if it remains open with a Fedora  'version'
of '24'.

Package Maintainer: If you wish for this bug to remain open because you
plan to fix it in a currently maintained version, simply change the 'version'
to a later Fedora version.

Thank you for reporting this issue and we are sorry that we were not
able to fix it before Fedora 24 is end of life. If you would still like
to see this bug fixed and are able to reproduce it against a later version
of Fedora, you are encouraged  change the 'version' to a later Fedora
version prior this bug is closed as described in the policy above.

Although we aim to fix as many bugs as possible during every release's
lifetime, sometimes those efforts are overtaken by events. Often a
more recent Fedora release includes newer upstream software that fixes
bugs or makes them obsolete.

Comment 3 Fedora End Of Life 2017-08-08 16:39:59 UTC
Fedora 24 changed to end-of-life (EOL) status on 2017-08-08. Fedora 24 is
no longer maintained, which means that it will not receive any further
security or bug fix updates. As a result we are closing this bug.

If you can reproduce this bug against a currently maintained version of
Fedora please feel free to reopen this bug against that version. If you
are unable to reopen this bug, please file a new report against the
current release. If you experience problems, please add a comment to this
bug.

Thank you for reporting this bug and we are sorry it could not be fixed.


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