Bug#657495: : network-manager: Fails to achieve stable link when IPv6 enabled on

I'm seeing the same problem (same version on network-manager on sid), when
connecting to wifi networks with IPv6. The connection comes up for 2-3 seconds
and then is dropped, at which point the connection is restarted. IPv4 at least
is working for those few seconds.

Have reproduced on 2 different systems with very different hardware. Previously
both have worked fine connecting to the same network. Can connect fine for both IPv4 and
IPv6 by removing network-manager and using wicd.

Here is my syslog for a single iteration of the loop:

Feb 28 11:08:07 kelp NetworkManager[26059]: <info> Auto-activating connection 'Serenity'.
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> Activation (wlan0) starting connection 'Serenity'
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> Activation (wlan0/wireless): access point 'Serenity' has security, but secrets a
re required.
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Feb 28 11:08:07 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Activation (wlan0/wireless): connection 'Serenity' has security, and secrets exi
st. No new secrets needed.
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Config: added 'ssid' value 'Serenity'
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Config: added 'scan_ssid' value '1'
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Config: added 'psk' value '<omitted>'
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> Config: set interface ap_scan to 1
Feb 28 11:08:09 kelp kernel: [370098.791148] rtl8192c_common: Loading firmware file rtlwifi/rtl8192cfw.bin
Feb 28 11:08:09 kelp NetworkManager[26059]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Feb 28 11:08:10 kelp wpa_supplicant[24284]: Trying to authenticate with bc:05:43:f7:1e:92 (SSID='Serenity' freq$62 MHz)
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Feb 28 11:08:10 kelp kernel: [370099.986152] wlan0: authenticate with bc:05:43:f7:1e:92 (try 1)
Feb 28 11:08:10 kelp wpa_supplicant[24284]: Trying to associate with bc:05:43:f7:1e:92 (SSID='Serenity' freq$62 MHz)
Feb 28 11:08:10 kelp kernel: [370099.988400] wlan0: authenticated
Feb 28 11:08:10 kelp kernel: [370099.989358] wlan0: associate with bc:05:43:f7:1e:92 (try 1)
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> (wlan0): supplicant interface state: authenticating -> associating
Feb 28 11:08:10 kelp kernel: [370099.993262] wlan0: RX ReassocResp from bc:05:43:f7:1e:92 (capab=0x431 status=0 aid=2)
Feb 28 11:08:10 kelp kernel: [370099.993266] wlan0: associated
Feb 28 11:08:10 kelp kernel: [370099.993271] wlan0: No basic rates in AssocResp. Using min supported rate instead.
Feb 28 11:08:10 kelp wpa_supplicant[24284]: Associated with bc:05:43:f7:1e:92
Feb 28 11:08:10 kelp kernel: [370100.004163] cfg80211: Calling CRDA for country: DE
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
Feb 28 11:08:10 kelp kernel: [370100.008339] cfg80211: Regulatory domain changed to country: DE
Feb 28 11:08:10 kelp kernel: [370100.008343] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Feb 28 11:08:10 kelp kernel: [370100.008347] cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm)
Feb 28 11:08:10 kelp kernel: [370100.008350] cfg80211: (5150000 KHz - 5250000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Feb 28 11:08:10 kelp kernel: [370100.008353] cfg80211: (5250000 KHz - 5350000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Feb 28 11:08:10 kelp kernel: [370100.008356] cfg80211: (5470000 KHz - 5725000 KHz @ 40000 KHz), (N/A, 2698 mBm)
Feb 28 11:08:10 kelp wpa_supplicant[24284]: WPA: Key negotiation completed with bc:05:43:f7:1e:92 [PTKÌMP GTK=TKIP]
Feb 28 11:08:10 kelp wpa_supplicant[24284]: CTRL-EVENT-CONNECTED - Connection to bc:05:43:f7:1e:92 completed (reauth) [id=0 id_str=]
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'Serenity'.
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> dhclient started with pid 30477
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> Activation (wlan0) Beginning IP6 addrconf.
Feb 28 11:08:10 kelp avahi-daemon[1521]: Withdrawing address record for fe80::6aa3:c4ff:feed:347e on wlan0.
Feb 28 11:08:10 kelp avahi-daemon[1521]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::6aa3:c4ff:feed:347e.
Feb 28 11:08:10 kelp avahi-daemon[1521]: Interface wlan0.IPv6 no longer relevant for mDNS.
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Feb 28 11:08:10 kelp dhclient: Internet Systems Consortium DHCP Client 4.2.2
Feb 28 11:08:10 kelp dhclient: Copyright 2004-2011 Internet Systems Consortium.
Feb 28 11:08:10 kelp dhclient: All rights reserved.
Feb 28 11:08:10 kelp dhclient: For info, please visit https://www.isc.org/software/dhcp/
Feb 28 11:08:10 kelp dhclient:
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Feb 28 11:08:10 kelp dhclient: Listening on LPF/wlan0/68:a3:c4:ed:34:7e
Feb 28 11:08:10 kelp dhclient: Sending on LPF/wlan0/68:a3:c4:ed:34:7e
Feb 28 11:08:10 kelp dhclient: Sending on Socket/fallback
Feb 28 11:08:10 kelp dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Feb 28 11:08:10 kelp dhclient: DHCPACK from 192.168.178.1
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> (wlan0): DHCPv4 state changed preinit -> reboot
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> address 192.168.178.26
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> prefix 24 (255.255.255.0)
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> gateway 192.168.178.1
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> nameserver '192.168.178.1'
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> domain name 'fritz.box'
Feb 28 11:08:10 kelp dhclient: bound to 192.168.178.26 -- renewal in 383862 seconds.
Feb 28 11:08:10 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
Feb 28 11:08:12 kelp avahi-daemon[1521]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::6aa3:c4ff:feed:347e.
Feb 28 11:08:12 kelp avahi-daemon[1521]: New relevant interface wlan0.IPv6 for mDNS.
Feb 28 11:08:12 kelp avahi-daemon[1521]: Registering new address record for fe80::6aa3:c4ff:feed:347e on wlan0.*.
Feb 28 11:08:14 kelp avahi-daemon[1521]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::6aa3:c4ff:feed:347e.
Feb 28 11:08:14 kelp avahi-daemon[1521]: Joining mDNS multicast group on interface wlan0.IPv6 with address 2001:44b8:31b5:ee00:6aa3:c4ff:feed:347e.
Feb 28 11:08:14 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) starting DHCPv6 as requested by IPv6 router...
Feb 28 11:08:14 kelp avahi-daemon[1521]: Registering new address record for 2001:44b8:31b5:ee00:6aa3:c4ff:feed:347e on wlan0.*.
Feb 28 11:08:14 kelp avahi-daemon[1521]: Withdrawing address record for fe80::6aa3:c4ff:feed:347e on wlan0.
Feb 28 11:08:14 kelp NetworkManager[26059]: <warn> Failed to add route Object not found
Feb 28 11:08:14 kelp NetworkManager[26059]: <error> [1330387694.652356] [nm-device.c:1692] dhcp6_start(): (wlan0): failed to add IPv6 multicast route: Object not found
Feb 28 11:08:14 kelp NetworkManager[26059]: <info> Activation (wlan0) Beginning DHCPv6 transaction (timeout in 45 seconds)
Feb 28 11:08:14 kelp NetworkManager[26059]: <info> dhclient started with pid 30521
Feb 28 11:08:14 kelp dhclient: Internet Systems Consortium DHCP Client 4.2.2
Feb 28 11:08:14 kelp dhclient: Copyright 2004-2011 Internet Systems Consortium.
Feb 28 11:08:14 kelp dhclient: All rights reserved.
Feb 28 11:08:14 kelp dhclient: For info, please visit https://www.isc.org/software/dhcp/
Feb 28 11:08:14 kelp dhclient:
Feb 28 11:08:14 kelp dhclient: Bound to *:546
eb 28 11:08:14 kelp dhclient: Listening on Socket/wlan0
Feb 28 11:08:14 kelp dhclient: Sending on Socket/wlan0
Feb 28 11:08:15 kelp dhclient: XMT: Info-Request on wlan0, interval 1010ms.
Feb 28 11:08:15 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled...
Feb 28 11:08:15 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started...
Feb 28 11:08:15 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
Feb 28 11:08:15 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete.
Feb 28 11:08:15 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Feb 28 11:08:15 kelp avahi-daemon[1521]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.26.
Feb 28 11:08:15 kelp avahi-daemon[1521]: New relevant interface wlan0.IPv4 for mDNS.
Feb 28 11:08:15 kelp avahi-daemon[1521]: Registering new address record for 192.168.178.26 on wlan0.IPv4.
Feb 28 11:08:15 kelp dhclient: RCV: Reply message on wlan0 from fe80::be05:43ff:fef7:1e8c.
Feb 28 11:08:15 kelp NetworkManager[26059]: <info> Clearing nscd hosts cache.
Feb 28 11:08:15 kelp NetworkManager[26059]: <info> Policy set 'Wired connection 1' (eth1) as default for IPv4 routing and DNS.
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> Clearing nscd hosts cache.
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> Policy set 'Wired connection 1' (eth1) as default for IPv4 routing and DNS.
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Feb 28 11:08:16 kelp NetworkManager[26059]: <warn> Failed to add route Missing attribute
Feb 28 11:08:16 kelp NetworkManager[26059]: <error> [1330387696.573267] [nm-system.c:1061] nm_system_replace_default_ip6_route(): (wlan0): failed to set IPv6 default route: -1
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> Clearing nscd hosts cache.
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> Policy set 'Serenity' (wlan0) as default for IPv6 routing and DNS.
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> Activation (wlan0) successful, device activated.
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> (wlan0): DHCPv6 client pid 30521 exited with status 0
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> (wlan0): DHCPv6 state changed end -> renew6
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> nameserver 'fd00::be05:43ff:fef7:1e8c'
Feb 28 11:08:16 kelp NetworkManager[26059]: <info> Clearing nscd hosts cache.
Feb 28 11:08:16 kelp avahi-daemon[1521]: Withdrawing address record for 2001:44b8:31b5:ee00:6aa3:c4ff:feed:347e on wlan0.
Feb 28 11:08:16 kelp avahi-daemon[1521]: Leaving mDNS multicast group on interface wlan0.IPv6 with address 2001:44b8:31b5:ee00:6aa3:c4ff:feed:347e.
Feb 28 11:08:16 kelp avahi-daemon[1521]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::6aa3:c4ff:feed:347e.
Feb 28 11:08:16 kelp avahi-daemon[1521]: Registering new address record for fe80::6aa3:c4ff:feed:347e on wlan0.*.
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> Clearing nscd hosts cache.
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> Policy set 'Wired connection 1' (eth1) as default for IPv4 routing and DNS.
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> (wlan0): device state change: activated -> failed (reason 'ip-config-unavailable') [100 120 5]
Feb 28 11:08:17 kelp NetworkManager[26059]: <warn> Activation (wlan0) failed for access point (Serenity)
Feb 28 11:08:17 kelp NetworkManager[26059]: <warn> Activation (wlan0) failed.
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> (wlan0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> (wlan0): deactivating device (reason 'none') [0]
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 30477
Feb 28 11:08:17 kelp kernel: [370106.933992] wlan0: deauthenticating from bc:05:43:f7:1e:92 by local choice (reason=3)
Feb 28 11:08:17 kelp avahi-daemon[1521]: Withdrawing address record for 192.168.178.26 on wlan0.
Feb 28 11:08:17 kelp avahi-daemon[1521]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.26.
Feb 28 11:08:17 kelp wpa_supplicant[24284]: CTRL-EVENT-DISCONNECTED bssid:00:00:00:00:00 reason=3
Feb 28 11:08:17 kelp avahi-daemon[1521]: Interface wlan0.IPv4 no longer relevant for mDNS.
Feb 28 11:08:17 kelp kernel: [370107.085267] cfg80211: Calling CRDA to update world regulatory domain
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> Clearing nscd hosts cache.
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> Policy set 'Wired connection 1' (eth1) as default for IPv4 routing and DNS.
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> Clearing nscd hosts cache.
Feb 28 11:08:17 kelp kernel: [370107.092411] cfg80211: World regulatory domain updated:
Feb 28 11:08:17 kelp kernel: [370107.092416] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Feb 28 11:08:17 kelp kernel: [370107.092421] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Feb 28 11:08:17 kelp kernel: [370107.092426] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Feb 28 11:08:17 kelp kernel: [370107.092430] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Feb 28 11:08:17 kelp kernel: [370107.092434] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Feb 28 11:08:17 kelp kernel: [370107.092438] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> Policy set 'Wired connection 1' (eth1) as default for IPv4 routing and DNS.
Feb 28 11:08:17 kelp NetworkManager[26059]: <info> (wlan0): supplicant interface state: completed -> disconnected
Feb 28 11:08:20 kelp NetworkManager[26059]: <info> Auto-activating connection 'Serenity'.
Feb 28 11:08:20 kelp NetworkManager[26059]: <info> Activation (wlan0) starting connection 'Serenity'

(and loops, ad-infinitum)

HTH,

David



To UNSUBSCRIBE, email to debian-bugs-dist-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org