Package: network-manager
Version: 0.9.4.0-10
Severity: serious

Hi,
it seems that network-manager stopped being able to handle my lan and
wlan devices properly after last series of updates (which don't seem to
be directly related to the nm packages). Although I can get connected to
both wired and wireless (to the system wide saved networks) nm-applet
(network-manager-gnome package) claims that there are "No network
devices available" so it doesn't give me access to neither of the
saved networks nor to the saved VPN configurations.

I originally wanted to report this agains network-manager-gnome package
but I think this comes from network-manager and the applet simply relays
to information.

/etc/NetworkManager/NetworkManager.conf is default one:
[main]
plugins=ifupdown,keyfile

[ifupdown]
managed=false

and /etc/network/interfaces:
# This file describes the network interfaces available on your system
# and how to activate them. For more information, see interfaces(5).

# The loopback network interface
auto lo
iface lo inet loopback

# The primary network interface
allow-hotplug eth0
#NetworkManager#iface lan0 inet dhcp


Here is what my log says after I start nm:
Sep 24 22:13:38 tiehlicka dbus[3380]: [system] Successfully activated service 
'org.freedesktop.nm_dispatcher'
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> NetworkManager (version 
0.9.4.0) is starting...
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> Read config file 
/etc/NetworkManager/NetworkManager.conf
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: init!
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: 
update_system_hostname
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPluginIfupdown: management 
mode: unmanaged
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: devices 
added (path: /sys/devices/pci0000:00/0000:00:19.0/net/lan0, iface: lan0)
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: device 
added (path: /sys/devices/pci0000:00/0000:00:19.0/net/lan0, iface: lan0): no 
ifupdown configuration found.
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: devices 
added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0/net/wlan0, 
iface: wlan0)
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: device 
added (path: /sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0/net/wlan0, 
iface: wlan0): no ifupdown configuration found.
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: devices 
added (path: /sys/devices/virtual/net/lo, iface: lo)
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: device 
added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration 
found.
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: devices 
added (path: /sys/devices/virtual/net/sit0, iface: sit0)
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: device 
added (path: /sys/devices/virtual/net/sit0, iface: sit0): no ifupdown 
configuration found.
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: end _init.
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> Loaded plugin ifupdown: 
(C) 2008 Canonical Ltd.  To report bugs please use the NetworkManager mailing 
list.
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> Loaded plugin keyfile: 
(c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager 
mailing list.
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    Ifupdown: get unmanaged 
devices count: 0
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: (7664400) 
... get_connections.
Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    SCPlugin-Ifupdown: (7664400) 
... get_connections (managed=false): return empty list.

[ ... keyfile: parsting XYZ removed ... ]

Sep 24 22:13:42 tiehlicka NetworkManager[9129]:    Ifupdown: get unmanaged 
devices count: 0
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> trying to start the 
modem manager...
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> monitoring kernel 
firmware directory '/lib/firmware'.
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> monitoring ifupdown 
state file '/run/network/ifstate'.
Sep 24 22:13:42 tiehlicka dbus[3380]: [system] Activating service 
name='org.bluez' (using servicehelper)
Sep 24 22:13:42 tiehlicka dbus[3380]: [system] Activated service 'org.bluez' 
failed: Launch helper exited with unknown return code 1
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> WiFi enabled by radio 
killswitch; enabled by state file
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> WWAN enabled by radio 
killswitch; enabled by state file
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> WiMAX enabled by radio 
killswitch; enabled by state file
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> Networking is enabled by 
state file
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <warn> failed to allocate link 
cache: (-26) Protocol mismatch
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> (lan0): carrier is OFF
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> (lan0): new Ethernet 
device (driver: 'e1000e' ifindex: 2)
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> (lan0): exported as 
/org/freedesktop/NetworkManager/Devices/0
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> (lan0): now managed
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> (lan0): device state 
change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 24 22:13:42 tiehlicka NetworkManager[9129]: <info> (lan0): bringing up 
device.
Sep 24 22:13:42 tiehlicka kernel: [ 3251.304391] e1000e 0000:00:19.0: irq 25 
for MSI/MSI-X
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (lan0): preparing device.
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (lan0): deactivating 
device (reason 'managed') [2]
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): using nl80211 
for WiFi device control
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): new 802.11 WiFi 
device (driver: 'iwlwifi' ifindex: 4)
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): exported as 
/org/freedesktop/NetworkManager/Devices/1
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): now managed
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): device state 
change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): bringing up 
device.
Sep 24 22:13:43 tiehlicka kernel: [ 3251.406177] e1000e 0000:00:19.0: irq 25 
for MSI/MSI-X
Sep 24 22:13:43 tiehlicka kernel: [ 3251.406361] IPv6: ADDRCONF(NETDEV_UP): 
lan0: link is not ready
Sep 24 22:13:43 tiehlicka kernel: [ 3251.408421] iwlwifi 0000:02:00.0: L1 
Enabled; Disabling L0S
Sep 24 22:13:43 tiehlicka kernel: [ 3251.408643] iwlwifi 0000:02:00.0: Radio 
type=0x0-0x3-0x1
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): preparing 
device.
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): deactivating 
device (reason 'managed') [2]
Sep 24 22:13:43 tiehlicka wpa_supplicant[3687]: rfkill: Cannot open RFKILL 
control device
Sep 24 22:13:43 tiehlicka kernel: [ 3251.516392] IPv6: ADDRCONF(NETDEV_UP): 
wlan0: link is not ready
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <warn> bluez error getting 
default adapter: Launch helper exited with unknown return code 1
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): supplicant 
interface state: starting -> ready
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): device state 
change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <info> (wlan0): supplicant 
interface state: ready -> inactive
Sep 24 22:13:43 tiehlicka NetworkManager[9129]: <warn> Trying to remove a 
non-existant call id.
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Auto-activating 
connection 'mameluci'.
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) 
starting connection 'mameluci'
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> (wlan0): device state 
change: disconnected -> prepare (reason 'none') [30 40 0]
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
1 of 5 (Device Prepare) scheduled...
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
1 of 5 (Device Prepare) started...
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
2 of 5 (Device Configure) scheduled...
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
1 of 5 (Device Prepare) complete.
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
2 of 5 (Device Configure) starting...
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> (wlan0): device state 
change: prepare -> config (reason 'none') [40 50 0]
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Activation 
(wlan0/wireless): connection 'mameluci' has security, and secrets exist.  No 
new secrets needed.
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Config: added 'ssid' 
value 'mameluci'
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Config: added 
'scan_ssid' value '1'
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Config: added 'key_mgmt' 
value 'NONE'
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Config: added 'wep_key0' 
value '<omitted>'
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Config: added 
'wep_tx_keyidx' value '0'
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
2 of 5 (Device Configure) complete.
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> Config: set interface 
ap_scan to 1
Sep 24 22:13:46 tiehlicka NetworkManager[9129]: <info> (wlan0): supplicant 
interface state: inactive -> scanning
Sep 24 22:13:49 tiehlicka wpa_supplicant[3687]: wlan0: SME: Trying to 
authenticate with c0:4a:00:f1:48:f2 (SSID='mameluci' freq=2412 MHz)
Sep 24 22:13:49 tiehlicka kernel: [ 3257.769425] wlan0: authenticate with 
c0:4a:00:f1:48:f2
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> (wlan0): supplicant 
interface state: scanning -> authenticating
Sep 24 22:13:49 tiehlicka kernel: [ 3257.776593] wlan0: send auth to 
c0:4a:00:f1:48:f2 (try 1/3)
Sep 24 22:13:49 tiehlicka wpa_supplicant[3687]: wlan0: Trying to associate with 
c0:4a:00:f1:48:f2 (SSID='mameluci' freq=2412 MHz)
Sep 24 22:13:49 tiehlicka kernel: [ 3257.779530] wlan0: authenticated
Sep 24 22:13:49 tiehlicka kernel: [ 3257.779743] iwlwifi 0000:02:00.0 wlan0: 
disabling HT/VHT due to WEP/TKIP use
Sep 24 22:13:49 tiehlicka kernel: [ 3257.782259] wlan0: associate with 
c0:4a:00:f1:48:f2 (try 1/3)
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> (wlan0): supplicant 
interface state: authenticating -> associating
Sep 24 22:13:49 tiehlicka kernel: [ 3257.785369] wlan0: RX AssocResp from 
c0:4a:00:f1:48:f2 (capab=0x31 status=0 aid=1)
Sep 24 22:13:49 tiehlicka wpa_supplicant[3687]: wlan0: Associated with 
c0:4a:00:f1:48:f2
Sep 24 22:13:49 tiehlicka wpa_supplicant[3687]: wlan0: CTRL-EVENT-CONNECTED - 
Connection to c0:4a:00:f1:48:f2 completed (auth) [id=0 id_str=]
Sep 24 22:13:49 tiehlicka kernel: [ 3257.791320] wlan0: associated
Sep 24 22:13:49 tiehlicka kernel: [ 3257.791360] IPv6: ADDRCONF(NETDEV_CHANGE): 
wlan0: link becomes ready
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> (wlan0): supplicant 
interface state: associating -> completed
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> Activation 
(wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to 
wireless network 'mameluci'.
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
3 of 5 (IP Configure Start) scheduled.
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
3 of 5 (IP Configure Start) started...
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> (wlan0): device state 
change: config -> ip-config (reason 'none') [50 70 0]
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) 
Beginning DHCPv4 transaction (timeout in 45 seconds)
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> dhclient started with 
pid 9153
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) 
Beginning IP6 addrconf.
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
3 of 5 (IP Configure Start) complete.
Sep 24 22:13:49 tiehlicka dhclient: Internet Systems Consortium DHCP Client 
4.3.1
Sep 24 22:13:49 tiehlicka dhclient: Copyright 2004-2014 Internet Systems 
Consortium.
Sep 24 22:13:49 tiehlicka dhclient: All rights reserved.
Sep 24 22:13:49 tiehlicka dhclient: For info, please visit 
https://www.isc.org/software/dhcp/
Sep 24 22:13:49 tiehlicka dhclient: 
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> (wlan0): DHCPv4 state 
changed nbi -> preinit
Sep 24 22:13:49 tiehlicka dhclient: Listening on LPF/wlan0/24:77:03:92:9a:c4
Sep 24 22:13:49 tiehlicka dhclient: Sending on   LPF/wlan0/24:77:03:92:9a:c4
Sep 24 22:13:49 tiehlicka dhclient: Sending on   Socket/fallback
Sep 24 22:13:49 tiehlicka dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 
port 67
Sep 24 22:13:49 tiehlicka dhclient: DHCPACK from 192.168.2.3
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> (wlan0): DHCPv4 state 
changed preinit -> reboot
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info>   address 192.168.2.5
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info>   prefix 24 
(255.255.255.0)
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info>   gateway 192.168.2.3
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info>   nameserver 
'192.168.2.3'
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
5 of 5 (IPv4 Configure Commit) scheduled...
Sep 24 22:13:49 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
5 of 5 (IPv4 Commit) started...
Sep 24 22:13:49 tiehlicka dhclient: bound to 192.168.2.5 -- renewal in 
2147483648 seconds.
Sep 24 22:13:50 tiehlicka NetworkManager[9129]: <info> (wlan0): device state 
change: ip-config -> activated (reason 'none') [70 100 0]
Sep 24 22:13:50 tiehlicka NetworkManager[9129]: <info> Policy set 'mameluci' 
(wlan0) as default for IPv4 routing and DNS.
Sep 24 22:13:50 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) 
successful, device activated.
Sep 24 22:13:50 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
5 of 5 (IPv4 Commit) complete.
Sep 24 22:13:50 tiehlicka dbus[3380]: [system] Activating service 
name='org.freedesktop.nm_dispatcher' (using servicehelper)
Sep 24 22:13:50 tiehlicka dbus[3380]: [system] Successfully activated service 
'org.freedesktop.nm_dispatcher'
Sep 24 22:14:00 tiehlicka ntpdate[9195]: adjust time server 195.113.144.201 
offset -0.008680 sec
Sep 24 22:14:09 tiehlicka NetworkManager[9129]: <info> (wlan0): IP6 addrconf 
timed out or failed.
Sep 24 22:14:09 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
4 of 5 (IPv6 Configure Timeout) scheduled...
Sep 24 22:14:09 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
4 of 5 (IPv6 Configure Timeout) started...
Sep 24 22:14:09 tiehlicka NetworkManager[9129]: <info> Activation (wlan0) Stage 
4 of 5 (IPv6 Configure Timeout) complete.

