Bug 1045860 - NetworkManager IPv6 autoconfiguration (RA) regression
Summary: NetworkManager IPv6 autoconfiguration (RA) regression
Keywords:
Status: CLOSED DUPLICATE of bug 1044757
Alias: None
Product: Fedora
Classification: Fedora
Component: NetworkManager
Version: 20
Hardware: All
OS: Linux
unspecified
medium
Target Milestone: ---
Assignee: Dan Williams
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2013-12-22 16:14 UTC by Seb L.
Modified: 2014-01-04 18:08 UTC (History)
3 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2014-01-04 18:08:36 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)

Description Seb L. 2013-12-22 16:14:35 UTC
Description of problem:
NetworkManager shipped with Fedora 20 (0.9.9.0-20.git20131003) does not support IPv6 autoconfiguration (via RA) anymore. This was still working with Fedora 18.

NetworkManager (auto)configures correctly the IPv6 addresses when the network device is first activated, then lets all of them expire without ever refreshing them, ignoring subsequent RAs.

Version-Release number of selected component (if applicable):
0.9.9.0-20.git20131003

How reproducible:
100% reproducible (tested on four fresh installations on various hardware and architectures, using both wired and wireless networks).

Steps to Reproduce:
1. Connect the computer to a network providing IPv6 autoconfiguration via RA.
2. Ensure that IPv6 addresses are correctly autoconfigured
3. Watch them expiring and being withdrawn without ever being refreshed.

Actual results:
Autoconfigured IPv6 addresses expires since these are never refreshed.

Expected results:
Autoconfigured IPv6 addresses should be refreshed before expiration.

Additional info:
Removing NetworkManager (yum remove NetworkManager) instantly solves the issue (autoconfigured IPv6 addresses do not expire anymore) but this solution might not be convenient for accessing wireless networks.

Sample log:

Dec 21 14:35:27 localhost NetworkManager[844]: <info> NetworkManager (version 0.9.9.0-20.git20131003.fc20) is starting...
Dec 21 14:35:27 localhost NetworkManager[844]: <info> Read config: /etc/NetworkManager/NetworkManager.conf
Dec 21 14:35:27 localhost NetworkManager[844]: <info> WEXT support is enabled
Dec 21 14:35:27 localhost NetworkManager[844]: <info> VPN: loaded org.freedesktop.NetworkManager.vpnc
Dec 21 14:35:27 localhost NetworkManager[844]: <info> VPN: loaded org.freedesktop.NetworkManager.openvpn
Dec 21 14:35:27 localhost NetworkManager[844]: <info> VPN: loaded org.freedesktop.NetworkManager.pptp
Dec 21 14:35:27 localhost NetworkManager[844]: <info> VPN: loaded org.freedesktop.NetworkManager.l2tp
Dec 21 14:35:27 localhost NetworkManager[844]: <info> VPN: loaded org.freedesktop.NetworkManager.openconnect
Dec 21 14:35:28 localhost NetworkManager[844]: ifcfg-rh: Acquired D-Bus service com.redhat.ifcfgrh1
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Loaded plugin ifcfg-rh: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Dec 21 14:35:28 localhost NetworkManager[844]: ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-em1 ...
Dec 21 14:35:28 localhost NetworkManager[844]: ifcfg-rh:     read connection 'em1'
Dec 21 14:35:28 localhost NetworkManager[844]: ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-lo ...
Dec 21 14:35:28 localhost NetworkManager[844]: <info> monitoring kernel firmware directory '/lib/firmware'.
Dec 21 14:35:28 localhost NetworkManager[844]: <info> WiFi hardware radio set enabled
Dec 21 14:35:28 localhost NetworkManager[844]: <info> WiFi enabled by radio killswitch; enabled by state file
Dec 21 14:35:28 localhost NetworkManager[844]: <info> WWAN enabled by radio killswitch; enabled by state file
Dec 21 14:35:28 localhost NetworkManager[844]: <info> WiMAX enabled by radio killswitch; enabled by state file
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Networking is enabled by state file
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (lo): link connected
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (lo): carrier is ON
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (lo): new Generic device (driver: 'unknown' ifindex: 1)
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (lo): exported as /org/freedesktop/NetworkManager/Devices/0
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (lo): No existing connection detected.
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): carrier is OFF
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): new Ethernet device (driver: 'atl1' ifindex: 2)
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): exported as /org/freedesktop/NetworkManager/Devices/1
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): No existing connection detected.
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): bringing up device.
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): link connected
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): device state change: unavailable -> disconnected (reason 'carrier-changed') [20 30 40]
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Auto-activating connection 'em1'.
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Activation (em1) starting connection 'em1'
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Dec 21 14:35:28 localhost NetworkManager[844]: <info> NetworkManager state is now CONNECTING
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 1 of 5 (Device Prepare) scheduled...
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 1 of 5 (Device Prepare) started...
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 2 of 5 (Device Configure) scheduled...
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 1 of 5 (Device Prepare) complete.
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 2 of 5 (Device Configure) starting...
Dec 21 14:35:28 localhost NetworkManager[844]: <info> (em1): device state change: prepare -> config (reason 'none') [40 50 0]
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 2 of 5 (Device Configure) successful.
Dec 21 14:35:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 2 of 5 (Device Configure) complete.
Dec 21 14:35:28 localhost NetworkManager[844]: <info> ModemManager available in the bus
Dec 21 14:35:31 localhost NetworkManager[844]: <info> Activation (em1) Stage 3 of 5 (IP Configure Start) scheduled.
Dec 21 14:35:31 localhost NetworkManager[844]: <info> Activation (em1) Stage 3 of 5 (IP Configure Start) started...
Dec 21 14:35:31 localhost NetworkManager[844]: <info> (em1): device state change: config -> ip-config (reason 'none') [50 70 0]
Dec 21 14:35:32 localhost NetworkManager[844]: <info> Activation (em1) Beginning DHCPv4 transaction (timeout in 45 seconds)
Dec 21 14:35:32 localhost NetworkManager[844]: <info> dhclient started with pid 1319
Dec 21 14:35:32 localhost NetworkManager[844]: <info> Activation (em1) Stage 3 of 5 (IP Configure Start) complete.
Dec 21 14:35:32 localhost NetworkManager[844]: <info> (em1): DHCPv4 state changed nbi -> preinit
Dec 21 14:35:32 localhost NetworkManager[844]: <info> (em1): DHCPv4 state changed preinit -> bound
[...]
Dec 21 14:35:32 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Dec 21 14:35:32 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv4 Commit) started...
Dec 21 14:35:32 localhost NetworkManager[844]: <info> (em1): device state change: ip-config -> ip-check (reason 'none') [70 80 0]
Dec 21 14:35:32 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv4 Commit) complete.
Dec 21 14:35:32 localhost NetworkManager[844]: <info> (em1): device state change: ip-check -> secondaries (reason 'none') [80 90 0]
Dec 21 14:35:32 localhost NetworkManager[844]: <info> (em1): device state change: secondaries -> activated (reason 'none') [90 100 0]
Dec 21 14:35:32 localhost NetworkManager[844]: <info> NetworkManager state is now CONNECTED_GLOBAL
Dec 21 14:35:32 localhost NetworkManager[844]: <info> Policy set 'em1' (em1) as default for IPv4 routing and DNS.
Dec 21 14:35:32 localhost NetworkManager[844]: <info> Activation (em1) successful, device activated.
Dec 21 14:35:32 localhost NetworkManager[844]: <info> startup complete
Dec 21 14:35:33 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:35:33 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:35:33 localhost NetworkManager[844]: <info> Policy set 'em1' (em1) as default for IPv6 routing and DNS.

