[Pkg-utopia-maintainers] Bug#755015: Bug#755015: network-manager: diff for NMU version 0.9.10.0-1.1

Mon Aug 18 13:33:38 UTC 2014

On Sat, Aug 16, 2014 at 08:28:56PM +0200, Michael Biebl wrote:

> > I've prepared an NMU for network-manager (versioned as 0.9.10.0-1.1) and
> > uploaded it to DELAYED/5-day. Please feel free to tell me if I
> > should delay it longer.
> 
> Are you sure the patch you applied actually fixes #755015?

For the record, I'm having the same issue as the original reporter (in
my case network-manager fiddling with an IPv6-in-IPv4 tunnel created by
isatapd) and upgrading to 0.9.10.0-1.1 does not fix it. When isatapd is
started and creates the is0 interface I get the following messages in
the syslog in a loop (dozen to hundred times)

Aug 18 15:26:08 client isatapd[1106]: isatapd[1106]: Link change detected. Re-creating tunnel.
Aug 18 15:26:08 client isatapd[1106]: isatapd[1106]: interface is0 down
Aug 18 15:26:08 client NetworkManager[30299]: <info> devices removed (path: /sys/devices/virtual/net/is0, iface: is0)
Aug 18 15:26:08 client isatapd[1106]: isatapd[1106]: interface is0 up
Aug 18 15:26:08 client isatapd[1106]: interface is0 up
Aug 18 15:26:08 client kernel: [23974.608152] IPv6: is0: Disabled Multicast RS
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): new Generic device (driver: 'sit' ifindex: 1515)
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): exported as /org/freedesktop/NetworkManager/Devices/338
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): found matching connection 'is0'
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): device state change: unmanaged -> unavailable (reason 'connection-assumed') [10 20 41]
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): device state change: unavailable -> disconnected (reason 'connection-assumed') [20 30 41]
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) starting connection 'is0'
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 1 of 5 (Device Prepare) scheduled...
Aug 18 15:26:08 client NetworkManager[30299]: <info> devices added (path: /sys/devices/virtual/net/is0, iface: is0)
Aug 18 15:26:08 client NetworkManager[30299]: <info> device added (path: /sys/devices/virtual/net/is0, iface: is0): no ifupdown configuration found.
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 1 of 5 (Device Prepare) started...
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 2 of 5 (Device Configure) scheduled...
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 1 of 5 (Device Prepare) complete.
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 2 of 5 (Device Configure) starting...
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): device state change: prepare -> config (reason 'none') [40 50 0]
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 2 of 5 (Device Configure) successful.
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 3 of 5 (IP Configure Start) scheduled.
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 2 of 5 (Device Configure) complete.
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 3 of 5 (IP Configure Start) started...
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): device state change: config -> ip-config (reason 'none') [50 70 0]
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): device state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
Aug 18 15:26:08 client NetworkManager[30299]: <info> Disabling autoconnect for connection 'is0'.
Aug 18 15:26:08 client NetworkManager[30299]: <warn> Activation (is0) failed for connection 'is0'
Aug 18 15:26:08 client NetworkManager[30299]: <info> Activation (is0) Stage 3 of 5 (IP Configure Start) complete.
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): deactivating device (reason 'none') [0]
Aug 18 15:26:08 client NetworkManager[30299]: <info> (is0): device state change: disconnected -> unmanaged (reason 'none') [30 10 0]
Aug 18 15:26:08 client kernel: [23974.624884] IPv6: ADDRCONF(NETDEV_UP): is0: link is not ready
Aug 18 15:26:09 client isatapd[1106]: isatapd[2832]: send_rdisc: Network is unreachable
Aug 18 15:26:09 client isatapd[2832]: send_rdisc: Network is unreachable
Aug 18 15:26:09 client isatapd[1106]: Link change detected. Re-creating tunnel.

root at client:~# LANG=C nmcli d
DEVICE             TYPE      STATE         CONNECTION 
eth0               ethernet  connected     eth0       
8C:77:12:81:DF:49  bt        disconnected  --         
cdc-wdm2           gsm       unavailable   --         
wlan0              wifi      unavailable   --         
lo                 loopback  unmanaged     --         
is0                sit       unmanaged     --         
sit0               sit       unmanaged     --         