I really do not understand what
"SCPlugin-Ifupdown: (7664400) ... get_connections (managed=false): return empty 
list."

means but to me it sounds like it wasn't able to find any device to
manage. It is still interesting to see that the wireless connection was
handled by NetworkManager anyway. So I might be completely wrong and
this can be the applet bug after all. 

I have a suspicion that the problem started around the same time
avahi-daemon was pulled in as a dependency of one of the updated
packages.

Let me know if you need any further information.

-- System Information:
Debian Release: jessie/sid
  APT prefers unstable
  APT policy: (500, 'unstable'), (500, 'testing'), (500, 'stable')
Architecture: amd64 (x86_64)

Kernel: Linux 3.17.0-rc6 (SMP w/2 CPU cores; PREEMPT)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash

Versions of packages network-manager depends on:
ii  adduser                3.113+nmu3
ii  dbus                   1.6.18-1
ii  dpkg                   1.17.13
ii  isc-dhcp-client        4.3.1-1
ii  libc6                  2.19-11
ii  libdbus-1-3            1.8.8-1
ii  libdbus-glib-1-2       0.102-1
ii  libgcrypt11            1.5.4-3
ii  libglib2.0-0           2.42.0-1
ii  libgnutls26            2.12.23-17
ii  libgudev-1.0-0         204-10
ii  libnl-3-200            3.2.24-2
ii  libnl-genl-3-200       3.2.24-2
ii  libnl-route-3-200      3.2.24-2
ii  libnm-glib4            0.9.10.0-2.1
ii  libnm-util2            0.9.10.0-2.1
ii  libpolkit-gobject-1-0  0.105-3
ii  libuuid1               2.20.1-5.8
ii  lsb-base               4.1+Debian13
ii  udev                   175-7.2
ii  wpasupplicant          1.0-3+b2

Versions of packages network-manager recommends:
ii  crda          3.13-1
ii  dnsmasq-base  2.71-1
ii  iptables      1.4.21-2
pn  modemmanager  <none>
ii  policykit-1   0.105-4
pn  ppp           <none>

Versions of packages network-manager suggests:
pn  avahi-autoipd  <none>

-- Configuration Files:
/etc/polkit-1/localauthority/10-vendor.d/org.freedesktop.NetworkManager.pkla 
[Errno 13] Permission denied: 
u'/etc/polkit-1/localauthority/10-vendor.d/org.freedesktop.NetworkManager.pkla'

-- no debconf information

-- 
Michal Hocko


-- 
To UNSUBSCRIBE, email to debian-bugs-rc-requ...@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmas...@lists.debian.org

Reply via email to