NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) starting connection 'Vodafone Predefinito'
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 1 of 5 (Device Prepare) started...
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 2 of 5 (Device Configure) starting...
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 2 of 5 (Device Configure) complete.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8 94:20:53:D9:C8:C8/bluetooth) Stage 2 of 5 (Device Configure) successful.  Will connect via DUN.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8/bluetooth) Stage 2 of 5 (Device Configure) waiting for modem to appear.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8/bluetooth) Stage 2 of 5 (Device Configure) modem found.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 3 of 5 (IP Configure Start) started...
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: config -> ip-config (reason 'none') [50 70 0]
NetworkManager[15808]: <info> starting PPP connection
NetworkManager[15808]: <info> pppd started with pid 15943
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 3 of 5 (IP Configure Start) 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'
using channel 28
NetworkManager[15808]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
NetworkManager[15808]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Using interface ppp0
Connect: ppp0 <--> /dev/rfcomm0
** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x8e920015> <pcomp> <accomp>]
rcvd [LCP ConfRej id=0x1 <magic 0x8e920015> <pcomp> <accomp>]
sent [LCP ConfReq id=0x2 <asyncmap 0x0>]
rcvd [LCP ConfAck id=0x2 <asyncmap 0x0>]
rcvd [LCP ConfReq id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>]
sent [LCP ConfAck id=0x0 <auth pap> <mru 1500> <asyncmap 0xa0000>]
** Message: nm-ppp-plugin: (nm_phasechange): status 6 / phase 'authenticate'
** Message: nm-ppp-plugin: (get_credentials): passwd-hook, requesting credentials...
** Message: nm-ppp-plugin: (get_credentials): got credentials from NetworkManager
sent [PAP AuthReq id=0x1 user="" password=<hidden>]
sent [PAP AuthReq id=0x2 user="" password=<hidden>]
rcvd [PAP AuthAck id=0x2 ""]
PAP 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 [IPCP ConfReq id=0x0 <addr 10.6.6.6>]
sent [IPCP ConfAck id=0x0 <addr 10.6.6.6>]
rcvd [LCP ProtRej id=0x0 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
NetworkManager[15808]: <info> (wlan0): IP6 addrconf timed out or failed.
NetworkManager[15808]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) scheduled...
NetworkManager[15808]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) started...
NetworkManager[15808]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
NetworkManager[15808]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
NetworkManager[15808]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) complete.
rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
rcvd [IPCP ConfNak id=0x2 <addr 10.28.167.154> <ms-dns1 217.77.165.81> <ms-dns2 217.77.161.131>]
sent [IPCP ConfReq id=0x3 <addr 10.28.167.154> <ms-dns1 217.77.165.81> <ms-dns2 217.77.161.131>]
rcvd [IPCP ConfReq id=0x0 <addr 10.6.6.6>]
sent [IPCP ConfAck id=0x0 <addr 10.6.6.6>]
rcvd [IPCP ConfAck id=0x3 <addr 10.28.167.154> <ms-dns1 217.77.165.81> <ms-dns2 217.77.161.131>]
local  IP address 10.28.167.154
remote IP address 10.6.6.6
primary   DNS address 217.77.165.81
secondary DNS address 217.77.161.131
** 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...
Script /etc/ppp/ip-up started (pid 15946)
NetworkManager[15808]: <info> PPP manager(IP Config Get) reply received.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 4 of 5 (IP4 Configure Get) scheduled...
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 4 of 5 (IP4 Configure Get) started...
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 5 of 5 (IP Configure Commit) started...
Script /etc/ppp/ip-up finished (pid 15946), status = 0x0
NetworkManager[15808]: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
NetworkManager[15808]: <info> Clearing nscd hosts cache.
NetworkManager[15808]: <info> Policy set 'Auto Marie' (wlan0) as default for IPv4 routing and DNS.
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: ip-config -> activated (reason 'none') [70 100 0]
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) successful, device activated.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 5 of 5 (IP Configure Commit) complete.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 4 of 5 (IP4 Configure Get) complete.
NetworkManager[15808]: <info> (wlan0): device state change: activated -> unavailable (reason 'none') [100 20 0]
NetworkManager[15808]: <info> (wlan0): deactivating device (reason 'none') [0]
NetworkManager[15808]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 15827
NetworkManager[15808]: <info> (wlan0): writing resolv.conf to /sbin/resolvconf
NetworkManager[15808]: <info> Clearing nscd hosts cache.