At some point it suddenly starts working (timing? ISATAP devices get
their address through RA from the network, so maybe the ISATAP 
relay answers fast enough here)

Aug 18 15:26:36 client isatapd[1106]: isatapd[1106]: Link change detected. Re-creating tunnel.
Aug 18 15:26:36 client isatapd[1106]: isatapd[1106]: interface is0 down
Aug 18 15:26:36 client NetworkManager[30299]: <info> devices removed (path: /sys/devices/virtual/net/is0, iface: is0)
Aug 18 15:26:36 client kernel: [24002.588006] IPv6: is0: Disabled Multicast RS
Aug 18 15:26:36 client isatapd[1106]: isatapd[1106]: interface is0 up
Aug 18 15:26:36 client isatapd[1106]: interface is0 up
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): new Generic device (driver: 'sit' ifindex: 1578)
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): exported as /org/freedesktop/NetworkManager/Devices/401
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): found matching connection 'is0'
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): device state change: unmanaged -> unavailable (reason 'connection-assumed') [10 20 41]
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): device state change: unavailable -> disconnected (reason 'connection-assumed') [20 30 41]
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) starting connection 'is0'
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 1 of 5 (Device Prepare) scheduled...
Aug 18 15:26:36 client NetworkManager[30299]: <info> devices added (path: /sys/devices/virtual/net/is0, iface: is0)
Aug 18 15:26:36 client NetworkManager[30299]: <info> device added (path: /sys/devices/virtual/net/is0, iface: is0): no ifupdown configuration found.
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 1 of 5 (Device Prepare) started...
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 2 of 5 (Device Configure) scheduled...
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 1 of 5 (Device Prepare) complete.
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 2 of 5 (Device Configure) starting...
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): device state change: prepare -> config (reason 'none') [40 50 0]
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 2 of 5 (Device Configure) successful.
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 3 of 5 (IP Configure Start) scheduled.
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 2 of 5 (Device Configure) complete.
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 3 of 5 (IP Configure Start) started...
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): device state change: config -> ip-config (reason 'none') [50 70 0]
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 5 of 5 (IPv6 Commit) scheduled...
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 3 of 5 (IP Configure Start) complete.
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 5 of 5 (IPv6 Commit) started...
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): device state change: ip-config -> ip-check (reason 'none') [70 80 0]
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) Stage 5 of 5 (IPv6 Commit) complete.
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): device state change: ip-check -> secondaries (reason 'none') [80 90 0]
Aug 18 15:26:36 client NetworkManager[30299]: <info> (is0): device state change: secondaries -> activated (reason 'none') [90 100 0]
Aug 18 15:26:36 client NetworkManager[30299]: <info> Activation (is0) successful, device activated.
Aug 18 15:26:36 client dbus[765]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Aug 18 15:26:36 client systemd[1]: Starting Network Manager Script Dispatcher Service...
Aug 18 15:26:36 client dbus[765]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 18 15:26:36 client systemd[1]: Started Network Manager Script Dispatcher Service.
Aug 18 15:26:36 client nm-dispatcher: Dispatching action 'up' for is0

root at client:~# LANG=C nmcli d
DEVICE             TYPE      STATE         CONNECTION 
eth0               ethernet  connected     eth0       
is0                sit       connected     is0        
8C:77:12:81:DF:49  bt        disconnected  --         
cdc-wdm2           gsm       unavailable   --         
wlan0              wifi      unavailable   --         
lo                 loopback  unmanaged     --         
sit0               sit       unmanaged     --         

root at client:~# LANG=C ls -al /sys/devices/virtual/net/
total 0
drwxr-xr-x  9 root root 0 Aug 18 15:32 .
drwxr-xr-x 20 root root 0 Aug 18 15:32 ..
drwxr-xr-x  5 root root 0 Aug 18 15:32 is0
drwxr-xr-x  5 root root 0 Aug 18 15:32 lo
drwxr-xr-x  5 root root 0 Aug 18 15:32 sit0

Bernhard