1325631 – 1.0.12-1 failed to manage a r8188eu USB-WIFI adapter, but 1.0.10-3 can

Bug 1325631 - 1.0.12-1 failed to manage a r8188eu USB-WIFI adapter, but 1.0.10-3 can

Summary: 1.0.12-1 failed to manage a r8188eu USB-WIFI adapter, but 1.0.10-3 can

Keywords:
Status:	CLOSED ERRATA
Alias:	None
Product:	Fedora
Classification:	Fedora
Component:	NetworkManager
Sub Component:
Version:	23
Hardware:	Unspecified
OS:	Unspecified
Priority:	unspecified
Severity:	unspecified
Target Milestone:	---
Assignee:	Lubomir Rintel
QA Contact:	Fedora Extras Quality Assurance
Docs Contact:
URL:
Whiteboard:
Duplicates (2):	1324009 1324975 (view as bug list)
Depends On:
Blocks:
TreeView+	depends on / blocked

Reported:	2016-04-10 06:53 UTC by Robin Lee
Modified:	2016-05-02 08:37 UTC (History)
CC List:	9 users (show)
Fixed In Version:	NetworkManager-1.0.12-2.fc23
Clone Of:
Environment:
Last Closed:	2016-04-18 03:19:44 UTC
Type:	Bug
Embargoed:
Dependent Products:

Attachments	(Terms of Use)
NetworkManager journal when enabling debug and attaching the adapter (11.75 KB, text/plain) 2016-04-11 08:35 UTC, Vesa Halttunen	no flags	Details
Core dump for the crash (477.60 KB, application/x-xz) 2016-04-11 08:56 UTC, Vesa Halttunen	no flags	Details
NetworkManager start TRACE log when the r8188eu adapter attached (79.48 KB, text/x-vhdl) 2016-04-13 14:06 UTC, Robin Lee	no flags	Details
View All

Links
System	ID	Private	Priority	Status	Summary	Last Updated
GNOME Bugzilla	764877	0	None	None	None	2016-04-11 11:33:49 UTC

Description Robin Lee 2016-04-10 06:53:11 UTC

Description of problem:
After upgrading to 1.0.12-1, NetworkManger failed to manage my USB-WIFI adapter.

After downgrading to 1.0.10-3, the WiFi works again.

My USB-WIFI adapter is using a staging module r8188eu, not compiled by default in Fedora kernel. My kernel is kernel-4.4.6-300.fc23.x86_64. The r8188eu module is compiled from 4.5 upstream kernel source.

lsusb -v output:
Bus 002 Device 002: ID 0bda:8179 Realtek Semiconductor Corp. RTL8188EUS 802.11n Wireless Network Adapter
Couldn't open device, some information will be missing
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            0 
  bDeviceSubClass         0 
  bDeviceProtocol         0 
  bMaxPacketSize0        64
  idVendor           0x0bda Realtek Semiconductor Corp.
  idProduct          0x8179 RTL8188EUS 802.11n Wireless Network Adapter
  bcdDevice            0.00
  iManufacturer           1 
  iProduct                2 
  iSerial                 3 
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength           39
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0 
    bmAttributes         0xa0
      (Bus Powered)
      Remote Wakeup
    MaxPower              500mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass    255 Vendor Specific Subclass
      bInterfaceProtocol    255 Vendor Specific Protocol
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x03  EP 3 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0


NetworkManager 1.0.12-1 start-up log:
-- Logs begin at Mon 2015-09-28 23:07:14 CST, end at Sun 2016-04-10 14:40:50 CST. --
Apr 10 13:47:24 cheese-1055T systemd[1]: Starting Network Manager...
Apr 10 13:47:25 cheese-1055T NetworkManager[931]: <info>  NetworkManager (version 1.0.12-1.fc23) is starting...
Apr 10 13:47:25 cheese-1055T NetworkManager[931]: <info>  Read config: /etc/NetworkManager/NetworkManager.conf and conf.d: 10-ibft-plugin.conf, 20-connectivity-fedora.conf
Apr 10 13:47:25 cheese-1055T systemd[1]: Started Network Manager.
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded settings plugin ifcfg-rh: (c) 2007 - 2015 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list. (/usr/lib64/NetworkManager/libnm-settings-plugin-ifcfg-rh.so)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded settings plugin iBFT: (c) 2014 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list. (/usr/lib64/NetworkManager/libnm-settings-plugin-ibft.so)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded settings plugin keyfile: (c) 2007 - 2015 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  ifcfg-rh: new connection /etc/sysconfig/network-scripts/ifcfg-enp2s0 (0af39bcc-0bc9-469c-9348-8b40f1348d8f,"enp2s0")
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  ifcfg-rh: new connection /etc/sysconfig/network-scripts/ifcfg-HiWiFi_25A5AE (d3cdcad5-64f2-43e3-973e-4166e44a469b,"HiWiFi_25A5AE")
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  VPN: loaded org.freedesktop.NetworkManager.vpnc
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  VPN: loaded org.freedesktop.NetworkManager.pptp
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  VPN: loaded org.freedesktop.NetworkManager.openswan
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  VPN: loaded org.freedesktop.NetworkManager.openconnect
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  VPN: loaded org.freedesktop.NetworkManager.l2tp
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  VPN: loaded org.freedesktop.NetworkManager.openvpn
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  monitoring kernel firmware directory '/lib/firmware'.
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  WiFi hardware radio set enabled
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  WWAN hardware radio set enabled
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMVxlanFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMVlanFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMVethFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMTunFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMMacvlanFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMInfinibandFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMGreFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMEthernetFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMBridgeFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMBondFactory (internal)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMAtmManager (/usr/lib64/NetworkManager/libnm-device-plugin-adsl.so)
Apr 10 13:47:26 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMTeamFactory (/usr/lib64/NetworkManager/libnm-device-plugin-team.so)
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMWifiFactory (/usr/lib64/NetworkManager/libnm-device-plugin-wifi.so)
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMBluezManager (/usr/lib64/NetworkManager/libnm-device-plugin-bluetooth.so)
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  Loaded device plugin: NMWwanFactory (/usr/lib64/NetworkManager/libnm-device-plugin-wwan.so)
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  WiFi enabled by radio killswitch; enabled by state file
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  WWAN enabled by radio killswitch; enabled by state file
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  WiMAX enabled by radio killswitch; enabled by state file
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  Networking is enabled by state file
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  (lo): link connected
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  (lo): new Generic device (carrier: ON, driver: 'unknown', ifindex: 1)
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  (wlp0s19f2u6): driver supports SSID scans (scan_capa 0x3F).
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  (wlp0s19f2u6): using WEXT for WiFi device control
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  (enp2s0): new Ethernet device (carrier: OFF, driver: 'r8169', ifindex: 2)
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  (enp2s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Apr 10 13:47:27 cheese-1055T NetworkManager[931]: <info>  ModemManager available in the bus
Apr 10 13:47:32 cheese-1055T NetworkManager[931]: <info>  startup complete

NetworkManager 1.0.10-3 start-up log:
Apr 10 14:19:10 cheese-1055T systemd[1]: Starting Network Manager...
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  NetworkManager (version 1.0.10-3.fc23) is starting...
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Read config: /etc/NetworkManager/NetworkManager.conf and conf.d: 10-ibft-plugin.conf, 20-connectivity-fedora.conf
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded settings plugin ifcfg-rh: (c) 2007 - 2015 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list. (/usr/lib64/NetworkManager/libnm-settings-plugin-ifcfg-rh.so)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded settings plugin iBFT: (c) 2014 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list. (/usr/lib64/NetworkManager/libnm-settings-plugin-ibft.so)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded settings plugin keyfile: (c) 2007 - 2015 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  ifcfg-rh: new connection /etc/sysconfig/network-scripts/ifcfg-enp2s0 (0af39bcc-0bc9-469c-9348-8b40f1348d8f,"enp2s0")
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  ifcfg-rh: new connection /etc/sysconfig/network-scripts/ifcfg-HiWiFi_25A5AE (d3cdcad5-64f2-43e3-973e-4166e44a469b,"HiWiFi_25A5AE")
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  VPN: loaded org.freedesktop.NetworkManager.vpnc
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  VPN: loaded org.freedesktop.NetworkManager.pptp
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  VPN: loaded org.freedesktop.NetworkManager.openswan
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  VPN: loaded org.freedesktop.NetworkManager.openconnect
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  VPN: loaded org.freedesktop.NetworkManager.l2tp
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  VPN: loaded org.freedesktop.NetworkManager.openvpn
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  monitoring kernel firmware directory '/lib/firmware'.
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  WiFi hardware radio set enabled
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  WWAN hardware radio set enabled
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMVxlanFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMVlanFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMVethFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMTunFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMMacvlanFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMInfinibandFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMGreFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMEthernetFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMBridgeFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMBondFactory (internal)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMWifiFactory (/usr/lib64/NetworkManager/libnm-device-plugin-wifi.so)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMTeamFactory (/usr/lib64/NetworkManager/libnm-device-plugin-team.so)
Apr 10 14:19:10 cheese-1055T systemd[1]: Started Network Manager.
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMBluezManager (/usr/lib64/NetworkManager/libnm-device-plugin-bluetooth.so)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMWwanFactory (/usr/lib64/NetworkManager/libnm-device-plugin-wwan.so)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Loaded device plugin: NMAtmManager (/usr/lib64/NetworkManager/libnm-device-plugin-adsl.so)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  WiFi enabled by radio killswitch; enabled by state file
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  WWAN enabled by radio killswitch; enabled by state file
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  WiMAX enabled by radio killswitch; enabled by state file
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  Networking is enabled by state file
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  (enp2s0): new Ethernet device (carrier: OFF, driver: 'r8169', ifindex: 2)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  (enp2s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  (lo): link connected
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  (lo): new Generic device (carrier: ON, driver: 'unknown', ifindex: 1)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): driver supports SSID scans (scan_capa 0x3F).
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): using WEXT for WiFi device control
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): new 802.11 WiFi device (carrier: UNKNOWN, driver: 'r8188eu', ifindex: 3)
Apr 10 14:19:10 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Apr 10 14:19:11 cheese-1055T NetworkManager[2663]: <info>  ModemManager available in the bus
Apr 10 14:19:11 cheese-1055T NetworkManager[2663]: <info>  wpa_supplicant running
Apr 10 14:19:11 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6) supports 1 scan SSIDs
Apr 10 14:19:11 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): supplicant interface state: starting -> ready
Apr 10 14:19:11 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): supplicant interface state: ready -> inactive
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  Auto-activating connection 'HiWiFi_25A5AE'.
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): Activation: starting connection 'HiWiFi_25A5AE' (d3cdcad5-64f2-43e3-973e-4166e44a469b)
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  NetworkManager state is now CONNECTING
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): device state change: prepare -> config (reason 'none') [40 50 0]
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): Activation: (wifi) connection 'HiWiFi_25A5AE' has security, and secrets exist.  No new secrets needed.
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  Config: added 'ssid' value 'HiWiFi_25A5AE'
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  Config: added 'scan_ssid' value '1'
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  Config: added 'key_mgmt' value 'WPA-PSK'
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  Config: added 'psk' value '<omitted>'
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  Config: added 'proto' value 'WPA RSN'
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  Config: set interface ap_scan to 1
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): supplicant interface state: inactive -> associating
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): supplicant interface state: associating -> associated
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): supplicant interface state: associated -> 4-way handshake
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): supplicant interface state: 4-way handshake -> completed
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'HiWiFi_25A5AE'.
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): device state change: config -> ip-config (reason 'none') [50 70 0]
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  Activation (wlp0s19f2u6) Beginning DHCPv4 transaction (timeout in 45 seconds)
Apr 10 14:19:12 cheese-1055T NetworkManager[2663]: <info>  dhclient started with pid 2712
Apr 10 14:19:13 cheese-1055T dhclient[2712]: DHCPDISCOVER on wlp0s19f2u6 to 255.255.255.255 port 67 interval 3 (xid=0xd77e055e)
Apr 10 14:19:13 cheese-1055T dhclient[2712]: DHCPREQUEST on wlp0s19f2u6 to 255.255.255.255 port 67 (xid=0xd77e055e)
Apr 10 14:19:13 cheese-1055T dhclient[2712]: DHCPOFFER from 192.168.199.1
Apr 10 14:19:13 cheese-1055T dhclient[2712]: DHCPACK from 192.168.199.1 (xid=0xd77e055e)
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>    address 192.168.199.176
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>    plen 24 (255.255.255.0)
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>    gateway 192.168.199.1
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>    server identifier 192.168.199.1
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>    lease time 43200
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>    hostname 'cheese-1055T'
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>    nameserver '192.168.199.1'
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>    domain name 'lan'
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): DHCPv4 state changed unknown -> bound
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): device state change: ip-config -> ip-check (reason 'none') [70 80 0]
Apr 10 14:19:13 cheese-1055T dhclient[2712]: bound to 192.168.199.176 -- renewal in 17135 seconds.
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): device state change: ip-check -> secondaries (reason 'none') [80 90 0]
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): device state change: secondaries -> activated (reason 'none') [90 100 0]
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>  NetworkManager state is now CONNECTED_LOCAL
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>  NetworkManager state is now CONNECTED_SITE
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>  Policy set 'HiWiFi_25A5AE' (wlp0s19f2u6) as default for IPv4 routing and DNS.
Apr 10 14:19:13 cheese-1055T NetworkManager[2663]: <info>  (wlp0s19f2u6): Activation: successful, device activated.
Apr 10 14:19:14 cheese-1055T NetworkManager[2663]: <info>  NetworkManager state is now CONNECTED_GLOBAL
Apr 10 14:19:15 cheese-1055T NetworkManager[2663]: <info>  startup complete