(NetworkManager:15808): GLib-GObject-CRITICAL **: g_object_unref: assertion `G_IS_OBJECT (object)' failed
NetworkManager[15808]: <info> Policy set 'Vodafone Predefinito' (ppp0) as default for IPv4 routing and DNS.
NetworkManager[15808]: <info> Policy set 'Vodafone Predefinito' (ppp0) as default for IPv4 routing and DNS.
NetworkManager[15808]: <info> (wlan0): taking down device.
NetworkManager[15808]: <info> WiFi hardware radio set disabled
NetworkManager[15808]: <info> WiFi now disabled by radio killswitch
NetworkManager[15808]: <info> sleep requested (sleeping: no  enabled: yes)
NetworkManager[15808]: <info> sleeping or disabling...
NetworkManager[15808]: <info> (eth0): now unmanaged
NetworkManager[15808]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
NetworkManager[15808]: <info> (eth0): cleaning up...
NetworkManager[15808]: <info> (eth0): taking down device.
NetworkManager[15808]: <info> (wlan0): now unmanaged
NetworkManager[15808]: <info> (wlan0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
NetworkManager[15808]: <info> (wlan0): cleaning up...
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): now unmanaged
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): deactivating device (reason 'sleeping') [37]
Terminating on signal 15
** 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 83479 bytes, received 291257 bytes.
NetworkManager[15808]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
NetworkManager[15808]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Script /etc/ppp/ip-down started (pid 16197)
** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
sent [LCP TermReq id=0x3 "User request"]
Modem hangup
** Message: nm-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
Connection terminated.
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): removing resolv.conf from /sbin/resolvconf
NetworkManager[15808]: <info> Clearing nscd hosts cache.
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): cleaning up...
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): taking down device.
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): now unmanaged
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): device state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): cleaning up...
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): taking down device.
NetworkManager[15808]:    SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
NetworkManager[15808]: <info> disconnect failed: (32) The serial port is not open.
NetworkManager[15808]: <info> BT device 94:20:53:D9:C8:C8 removed
NetworkManager[15808]: <info> BT device A8:F2:74:07:48:88 removed
NetworkManager[15808]: <warn> (94:20:53:D9:C8:C8): failed to look up interface index
NetworkManager[15808]: <info> BT device Eudoxos (94:20:53:D9:C8:C8) added (DUN)
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): new Bluetooth device (driver: 'bluez' ifindex: 0)
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): exported as /org/freedesktop/NetworkManager/Devices/4
NetworkManager[15808]: <warn> (A8:F2:74:07:48:88): failed to look up interface index
NetworkManager[15808]: <info> BT device EudoxosTooth (A8:F2:74:07:48:88) added (DUN)
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): new Bluetooth device (driver: 'bluez' ifindex: 0)
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): exported as /org/freedesktop/NetworkManager/Devices/5
NetworkManager[15808]: <info> wake requested (sleeping: yes  enabled: yes)
NetworkManager[15808]: <info> waking up and re-enabling...
NetworkManager[15808]: <info> WWAN now enabled by management service
NetworkManager[15808]: <info> (eth0): now managed
NetworkManager[15808]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[15808]: <info> (eth0): bringing up device.
NetworkManager[15808]: <info> (eth0): preparing device.
NetworkManager[15808]: <info> (eth0): deactivating device (reason 'managed') [2]
NetworkManager[15808]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
NetworkManager[15808]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
NetworkManager[15808]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
NetworkManager[15808]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
NetworkManager[15808]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
NetworkManager[15808]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
NetworkManager[15808]: <info> (wlan0): now managed
NetworkManager[15808]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[15808]: <info> (wlan0): bringing up device.
NetworkManager[15808]: <info> (wlan0): deactivating device (reason 'managed') [2]
NetworkManager[15808]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
NetworkManager[15808]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): now managed
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): deactivating device (reason 'managed') [2]
NetworkManager[15808]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
NetworkManager[15808]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
NetworkManager[15808]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): now managed
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): deactivating device (reason 'managed') [2]
NetworkManager[15808]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
NetworkManager[15808]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
NetworkManager[15808]: <info> (A8:F2:74:07:48:88): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) starting connection 'Vodafone Predefinito'
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 1 of 5 (Device Prepare) started...
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 2 of 5 (Device Configure) starting...
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8) Stage 2 of 5 (Device Configure) complete.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8 94:20:53:D9:C8:C8/bluetooth) Stage 2 of 5 (Device Configure) successful.  Will connect via DUN.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8/bluetooth) Stage 2 of 5 (Device Configure) waiting for modem to appear.
NetworkManager[15808]: <info> Activation (94:20:53:D9:C8:C8/bluetooth) Stage 2 of 5 (Device Configure) modem found.
NetworkManager[15808]: <warn> GSM connection failed: (32) Unknown registration status response
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: config -> failed (reason 'unknown') [50 120 1]
NetworkManager[15808]: <warn> Activation (94:20:53:D9:C8:C8) failed.
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): device state change: failed -> disconnected (reason 'none') [120 30 0]
NetworkManager[15808]: <info> (94:20:53:D9:C8:C8): deactivating device (reason 'none') [0]
NetworkManager[15808]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
NetworkManager[15808]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed

