[Pkg-utopia-maintainers] Bug#702331: network-manager: does not apply dispatcher scripts for ppp0 down when switching mobile broadband to off
Phil Reynolds
phil-debian at tinsleyviaduct.com
Tue Mar 5 10:16:40 UTC 2013
Package: network-manager
Version: 0.9.4.0-10
Severity: normal
Tags: ipv6
Dear Maintainer,
network-manager manages the 3G connection on my system (a Lenovo ThinkPad
X131e) very well, but for one thing - the only way I have found to hang up
the connection is to set "Mobile Broadband" to "OFF" in the network-manager
menu. This is not normally a problem, but my provider have a 6in4 tunnel
I can use with this connection, and this bug relates to it hence the "ipv6"
tag.
In order to stop the 6in4 tunnel interfering with connections to wireless
networks, I have added a script to /etc/NetworkManager/dispatcher.d to
bring the tunnel up and down with the ppp0 interface. It is brought up
correctly. but not taken down. This is either because I am not doing the
hangup in the recommended manner, or because setting "Mobile Broadband"
to "OFF" does not trigger the ppp0 down event when perhaps it should.
Is this something I can correct/workaround, or is it actually something
that needs correcting in the package?
I attach my dispatcher script and the logs from both modem-manager and
network-manager.
-- System Information:
Debian Release: 7.0
APT prefers testing
APT policy: (500, 'testing')
Architecture: amd64 (x86_64)
Foreign Architectures: i386
Kernel: Linux 3.2.0-4-amd64 (SMP w/2 CPU cores)
Locale: LANG=en_GB.UTF-8, LC_CTYPE=en_GB.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.8-1
ii dpkg 1.16.9
ii isc-dhcp-client 4.2.2.dfsg.1-5+deb70u2
ii libc6 2.13-38
ii libdbus-1-3 1.6.8-1
ii libdbus-glib-1-2 0.100.1-1
ii libgcrypt11 1.5.0-3
ii libglib2.0-0 2.33.12+really2.32.4-5
ii libgnutls26 2.12.20-4
ii libgudev-1.0-0 175-7.1
ii libnl-3-200 3.2.7-4
ii libnl-genl-3-200 3.2.7-4
ii libnl-route-3-200 3.2.7-4
ii libnm-glib4 0.9.4.0-10
ii libnm-util2 0.9.4.0-10
ii libpolkit-gobject-1-0 0.105-3
ii libuuid1 2.20.1-5.3
ii lsb-base 4.1+Debian8
ii udev 175-7.1
ii wpasupplicant 1.0-3+b2
Versions of packages network-manager recommends:
ii crda 1.1.2-1
ii dnsmasq-base 2.62-3+deb7u1
ii iptables 1.4.14-3
ii modemmanager 0.5.2.0-2
ii policykit-1 0.105-3
ii ppp 2.4.5-5.1+b1
Versions of packages network-manager suggests:
ii avahi-autoipd 0.6.31-1
-- Configuration Files:
/etc/NetworkManager/NetworkManager.conf changed:
[main]
plugins=ifupdown,keyfile
[ifupdown]
managed=false
[logging]
level=INFO
/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'
-- debconf-show failed
-------------- next part --------------
A non-text attachment was scrubbed...
Name: 99smart6in4.sh
Type: text/x-shellscript
Size: 164 bytes
Desc: not available
URL: <http://lists.alioth.debian.org/pipermail/pkg-utopia-maintainers/attachments/20130305/40e9e90c/attachment-0002.bin>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: mm.log
Type: text/x-c
Size: 39834 bytes
Desc: not available
URL: <http://lists.alioth.debian.org/pipermail/pkg-utopia-maintainers/attachments/20130305/40e9e90c/attachment-0003.bin>
-------------- next part --------------
Script started on Tue 05 Mar 2013 09:55:07 GMT
[1m[7m#[27m[1m[0m
[0m[27m[24m[J[1m[0m[4mwincobank[24m# [K[58C[32m/home/phil[39m[68DNNM_PPP_DEBUG=1 Not etworkManager[1m [0m[0m --no-daemon
NetworkManager[6125]: <info> NetworkManager (version 0.9.4.0) is starting...
NetworkManager[6125]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
NetworkManager[6125]: SCPlugin-Ifupdown: init!
NetworkManager[6125]: SCPlugin-Ifupdown: update_system_hostname
NetworkManager[6125]: SCPluginIfupdown: guessed connection type (6in4) = 802-3-ethernet
NetworkManager[6125]: SCPlugin-Ifupdown: update_connection_setting_from_if_block: name:6in4, type:802-3-ethernet, id:Ifupdown (6in4), uuid: e07d0ea6-2a24-18ac-74ef-ab51f60ee44c
NetworkManager[6125]: SCPlugin-Ifupdown: addresses count: 1
NetworkManager[6125]: SCPlugin-Ifupdown: No dns-nameserver configured in /etc/network/interfaces
NetworkManager[6125]: SCPlugin-Ifupdown: adding 6in4 to iface_connections
NetworkManager[6125]: SCPlugin-Ifupdown: adding iface 6in4 to well_known_interfaces
NetworkManager[6125]: SCPluginIfupdown: management mode: unmanaged
NetworkManager[6125]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:05.0/0000:02:00.0/net/wlan0, iface: wlan0)
NetworkManager[6125]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:05.0/0000:02:00.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
NetworkManager[6125]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:06.0/0000:03:00.0/net/eth0, iface: eth0)
NetworkManager[6125]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:06.0/0000:03:00.0/net/eth0, iface: eth0): no ifupdown configuration found.
NetworkManager[6125]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:13.2/usb4/4-2/4-2:1.6/net/usb0, iface: usb0)
NetworkManager[6125]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:13.2/usb4/4-2/4-2:1.6/net/usb0, iface: usb0): no ifupdown configuration found.
NetworkManager[6125]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/lo, iface: lo)
NetworkManager[6125]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
NetworkManager[6125]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/sit0, iface: sit0)
NetworkManager[6125]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/sit0, iface: sit0): no ifupdown configuration found.
NetworkManager[6125]: SCPlugin-Ifupdown: end _init.
NetworkManager[6125]: <info> Loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the NetworkManager mailing list.
NetworkManager[6125]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
NetworkManager[6125]: Ifupdown: get unmanaged devices count: 0
NetworkManager[6125]: SCPlugin-Ifupdown: (34320544) ... get_connections.
NetworkManager[6125]: SCPlugin-Ifupdown: (34320544) ... get_connections (managed=false): return empty list.
NetworkManager[6125]: keyfile: parsing Auto virginmedia2023012 ...
NetworkManager[6125]: keyfile: read connection 'Auto virginmedia2023012'
NetworkManager[6125]: keyfile: parsing tinsleymobile (Vodafone) ...
NetworkManager[6125]: keyfile: read connection 'tinsleymobile (Vodafone)'
NetworkManager[6125]: keyfile: parsing AAISP.NET at Home ...
NetworkManager[6125]: keyfile: read connection 'AAISP.NET at Home'
NetworkManager[6125]: keyfile: parsing BT at James' ...
NetworkManager[6125]: keyfile: read connection 'BT at James''
NetworkManager[6125]: keyfile: parsing 3 Internet ...
NetworkManager[6125]: keyfile: read connection '3 Internet'
NetworkManager[6125]: Ifupdown: get unmanaged devices count: 0
NetworkManager[6125]: <info> modem-manager is now available
NetworkManager[6125]: <info> monitoring kernel firmware directory '/lib/firmware'.
NetworkManager[6125]: <info> monitoring ifupdown state file '/run/network/ifstate'.
NetworkManager[6125]: <info> found WiFi radio killswitch rfkill3 (at /sys/devices/pci0000:00/0000:00:05.0/0000:02:00.0/ieee80211/phy0/rfkill3) (driver (unknown))
NetworkManager[6125]: <info> found WWAN radio killswitch rfkill2 (at /sys/devices/platform/thinkpad_acpi/rfkill/rfkill2) (driver thinkpad_acpi)
NetworkManager[6125]: <info> WiFi disabled by radio killswitch; disabled by state file
NetworkManager[6125]: <info> WWAN enabled by radio killswitch; disabled by state file
NetworkManager[6125]: <info> WiMAX enabled by radio killswitch; enabled by state file
NetworkManager[6125]: <info> Networking is enabled by state file
NetworkManager[6125]: <info> (wlan0): using nl80211 for WiFi device control
NetworkManager[6125]: <error> [1362477341.992320] [nm-device-wifi.c:2590] real_update_permanent_hw_address(): (wlan0): unable to read permanent MAC address (error 0)
NetworkManager[6125]: <info> (wlan0): new 802.11 WiFi device (driver: 'wl' ifindex: 4)
NetworkManager[6125]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/0
NetworkManager[6125]: <info> (wlan0): now managed
NetworkManager[6125]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[6125]: <info> (wlan0): bringing up device.
NetworkManager[6125]: <info> (wlan0): deactivating device (reason 'managed') [2]
NetworkManager[6125]: <warn> failed to allocate link cache: (-10) Operation not supported
NetworkManager[6125]: <info> (eth0): carrier is OFF
NetworkManager[6125]: <info> (eth0): new Ethernet device (driver: 'r8169' ifindex: 2)
NetworkManager[6125]: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/1
NetworkManager[6125]: <info> (eth0): now managed
NetworkManager[6125]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[6125]: <info> (eth0): bringing up device.
NetworkManager[6125]: <info> (eth0): preparing device.
NetworkManager[6125]: <info> (eth0): deactivating device (reason 'managed') [2]
NetworkManager[6125]: <info> Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:06.0/0000:03:00.0/net/eth0
NetworkManager[6125]: <warn> failed to allocate link cache: (-10) Operation not supported
NetworkManager[6125]: <info> (usb0): carrier is OFF
NetworkManager[6125]: <error> [1362477342.143445] [nm-device-ethernet.c:456] real_update_permanent_hw_address(): (usb0): unable to read permanent MAC address (error 0)
NetworkManager[6125]: <info> (usb0): new Ethernet device (driver: 'cdc_ncm' ifindex: 3)
NetworkManager[6125]: <info> (usb0): exported as /org/freedesktop/NetworkManager/Devices/2
NetworkManager[6125]: <info> (usb0): now managed
NetworkManager[6125]: <info> (usb0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[6125]: <info> (usb0): bringing up device.
NetworkManager[6125]: <info> (usb0): preparing device.
NetworkManager[6125]: <info> (usb0): deactivating device (reason 'managed') [2]
NetworkManager[6125]: <info> Added default wired connection 'Wired connection 2' for /sys/devices/pci0000:00/0000:00:13.2/usb4/4-2/4-2:1.6/net/usb0
/sbin/ifup: interface lo already configured
NetworkManager[6125]: <warn> (ttyACM0): failed to look up interface index
NetworkManager[6125]: <info> (ttyACM0): new GSM/UMTS device (driver: 'cdc_acm' ifindex: 0)
NetworkManager[6125]: <info> (ttyACM0): exported as /org/freedesktop/NetworkManager/Devices/3
NetworkManager[6125]: <info> (ttyACM0): now managed
NetworkManager[6125]: <info> (ttyACM0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[6125]: <info> (ttyACM0): deactivating device (reason 'managed') [2]
NetworkManager[6125]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
NetworkManager[6125]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
NetworkManager[6125]: <info> (ttyACM0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
NetworkManager[6125]: <info> WWAN now enabled by management service
NetworkManager[6125]: <info> Activation (ttyACM0) starting connection '3 Internet'
NetworkManager[6125]: <info> (ttyACM0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[6125]: <info> (ttyACM0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 2 of 5 (Device Configure) successful.
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 2 of 5 (Device Configure) complete.
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager[6125]: <info> (ttyACM0): device state change: config -> ip-config (reason 'none') [50 70 0]
NetworkManager[6125]: <info> starting PPP connection
NetworkManager[6125]: <info> pppd started with pid 6141
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 4 of 5 (IPv6 Configure Timeout) started...
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Plugin /usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
** Message: nm-ppp-plugin: (plugin_init): initializing
** Message: nm-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
Removed stale lock on ttyACM0 (pid 5740)
using channel 2
Using interface ppp0
Connect: ppp0 <--> /dev/ttyACM0
** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x2dc061e8> <pcomp> <accomp>]
rcvd [LCP ConfReq id=0x2 <auth chap MD5> <accomp> <pcomp> <mru 1500> <asyncmap 0x0> <magic 0x49e38044>]
sent [LCP ConfAck id=0x2 <auth chap MD5> <accomp> <pcomp> <mru 1500> <asyncmap 0x0> <magic 0x49e38044>]
rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x2dc061e8> <pcomp> <accomp>]
sent [LCP EchoReq id=0x0 magic=0x2dc061e8]
** Message: nm-ppp-plugin: (nm_phasechange): status 6 / phase 'authenticate'
NetworkManager[6125]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
NetworkManager[6125]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
rcvd [LCP EchoRep id=0x0 magic=0x49e38044]
rcvd [CHAP Challenge id=0x0 <54fdf243c0f93e9fecb54abbd8311697846da233f069ee8f1c25faab08a1c687b4dd522320d99e>, name = "Kermit"]
** Message: nm-ppp-plugin: (get_credentials): passwd-hook, requesting credentials...
** Message: nm-ppp-plugin: (get_credentials): got credentials from NetworkManager
sent [CHAP Response id=0x0 <58bc32a2b274a0cfe5240d2bf66f9612>, name = ""]
rcvd [CHAP Success id=0x0 "Congratulations!"]
CHAP authentication succeeded: Congratulations!
CHAP authentication succeeded
** Message: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
rcvd [LCP ProtRej id=0x1 80 fd 01 01 00 0f 1a 04 78 00 18 04 78 00 15 03 2f]
Protocol-Reject for 'Compression Control Protocol' (0x80fd) received
sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
rcvd [IPCP ConfReq id=0x1]
sent [IPCP ConfNak id=0x1 <addr 0.0.0.0>]
rcvd [IPCP ConfNak id=0x1 <addr 81.2.123.4> <ms-dns1 217.169.20.20> <ms-dns2 217.169.20.21>]
sent [IPCP ConfReq id=0x2 <compress VJ 0f 01> <addr 81.2.123.4> <ms-dns1 217.169.20.20> <ms-dns2 217.169.20.21>]
rcvd [IPCP ConfReq id=0x2]
sent [IPCP ConfAck id=0x2]
rcvd [IPCP ConfAck id=0x2 <compress VJ 0f 01> <addr 81.2.123.4> <ms-dns1 217.169.20.20> <ms-dns2 217.169.20.21>]
Could not determine remote IP address: defaulting to 10.64.64.64
local IP address 81.2.123.4
remote IP address 10.64.64.64
primary DNS address 217.169.20.20
secondary DNS address 217.169.20.21
** Message: nm-ppp-plugin: (nm_phasechange): status 9 / phase 'running'
** Message: nm-ppp-plugin: (nm_ip_up): ip-up event
** Message: nm-ppp-plugin: (nm_ip_up): sending Ip4Config to NetworkManager...
NetworkManager[6125]: <info> PPP manager(IP Config Get) reply received.
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 5 of 5 (IPv4 Commit) started...
Script /etc/ppp/ip-up started (pid 6150)
Script /etc/ppp/ip-up finished (pid 6150), status = 0x0
NetworkManager[6125]: <info> (ttyACM0): device state change: ip-config -> activated (reason 'none') [70 100 0]
NetworkManager[6125]: <info> Policy set '3 Internet' (ppp0) as default for IPv4 routing and DNS.
NetworkManager[6125]: <info> Activation (ttyACM0) successful, device activated.
NetworkManager[6125]: <info> Activation (ttyACM0) Stage 5 of 5 (IPv4 Commit) complete.
NetworkManager[6125]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/6in4, iface: 6in4)
NetworkManager[6125]: SCPluginIfupdown: failed to parse MAC address '00:00:00:00' for 6in4
NetworkManager[6125]: Ifupdown: get unmanaged devices count: 1
NetworkManager[6125]: <info> (ttyACM0): device state change: activated -> disconnected (reason 'none') [100 30 0]
NetworkManager[6125]: <info> (ttyACM0): deactivating device (reason 'none') [0]
NetworkManager[6125]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Terminating on signal 15
NetworkManager[6125]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
** Message: nm-ppp-plugin: (nm_phasechange): status 10 / phase 'terminate'
** Message: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
Connect time 1.0 minutes.
Sent 14689 bytes, received 10882 bytes.
Script /etc/ppp/ip-down started (pid 6373)
** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
sent [LCP TermReq id=0x2 "User request"]
rcvd [LCP TermAck id=0x2]
** Message: nm-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
Connection terminated.
NetworkManager[6125]: <info> WWAN now disabled by management service
NetworkManager[6125]: <info> disconnect failed: (32) The device is already being disconnected.
NetworkManager[6125]: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
** Message: nm-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
Script /etc/ppp/ip-down finished (pid 6373), status = 0x0
** Message: nm-ppp-plugin: (nm_exit_notify): cleaning up
^CNetworkManager[6125]: <info> caught signal 2, shutting down normally.
NetworkManager[6125]: <warn> quit request received, terminating...
NetworkManager[6125]: <info> (wlan0): now unmanaged
NetworkManager[6125]: <info> (wlan0): device state change: unavailable -> unmanaged (reason 'removed') [20 10 36]
NetworkManager[6125]: <info> (eth0): now unmanaged
NetworkManager[6125]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'removed') [20 10 36]
NetworkManager[6125]: <info> (eth0): cleaning up...
NetworkManager[6125]: <info> (eth0): taking down device.
NetworkManager[6125]: <info> (usb0): now unmanaged
NetworkManager[6125]: <info> (usb0): device state change: unavailable -> unmanaged (reason 'removed') [20 10 36]
NetworkManager[6125]: <info> (usb0): cleaning up...
NetworkManager[6125]: <info> (usb0): taking down device.
NetworkManager[6125]: <info> (ttyACM0): now unmanaged
NetworkManager[6125]: <info> (ttyACM0): device state change: disconnected -> unmanaged (reason 'removed') [30 10 36]
NetworkManager[6125]: <info> (ttyACM0): cleaning up...
NetworkManager[6125]: <info> (ttyACM0): taking down device.
NetworkManager[6125]: <info> exiting (success)
[1m[7m#[27m[1m[0m
[0m[27m[24m[J[1m[0m[4mwincobank[24m# [K[58C[32m/home/phil[39m[68D
Script done on Tue 05 Mar 2013 09:57:52 GMT
More information about the Pkg-utopia-maintainers
mailing list