Comment 1 Beniamino Galvani 2016-04-11 07:08:57 UTC

Hi, can you please attach debug logs for 1.0.12? (see https://cgit.freedesktop.org/NetworkManager/NetworkManager/tree/contrib/fedora/rpm/NetworkManager.conf#n25 for how to increase logging verbosity). Thanks!

Comment 2 Beniamino Galvani 2016-04-11 07:43:29 UTC

*** Bug 1324009 has been marked as a duplicate of this bug. ***

Comment 3 Vesa Halttunen 2016-04-11 08:35:19 UTC

Created attachment 1145865 [details]
NetworkManager journal when enabling debug and attaching the adapter

Here's the journal with debug enabled. It seems that NetworkManager segfaults when plugging in the adapter and subsequently restarts without recognizing the adapter.

Comment 4 Vesa Halttunen 2016-04-11 08:56:59 UTC

Created attachment 1145870 [details]
Core dump for the crash

Here's a core dump for the crash. Abrt says it's been reported as bug #1305448 which I cannot access.

Comment 5 Vesa Halttunen 2016-04-11 09:01:22 UTC

When attaching the adapter again, nothing crashes, but the only lines in the journal are:

Apr 11 10:59:27 localhost NetworkManager[3124]: <trace> [1460365167.250940] [platform/nm-linux-platform.c:2395] event_notification(): platform-linux: event-notification: NEWLINK, seq 0: 8: wlan0 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi not-init addrgenmode eui64 addr C8:BE:19:D6:0F:23
Apr 11 10:59:27 localhost NetworkManager[3124]: <debug> [1460365167.251016] [platform/nm-platform.c:2896] log_link(): platform: signal: link   added: 8: wlan0 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi not-init addrgenmode eui64 addr C8:BE:19:D6:0F:23 driver r8712u
Apr 11 10:59:27 localhost NetworkManager[3124]: <debug> [1460365167.251159] [platform/wifi/wifi-utils-nl80211.c:890] wifi_nl80211_init(): (wlan0): NL80211_CMD_GET_WIPHY request failed
Apr 11 10:59:27 localhost NetworkManager[3124]: <info>  (wlan0): driver does not support SSID scans (scan_capa 0x00).
Apr 11 10:59:27 localhost NetworkManager[3124]: <info>  (wlan0): using WEXT for WiFi device control
Apr 11 10:59:28 localhost NetworkManager[3124]: <trace> [1460365168.295807] [platform/nm-linux-platform.c:2395] event_notification(): platform-linux: event-notification: NEWLINK, seq 0: 8: wlp0s29f7u1 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi not-init addrgenmode eui64 addr C8:BE:19:D6:0F:23
Apr 11 10:59:28 localhost NetworkManager[3124]: <debug> [1460365168.295892] [platform/nm-platform.c:2896] log_link(): platform: signal: link changed: 8: wlp0s29f7u1 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi not-init addrgenmode eui64 addr C8:BE:19:D6:0F:23 driver r8712u
Apr 11 10:59:28 localhost NetworkManager[3124]: <debug> [1460365168.299053] [platform/nm-linux-platform.c:4841] handle_udev_event(): platform-linux: UDEV event: action 'add' subsys 'net' device 'wlp0s29f7u1' (8); seqnum=2578
Apr 11 10:59:28 localhost NetworkManager[3124]: <debug> [1460365168.299195] [platform/nm-platform.c:2896] log_link(): platform: signal: link changed: 8: wlp0s29f7u1 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi init addrgenmode eui64 addr C8:BE:19:D6:0F:23 driver r8712u
Apr 11 10:59:28 localhost NetworkManager[3124]: <debug> [1460365168.301542] [platform/nm-linux-platform.c:4841] handle_udev_event(): platform-linux: UDEV event: action 'move' subsys 'net' device 'wlp0s29f7u1' (8); seqnum=2581
Apr 11 10:59:28 localhost NetworkManager[3124]: <debug> [1460365168.301695] [platform/nm-platform.c:2896] log_link(): platform: signal: link changed: 8: wlp0s29f7u1 <DOWN;broadcast,multicast> mtu 1500 arp 1 wifi init addrgenmode eui64 addr C8:BE:19:D6:0F:23 driver r8712u

The device wlp0s29f7u1 does not appear in 'nmcli d' or 'ifconfig' after this but does show up in 'iwconfig'.

Comment 6 Vesa Halttunen 2016-04-11 09:03:04 UTC

(In reply to Vesa Halttunen from comment #4)
> Here's a core dump for the crash. Abrt says it's been reported as bug
> #1305448 which I cannot access.

Abrt-cli also says it's this one: https://retrace.fedoraproject.org/faf/reports/923375/

Comment 7 Beniamino Galvani 2016-04-11 11:33:50 UTC

See upstream bug: https://bugzilla.gnome.org/show_bug.cgi?id=764877

Comment 8 Beniamino Galvani 2016-04-11 11:36:10 UTC

*** Bug 1324975 has been marked as a duplicate of this bug. ***

Comment 9 Francesco Giudici 2016-04-11 12:59:38 UTC

Seems to me that here we have two issues, and the first one triggers the second.
1) The first one is related to the wireless rtl driver: it reports the device in "Auto" mode. You can find more on this here:
https://bugzilla.gnome.org/show_bug.cgi?id=763388
A patch for this has been just pushed upstream in the nm stable branch, as reported by Beniamino
https://bugzilla.gnome.org/show_bug.cgi?id=764877

Once this fix is in place you should not see the issue anymore.

2) The second issue is related to a bug present in the nm stable branch. In the platform_link_added() function, is called:
nm_device_factory_new_link (factory, plink, &ignore, &error);
when returns, error will be NULL if the issue 1) happens and the following call to
nm_log_warn (LOGD_HW, "%s: factory failed to create device: %s",
                                             plink->name, error->message);