# at that point, autoconfigured IPv6 addresses have been activated (lifetime = 300 s)

Dec 21 14:35:33 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:36:02 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:36:02 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:36:02 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:36:18 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:36:18 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:36:18 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:36:37 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:36:37 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:36:37 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:36:55 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:36:55 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:36:55 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:37:19 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:37:19 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:37:19 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:37:36 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:37:36 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:37:36 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:38:00 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:38:00 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:38:00 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:38:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:38:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:38:28 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:38:44 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:38:44 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:38:44 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:38:55 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:38:55 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:38:55 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:39:11 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:39:11 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:39:11 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:39:40 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:39:40 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:39:40 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:40:03 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:40:03 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:40:03 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:40:24 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:40:24 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:40:24 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:40:52 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:40:52 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:40:52 localhost NetworkManager[844]: nm_platform_ip6_address_add: assertion 'lifetime > 0' failed

# at this point, all autoconfigured IPv6 addresses have expired and have been withdrawn

Dec 21 14:40:52 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:41:03 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:41:03 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:41:03 localhost NetworkManager[844]: nm_platform_ip6_address_add: assertion 'lifetime > 0' failed
Dec 21 14:41:03 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) complete.
Dec 21 14:41:14 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) scheduled...
Dec 21 14:41:14 localhost NetworkManager[844]: <info> Activation (em1) Stage 5 of 5 (IPv6 Commit) started...
Dec 21 14:41:14 localhost NetworkManager[844]: nm_platform_ip6_address_add: assertion 'lifetime > 0' failed
[...]

Comment 1 bugzilla 2013-12-31 09:39:43 UTC
Can confirm this. Very annoying as it floods the messages - even an ignore on ipv6 in network manager does not stop that.

Comment 2 gr88gxp 2014-01-03 06:23:35 UTC
you might try 

systemctl enable NetworkManager-dispatcher.service

Comment 3 Seb L. 2014-01-04 18:05:50 UTC
NetworkManager-dispatcher.service is already enabled by default.

This is a bug in NetworkManager, which has been fixed by:
  https://bugzilla.redhat.com/show_bug.cgi?id=1044757#c13

Comment 4 Seb L. 2014-01-04 18:08:36 UTC

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


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