Bug 91242 - GOBI1000 segfault when connecting [without password].
Summary: GOBI1000 segfault when connecting [without password].
Status: RESOLVED MOVED
Alias: None
Product: ModemManager
Classification: Unclassified
Component: general (show other bugs)
Version: 1.4
Hardware: x86-64 (AMD64) Linux (All)
: medium normal
Assignee: ModemManager bug user
QA Contact:
URL:
Whiteboard:
Keywords:
Depends on:
Blocks:
 
Reported: 2015-07-06 14:38 UTC by tkteun
Modified: 2018-06-10 08:59 UTC (History)
1 user (show)

See Also:
i915 platform:
i915 features:


Attachments
Debug script of ModemManager (125.92 KB, text/plain)
2015-07-06 17:51 UTC, tkteun
Details
Debug script of NetworkManager (56.75 KB, text/plain)
2015-07-06 17:52 UTC, tkteun
Details

Description tkteun 2015-07-06 14:38:00 UTC
I have an HP EliteBook 2540p which is locked to GOBI1000 WWAN cards. I have loaded the Windows drivers and verified it works.

When opening on Linux Mint Debian 2, it does register on the network, but as soon as I try connecting it disappears. I have attached an extract from the syslog below.

IMHO, it seems this card's older QMI versions cause modemmanager to crash and the connection attempt to fail.

After several tries, the card stops responding or resets (asking for the SIM PIN again).

---

Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 16:03:49 tk-2540p ModemManager[4215]: <info>  Simple connect started...
Jul  6 16:03:49 tk-2540p ModemManager[4215]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 16:03:49 tk-2540p ModemManager[4215]: <info>  Simple connect state (5/8): Register
Jul  6 16:03:49 tk-2540p ModemManager[4215]: <info>  Simple connect state (6/8): Bearer
Jul  6 16:03:49 tk-2540p ModemManager[4215]: <info>  Simple connect state (7/8): Connect
Jul  6 16:03:49 tk-2540p ModemManager[4215]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 16:03:49 tk-2540p ModemManager[4215]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 16:03:49 tk-2540p ModemManager[4215]: [/dev/cdc-wdm0] Registered 'wds' (version 1.1) client with ID '2'
Jul  6 16:03:49 tk-2540p ModemManager[4215]: qmi_message_add_raw_tlv: assertion 'length > 0' failed
Jul  6 16:03:49 tk-2540p ModemManager[4215]: qmi_message_wds_start_network_output_ref: assertion 'self != NULL' failed
Jul  6 16:03:49 tk-2540p ModemManager[4215]: qmi_message_wds_start_network_output_get_packet_data_handle: assertion 'self != NULL' failed
Jul  6 16:03:49 tk-2540p kernel: [ 1139.643865] ModemManager[4215]: segfault at 0 ip 000000000043b1c3 sp 00007ffef0359928 error 6 in ModemManager[400000+104000]
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> ModemManager disappeared from bus
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): device state change: prepare -> unmanaged (reason 'removed') [40 10 36]
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): deactivating device (reason 'removed') [36]
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <warn> (cdc-wdm0) failed to connect modem: Message did not receive a reply (timeout by message bus)
Jul  6 16:03:49 tk-2540p dbus[2269]: [system] Activating service name='org.freedesktop.ModemManager1' (using servicehelper)
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <warn> (cdc-wdm0) failed to disconnect modem: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.67 was not provided by any .service files
Jul  6 16:03:49 tk-2540p ModemManager[4557]: <info>  ModemManager (version 1.4.0) starting in system bus...
Jul  6 16:03:49 tk-2540p dbus[2269]: [system] Successfully activated service 'org.freedesktop.ModemManager1'
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> ModemManager disappeared from bus
Jul  6 16:03:49 tk-2540p NetworkManager[2556]: <info> ModemManager available in the bus
Jul  6 16:03:49 tk-2540p ModemManager[4557]: <warn>  (ttyUSB0): port attributes not fully set
Jul  6 16:03:49 tk-2540p ModemManager[4557]: <warn>  (ttyUSB1): port attributes not fully set
Jul  6 16:03:49 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Jul  6 16:03:49 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Jul  6 16:03:49 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] QMI Device supports 6 services:
Jul  6 16:03:49 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    ctl (1.0)
Jul  6 16:03:49 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    wds (1.1)
Jul  6 16:03:49 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    dms (1.1)
Jul  6 16:03:49 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    nas (1.0)
Jul  6 16:03:49 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    wms (1.0)
Jul  6 16:03:49 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    pds (1.0)
Jul  6 16:03:52 tk-2540p ModemManager[4557]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:19.0': not supported by any plugin
Jul  6 16:03:52 tk-2540p ModemManager[4557]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.3/0000:43:00.0': not supported by any plugin
Jul  6 16:03:52 tk-2540p ModemManager[4557]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2': not supported by any plugin
Jul  6 16:03:59 tk-2540p ModemManager[4557]: <info>  Creating modem with plugin 'Gobi' and '4' ports
Jul  6 16:03:59 tk-2540p ModemManager[4557]: <info>  Modem for device at '/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2' successfully created
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] QMI Device supports 6 services:
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    ctl (1.0)
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    wds (1.1)
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    dms (1.1)
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    nas (1.0)
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    wms (1.0)
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    pds (1.0)
Jul  6 16:03:59 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Setting network port data format...
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] QMI Device supports 6 services:
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    ctl (1.0)
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    wds (1.1)
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    dms (1.1)
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    nas (1.0)
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    wms (1.0)
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0]    pds (1.0)
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Registered 'dms' (version 1.1) client with ID '3'
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '3'
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Registered 'wms' (version 1.0) client with ID '3'
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:04:04 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Registered 'pds' (version 1.0) client with ID '3'
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <warn>  (ttyUSB1): port attributes not fully set
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <warn>  couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <warn>  couldn't load SIM identifier: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <warn>  couldn't load IMSI: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <warn>  couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <warn>  couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <warn>  couldn't load current Bands: 'QMI operation failed: Cannot send message: QMI service 'nas' version '1.1' required, got version '1.0''
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <info>  Modem: state changed (unknown -> disabled)
Jul  6 16:04:04 tk-2540p NetworkManager[2556]: <warn> (cdc-wdm0): failed to look up interface index
Jul  6 16:04:04 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): modem state changed, 'disabled' --> 'enabling' (reason: user preference)
Jul  6 16:04:04 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): new Broadband device (driver: 'qcserial, qmi_wwan' ifindex: 0)
Jul  6 16:04:04 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): exported as /org/freedesktop/NetworkManager/Devices/10
Jul  6 16:04:04 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jul  6 16:04:04 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): preparing device
Jul  6 16:04:04 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): modem state 'enabling'
Jul  6 16:04:04 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <warn>  (ttyUSB1): port attributes not fully set
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
Jul  6 16:04:04 tk-2540p ModemManager[4557]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
Jul  6 16:04:04 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): modem state changed, 'enabling' --> 'registered' (reason: user-requested)
Jul  6 16:04:09 tk-2540p ModemManager[4557]: <info>  Creating modem with plugin 'Generic' and '1' ports
Jul  6 16:04:09 tk-2540p ModemManager[4557]: <warn>  Could not grab port (tty/ttyS0): 'Cannot add port 'tty/ttyS0', unhandled serial type'
Jul  6 16:04:09 tk-2540p ModemManager[4557]: <warn>  Couldn't create modem for device at '/sys/devices/pci0000:00/0000:00:16.3': Failed to find primary AT port
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 16:06:24 tk-2540p ModemManager[4557]: <info>  Simple connect started...
Jul  6 16:06:24 tk-2540p ModemManager[4557]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 16:06:24 tk-2540p ModemManager[4557]: <info>  Simple connect state (5/8): Register
Jul  6 16:06:24 tk-2540p ModemManager[4557]: <info>  Simple connect state (6/8): Bearer
Jul  6 16:06:24 tk-2540p ModemManager[4557]: <info>  Simple connect state (7/8): Connect
Jul  6 16:06:24 tk-2540p ModemManager[4557]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 16:06:24 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 16:06:24 tk-2540p ModemManager[4557]: [/dev/cdc-wdm0] Registered 'wds' (version 1.1) client with ID '3'
Jul  6 16:06:24 tk-2540p ModemManager[4557]: qmi_message_add_raw_tlv: assertion 'length > 0' failed
Jul  6 16:06:24 tk-2540p ModemManager[4557]: qmi_message_wds_start_network_output_ref: assertion 'self != NULL' failed
Jul  6 16:06:24 tk-2540p ModemManager[4557]: qmi_message_wds_start_network_output_get_packet_data_handle: assertion 'self != NULL' failed
Jul  6 16:06:24 tk-2540p kernel: [ 1294.082580] ModemManager[4557]: segfault at 0 ip 000000000043b1c3 sp 00007ffe83765e68 error 6 in ModemManager[400000+104000]
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> ModemManager disappeared from bus
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): device state change: prepare -> unmanaged (reason 'removed') [40 10 36]
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): deactivating device (reason 'removed') [36]
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <warn> (cdc-wdm0) failed to connect modem: Message did not receive a reply (timeout by message bus)
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <warn> (cdc-wdm0) failed to disconnect modem: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.80 was not provided by any .service files
Jul  6 16:06:24 tk-2540p dbus[2269]: [system] Activating service name='org.freedesktop.ModemManager1' (using servicehelper)
Jul  6 16:06:24 tk-2540p ModemManager[4621]: <info>  ModemManager (version 1.4.0) starting in system bus...
Jul  6 16:06:24 tk-2540p dbus[2269]: [system] Successfully activated service 'org.freedesktop.ModemManager1'
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> ModemManager disappeared from bus
Jul  6 16:06:24 tk-2540p ModemManager[4621]: <warn>  (ttyUSB0): port attributes not fully set
Jul  6 16:06:24 tk-2540p ModemManager[4621]: <warn>  (ttyUSB1): port attributes not fully set
Jul  6 16:06:24 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Jul  6 16:06:24 tk-2540p NetworkManager[2556]: <info> ModemManager available in the bus
Jul  6 16:06:24 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Jul  6 16:06:24 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] QMI Device supports 6 services:
Jul  6 16:06:24 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    ctl (1.0)
Jul  6 16:06:24 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    wds (1.1)
Jul  6 16:06:24 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    dms (1.1)
Jul  6 16:06:24 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    nas (1.0)
Jul  6 16:06:24 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    wms (1.0)
Jul  6 16:06:24 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    pds (1.0)
Jul  6 16:06:26 tk-2540p ModemManager[4621]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:19.0': not supported by any plugin
Jul  6 16:06:26 tk-2540p ModemManager[4621]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.3/0000:43:00.0': not supported by any plugin
Jul  6 16:06:26 tk-2540p ModemManager[4621]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2': not supported by any plugin
Jul  6 16:06:33 tk-2540p ModemManager[4621]: <info>  Creating modem with plugin 'Gobi' and '4' ports
Jul  6 16:06:33 tk-2540p ModemManager[4621]: <info>  Modem for device at '/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2' successfully created
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] QMI Device supports 6 services:
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    ctl (1.0)
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    wds (1.1)
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    dms (1.1)
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    nas (1.0)
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    wms (1.0)
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    pds (1.0)
Jul  6 16:06:33 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Setting network port data format...
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] QMI Device supports 6 services:
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    ctl (1.0)
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    wds (1.1)
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    dms (1.1)
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    nas (1.0)
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    wms (1.0)
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0]    pds (1.0)
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:06:38 tk-2540p ModemManager[4621]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 16:06:38 tk-2540p ModemManager[4621]: <warn>  (ttyUSB1): port attributes not fully set
Jul  6 16:06:38 tk-2540p ModemManager[4621]: <warn>  Modem couldn't be initialized: couldn't load current capabilities: Couldn't peek client for service 'nas'
Jul  6 16:06:38 tk-2540p NetworkManager[2556]: <warn> (cdc-wdm0): failed to look up interface index
Jul  6 16:06:38 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): new Broadband device (driver: 'qcserial, qmi_wwan' ifindex: 0)
Jul  6 16:06:38 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): exported as /org/freedesktop/NetworkManager/Devices/11
Jul  6 16:06:38 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jul  6 16:06:38 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): preparing device
Jul  6 16:06:38 tk-2540p NetworkManager[2556]: <info> (cdc-wdm0): modem state 'unknown'
Jul  6 16:06:43 tk-2540p ModemManager[4621]: <info>  Creating modem with plugin 'Generic' and '1' ports
Jul  6 16:06:43 tk-2540p ModemManager[4621]: <warn>  Could not grab port (tty/ttyS0): 'Cannot add port 'tty/ttyS0', unhandled serial type'
Jul  6 16:06:43 tk-2540p ModemManager[4621]: <warn>  Couldn't create modem for device at '/sys/devices/pci0000:00/0000:00:16.3': Failed to find primary AT port