will cause the segfault

This issue has already been fixed upstream in 1.2:
https://bugzilla.gnome.org/show_bug.cgi?id=764606
Anyway, the related code is changed a bit from 1.0.x and so cannot be cherry-picked as is. I will backport it before putting this to POST.


In the meanwhile, a trick that may avoid the issue is
 sudo iwconfig <your wlan device> mode managed
before starting NM (or just restarting nm after issuing that).

Comment 10 Francesco Giudici 2016-04-11 14:16:08 UTC

Patch for 2) in upstream repo:
fg/nm-1-0_wifi_segfault_rh1325631
needs review.

Comment 11 Vesa Halttunen 2016-04-12 00:26:06 UTC

(In reply to Francesco Giudici from comment #9)
> In the meanwhile, a trick that may avoid the issue is
>  sudo iwconfig <your wlan device> mode managed
> before starting NM (or just restarting nm after issuing that).

I can confirm that this indeed works around the bug I reported, bug #1324009.

Comment 12 Francesco Giudici 2016-04-12 07:51:35 UTC

(In reply to Vesa Halttunen from comment #11)
> I can confirm that this indeed works around the bug I reported, bug #1324009.

Thanks Vesa,
  than on next alignment on upstream stable branch this issue will be no more present.
Still would be better to have also the patch for 2), that will avoid NM segfault in few cases, when dealing with devices that cannot be managed.

Comment 13 Francesco Giudici 2016-04-13 09:03:42 UTC

As the patch that will solve the issue (point 1) is already in the upstream stable branch, moving this to POST state.

Comment 14 Beniamino Galvani 2016-04-13 09:23:42 UTC

(In reply to Francesco Giudici from comment #10)
> Patch for 2) in upstream repo:
> fg/nm-1-0_wifi_segfault_rh1325631
> needs review.

 nm_device_factory_new_link (NMDeviceFactory *factory,
[...]
+	if (!device && !*error) {
+		if (ignore) {

IMO, we shouldn't de-reference @error without checking if it's not NULL before (at the moment the only caller of the function passes a valid pointer, but this might change it the future). All other uses of @error in the function accept a NULL value.

The rest looks good to me.

Comment 15 Francesco Giudici 2016-04-13 10:49:10 UTC

Ok, thanks Beniamino, changed to:

+ if (!device && error && !*error) {

fixed and repushed to fg/nm-1-0_wifi_segfault_rh1325631

Comment 16 Robin Lee 2016-04-13 14:06:09 UTC

Created attachment 1146861 [details]
NetworkManager start TRACE log when the r8188eu adapter attached

Comment 17 Beniamino Galvani 2016-04-13 14:23:36 UTC

(In reply to Francesco Giudici from comment #15)
> Ok, thanks Beniamino, changed to:
> 
> + if (!device && error && !*error) {
> 
> fixed and repushed to fg/nm-1-0_wifi_segfault_rh1325631

Now LGTM.

Comment 18 Fedora Update System 2016-04-13 14:46:41 UTC

NetworkManager-1.0.12-2.fc23 has been submitted as an update to Fedora 23. https://bodhi.fedoraproject.org/updates/FEDORA-2016-91bcf848a4

Comment 19 Francesco Giudici 2016-04-13 17:08:22 UTC

Quick Recap

1) Bugzilla issue fixed in:
https://cgit.freedesktop.org/NetworkManager/NetworkManager/commit/?h=nm-1-0&id=70c0defe753bc98ac75725cc32a84b36f32258e4
backported in Fedora NM package (under test).

2) Merged fg/nm-1-0_wifi_segfault_rh1325631 on nm-1-0 upstream:
https://cgit.freedesktop.org/NetworkManager/NetworkManager/commit/?h=nm-1-0&id=a5dc355d4d7a85c72cd072bbc9b3c56056ae329a
will be put in next stable NM release, not backported to current Fedora NM package as not critical (and not needed to solve this bug).

Comment 20 Fedora Update System 2016-04-16 19:27:27 UTC

NetworkManager-1.0.12-2.fc23 has been pushed to the Fedora 23 testing repository. If problems still persist, please make note of it in this bug report.
See https://fedoraproject.org/wiki/QA:Updates_Testing for
instructions on how to install test updates.
You can provide feedback for this update here: https://bodhi.fedoraproject.org/updates/FEDORA-2016-91bcf848a4

Comment 21 Fedora Update System 2016-04-18 03:19:39 UTC

NetworkManager-1.0.12-2.fc23 has been pushed to the Fedora 23 stable repository. If problems still persist, please make note of it in this bug report.

Comment 22 Iiro Laiho 2016-05-02 08:22:26 UTC

I just plugged in a Realtek adapter. The lsusb command says:

Bus 003 Device 007: ID 0bda:8179 Realtek Semiconductor Corp. RTL8188EUS 802.11n Wireless Network Adapter

On Raspbian, it works fine. I don't know if it is this bug or some another.

Comment 23 Iiro Laiho 2016-05-02 08:37:23 UTC

(In reply to Iiro Laiho from comment #22)
> I just plugged in a Realtek adapter. The lsusb command says:
> 
> Bus 003 Device 007: ID 0bda:8179 Realtek Semiconductor Corp. RTL8188EUS
> 802.11n Wireless Network Adapter
> 
> On Raspbian, it works fine. I don't know if it is this bug or some another.

Oops, it seems that the module isn't just compiled by default in Fedora.

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