Bug 999176 - NetworkManager can't establish dhcp addresses if ipv6 is disabled
Summary: NetworkManager can't establish dhcp addresses if ipv6 is disabled
Keywords:
Status: CLOSED DUPLICATE of bug 1012151
Alias: None
Product: Fedora
Classification: Fedora
Component: NetworkManager
Version: 20
Hardware: All
OS: Linux
unspecified
high
Target Milestone: ---
Assignee: Dan Williams
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2013-08-20 21:13 UTC by kevin martin
Modified: 2013-10-25 21:08 UTC (History)
3 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2013-10-25 21:08:02 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)

Description kevin martin 2013-08-20 21:13:35 UTC
Description of problem:
NetworkManager doesn't establish a network address on my wired nic anymore.  I see this in /var/log/messages after I kill it and restart it:

Aug 20 16:04:57 ktmtoshiba dbus-daemon: dbus[467]: [system] Activating via systemd: service name='org.freedesktop.NetworkManager' unit='dbus-org.freedesktop.NetworkManager.service'
Aug 20 16:04:57 ktmtoshiba dbus[467]: [system] Activating via systemd: service name='org.freedesktop.NetworkManager' unit='dbus-org.freedesktop.NetworkManager.service'
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> NetworkManager (version 0.9.9.0-9.git20130807.fc20) is starting...
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Read config: /etc/NetworkManager/NetworkManager.conf
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> WEXT support is enabled
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> VPN: loaded org.freedesktop.NetworkManager.vpnc
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> VPN: loaded org.freedesktop.NetworkManager.pptp
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> VPN: loaded org.freedesktop.NetworkManager.openconnect
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> VPN: loaded org.freedesktop.NetworkManager.openvpn
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: ifcfg-rh: Acquired D-Bus service com.redhat.ifcfgrh1
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Loaded plugin ifcfg-rh: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-HTCKTM ...
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: ifcfg-rh:     read connection 'HTCKTM'
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-littlehouse ...
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: ifcfg-rh:     read connection 'littlehouse'
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-lo ...
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-Wi-Fi_connection_1 ...
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: ifcfg-rh:     read connection 'Wi-Fi connection 1'
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> monitoring kernel firmware directory '/lib/firmware'.
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> rfkill0: found WiFi radio killswitch (at /sys/devices/pci0000:00/0000:00:1c.4/0000:0a:00.0/ieee80211/phy0/rfkill0) (driver rtl8192se)
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> WiFi hardware radio set enabled
Aug 20 16:04:57 ktmtoshiba dbus[467]: [system] Successfully activated service 'org.freedesktop.NetworkManager'
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> WiFi enabled by radio killswitch; enabled by state file
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> WWAN enabled by radio killswitch; enabled by state file
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> WiMAX enabled by radio killswitch; enabled by state file
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Networking is enabled by state file
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (lo): link connected
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (lo): carrier is ON
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (lo): new Generic device (driver: 'unknown' ifindex: 1)
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (lo): exported as /org/freedesktop/NetworkManager/Devices/0
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (eth0): link connected
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (eth0): carrier is ON
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (eth0): new Ethernet device (driver: 'atl1c' ifindex: 2)
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/1
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:1c.6/0000:0b:00.0/net/eth0
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0): using nl80211 for WiFi device control
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0): driver supports Access Point (AP) mode
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0): new 802.11 WiFi device (driver: 'rtl8192se' ifindex: 3)
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0): exported as /org/freedesktop/NetworkManager/Devices/2
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <warn> bluez error getting default adapter: Method "DefaultAdapter" with signature "" on interface "org.bluez.Manager" doesn't exist
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (eth0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Auto-activating connection 'Wired connection 1'.
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) starting connection 'Wired connection 1'
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (eth0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0) supports 4 scan SSIDs
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0): supplicant interface state: starting -> ready
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0): supplicant interface state: ready -> inactive
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (wlp10s0) supports 4 scan SSIDs
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Aug 20 16:04:57 ktmtoshiba NetworkManager[1646]: <info> (eth0): device state change: prepare -> config (reason 'none') [40 50 0]
Aug 20 16:04:57 ktmtoshiba dbus-daemon: dbus[467]: [system] Successfully activated service 'org.freedesktop.NetworkManager'
Aug 20 16:04:58 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Aug 20 16:04:58 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Aug 20 16:04:58 ktmtoshiba NetworkManager: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 4 (xid=0x9222496)
Aug 20 16:04:58 ktmtoshiba NetworkManager[1646]: <warn> (eth0) firewall zone add/change failed: (32) ZONE_ALREADY_SET
Aug 20 16:04:58 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Aug 20 16:04:58 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Aug 20 16:04:58 ktmtoshiba NetworkManager[1646]: <info> (eth0): device state change: config -> ip-config (reason 'none') [50 70 0]
Aug 20 16:04:58 ktmtoshiba NetworkManager[1646]: <info> Activation (eth0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Aug 20 16:05:01 ktmtoshiba NetworkManager: DHCPREQUEST on eth0 to 255.255.255.255 port 67 (xid=0x9222496)
Aug 20 16:05:01 ktmtoshiba NetworkManager: DHCPOFFER from 192.168.238.1
Aug 20 16:05:01 ktmtoshiba NetworkManager: DHCPACK from 192.168.238.1 (xid=0x9222496)
Aug 20 16:05:02 ktmtoshiba NetworkManager[1646]: <info> dhclient started with pid 1666
Aug 20 16:05:02 ktmtoshiba NetworkManager: libndp: ndp_sock_open: Failed to create ICMP6 socket.
Aug 20 16:05:02 ktmtoshiba NetworkManager: **
Aug 20 16:05:02 ktmtoshiba NetworkManager: ERROR:rdisc/nm-lndp-rdisc.c:601:nm_lndp_rdisc_init: assertion failed: (!error)
Aug 20 16:05:02 ktmtoshiba NetworkManager: Internet Systems Consortium DHCP Client 4.2.5
Aug 20 16:05:02 ktmtoshiba NetworkManager: Copyright 2004-2013 Internet Systems Consortium.
Aug 20 16:05:02 ktmtoshiba NetworkManager: All rights reserved.
Aug 20 16:05:02 ktmtoshiba NetworkManager: For info, please visit https://www.isc.org/software/dhcp/
Aug 20 16:05:02 ktmtoshiba abrt[1669]: Saved core dump of pid 1646 (/usr/sbin/NetworkManager) to /var/tmp/abrt/ccpp-2013-08-20-16:05:02-1646 (27525120 bytes)
Aug 20 16:05:02 ktmtoshiba systemd: NetworkManager.service: main process exited, code=dumped, status=6/ABRT
Aug 20 16:05:02 ktmtoshiba systemd: Unit NetworkManager.service entered failed state.
Aug 20 16:05:02 ktmtoshiba NetworkManager: Listening on LPF/eth0/c8:0a:a9:b1:46:c2
Aug 20 16:05:02 ktmtoshiba NetworkManager: Sending on   LPF/eth0/c8:0a:a9:b1:46:c2
Aug 20 16:05:02 ktmtoshiba NetworkManager: Sending on   Socket/fallback
Aug 20 16:05:02 ktmtoshiba NetworkManager: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8 (xid=0x11c0cd24)
Aug 20 16:05:02 ktmtoshiba NetworkManager: DHCPREQUEST on eth0 to 255.255.255.255 port 67 (xid=0x11c0cd24)
Aug 20 16:05:02 ktmtoshiba NetworkManager: DHCPOFFER from 192.168.238.1
Aug 20 16:05:02 ktmtoshiba NetworkManager: DHCPACK from 192.168.238.1 (xid=0x11c0cd24)
Aug 20 16:05:02 ktmtoshiba NetworkManager: Error: could not connect to NetworkManager DBus socket: (org.freedesktop.DBus.Error.NoServer) Failed to connect to socket /var/run/NetworkManager/private-dhcp: Connection refused
Aug 20 16:05:02 ktmtoshiba NetworkManager: DHCPDECLINE on eth0 to 255.255.255.255 port 67 (xid=0x11c0cd24)
Aug 20 16:05:06 ktmtoshiba NetworkManager: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 4 (xid=0x65519f11)
Aug 20 16:05:06 ktmtoshiba NetworkManager: DHCPREQUEST on eth0 to 255.255.255.255 port 67 (xid=0x65519f11)
Aug 20 16:05:06 ktmtoshiba NetworkManager: DHCPOFFER from 192.168.238.1
Aug 20 16:05:06 ktmtoshiba NetworkManager: DHCPACK from 192.168.238.1 (xid=0x65519f11)
Aug 20 16:05:06 ktmtoshiba NetworkManager: Error: could not connect to NetworkManager DBus socket: (org.freedesktop.DBus.Error.NoServer) Failed to connect to socket /var/run/NetworkManager/private-dhcp: Connection refused
Aug 20 16:05:06 ktmtoshiba NetworkManager: DHCPDECLINE on eth0 to 255.255.255.255 port 67 (xid=0x65519f11)
Aug 20 16:05:11 ktmtoshiba NetworkManager: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 4 (xid=0x4456fa40)
Aug 20 16:05:13 ktmtoshiba NetworkManager: DHCPREQUEST on eth0 to 255.255.255.255 port 67 (xid=0x4456fa40)
Aug 20 16:05:13 ktmtoshiba NetworkManager: DHCPOFFER from 192.168.238.1
Aug 20 16:05:13 ktmtoshiba NetworkManager: DHCPACK from 192.168.238.1 (xid=0x4456fa40)
Aug 20 16:05:14 ktmtoshiba NetworkManager: Error: could not connect to NetworkManager DBus socket: (org.freedesktop.DBus.Error.NoServer) Failed to connect to socket /var/run/NetworkManager/private-dhcp: Connection refused
Aug 20 16:05:14 ktmtoshiba NetworkManager: DHCPDECLINE on eth0 to 255.255.255.255 port 67 (xid=0x4456fa40)
Aug 20 16:05:16 ktmtoshiba NetworkManager: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8 (xid=0x1153558a)
Aug 20 16:05:16 ktmtoshiba NetworkManager: DHCPREQUEST on eth0 to 255.255.255.255 port 67 (xid=0x1153558a)
Aug 20 16:05:16 ktmtoshiba NetworkManager: DHCPOFFER from 192.168.238.1
Aug 20 16:05:16 ktmtoshiba NetworkManager: DHCPACK from 192.168.238.1 (xid=0x1153558a)
Aug 20 16:05:16 ktmtoshiba NetworkManager: Error: could not connect to NetworkManager DBus socket: (org.freedesktop.DBus.Error.NoServer) Failed to connect to socket /var/run/NetworkManager/private-dhcp: Connection refused


Version-Release number of selected component (if applicable):
0.9.9.0-9.git20130807.fc20

How reproducible:
every time I reboot

Steps to Reproduce:
1.reboot
2.fails
3.

Actual results:
fails to set ip address

Expected results:
get's dhcp address and set's it correctly

Additional info:
happens no matter what my nic name is (eth0, p6p1)...I even tried removing the private_dhcp file to see if that mattered and no joy.

Comment 1 Orion Poplawski 2013-09-11 22:46:53 UTC
I seem to be seeing this as well in F20 - 0.9.9.0-9.git20130807.fc20.i686.  I have 'ipv6.disable=1' in my kernel boot line, I suspect this may be the trigger.  Removing it seems to have fixed it for me.  

Aug 20 16:05:02 ktmtoshiba NetworkManager: libndp: ndp_sock_open: Failed to create ICMP6 socket.
Aug 20 16:05:02 ktmtoshiba NetworkManager: **
Aug 20 16:05:02 ktmtoshiba NetworkManager: ERROR:rdisc/nm-lndp-rdisc.c:601:nm_lndp_rdisc_init: assertion failed: (!error)

Not sure if this is a libndp issue (init should succeed even if IPv6 isn't available) or if NM should be calling it differently.  Re-assigning to libndp for comment.

Comment 2 Orion Poplawski 2013-10-24 18:34:50 UTC
New different error messages now, same old problem.  I need to disable ipv6 due to a defective home dsl router.

Oct 24 07:30:00 pacas NetworkManager[351]: <info> Activation (wlp3s0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Oct 24 07:30:00 pacas NetworkManager[351]: <info> dhclient started with pid 1467
Oct 24 07:30:00 pacas NetworkManager: libndp: ndp_sock_open: Failed to create ICMP6 socket.
Oct 24 07:30:00 pacas NetworkManager[351]: nm_platform_sysctl_set: assertion 'path' failed
Oct 24 07:30:00 pacas NetworkManager[351]: <error> [1382621400.887309] [devices/nm-device.c:3173] addrconf6_start(): Failed to start router discovery.
Oct 24 07:30:00 pacas NetworkManager[351]: <info> (wlp3s0): device state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
Oct 24 07:30:00 pacas NetworkManager[351]: <info> NetworkManager state is now DISCONNECTED
Oct 24 07:30:00 pacas NetworkManager[351]: <warn> Activation (wlp3s0) failed for connection 'The Shire'

NetworkManager-0.9.9.0-14.git20131003.fc20.x86_64
libndp-1.2-1.fc20.x86_64

Comment 3 Dan Williams 2013-10-25 21:08:02 UTC
Pretty sure this is the same issue as bug 1012151, recently fixed upstream.  Should get into the next snapshot build.

*** This bug has been marked as a duplicate of bug 1012151 ***


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