--- --------------------------------
--- Chatter since boot:
---


Jul  6 15:19:05 tk-2540p kernel: [   14.089471] usbcore: registered new interface driver usbserial
Jul  6 15:19:05 tk-2540p kernel: [   14.089482] usbcore: registered new interface driver usbserial_generic
Jul  6 15:19:05 tk-2540p kernel: [   14.089492] usbserial: USB Serial support registered for generic
Jul  6 15:19:05 tk-2540p kernel: [   14.134527] usbcore: registered new interface driver cdc_wdm
Jul  6 15:19:05 tk-2540p kernel: [   14.146235] qmi_wwan 1-1.2:1.3: cdc-wdm0: USB WDM device
Jul  6 15:19:05 tk-2540p kernel: [   14.146435] qmi_wwan 1-1.2:1.3 wwan0: register 'qmi_wwan' at usb-0000:00:1a.0-1.2, WWAN/QMI device, aa:00:5c:4f:98:97
Jul  6 15:19:05 tk-2540p kernel: [   14.146464] usbcore: registered new interface driver qmi_wwan
Jul  6 15:19:05 tk-2540p kernel: [   14.169585] usbcore: registered new interface driver qcserial
Jul  6 15:19:05 tk-2540p kernel: [   14.169597] usbserial: USB Serial support registered for Qualcomm USB modem
Jul  6 15:19:05 tk-2540p kernel: [   14.170247] qcserial 1-1.2:1.0: Qualcomm USB modem converter detected
Jul  6 15:19:05 tk-2540p kernel: [   14.170327] usb 1-1.2: Qualcomm USB modem converter now attached to ttyUSB0
Jul  6 15:19:05 tk-2540p kernel: [   14.171393] qcserial 1-1.2:1.2: Qualcomm USB modem converter detected
Jul  6 15:19:05 tk-2540p kernel: [   14.171490] usb 1-1.2: Qualcomm USB modem converter now attached to ttyUSB1
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> NetworkManager (version 0.9.10.0) is starting...
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> Read config: /etc/NetworkManager/NetworkManager.conf
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> WEXT support is enabled
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> VPN: loaded org.freedesktop.NetworkManager.pptp
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> init!
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> update_system_hostname
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info>       interface-parser: parsing file /etc/network/interfaces
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <warn> ignoring out-of-block data 'source-directory /etc/network/interfaces.d'
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info>       interface-parser: finished parsing file /etc/network/interfaces
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> management mode: unmanaged
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> devices added (path: /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.3/net/wwan0, iface: wwa
n0)
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> device added (path: /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.3/net/wwan0, iface: wwan
0): no ifupdown configuration found.
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> end _init.
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> Loaded plugin ifupdown: (C) 2008 Canonical Ltd.  To report bugs please use the NetworkManager mailing li
st.
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> Loaded plugin keyfile: (c) 2007 - 2013 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> (25036320) ... get_connections.
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> (25036320) ... get_connections (managed=false): return empty list.
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> new connection /etc/NetworkManager/system-connections/Vodafone Non-business

Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> get unmanaged devices count: 0
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <warn> Skipping Sleep Monitor setup, system not booted with systemd
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> monitoring kernel firmware directory '/lib/firmware'.
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> monitoring ifupdown state file '/run/network/ifstate'.
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> rfkill3: found WWAN radio killswitch (at /sys/devices/platform/hp-wmi/rfkill/rfkill3) (platform driver hp-wmi)
Jul  6 15:19:11 tk-2540p NetworkManager[2669]: <info> WiFi hardware radio set enabled
Jul  6 15:19:12 tk-2540p NetworkManager[2669]: <info> WWAN hardware radio set enabled
Jul  6 15:19:12 tk-2540p NetworkManager[2669]: <info> Loaded device plugin: /usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-bluetooth.so
Jul  6 15:19:12 tk-2540p NetworkManager[2669]: <info> Loaded device plugin: /usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wwan.so
Jul  6 15:19:12 tk-2540p NetworkManager[2669]: <info> Loaded device plugin: /usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wifi.so
Jul  6 15:19:12 tk-2540p NetworkManager[2669]: <info> Loaded device plugin: /usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-adsl.so
Jul  6 15:19:12 tk-2540p NetworkManager[2669]: <info> WiFi enabled by radio killswitch; enabled by state file
Jul  6 15:19:12 tk-2540p NetworkManager[2669]: <info> WWAN enabled by radio killswitch; enabled by state file
Jul  6 15:19:12 tk-2540p NetworkManager[2669]: <info> WiMAX enabled by radio killswitch; enabled by state file
Jul  6 15:19:12 tk-2540p NetworkManager[2669]: <info> Networking is enabled by state file
Jul  6 15:19:13 tk-2540p ModemManager[2859]: <info>  ModemManager (version 1.4.0) starting in system bus...
Jul  6 15:19:14 tk-2540p dbus[2291]: [system] Successfully activated service 'org.freedesktop.ModemManager1'
Jul  6 15:19:14 tk-2540p NetworkManager[2669]: <info> ModemManager disappeared from bus
Jul  6 15:19:14 tk-2540p NetworkManager[2669]: <info> ModemManager available in the bus
Jul  6 15:19:14 tk-2540p ModemManager[2859]: <warn>  (ttyUSB0): port attributes not fully set
Jul  6 15:19:14 tk-2540p ModemManager[2859]: <warn>  (ttyUSB1): port attributes not fully set
Jul  6 15:19:14 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Jul  6 15:19:14 tk-2540p ModemManager[2859]: cannot connect to proxy: Could not connect: Connection refused
Jul  6 15:19:14 tk-2540p ModemManager[2859]: spawning new qmi-proxy (try 1)...
Jul  6 15:19:14 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Jul  6 15:19:14 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] QMI Device supports 6 services:
Jul  6 15:19:14 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    ctl (1.0)
Jul  6 15:19:14 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    wds (1.1)
Jul  6 15:19:14 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    dms (1.1)
Jul  6 15:19:14 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    nas (1.0)
Jul  6 15:19:14 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    wms (1.0)
Jul  6 15:19:14 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    pds (1.0)
Jul  6 15:19:16 tk-2540p ModemManager[2859]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:19.0': not supported by any plugin
Jul  6 15:19:16 tk-2540p ModemManager[2859]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.3/0000:43:00.0': not supported by any plugin
Jul  6 15:19:16 tk-2540p NetworkManager[2669]: <info> (wlan0): supplicant interface state: disconnected -> inactive
Jul  6 15:19:17 tk-2540p NetworkManager[2669]: <info> startup complete
Jul  6 15:19:23 tk-2540p ModemManager[2859]: <info>  Creating modem with plugin 'Gobi' and '4' ports
Jul  6 15:19:23 tk-2540p ModemManager[2859]: <info>  Modem for device at '/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2' successfully created
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] QMI Device supports 6 services:
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    ctl (1.0)
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    wds (1.1)
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    dms (1.1)
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    nas (1.0)
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    wms (1.0)
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    pds (1.0)
Jul  6 15:19:23 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Setting network port data format...
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] QMI Device supports 6 services:
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    ctl (1.0)
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    wds (1.1)
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    dms (1.1)
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    nas (1.0)
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    wms (1.0)
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0]    pds (1.0)
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Registered 'dms' (version 1.1) client with ID '4'
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '4'
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Registered 'wms' (version 1.0) client with ID '4'
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 15:19:28 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Registered 'pds' (version 1.0) client with ID '4'
Jul  6 15:19:28 tk-2540p ModemManager[2859]: <warn>  (ttyUSB1): port attributes not fully set
Jul  6 15:19:28 tk-2540p ModemManager[2859]: <warn>  couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
Jul  6 15:19:28 tk-2540p ModemManager[2859]: <warn>  couldn't load SIM identifier: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
Jul  6 15:19:28 tk-2540p ModemManager[2859]: <warn>  couldn't load IMSI: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
Jul  6 15:19:28 tk-2540p ModemManager[2859]: <warn>  couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
Jul  6 15:19:28 tk-2540p ModemManager[2859]: <warn>  couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
Jul  6 15:19:28 tk-2540p ModemManager[2859]: <warn>  couldn't load current Bands: 'QMI operation failed: Cannot send message: QMI service 'nas' version '1.1' required, got version '1.0''
Jul  6 15:19:28 tk-2540p ModemManager[2859]: <info>  Modem: state changed (unknown -> disabled)
Jul  6 15:19:28 tk-2540p NetworkManager[2669]: <warn> (cdc-wdm0): failed to look up interface index
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'disabled' --> 'enabling' (reason: user preference)
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): new Broadband device (driver: 'qcserial, qmi_wwan' ifindex: 0)
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): exported as /org/freedesktop/NetworkManager/Devices/3
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): preparing device
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state 'enabling'
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> Auto-activating connection 'Vodafone Non-business'.
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now CONNECTING
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <warn>  (ttyUSB1): port attributes not fully set
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Simple connect started...
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Simple connect state (5/8): Register
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Simple connect state (6/8): Bearer
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'enabling' --> 'registered' (reason: user-requested)
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Simple connect state (7/8): Connect
Jul  6 15:19:29 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 15:19:29 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Allocating new client ID...
Jul  6 15:19:29 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 15:19:29 tk-2540p ModemManager[2859]: [/dev/cdc-wdm0] Registered 'wds' (version 1.1) client with ID '4'
Jul  6 15:19:33 tk-2540p ModemManager[2859]: <info>  Creating modem with plugin 'Generic' and '1' ports
Jul  6 15:19:33 tk-2540p ModemManager[2859]: <warn>  Could not grab port (tty/ttyS0): 'Cannot add port 'tty/ttyS0', unhandled serial type'
Jul  6 15:19:33 tk-2540p ModemManager[2859]: <warn>  Couldn't create modem for device at '/sys/devices/pci0000:00/0000:00:16.3': Failed to find primary AT port
Jul  6 15:19:37 tk-2540p ModemManager[2859]: <info>  error: couldn't get current settings: Cannot send message: QMI service 'wds' version '1.2' required, got version '1.1'
Jul  6 15:19:37 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
Jul  6 15:19:37 tk-2540p ModemManager[2859]: <info>  Simple connect state (8/8): All done
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <warn> (cdc-wdm0) failed to connect modem: invalid IP config
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> failed (reason '(null)') [40 120 1]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now DISCONNECTED
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): deactivating device (reason 'none') [0]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> Auto-activating connection 'Vodafone Non-business'.
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now CONNECTING
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:19:37 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: need-auth -> failed (reason 'modem-no-carrier') [60 120 25]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now DISCONNECTED
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> prepare (reason 'none') [120 40 0]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:19:37 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered)
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <warn> No agents were available for this request.
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: need-auth -> failed (reason 'no-secrets') [60 120 7]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): deactivating device (reason 'none') [0]
Jul  6 15:19:37 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
Jul  6 15:24:26 tk-2540p colord: Device added: sysfs-HP_un2400_Mobile_Broadband_Module-HP_un2400_Mobile_Broadband_Module
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> Auto-activating connection 'Vodafone Non-business'.
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now CONNECTING
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:24:30 tk-2540p ModemManager[2859]: <info>  Simple connect started...
Jul  6 15:24:30 tk-2540p ModemManager[2859]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 15:24:30 tk-2540p ModemManager[2859]: <info>  Simple connect state (5/8): Register
Jul  6 15:24:30 tk-2540p ModemManager[2859]: <info>  Simple connect state (6/8): Bearer
Jul  6 15:24:30 tk-2540p ModemManager[2859]: <info>  Simple connect state (7/8): Connect
Jul  6 15:24:30 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 15:24:30 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  error: couldn't get current settings: Cannot send message: QMI service 'wds' version '1.2' required, got version '1.1'
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect state (8/8): All done
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <warn> (cdc-wdm0) failed to connect modem: invalid IP config
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> failed (reason '(null)') [40 120 1]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now DISCONNECTED
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): deactivating device (reason 'none') [0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Auto-activating connection 'Vodafone Non-business'.
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered)
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now CONNECTING
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect started...
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect state (5/8): Register
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect state (6/8): Bearer
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect state (7/8): Connect
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  error: couldn't start network: QMI protocol error (14): 'CallFailed'
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  call end reason (2): 'generic-client-end'
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connecting' --> 'registered' (reason: user-requested)
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <warn> (cdc-wdm0) failed to connect modem: QMI protocol error (14): 'CallFailed'
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> failed (reason '(null)') [40 120 1]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now DISCONNECTED
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): deactivating device (reason 'none') [0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Auto-activating connection 'Vodafone Non-business'.
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now CONNECTING
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect started...
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect state (5/8): Register
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect state (6/8): Bearer
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Simple connect state (7/8): Connect
Jul  6 15:24:38 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 15:24:38 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  error: couldn't get current settings: Cannot send message: QMI service 'wds' version '1.2' required, got version '1.1'
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Simple connect state (8/8): All done
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <warn> (cdc-wdm0) failed to connect modem: invalid IP config
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> failed (reason '(null)') [40 120 1]
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now DISCONNECTED
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): deactivating device (reason 'none') [0]
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> Auto-activating connection 'Vodafone Non-business'.
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered)
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now CONNECTING
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Simple connect started...
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Simple connect state (5/8): Register
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Simple connect state (6/8): Bearer
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Simple connect state (7/8): Connect
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  error: couldn't start network: QMI protocol error (14): 'CallFailed'
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  call end reason (2): 'generic-client-end'
Jul  6 15:24:41 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connecting' --> 'registered' (reason: user-requested)
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <warn> (cdc-wdm0) failed to connect modem: QMI protocol error (14): 'CallFailed'
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> failed (reason '(null)') [40 120 1]
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now DISCONNECTED
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> Disabling autoconnect for connection 'Vodafone Non-business'.
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  6 15:24:41 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): deactivating device (reason 'none') [0]
Jul  6 15:25:17 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 15:25:17 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:25:17 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:25:17 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 15:25:17 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now CONNECTING
Jul  6 15:25:17 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:25:17 tk-2540p ModemManager[2859]: <info>  Simple connect started...
Jul  6 15:25:17 tk-2540p ModemManager[2859]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 15:25:17 tk-2540p ModemManager[2859]: <info>  Simple connect state (5/8): Register
Jul  6 15:25:17 tk-2540p ModemManager[2859]: <info>  Simple connect state (6/8): Bearer
Jul  6 15:25:17 tk-2540p ModemManager[2859]: <info>  Simple connect state (7/8): Connect
Jul  6 15:25:17 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 15:25:17 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 15:25:24 tk-2540p ModemManager[2859]: <info>  error: couldn't get current settings: Cannot send message: QMI service 'wds' version '1.2' required, got version '1.1'
Jul  6 15:25:24 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
Jul  6 15:25:24 tk-2540p ModemManager[2859]: <info>  Simple connect state (8/8): All done
Jul  6 15:25:24 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Jul  6 15:25:24 tk-2540p NetworkManager[2669]: <warn> (cdc-wdm0) failed to connect modem: invalid IP config
Jul  6 15:25:24 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> failed (reason '(null)') [40 120 1]
Jul  6 15:25:24 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now DISCONNECTED
Jul  6 15:25:24 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:25:24 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  6 15:25:24 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): deactivating device (reason 'none') [0]
Jul  6 15:25:24 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
Jul  6 15:25:24 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
Jul  6 15:25:24 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered)
Jul  6 15:25:24 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now CONNECTING
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:26:16 tk-2540p ModemManager[2859]: <info>  Simple connect started...
Jul  6 15:26:16 tk-2540p ModemManager[2859]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 15:26:16 tk-2540p ModemManager[2859]: <info>  Simple connect state (5/8): Register
Jul  6 15:26:16 tk-2540p ModemManager[2859]: <info>  Simple connect state (6/8): Bearer
Jul  6 15:26:16 tk-2540p ModemManager[2859]: <info>  Simple connect state (7/8): Connect
Jul  6 15:26:16 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 15:26:16 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 15:26:23 tk-2540p ModemManager[2859]: <info>  error: couldn't get current settings: Cannot send message: QMI service 'wds' version '1.2' required, got version '1.1'
Jul  6 15:26:23 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
Jul  6 15:26:23 tk-2540p ModemManager[2859]: <info>  Simple connect state (8/8): All done
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <warn> (cdc-wdm0) failed to connect modem: invalid IP config
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> failed (reason '(null)') [40 120 1]
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now DISCONNECTED
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <info> Disabling autoconnect for connection 'Vodafone Non-business'.
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): deactivating device (reason 'none') [0]
Jul  6 15:26:23 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
Jul  6 15:26:23 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered)
Jul  6 15:26:23 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
Jul  6 15:30:42 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) starting connection 'Vodafone Non-business'
Jul  6 15:30:42 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  6 15:30:42 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Jul  6 15:30:42 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  6 15:30:42 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now CONNECTING
Jul  6 15:30:42 tk-2540p NetworkManager[2669]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Jul  6 15:30:42 tk-2540p ModemManager[2859]: <info>  Simple connect started...
Jul  6 15:30:42 tk-2540p ModemManager[2859]: <info>  Simple connect state (4/8): Wait to get fully enabled
Jul  6 15:30:42 tk-2540p ModemManager[2859]: <info>  Simple connect state (5/8): Register
Jul  6 15:30:42 tk-2540p ModemManager[2859]: <info>  Simple connect state (6/8): Bearer
Jul  6 15:30:42 tk-2540p ModemManager[2859]: <info>  Simple connect state (7/8): Connect
Jul  6 15:30:42 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Jul  6 15:30:42 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Jul  6 15:30:50 tk-2540p ModemManager[2859]: <info>  error: couldn't get current settings: Cannot send message: QMI service 'wds' version '1.2' required, got version '1.1'
Jul  6 15:30:50 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
Jul  6 15:30:50 tk-2540p ModemManager[2859]: <info>  Simple connect state (8/8): All done
Jul  6 15:30:50 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Jul  6 15:30:50 tk-2540p NetworkManager[2669]: <warn> (cdc-wdm0) failed to connect modem: invalid IP config
Jul  6 15:30:50 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: prepare -> failed (reason '(null)') [40 120 1]
Jul  6 15:30:50 tk-2540p NetworkManager[2669]: <info> NetworkManager state is now DISCONNECTED
Jul  6 15:30:50 tk-2540p NetworkManager[2669]: <warn> Activation (cdc-wdm0) failed for connection 'Vodafone Non-business'
Jul  6 15:30:50 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
Jul  6 15:30:50 tk-2540p ModemManager[2859]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered)
Jul  6 15:30:50 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
Jul  6 15:30:50 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
Jul  6 15:30:50 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  6 15:30:50 tk-2540p NetworkManager[2669]: <info> (cdc-wdm0): deactivating device (reason 'none') [0]
Comment 1 Aleksander Morgado 2015-07-06 15:57:07 UTC
Could you get debug logs?
http://www.freedesktop.org/wiki/Software/ModemManager/Debugging/
Comment 2 tkteun 2015-07-06 17:51:53 UTC
Created attachment 116984 [details]
Debug script of ModemManager
Comment 3 tkteun 2015-07-06 17:52:51 UTC
Created attachment 116985 [details]
Debug script of NetworkManager
Comment 4 tkteun 2015-07-06 17:58:45 UTC
Hi Aleksander,

Thanks for your quick response. I have attached two debug scripts of NetworkManager and ModemManager in which you can see this issue happening.

Before this, I have used rfkill to enable and gobi_loader to upload the firmware to this card.

The output of mmcli was:

/org/freedesktop/ModemManager1/Modem/0 (device id '2e4001d58dfdbab606869bcd9f58ab041eabddae')
  -------------------------
  Hardware |   manufacturer: 'QUALCOMM INCORPORATED'
           |          model: '88'
           |       revision: 'D1020-SUUAASFA-4352  1  [Apr 14 2008 18:00:00]'
           |      supported: 'gsm-umts'
           |        current: 'gsm-umts'
           |   equipment id: '980049000460981'
  -------------------------
  System   |         device: '/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2'
           |        drivers: 'qcserial, qmi_wwan'
           |         plugin: 'Gobi'
           |   primary port: 'cdc-wdm0'
           |          ports: 'ttyUSB0 (qcdm), ttyUSB1 (at), cdc-wdm0 (qmi), wwan0 (net)'
  -------------------------
  Numbers  |           own : 'unknown'
  -------------------------
  Status   |           lock: 'sim-pin2'
           | unlock retries: 'sim-pin (3), sim-pin2 (3), sim-puk (10), sim-puk2 (10)'
           |          state: 'registered'
           |    power state: 'on'
           |    access tech: 'umts'
           | signal quality: '25' (recent)
  -------------------------
  Modes    |      supported: 'allowed: 2g; preferred: none
           |                  allowed: 3g; preferred: none
           |                  allowed: 2g, 3g; preferred: none
           |                  allowed: 2g, 3g; preferred: 2g
           |                  allowed: 2g, 3g; preferred: 3g'
           |        current: 'allowed: any; preferred: none'
  -------------------------
  Bands    |      supported: 'unknown'
           |        current: 'unknown'
  -------------------------
  IP       |      supported: 'ipv4, ipv6, ipv4v6'
  -------------------------
  3GPP     |           imei: **(correct)**
           |  enabled locks: 'none'
           |    operator id: '20404'
           |  operator name: 'vodafone NL'
           |   subscription: 'unknown'
           |   registration: 'home'
  -------------------------
  SIM      |           path: '/org/freedesktop/ModemManager1/SIM/0'

  -------------------------
  Bearers  |          paths: 'none'
Comment 5 tkteun 2015-07-06 18:23:48 UTC
Hi Aleksander,

I believe I have found the core of this issue: I haven't entered a password for this connection.

Whenever I do enter a password (my provider allows any) I can eventually get a connection. (after getting some confusing PPP IP address errors, which disappeared).

Regards,
  Teun
Comment 6 Aleksander Morgado 2015-12-02 19:31:18 UTC
I believe this commit fixed this issue:
http://cgit.freedesktop.org/ModemManager/ModemManager/commit/?h=mm-1-4&id=b32a370941bd2ae3e429e0a57c4c0c511f402f4e

Any chance you could try to compile your own ModemManager from git master or the mm-1-4 branch and retry?
Comment 7 GitLab Migration User 2018-06-10 08:59:54 UTC
-- GitLab Migration Automatic Message --

This bug has been migrated to freedesktop.org's GitLab instance and has been closed from further activity.

You can subscribe and participate further through the new bug through this link to our GitLab instance: https://gitlab.freedesktop.org/mobile-broadband/ModemManager/issues/1.


Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.