E1750 Reloaded - QMI - STATIC - DHCP - IPv4
poma
pomidorabelisima at gmail.com
Wed Aug 6 21:54:16 PDT 2014
On 07.08.2014 02:28, poma wrote:
> On 07.08.2014 00:07, poma wrote:
>>
>> /etc/NetworkManager/system-connections/MBB
>> [connection]
>> id=MBB
>> uuid=12345678-1234-1234-1234-123456789abc
>> type=gsm
>> autoconnect=false
>> timestamp=1407274428
>> zone=block
>>
>> [ipv6]
>> method=ignore
>>
>> [gsm]
>> username=username
>> password=password
>> apn=access.point.name
>> home-only=true
>>
>> [ppp]
>> refuse-eap=true
>> refuse-chap=true
>> refuse-mschap=true
>> refuse-mschapv2=true
>> nobsdcomp=true
>> nodeflate=true
>> no-vj-comp=true
>>
>> [serial]
>> baud=115200
>>
>> [ipv4]
>> method=auto
>>
>> ~~~~~~~~~~~
>>
>> Starting Modem Manager...
>> <info> [000.000071] [main.c:140] main(): ModemManager (version 1.3.0-1.git20140804.fc21) starting in system bus...
>> <debug> [001.754017] [main.c:65] bus_acquired_cb(): Bus acquired, creating manager...
>> <debug> [002.136766] [mm-plugin-manager.c:852] load_plugins(): Looking for plugins in '/usr/lib64/ModemManager'
>> <debug> [002.137372] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Huawei'
>> <warn> [002.137400] [mm-plugin-manager.c:889] load_plugins(): Generic plugin not loaded
>> <debug> [002.137409] [mm-plugin-manager.c:902] load_plugins(): Successfully loaded 1 plugins
>> <debug> [002.138496] [main.c:87] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
>> <debug> [002.138527] [mm-base-manager.c:479] mm_base_manager_start(): Starting automatic device scan...
>> Started Modem Manager.
>> <debug> [002.140207] [mm-base-manager.c:518] mm_base_manager_start(): Finished device scan...
>> <debug> [002.140394] [mm-base-manager.c:297] device_added(): (tty/ttyS0): port's parent device is blacklisted
>> <debug> [002.140507] [mm-base-manager.c:297] device_added(): (tty/ttyS1): port's parent device is blacklisted
>> <debug> [002.140602] [mm-base-manager.c:314] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
>> <debug> [002.140687] [mm-base-manager.c:314] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
>> <debug> [002.198625] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3] Checking device support...
>> <debug> [002.198871] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wwan0] Found '1' plugins to try...
>> <debug> [002.198900] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0] Will try with plugin 'Huawei'
>> <debug> [002.198930] [mm-plugin.c:689] mm_plugin_supports_port(): (Huawei) [wwan0] probing deferred until result suggested
>> <debug> [002.199718] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '1' plugins to try...
>> <debug> [002.199751] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Huawei'
>> <debug> [002.199794] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [cdc-wdm0] probe required: 'qmi'
>> <debug> [002.199823] [mm-port-probe.c:1353] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi'
>> <debug> [002.199871] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wwan0] deferring support check until result suggested
>> <debug> [002.199908] [mm-port-probe.c:456] wdm_probe_qmi(): (usbmisc/cdc-wdm0) probing QMI...
>> [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
>> cannot connect to proxy: Could not connect: Connection refused
>> spawning new qmi-proxy (try 1)...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 28
>> <<<<<< data = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 27
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 1
>> <<<<<< tlv_length = 16
>> <<<<<< message = "Internal Proxy Open" (0xFF00)
>> <<<<<< TLV:
>> <<<<<< type = "Device Path" (0x01)
>> <<<<<< length = 13
>> <<<<<< value = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>> <<<<<< translated = /dev/cdc-wdm0
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 19
>> >>>>>> data = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 18
>> >>>>>> flags = 0x00
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 1
>> >>>>>> tlv_length = 7
>> >>>>>> message = "Internal Proxy Open" (0xFF00)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> [/dev/cdc-wdm0] Checking version info (10 retries)...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 12
>> <<<<<< data = 01:0B:00:00:00:00:00:02:21:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 11
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 2
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Version Info" (0x0021)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 48
>> >>>>>> data = 01:2F:00:80:00:00:01:02:21:00:24:00:02:04:00:00:00:00:00:01:1A:00:05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 47
>> >>>>>> flags = 0x80
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 2
>> >>>>>> tlv_length = 36
>> >>>>>> message = "Get Version Info" (0x0021)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Service list" (0x01)
>> >>>>>> length = 26
>> >>>>>> value = 05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>> >>>>>> translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '2' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '3' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '2' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] ' [4] = '[ service = 'cat' major_version = '0' minor_version = '0' ] '}
>> [/dev/cdc-wdm0] QMI Device supports 5 services:
>> [/dev/cdc-wdm0] ctl (1.2)
>> [/dev/cdc-wdm0] wds (1.3)
>> [/dev/cdc-wdm0] dms (1.2)
>> [/dev/cdc-wdm0] nas (1.0)
>> [/dev/cdc-wdm0] cat (0.0)
>> <debug> [002.315177] [mm-port-probe.c:271] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable
>> <debug> [002.315362] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [cdc-wdm0] found best plugin for port
>> <debug> [002.315393] [mm-plugin-manager.c:232] port_probe_context_finished(): (Plugin Manager) (Huawei) [cdc-wdm0]: found best plugin for device (/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3)
>> <debug> [002.315432] [mm-plugin-manager.c:361] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [wwan0] deferred task completed, got suggested plugin
>> <debug> [002.315459] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 1 running probes in this device (wwan0)
>> <debug> [002.315541] [mm-plugin.c:689] mm_plugin_supports_port(): (Huawei) [wwan0] probing deferred until result suggested
>> <debug> [002.319356] [mm-plugin-manager.c:549] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [wwan0] task completed, got suggested plugin
>> <debug> [002.319489] [mm-plugin-manager.c:313] port_probe_context_finished(): (Plugin Manager) 'wwan0' port probe finished, last one in device, but minimum probing time not consumed yet ('0.120755' seconds elapsed)
>> <debug> [004.898294] [mm-plugin-manager.c:703] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3] Minimum probing time consumed and no more ports to probe
>> <debug> [004.898377] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3] device support check finished in '2.699644' seconds
>> <info> [004.898425] [mm-device.c:509] mm_device_create_modem(): Creating modem with plugin 'Huawei' and '2' ports
>> <debug> [004.898488] [huawei/mm-plugin-huawei.c:491] create_modem(): QMI-powered Huawei modem found...
>> <debug> [004.900983] [huawei/mm-plugin-huawei.c:554] grab_port(): (usbmisc/cdc-wdm0) Port will have AT flags 'none'
>> <debug> [004.901097] [mm-base-modem.c:304] mm_base_modem_grab_port(): (cdc-wdm0) type 'qmi' claimed by /sys/devices/pci0000:00/0000:00:04.1/usb2/2-3
>> <debug> [004.901142] [huawei/mm-plugin-huawei.c:554] grab_port(): (net/wwan0) Port will have AT flags 'none'
>> <debug> [004.901183] [mm-base-modem.c:304] mm_base_modem_grab_port(): (wwan0) type 'net' claimed by /sys/devices/pci0000:00/0000:00:04.1/usb2/2-3
>> <debug> [004.901236] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3) net/wwan0 data (primary)
>> <debug> [004.901259] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3) usb/cdc-wdm0 qmi (primary)
>> <info> [004.901754] [mm-base-manager.c:162] find_device_support_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3' successfully created
>> [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 28
>> <<<<<< data = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 27
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 1
>> <<<<<< tlv_length = 16
>> <<<<<< message = "Internal Proxy Open" (0xFF00)
>> <<<<<< TLV:
>> <<<<<< type = "Device Path" (0x01)
>> <<<<<< length = 13
>> <<<<<< value = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>> <<<<<< translated = /dev/cdc-wdm0
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 19
>> >>>>>> data = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 18
>> >>>>>> flags = 0x00
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 1
>> >>>>>> tlv_length = 7
>> >>>>>> message = "Internal Proxy Open" (0xFF00)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> [/dev/cdc-wdm0] Checking version info (10 retries)...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 12
>> <<<<<< data = 01:0B:00:00:00:00:00:02:21:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 11
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 2
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Version Info" (0x0021)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 48
>> >>>>>> data = 01:2F:00:80:00:00:01:02:21:00:24:00:02:04:00:00:00:00:00:01:1A:00:05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 47
>> >>>>>> flags = 0x80
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 2
>> >>>>>> tlv_length = 36
>> >>>>>> message = "Get Version Info" (0x0021)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Service list" (0x01)
>> >>>>>> length = 26
>> >>>>>> value = 05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>> >>>>>> translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '2' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '3' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '2' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] ' [4] = '[ service = 'cat' major_version = '0' minor_version = '0' ] '}
>> [/dev/cdc-wdm0] QMI Device supports 5 services:
>> [/dev/cdc-wdm0] ctl (1.2)
>> [/dev/cdc-wdm0] wds (1.3)
>> [/dev/cdc-wdm0] dms (1.2)
>> [/dev/cdc-wdm0] nas (1.0)
>> [/dev/cdc-wdm0] cat (0.0)
>> [/dev/cdc-wdm0] Setting network port data format...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 21
>> <<<<<< data = 01:14:00:00:00:00:00:03:26:00:09:00:10:02:00:01:00:01:01:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 20
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 3
>> <<<<<< tlv_length = 9
>> <<<<<< message = "Set Data Format" (0x0026)
>> <<<<<< TLV:
>> <<<<<< type = "Protocol" (0x10)
>> <<<<<< length = 2
>> <<<<<< value = 01:00
>> <<<<<< translated = 802-3
>> <<<<<< TLV:
>> <<<<<< type = "Format" (0x01)
>> <<<<<< length = 1
>> <<<<<< value = 00
>> <<<<<< translated = absent
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 19
>> >>>>>> data = 01:12:00:80:00:00:01:03:26:00:07:00:02:04:00:01:00:2D:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 18
>> >>>>>> flags = 0x80
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 3
>> >>>>>> tlv_length = 7
>> >>>>>> message = "Set Data Format" (0x0026)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 01:00:2D:00
>> >>>>>> translated = FAILURE: (null)
>> [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 28
>> <<<<<< data = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 27
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 1
>> <<<<<< tlv_length = 16
>> <<<<<< message = "Internal Proxy Open" (0xFF00)
>> <<<<<< TLV:
>> <<<<<< type = "Device Path" (0x01)
>> <<<<<< length = 13
>> <<<<<< value = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>> <<<<<< translated = /dev/cdc-wdm0
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 19
>> >>>>>> data = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 18
>> >>>>>> flags = 0x00
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 1
>> >>>>>> tlv_length = 7
>> >>>>>> message = "Internal Proxy Open" (0xFF00)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> [/dev/cdc-wdm0] Checking version info (10 retries)...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 12
>> <<<<<< data = 01:0B:00:00:00:00:00:02:21:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 11
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 2
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Version Info" (0x0021)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 48
>> >>>>>> data = 01:2F:00:80:00:00:01:02:21:00:24:00:02:04:00:00:00:00:00:01:1A:00:05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 47
>> >>>>>> flags = 0x80
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 2
>> >>>>>> tlv_length = 36
>> >>>>>> message = "Get Version Info" (0x0021)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Service list" (0x01)
>> >>>>>> length = 26
>> >>>>>> value = 05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>> >>>>>> translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '2' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '3' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '2' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] ' [4] = '[ service = 'cat' major_version = '0' minor_version = '0' ] '}
>> [/dev/cdc-wdm0] QMI Device supports 5 services:
>> [/dev/cdc-wdm0] ctl (1.2)
>> [/dev/cdc-wdm0] wds (1.3)
>> [/dev/cdc-wdm0] dms (1.2)
>> [/dev/cdc-wdm0] nas (1.0)
>> [/dev/cdc-wdm0] cat (0.0)
>> [/dev/cdc-wdm0] Allocating new client ID...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 16
>> <<<<<< data = 01:0F:00:00:00:00:00:03:22:00:04:00:01:01:00:02
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 15
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 3
>> <<<<<< tlv_length = 4
>> <<<<<< message = "Allocate CID" (0x0022)
>> <<<<<< TLV:
>> <<<<<< type = "Service" (0x01)
>> <<<<<< length = 1
>> <<<<<< value = 02
>> <<<<<< translated = dms
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 24
>> >>>>>> data = 01:17:00:80:00:00:01:03:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:17
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 23
>> >>>>>> flags = 0x80
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 3
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Allocate CID" (0x0022)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Allocation Info" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = 02:17
>> >>>>>> translated = [ service = 'dms' cid = '23' ]
>> [/dev/cdc-wdm0] Registered 'dms' (version 1.2) client with ID '23'
>> [/dev/cdc-wdm0] Allocating new client ID...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 16
>> <<<<<< data = 01:0F:00:00:00:00:00:04:22:00:04:00:01:01:00:03
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 15
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 4
>> <<<<<< tlv_length = 4
>> <<<<<< message = "Allocate CID" (0x0022)
>> <<<<<< TLV:
>> <<<<<< type = "Service" (0x01)
>> <<<<<< length = 1
>> <<<<<< value = 03
>> <<<<<< translated = nas
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 24
>> >>>>>> data = 01:17:00:80:00:00:01:04:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:03:13
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 23
>> >>>>>> flags = 0x80
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 4
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Allocate CID" (0x0022)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Allocation Info" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = 03:13
>> >>>>>> translated = [ service = 'nas' cid = '19' ]
>> [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '19'
>> <debug> [004.925259] [mm-broadband-modem-qmi.c:9995] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'wms': Couldn't create client for service 'wms': Service 'wms' not supported by the device
>> <debug> [004.925315] [mm-broadband-modem-qmi.c:9995] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'pds': Couldn't create client for service 'pds': Service 'pds' not supported by the device
>> <debug> [004.925367] [mm-broadband-modem-qmi.c:9995] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'oma': Couldn't create client for service 'oma': Service 'oma' not supported by the device
>> <debug> [004.925454] [mm-broadband-modem-qmi.c:9966] parent_initialization_started_ready(): Couldn't start parent initialization: Couldn't open ports during modem initialization: Couldn't get primary port
>> <debug> [004.926855] [mm-broadband-modem-qmi.c:531] modem_load_current_capabilities(): loading current capabilities...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:01:00:2B:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 1
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Technology Preference" (0x002B)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 20
>> >>>>>> data = 01:13:00:80:03:13:02:01:00:2B:00:07:00:02:04:00:01:00:47:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 19
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 1
>> >>>>>> tlv_length = 7
>> >>>>>> message = "Get Technology Preference" (0x002B)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 01:00:47:00
>> >>>>>> translated = FAILURE: InvalidQmiCommand
>> <debug> [004.928840] [mm-broadband-modem-qmi.c:426] load_current_capabilities_get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:01:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 1
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Capabilities" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 36
>> >>>>>> data = 01:23:00:80:02:17:02:01:00:20:00:17:00:02:04:00:00:00:00:00:01:0D:00:60:4D:2F:00:60:4D:2F:00:04:02:02:04:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 35
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 1
>> >>>>>> tlv_length = 23
>> >>>>>> message = "Get Capabilities" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Info" (0x01)
>> >>>>>> length = 13
>> >>>>>> value = 60:4D:2F:00:60:4D:2F:00:04:02:02:04:05
>> >>>>>> translated = [ max_tx_channel_rate = '3100000' max_rx_channel_rate = '3100000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'gsm ' [1] = 'umts '}' ]
>> <debug> [004.930919] [mm-modem-helpers-qmi.c:1268] mm_modem_capability_from_qmi_capabilities_context(): Current capabilities built: 'gsm-umts'
>> SSP mode preference: 'unknown'
>> TP: 'auto'
>> DMS Capabilities: 'gsm-umts'
>> <debug> [004.931030] [mm-broadband-modem-qmi.c:693] modem_load_supported_capabilities(): loading supported capabilities...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:02:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 2
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Capabilities" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 36
>> >>>>>> data = 01:23:00:80:02:17:02:02:00:20:00:17:00:02:04:00:00:00:00:00:01:0D:00:60:4D:2F:00:60:4D:2F:00:04:02:02:04:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 35
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 2
>> >>>>>> tlv_length = 23
>> >>>>>> message = "Get Capabilities" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Info" (0x01)
>> >>>>>> length = 13
>> >>>>>> value = 60:4D:2F:00:60:4D:2F:00:04:02:02:04:05
>> >>>>>> translated = [ max_tx_channel_rate = '3100000' max_rx_channel_rate = '3100000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'gsm ' [1] = 'umts '}' ]
>> <debug> [004.934774] [mm-iface-modem.c:4327] interface_initialization_step(): Modem allows up to 1 bearers
>> <debug> [004.934786] [mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
>> <debug> [004.934838] [mm-broadband-modem-qmi.c:995] modem_load_manufacturer(): loading manufacturer...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:03:00:21:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 3
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Manufacturer" (0x0021)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 44
>> >>>>>> data = 01:2B:00:80:02:17:02:03:00:21:00:1F:00:02:04:00:00:00:00:00:01:15:00:51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 43
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 3
>> >>>>>> tlv_length = 31
>> >>>>>> message = "Get Manufacturer" (0x0021)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Manufacturer" (0x01)
>> >>>>>> length = 21
>> >>>>>> value = 51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44
>> >>>>>> translated = QUALCOMM INCORPORATED
>> <debug> [004.936731] [mm-broadband-modem-qmi.c:942] modem_load_manufacturer_finish(): loaded manufacturer: QUALCOMM INCORPORATED
>> <debug> [004.936766] [mm-broadband-modem-qmi.c:1071] modem_load_model(): loading model...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:04:00:22:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 4
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Model" (0x0022)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:02:17:02:04:00:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:32:39
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 4
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Model" (0x0022)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Model" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = 32:39
>> >>>>>> translated = 29
>> <debug> [004.940858] [mm-broadband-modem-qmi.c:1018] modem_load_model_finish(): loaded model: 29
>> <debug> [004.940929] [mm-broadband-modem-qmi.c:1147] modem_load_revision(): loading revision...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:05:00:23:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 5
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Revision" (0x0023)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 74
>> >>>>>> data = 01:49:00:80:02:17:02:05:00:23:00:3D:00:02:04:00:00:00:00:00:01:33:00:4D:36:32:39:30:41:2D:4B:50:52:42:4C:2D:2E:2E:2E
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 73
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 5
>> >>>>>> tlv_length = 61
>> >>>>>> message = "Get Revision" (0x0023)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Revision" (0x01)
>> >>>>>> length = 51
>> >>>>>> value = 4D:36:32:39:30:41:2D:4B:50:52:42:4C:2D:2E:2E:2E
>> >>>>>> translated = M6290A-KPRBL-...
>> <debug> [004.944923] [mm-broadband-modem-qmi.c:1094] modem_load_revision_finish(): loaded revision: M6290A-KPRBL-...
>> <debug> [004.944995] [mm-broadband-modem-qmi.c:1289] modem_load_equipment_identifier(): loading equipment identifier...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:06:00:25:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 6
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get IDs" (0x0025)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 39
>> >>>>>> data = 01:26:00:80:02:17:02:06:00:25:00:1A:00:02:04:00:00:00:00:00:11:10:00:33:35:33:2E:2E:2E:01
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 38
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 6
>> >>>>>> tlv_length = 26
>> >>>>>> message = "Get IDs" (0x0025)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Imei" (0x11)
>> >>>>>> length = 16
>> >>>>>> value = 33:35:33:2E:2E:2E:01
>> >>>>>> translated = 353...
>> <debug> [004.948882] [mm-broadband-modem-qmi.c:1186] modem_load_equipment_identifier_finish(): loaded equipment identifier: 353...
>> <debug> [004.948947] [mm-broadband-modem-qmi.c:1324] modem_load_device_identifier(): loading device identifier...
>> <debug> [004.949006] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '000012d10000140c353...M6290A-KPRBL-...29QUALCOMMINCORPORATED'
>> <debug> [004.949028] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID 'eb8d2760525a500bcbf18a9e9b89cd55098848e4'
>> <debug> [004.949128] [mm-broadband-modem-qmi.c:1312] modem_load_device_identifier_finish(): loaded device identifier: eb8d2760525a500bcbf18a9e9b89cd55098848e4
>> <debug> [004.949301] [mm-broadband-modem-qmi.c:1727] modem_load_supported_bands(): loading band capabilities...
>> <warn> [004.949381] [mm-iface-modem.c:3982] load_supported_bands_ready(): couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>> <debug> [004.949453] [mm-broadband-modem-qmi.c:2682] load_power_state(): Getting device operating mode...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:08:00:2D:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 8
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Operating Mode" (0x002D)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 24
>> >>>>>> data = 01:17:00:80:02:17:02:08:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 23
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 8
>> >>>>>> tlv_length = 11
>> >>>>>> message = "Get Operating Mode" (0x002D)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Mode" (0x01)
>> >>>>>> length = 1
>> >>>>>> value = 00
>> >>>>>> translated = online
>> <debug> [004.952892] [mm-broadband-modem-qmi.c:1533] modem_load_unlock_required(): loading unlock required...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:09:00:2B:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 9
>> <<<<<< tlv_length = 0
>> <<<<<< message = "UIM Get PIN Status" (0x002B)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 32
>> >>>>>> data = 01:1F:00:80:02:17:02:09:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:03:03:0A
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 31
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 9
>> >>>>>> tlv_length = 19
>> >>>>>> message = "UIM Get PIN Status" (0x002B)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "PIN2 Status" (0x12)
>> >>>>>> length = 3
>> >>>>>> value = 01:03:0A
>> >>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>> >>>>>> TLV:
>> >>>>>> type = "PIN1 Status" (0x11)
>> >>>>>> length = 3
>> >>>>>> value = 03:03:0A
>> >>>>>> translated = [ current_status = 'disabled' verify_retries_left = '3' unblock_retries_left = '10' ]
>> <debug> [004.956944] [mm-broadband-modem-qmi.c:1626] modem_load_unlock_retries(): loading unlock retries...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:0A:00:2B:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 10
>> <<<<<< tlv_length = 0
>> <<<<<< message = "UIM Get PIN Status" (0x002B)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 32
>> >>>>>> data = 01:1F:00:80:02:17:02:0A:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:03:03:0A
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 31
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 10
>> >>>>>> tlv_length = 19
>> >>>>>> message = "UIM Get PIN Status" (0x002B)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "PIN2 Status" (0x12)
>> >>>>>> length = 3
>> >>>>>> value = 01:03:0A
>> >>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>> >>>>>> TLV:
>> >>>>>> type = "PIN1 Status" (0x11)
>> >>>>>> length = 3
>> >>>>>> value = 03:03:0A
>> >>>>>> translated = [ current_status = 'disabled' verify_retries_left = '3' unblock_retries_left = '10' ]
>> <debug> [004.961125] [mm-iface-modem.c:3076] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
>> <debug> [004.961565] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>> <debug> [004.961665] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>> <warn> [004.961730] [mm-base-sim.c:1428] load_sim_identifier_ready(): couldn't load SIM identifier: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>> <debug> [004.961776] [mm-sim-qmi.c:224] load_imsi(): loading IMSI...
>> <warn> [004.961848] [mm-base-sim.c:1464] load_imsi_ready(): couldn't load IMSI: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>> <debug> [004.961985] [mm-broadband-modem-qmi.c:1408] modem_load_own_numbers(): loading own numbers...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:0E:00:24:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 14
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get MSISDN" (0x0024)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 20
>> >>>>>> data = 01:13:00:80:02:17:02:0E:00:24:00:07:00:02:04:00:01:00:10:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 19
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 14
>> >>>>>> tlv_length = 7
>> >>>>>> message = "Get MSISDN" (0x0024)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 01:00:10:00
>> >>>>>> translated = FAILURE: NotProvisioned
>> <warn> [004.964827] [mm-iface-modem.c:4112] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:02:00:2B:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 2
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Technology Preference" (0x002B)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 20
>> >>>>>> data = 01:13:00:80:03:13:02:02:00:2B:00:07:00:02:04:00:01:00:47:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 19
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 2
>> >>>>>> tlv_length = 7
>> >>>>>> message = "Get Technology Preference" (0x002B)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 01:00:47:00
>> >>>>>> translated = FAILURE: InvalidQmiCommand
>> <debug> [004.966996] [mm-broadband-modem-qmi.c:2853] get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
>> <warn> [004.967083] [mm-iface-modem.c:4141] load_current_modes_ready(): couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
>> <debug> [004.967134] [mm-broadband-modem-qmi.c:1875] modem_load_current_bands(): loading current bands...
>> <warn> [004.967217] [mm-iface-modem.c:4162] load_current_bands_ready(): couldn't load current Bands: 'QMI operation failed: Cannot send message: QMI service 'nas' version '1.1' required, got version '1.0''
>> <debug> [004.967916] [mm-broadband-modem-qmi.c:3281] modem_3gpp_load_imei_finish(): loaded IMEI: 353...
>> <debug> [004.968034] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'ph-sim' status, assuming disabled
>> <debug> [004.968108] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-pers' status, assuming disabled
>> <debug> [004.968162] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-sub-pers' status, assuming disabled
>> <debug> [004.968215] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'provider-pers' status, assuming disabled
>> <debug> [004.968268] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'corp-pers' status, assuming disabled
>> <debug> [004.968706] [mm-broadband-modem.c:8997] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
>> <debug> [004.969361] [mm-broadband-modem.c:9000] iface_modem_messaging_initialize_ready(): Couldn't initialize interface: 'Messaging not supported'
>> <debug> [004.969576] [mm-broadband-modem.c:9001] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
>> <debug> [004.969668] [mm-broadband-modem-qmi.c:9424] signal_check_support(): Extended signal capabilities supported
>> <debug> [004.969789] [mm-broadband-modem-qmi.c:8138] oma_check_support(): OMA capabilities not supported
>> <debug> [004.969846] [mm-broadband-modem.c:9003] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
>> <debug> [004.969900] [mm-broadband-modem-qmi.c:9090] firmware_check_support(): loading firmware images...
>> <debug> [004.969938] [mm-broadband-modem.c:9004] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
>> <info> [004.970021] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
>> <debug> [004.970120] [mm-base-modem.c:939] initialize_ready(): modem properly initialized
>> <debug> [004.970655] [mm-device.c:436] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3' at path '/org/freedesktop/ModemManager1/Modem/0'
>> <debug> [004.970677] [mm-device.c:446] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Huawei' modem, VID 0x12D1 PID 0x140C (usb)
>> <info> [005.373629] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
>> <debug> [005.374179] [mm-broadband-modem.c:8002] enabling_started(): Skipping modem initialization: not required
>> <debug> [005.374250] [mm-broadband-modem-qmi.c:9891] parent_enabling_started_ready(): Couldn't start parent enabling: Couldn't open ports during modem enabling: Couldn't get primary port
>> <debug> [005.374301] [mm-broadband-modem-qmi.c:2682] load_power_state(): Getting device operating mode...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:02:17:00:15:00:2D:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "dms"
>> <<<<<< client = 23
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 21
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Operating Mode" (0x002D)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 24
>> >>>>>> data = 01:17:00:80:02:17:02:15:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 23
>> >>>>>> flags = 0x80
>> >>>>>> service = "dms"
>> >>>>>> client = 23
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 21
>> >>>>>> tlv_length = 11
>> >>>>>> message = "Get Operating Mode" (0x002D)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Mode" (0x01)
>> >>>>>> length = 1
>> >>>>>> value = 00
>> >>>>>> translated = online
>> <debug> [005.376905] [mm-iface-modem.c:3259] set_power_state(): No need to change power state: already in 'on' power state
>> <debug> [005.376980] [mm-broadband-modem.c:8591] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 23
>> <<<<<< data = 01:16:00:00:03:13:00:04:00:02:00:0A:00:10:07:00:01:05:B0:D8:00:28:50
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 22
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 4
>> <<<<<< tlv_length = 10
>> <<<<<< message = "Set Event Report" (0x0002)
>> <<<<<< TLV:
>> <<<<<< type = "Signal Strength Indicator" (0x10)
>> <<<<<< length = 7
>> <<<<<< value = 01:05:B0:D8:00:28:50
>> <<<<<< translated = [ report = 'yes' thresholds = '{ [0] = '-80 ' [1] = '-40 ' [2] = '0 ' [3] = '40 ' [4] = '80 '}' ]
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 20
>> >>>>>> data = 01:13:00:80:03:13:02:04:00:02:00:07:00:02:04:00:00:00:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 19
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 4
>> >>>>>> tlv_length = 7
>> >>>>>> message = "Set Event Report" (0x0002)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> <debug> [005.380829] [mm-broadband-modem-qmi.c:4769] modem_3gpp_enable_unsolicited_registration_events(): Assuming serving system indications are always enabled
>> <debug> [005.380879] [mm-iface-modem-3gpp.c:751] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:05:00:24:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 5
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Serving System" (0x0024)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 54
>> >>>>>> data = 01:35:00:80:03:13:02:05:00:24:00:29:00:02:04:00:00:00:00:00:01:06:00:01:01:01:02:01:05:10:01:00:01:12:0D:00:DB:00:02:00:08:4F:70:65:72:61:74:6F:72:11:02:00:01:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 53
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 5
>> >>>>>> tlv_length = 41
>> >>>>>> message = "Get Serving System" (0x0024)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Serving System" (0x01)
>> >>>>>> length = 6
>> >>>>>> value = 01:01:01:02:01:05
>> >>>>>> translated = [ registration_state = 'registered' cs_attach_state = 'attached' ps_attach_state = 'attached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>> >>>>>> TLV:
>> >>>>>> type = "Roaming Indicator" (0x10)
>> >>>>>> length = 1
>> >>>>>> value = 01
>> >>>>>> translated = off
>> >>>>>> TLV:
>> >>>>>> type = "Current PLMN" (0x12)
>> >>>>>> length = 13
>> >>>>>> value = DB:00:02:00:08:4F:70:65:72:61:74:6F:72
>> >>>>>> translated = [ mcc = '000' mnc = '0' description = 'Operator' ]
>> >>>>>> TLV:
>> >>>>>> type = "Data Service Capability" (0x11)
>> >>>>>> length = 2
>> >>>>>> value = 01:05
>> >>>>>> translated = { [0] = 'wcdma '}
>> <debug> [005.384906] [mm-broadband-modem-qmi.c:3907] common_process_serving_system_3gpp(): Processing 3GPP info...
>> <info> [005.384959] [mm-iface-modem-3gpp.c:1154] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
>> <debug> [005.385166] [mm-broadband-modem.c:8632] enabling_step(): Modem has location capabilities, enabling the Location interface...
>> <debug> [005.385207] [mm-iface-modem-location.c:759] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
>> <debug> [005.385300] [mm-iface-modem-3gpp.c:751] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:06:00:24:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 6
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Serving System" (0x0024)
>> <info> [005.386088] [mm-iface-modem-3gpp.c:1079] update_registration_reload_current_registration_info_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
>> <debug> [005.386186] [mm-iface-modem.c:1561] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
>> <debug> [005.386224] [mm-broadband-modem.c:8671] enabling_step(): Modem has extended signal reporting capabilities, enabling the Signal interface...
>> <debug> [005.386277] [mm-iface-modem-signal.c:206] setup_refresh_context(): Extended signal information reporting disabled (rate: 0 seconds)
>> <info> [005.386530] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
>> <debug> [005.386889] [mm-iface-modem.c:1316] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
>> <debug> [005.386925] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:07:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 7
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 54
>> >>>>>> data = 01:35:00:80:03:13:02:06:00:24:00:29:00:02:04:00:00:00:00:00:01:06:00:01:01:01:02:01:05:10:01:00:01:12:0D:00:DB:00:02:00:08:4F:70:65:72:61:74:6F:72:11:02:00:01:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 53
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 6
>> >>>>>> tlv_length = 41
>> >>>>>> message = "Get Serving System" (0x0024)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Serving System" (0x01)
>> >>>>>> length = 6
>> >>>>>> value = 01:01:01:02:01:05
>> >>>>>> translated = [ registration_state = 'registered' cs_attach_state = 'attached' ps_attach_state = 'attached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>> >>>>>> TLV:
>> >>>>>> type = "Roaming Indicator" (0x10)
>> >>>>>> length = 1
>> >>>>>> value = 01
>> >>>>>> translated = off
>> >>>>>> TLV:
>> >>>>>> type = "Current PLMN" (0x12)
>> >>>>>> length = 13
>> >>>>>> value = DB:00:02:00:08:4F:70:65:72:61:74:6F:72
>> >>>>>> translated = [ mcc = '000' mnc = '0' description = 'Operator' ]
>> >>>>>> TLV:
>> >>>>>> type = "Data Service Capability" (0x11)
>> >>>>>> length = 2
>> >>>>>> value = 01:05
>> >>>>>> translated = { [0] = 'wcdma '}
>> <debug> [005.388620] [mm-broadband-modem-qmi.c:3907] common_process_serving_system_3gpp(): Processing 3GPP info...
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:07:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 7
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [005.390582] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [005.390591] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <info> [005.390625] [mm-iface-modem.c:894] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> umts)
>> <debug> [005.390660] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <debug> [005.390671] [mm-iface-modem.c:1235] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s)
>> <debug> [035.926131] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:08:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 8
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:08:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 8
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [035.929085] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [035.929117] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [035.929252] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <debug> [065.914455] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:09:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 9
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:09:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 9
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [065.917316] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [065.917346] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [065.917473] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <debug> [095.922543] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:0A:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 10
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:0A:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 10
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [095.925589] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [095.925618] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [095.925748] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <debug> [125.904889] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:0B:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 11
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:0B:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 11
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [125.907518] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [125.907548] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [125.907676] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <debug> [155.925855] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:0C:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 12
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:0C:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 12
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [155.929687] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [155.929717] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [155.929847] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>>
>>
>> $ nmcli connection up MBB
>>
>>
>> <debug> [185.926004] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:0D:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 13
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:0D:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 13
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [185.930010] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [185.930059] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [185.930191] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <debug> [215.925346] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:0E:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 14
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:0E:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 14
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [215.928001] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [215.928029] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [215.928185] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <debug> [245.903890] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:0F:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 15
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:0F:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 15
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [245.908254] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [245.908283] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [245.908413] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <debug> [275.916398] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:10:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 16
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:10:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 16
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [275.920440] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [275.920468] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [275.920591] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <info> [293.146515] [mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...
>> <debug> [293.146572] [mm-iface-modem-simple.c:651] connect_auth_ready(): PIN: unspecified
>> <debug> [293.146595] [mm-iface-modem-simple.c:653] connect_auth_ready(): Operator ID: unspecified
>> <debug> [293.285711] [mm-iface-modem-simple.c:655] connect_auth_ready(): Allowed roaming: no
>> <debug> [293.286050] [mm-iface-modem-simple.c:657] connect_auth_ready(): APN: access.point.name
>> <debug> [293.286323] [mm-iface-modem-simple.c:662] connect_auth_ready(): IP family: ipv4
>> <debug> [293.286581] [mm-iface-modem-simple.c:670] connect_auth_ready(): Allowed authentication: none, pap
>> <debug> [293.286816] [mm-iface-modem-simple.c:675] connect_auth_ready(): User: username
>> <debug> [293.287069] [mm-iface-modem-simple.c:677] connect_auth_ready(): Password: password
>> <debug> [293.287311] [mm-iface-modem-simple.c:679] connect_auth_ready(): Number: unspecified
>> <info> [293.287568] [mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled
>> <info> [293.287932] [mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register
>> <debug> [293.288131] [mm-iface-modem-3gpp.c:390] mm_iface_modem_3gpp_register_in_network(): Already registered in network '00000', automatic registration not launched...
>> <info> [293.288419] [mm-iface-modem-simple.c:501] connection_step(): Simple connect state (6/8): Bearer
>> <debug> [293.288650] [mm-iface-modem-simple.c:521] connection_step(): Creating new bearer...
>> <debug> [293.288901] [mm-broadband-modem-qmi.c:320] modem_create_bearer(): Creating QMI bearer in QMI modem
>> <debug> [293.289774] [mm-broadband-modem-qmi.c:299] modem_create_bearer_finish(): New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
>> <info> [293.290089] [mm-iface-modem-simple.c:583] connection_step(): Simple connect state (7/8): Connect
>> <debug> [293.290131] [mm-base-bearer.c:590] mm_base_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
>> <info> [293.290184] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
>> <debug> [293.290570] [mm-bearer-qmi.c:952] _connect(): Launching connection with QMI port (usb/cdc-wdm0) and data port (net/wwan0)
>> <debug> [293.290610] [mm-bearer-qmi.c:655] connect_context_step(): Running IPv4 connection setup
>> <debug> [293.290632] [mm-bearer-qmi.c:668] connect_context_step(): Allocating IPv4-specific WDS client
>> [/dev/cdc-wdm0] Allocating new client ID...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 16
>> <<<<<< data = 01:0F:00:00:00:00:00:05:22:00:04:00:01:01:00:01
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 15
>> <<<<<< flags = 0x00
>> <<<<<< service = "ctl"
>> <<<<<< client = 0
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 5
>> <<<<<< tlv_length = 4
>> <<<<<< message = "Allocate CID" (0x0022)
>> <<<<<< TLV:
>> <<<<<< type = "Service" (0x01)
>> <<<<<< length = 1
>> <<<<<< value = 01
>> <<<<<< translated = wds
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 24
>> >>>>>> data = 01:17:00:80:00:00:01:05:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:01:18
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 23
>> >>>>>> flags = 0x80
>> >>>>>> service = "ctl"
>> >>>>>> client = 0
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 5
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Allocate CID" (0x0022)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Allocation Info" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = 01:18
>> >>>>>> translated = [ service = 'wds' cid = '24' ]
>> [/dev/cdc-wdm0] Registered 'wds' (version 1.3) client with ID '24'
>> <debug> [293.295378] [mm-bearer-qmi.c:710] connect_context_step(): Starting IPv4 connection...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 70
>> <<<<<< data = 01:45:00:00:01:18:00:01:00:20:00:39:00:19:01:00:04:18:09:00:70:61:73:73:77:6F:72:64:17:10:00:75:73:65:72:6E:61:6D:65:16:01:00:01:14:0F:00:61:63:63:65:73:73:2E:70:6F:69:6E:74:2E:6E:61:6D:65
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 69
>> <<<<<< flags = 0x00
>> <<<<<< service = "wds"
>> <<<<<< client = 24
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 1
>> <<<<<< tlv_length = 57
>> <<<<<< message = "Start Network" (0x0020)
>> <<<<<< TLV:
>> <<<<<< type = "IP Family Preference" (0x19)
>> <<<<<< length = 1
>> <<<<<< value = 04
>> <<<<<< translated = ipv4
>> <<<<<< TLV:
>> <<<<<< type = "Password" (0x18)
>> <<<<<< length = 9
>> <<<<<< value = 70:61:73:73:77:6F:72:64
>> <<<<<< translated = password
>> <<<<<< TLV:
>> <<<<<< type = "Username" (0x17)
>> <<<<<< length = 16
>> <<<<<< value = 75:73:65:72:6E:61:6D:65
>> <<<<<< translated = username
>> <<<<<< TLV:
>> <<<<<< type = "Authentication Preference" (0x16)
>> <<<<<< length = 1
>> <<<<<< value = 01
>> <<<<<< translated = pap
>> <<<<<< TLV:
>> <<<<<< type = "APN" (0x14)
>> <<<<<< length = 15
>> <<<<<< value = 61:63:63:65:73:73:2E:70:6F:69:6E:74:2E:6E:61:6D:65
>> <<<<<< translated = access.point.name
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 27
>> >>>>>> data = 01:1A:00:80:01:18:02:01:00:20:00:0E:00:02:04:00:00:00:00:00:01:04:00:34:91:CF:01
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 26
>> >>>>>> flags = 0x80
>> >>>>>> service = "wds"
>> >>>>>> client = 24
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 1
>> >>>>>> tlv_length = 14
>> >>>>>> message = "Start Network" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Packet Data Handle" (0x01)
>> >>>>>> length = 4
>> >>>>>> value = 34:91:CF:01
>> >>>>>> translated = 30380340
>> <debug> [296.467180] [mm-bearer-qmi.c:725] connect_context_step(): Getting IPv4 configuration...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 20
>> <<<<<< data = 01:13:00:00:01:18:00:02:00:2D:00:07:00:10:04:00:30:E3:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 19
>> <<<<<< flags = 0x00
>> <<<<<< service = "wds"
>> <<<<<< client = 24
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 2
>> <<<<<< tlv_length = 7
>> <<<<<< message = "Get Current Settings" (0x002D)
>> <<<<<< TLV:
>> <<<<<< type = "Requested Settings" (0x10)
>> <<<<<< length = 4
>> <<<<<< value = 30:E3:00:00
>> <<<<<< translated = dns-address, granted-qos, ip-address, gateway-info, mtu, domain-name-list, ip-family
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 18
>> >>>>>> data = 01:11:00:80:01:FF:04:00:00:22:00:05:00:01:02:00:02:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 17
>> >>>>>> flags = 0x80
>> >>>>>> service = "wds"
>> >>>>>> client = 255
>> >>>>>> QMI:
>> >>>>>> flags = "indication"
>> >>>>>> transaction = 0
>> >>>>>> tlv_length = 5
>> >>>>>> message = (0x0022)
>> >>>>>> TLV:
>> >>>>>> type = 0x01
>> >>>>>> length = 2
>> >>>>>> value = 02:00
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 91
>> >>>>>> data = 01:5A:00:80:01:18:02:02:00:2D:00:4E:00:02:04:00:00:00:00:00:1E:04:00:4A:3E:93:1F:15:04:00:82:B8:C6:C1:16:04:00:8C:B8:C6:C1:20:04:00:49:3E:93:1F:21:04:00:FC:FF:FF:FF:17:21:00:04:F5:02:00:00:3E:03:00:00:10:00:00:00:80:00:00:00:02:DC:05:00:00:04:07:03:00:00:00:00:03:00:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 90
>> >>>>>> flags = 0x80
>> >>>>>> service = "wds"
>> >>>>>> client = 24
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 2
>> >>>>>> tlv_length = 78
>> >>>>>> message = "Get Current Settings" (0x002D)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "IPv4 Address" (0x1e)
>> >>>>>> length = 4
>> >>>>>> value = 4E:38:EA:01
>> >>>>>> translated = 32127054
>> >>>>>> TLV:
>> >>>>>> type = "Primary IPv4 DNS Address" (0x15)
>> >>>>>> length = 4
>> >>>>>> value = 08:08:08:08
>> >>>>>> translated = 134744072
>> >>>>>> TLV:
>> >>>>>> type = "Secondary IPv4 DNS Address" (0x16)
>> >>>>>> length = 4
>> >>>>>> value = 04:04:08:08
>> >>>>>> translated = 134743044
>> >>>>>> TLV:
>> >>>>>> type = "IPv4 Gateway Address" (0x20)
>> >>>>>> length = 4
>> >>>>>> value = 4D:38:EA:01
>> >>>>>> translated = 32127053
>> >>>>>> TLV:
>> >>>>>> type = "IPv4 Gateway Subnet Mask" (0x21)
>> >>>>>> length = 4
>> >>>>>> value = FC:FF:FF:FF
>> >>>>>> translated = 4294967292
>> >>>>>> TLV:
>> >>>>>> type = 0x17
>> >>>>>> length = 33
>> >>>>>> value = 04:F5:02:00:00:3E:03:00:00:10:00:00:00:80:00:00:00:02:DC:05:00:00:04:07:03:00:00:00:00:03:00:00:00
>> <debug> [296.471217] [mm-bearer-qmi.c:461] get_current_settings_ready(): IP Family: failed (Field 'IP Family' was not found in the message); assuming IPv4
>> <debug> [296.471244] [mm-bearer-qmi.c:467] get_current_settings_ready(): IP Family: IPv4
>> <debug> [296.471265] [mm-bearer-qmi.c:470] get_current_settings_ready(): MTU: failed (Field 'MTU' was not found in the message)
>> <debug> [296.471307] [mm-bearer-qmi.c:291] get_ipv4_config(): QMI IPv4 Settings:
>> <debug> [296.471369] [mm-bearer-qmi.c:300] get_ipv4_config(): Address: 01.234.56.78/30
>> <debug> [296.471394] [mm-bearer-qmi.c:306] get_ipv4_config(): Gateway: 01.234.56.77
>> <debug> [296.471413] [mm-bearer-qmi.c:316] get_ipv4_config(): DNS #1: 8.8.8.8
>> <debug> [296.471708] [mm-bearer-qmi.c:326] get_ipv4_config(): DNS #2: 8.8.4.4
>> <debug> [296.473506] [mm-bearer-qmi.c:492] get_current_settings_ready(): Domains: failed (Field 'Domain Name List' was not found in the message)
>> <debug> [296.474389] [mm-port.c:95] mm_port_set_connected(): (wwan0): port now connected
>> <debug> [296.475147] [mm-base-bearer.c:488] connect_ready(): Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
>> <info> [296.475941] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
>> <info> [296.478308] [mm-iface-modem-simple.c:602] connection_step(): Simple connect state (8/8): All done
>> <debug> [305.905471] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:11:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 17
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:11:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 17
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [305.910694] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [305.910723] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [305.910852] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>>
>>
>> $ nmcli connection down MBB
>>
>>
>> <debug> [335.906464] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:12:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 18
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:12:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 18
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [335.910839] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [335.910868] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [335.910997] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>> <debug> [350.667701] [mm-base-bearer.c:760] mm_base_bearer_disconnect(): Disconnecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
>> <info> [350.668805] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 20
>> <<<<<< data = 01:13:00:00:01:18:00:03:00:21:00:07:00:01:04:00:34:91:CF:01
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 19
>> <<<<<< flags = 0x00
>> <<<<<< service = "wds"
>> <<<<<< client = 24
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 3
>> <<<<<< tlv_length = 7
>> <<<<<< message = "Stop Network" (0x0021)
>> <<<<<< TLV:
>> <<<<<< type = "Packet Data Handle" (0x01)
>> <<<<<< length = 4
>> <<<<<< value = 34:91:CF:01
>> <<<<<< translated = 30380340
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 20
>> >>>>>> data = 01:13:00:80:01:18:02:03:00:21:00:07:00:02:04:00:00:00:00:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 19
>> >>>>>> flags = 0x80
>> >>>>>> service = "wds"
>> >>>>>> client = 24
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 3
>> >>>>>> tlv_length = 7
>> >>>>>> message = "Stop Network" (0x0021)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> <debug> [350.677467] [mm-port.c:95] mm_port_set_connected(): (wwan0): port now disconnected
>> <debug> [350.677486] [mm-base-bearer.c:693] disconnect_ready(): Disconnected bearer '/org/freedesktop/ModemManager1/Bearer/0'
>> <info> [350.677516] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 23
>> >>>>>> data = 01:16:00:80:01:FF:04:00:00:22:00:0A:00:01:02:00:01:00:10:02:00:01:00
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 22
>> >>>>>> flags = 0x80
>> >>>>>> service = "wds"
>> >>>>>> client = 255
>> >>>>>> QMI:
>> >>>>>> flags = "indication"
>> >>>>>> transaction = 0
>> >>>>>> tlv_length = 10
>> >>>>>> message = (0x0022)
>> >>>>>> TLV:
>> >>>>>> type = 0x01
>> >>>>>> length = 2
>> >>>>>> value = 01:00
>> >>>>>> TLV:
>> >>>>>> type = 0x10
>> >>>>>> length = 2
>> >>>>>> value = 01:00
>> <debug> [365.910100] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>> [/dev/cdc-wdm0] Sent message...
>> <<<<<< RAW:
>> <<<<<< length = 13
>> <<<<<< data = 01:0C:00:00:03:13:00:13:00:20:00:00:00
>> [/dev/cdc-wdm0] Sent message (translated)...
>> <<<<<< QMUX:
>> <<<<<< length = 12
>> <<<<<< flags = 0x00
>> <<<<<< service = "nas"
>> <<<<<< client = 19
>> <<<<<< QMI:
>> <<<<<< flags = "none"
>> <<<<<< transaction = 19
>> <<<<<< tlv_length = 0
>> <<<<<< message = "Get Signal Strength" (0x0020)
>> [/dev/cdc-wdm0] Received message...
>> >>>>>> RAW:
>> >>>>>> length = 25
>> >>>>>> data = 01:18:00:80:03:13:02:13:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:AB:05
>> [/dev/cdc-wdm0] Received message (translated)...
>> >>>>>> QMUX:
>> >>>>>> length = 24
>> >>>>>> flags = 0x80
>> >>>>>> service = "nas"
>> >>>>>> client = 19
>> >>>>>> QMI:
>> >>>>>> flags = "response"
>> >>>>>> transaction = 19
>> >>>>>> tlv_length = 12
>> >>>>>> message = "Get Signal Strength" (0x0020)
>> >>>>>> TLV:
>> >>>>>> type = "Result" (0x02)
>> >>>>>> length = 4
>> >>>>>> value = 00:00:00:00
>> >>>>>> translated = SUCCESS
>> >>>>>> TLV:
>> >>>>>> type = "Signal Strength" (0x01)
>> >>>>>> length = 2
>> >>>>>> value = AB:05
>> >>>>>> translated = [ strength = '-85' radio_interface = 'umts' ]
>> <debug> [365.912861] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -85 dBm
>> <debug> [365.912890] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -85 dBm --> 46%
>> <debug> [365.913019] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (46)
>>
>
> Starting Network Manager...
> <info> NetworkManager (version 0.9.11.0-1.20140805.gite9fbf80.fc21) is starting...
> <info> Read config: /etc/NetworkManager/NetworkManager.conf and conf.d: 20-connectivity-fedora.conf, keyfile-plugin.conf
> <info> WEXT support is enabled
> <debug> [137.839669] [platform/nm-linux-platform.c:4023] setup(): Netlink socket for requests established: 440402636
> <debug> [137.839728] [platform/nm-linux-platform.c:4040] setup(): Netlink socket for events established: 1291846348
> <debug> [137.874368] [platform/nm-platform.c:2565] log_link(): signal: link added: 2: wwan0 <DOWN> mtu 1500 wwan driver 'qmi_wwan' udi '/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3/2-3:1.1/net/wwan0'
> <debug> [137.874493] [platform/nm-linux-platform.c:589] ethtool_get(): ethtool: Request failed: Operation not supported
> <debug> [137.874509] [platform/nm-platform.c:2565] log_link(): signal: link added: 1: lo <UP,LOWER_UP> mtu 65536 loopback driver 'unknown' udi '/sys/devices/virtual/net/lo'
> <debug> [137.874580] [nm-dbus-manager.c:190] private_server_new(): (private) creating private socket unix:path=/var/run/NetworkManager/private.
> <debug> [138.015850] [nm-dbus-manager.c:190] private_server_new(): (dhcp) creating private socket unix:path=/var/run/NetworkManager/private-dhcp.
> <debug> [138.254982] [nm-dispatcher.c:736] dispatcher_dir_changed(): dispatcher: default script directory '/etc/NetworkManager/dispatcher.d' has scripts
> <debug> [138.791530] [nm-dispatcher.c:738] dispatcher_dir_changed(): dispatcher: pre-up script directory '/etc/NetworkManager/dispatcher.d/pre-up.d' has no scripts
> <debug> [139.003056] [nm-dispatcher.c:738] dispatcher_dir_changed(): dispatcher: pre-down script directory '/etc/NetworkManager/dispatcher.d/pre-down.d' has no scripts
> <info> Loaded plugin keyfile: (c) 2007 - 2013 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
> <info> new connection /etc/NetworkManager/system-connections/MBB
> <debug> [139.047253] [settings/nm-settings-connection.c:1711] nm_settings_connection_read_and_fill_timestamp(): failed to read connection timestamp for '12345678-1234-1234-1234-123456789abc': (3) Key file does not have key '12345678-1234-1234-1234-123456789abc'
> <debug> [139.051699] [nm-sleep-monitor-systemd.c:110] take_inhibitor(): Taking systemd sleep inhibitor
> <info> monitoring kernel firmware directory '/lib/firmware'.
> <debug> [139.052669] [firewall-manager/nm-firewall-manager.c:286] nm_firewall_manager_init(): firewall is running
> <info> WiFi hardware radio set enabled
> <info> WWAN hardware radio set enabled
> <info> Loaded device plugin: /usr/lib64/NetworkManager/libnm-device-plugin-wwan.so
> <info> WiFi enabled by radio killswitch; enabled by state file
> <info> WWAN enabled by radio killswitch; enabled by state file
> <info> WiMAX enabled by radio killswitch; enabled by state file
> <info> Networking is enabled by state file
> <debug> [139.145282] [platform/nm-linux-platform.c:589] ethtool_get(): ethtool: Request failed: Operation not supported
> <debug> [139.145622] [platform/nm-platform.c:2565] log_link(): signal: link added: 1: lo <UP,LOWER_UP> mtu 65536 loopback driver 'unknown' udi '/sys/devices/virtual/net/lo'
> <debug> [139.146184] [devices/nm-device.c:7135] constructor(): [0x7f2db8764280] (lo): constructor(): NMDeviceGeneric
> <debug> [139.146254] [devices/nm-device.c:7102] device_get_driver_info(): [0x7f2db8764280] (lo): SIOCETHTOOL ioctl() failed: cmd=ETHTOOL_GDRVINFO, iface=lo, errno=95
> <debug> [139.146316] [devices/nm-device.c:6940] nm_device_update_hw_address(): [0x7f2db8764280] (lo): hardware address now 00:00:00:00:00:00
> <info> (lo): link connected
> <info> (lo): carrier is ON
> <debug> [139.146476] [platform/nm-linux-platform.c:2121] sysctl_get(): error reading /sys/class/net/lo/phys_port_id: Failed to read from file '/sys/class/net/lo/phys_port_id': Operation not supported
> <info> (lo): new Generic device (driver: 'unknown' ifindex: 1)
> <info> (lo): exported as /org/freedesktop/NetworkManager/Devices/0
> <debug> [139.146906] [nm-manager.c:1149] system_create_virtual_devices(): creating virtual devices...
> <debug> [139.146973] [platform/nm-platform.c:2565] log_link(): signal: link added: 2: wwan0 <DOWN> mtu 1500 wwan driver 'qmi_wwan' udi '/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3/2-3:1.1/net/wwan0'
> <debug> [139.147006] [nm-manager.c:1149] system_create_virtual_devices(): creating virtual devices...
> <info> startup complete
> <debug> [139.147062] [main.c:670] main(): setting up local loopback
> <debug> [139.147086] [platform/nm-platform.c:806] nm_platform_link_set_up(): link: setting up 'lo' (1)
> <debug> [139.147130] [platform/nm-linux-platform.c:2378] link_change_flags(): link: change 1: flags set 'up' (1)
> <debug> [139.147358] [platform/nm-linux-platform.c:414] get_kernel_object(): get_kernel_object for link: lo (1, family 0)
> <debug> [139.147416] [platform/nm-linux-platform.c:589] ethtool_get(): ethtool: Request failed: Operation not supported
> <debug> [139.147472] [platform/nm-platform.c:2565] log_link(): signal: link changed: 1: lo <UP,LOWER_UP> mtu 65536 loopback driver 'unknown' udi '/sys/devices/virtual/net/lo'
> <debug> [139.147594] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 20)
> <debug> [139.148555] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 3 returned 0x7f2db8718210
> <debug> [139.148722] [nm-sleep-monitor-systemd.c:99] inhibit_done(): Inhibitor fd is 17
> <debug> [139.148964] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db8764280] (lo): add_pending_action (1): 'autoactivate'
> <debug> [139.149673] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db8764280] (lo): remove_pending_action (0): 'autoactivate'
> <info> ModemManager available in the bus
> <debug> [139.153725] [platform/nm-platform.c:567] nm_platform_link_get_ifindex(): link not found: cdc-wdm0
> <warn> (cdc-wdm0): failed to look up interface index
> <debug> [139.153785] [devices/nm-device.c:7135] constructor(): [0x7f2db87703b0] (cdc-wdm0): constructor(): NMDeviceModem
> <debug> [139.157680] [devices/nm-device.c:7102] device_get_driver_info(): [0x7f2db87703b0] (cdc-wdm0): SIOCETHTOOL ioctl() failed: cmd=ETHTOOL_GDRVINFO, iface=cdc-wdm0, errno=19
> <debug> [139.157836] [nm-manager.c:1387] manager_rfkill_update_one_type(): WWAN hw-enabled 1 sw-enabled 1
> <info> (cdc-wdm0): modem state changed, 'disabled' --> 'enabling' (reason: user preference)
> <info> (cdc-wdm0): new Broadband device (driver: 'qmi_wwan' ifindex: 0)
> <info> (cdc-wdm0): exported as /org/freedesktop/NetworkManager/Devices/1
> <info> (cdc-wdm0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
> <debug> [139.158079] [platform/nm-linux-platform.c:2121] sysctl_get(): error reading /proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra: Failed to open file '/proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra': No such file or directory
> <debug> [139.158101] [platform/nm-linux-platform.c:2121] sysctl_get(): error reading /proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra_defrtr: Failed to open file '/proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra_defrtr': No such file or directory
> <debug> [139.158119] [platform/nm-linux-platform.c:2121] sysctl_get(): error reading /proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra_pinfo: Failed to open file '/proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra_pinfo': No such file or directory
> <debug> [139.158135] [platform/nm-linux-platform.c:2121] sysctl_get(): error reading /proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra_rtr_pref: Failed to open file '/proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra_rtr_pref': No such file or directory
> <debug> [139.158151] [platform/nm-linux-platform.c:2121] sysctl_get(): error reading /proc/sys/net/ipv6/conf/cdc-wdm0/disable_ipv6: Failed to open file '/proc/sys/net/ipv6/conf/cdc-wdm0/disable_ipv6': No such file or directory
> <debug> [139.158167] [platform/nm-linux-platform.c:2121] sysctl_get(): error reading /proc/sys/net/ipv6/conf/cdc-wdm0/hop_limit: Failed to open file '/proc/sys/net/ipv6/conf/cdc-wdm0/hop_limit': No such file or directory
> <debug> [139.158183] [platform/nm-linux-platform.c:2121] sysctl_get(): error reading /proc/sys/net/ipv6/conf/cdc-wdm0/use_tempaddr: Failed to open file '/proc/sys/net/ipv6/conf/cdc-wdm0/use_tempaddr': No such file or directory
> <debug> [139.158198] [platform/nm-linux-platform.c:2022] sysctl_set(): sysctl: failed to open '/proc/sys/net/ipv6/conf/cdc-wdm0/disable_ipv6': (2) No such file or directory
> <debug> [139.158212] [platform/nm-linux-platform.c:2022] sysctl_set(): sysctl: failed to open '/proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra_defrtr': (2) No such file or directory
> <debug> [139.158362] [platform/nm-linux-platform.c:2022] sysctl_set(): sysctl: failed to open '/proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra_pinfo': (2) No such file or directory
> <debug> [139.158569] [platform/nm-linux-platform.c:2022] sysctl_set(): sysctl: failed to open '/proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra_rtr_pref': (2) No such file or directory
> <debug> [139.158758] [platform/nm-linux-platform.c:2022] sysctl_set(): sysctl: failed to open '/proc/sys/net/ipv6/conf/cdc-wdm0/use_tempaddr': (2) No such file or directory
> <debug> [139.158914] [devices/nm-device.c:5546] nm_device_bring_up(): [0x7f2db87703b0] (cdc-wdm0): bringing up device.
> <info> (cdc-wdm0): preparing device
> <debug> [139.163534] [devices/nm-device.c:6386] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (0): 'autoconf6' not pending (expected)
> <debug> [139.163559] [platform/nm-linux-platform.c:2022] sysctl_set(): sysctl: failed to open '/proc/sys/net/ipv6/conf/cdc-wdm0/disable_ipv6': (2) No such file or directory
> <debug> [139.163572] [platform/nm-linux-platform.c:2022] sysctl_set(): sysctl: failed to open '/proc/sys/net/ipv6/conf/cdc-wdm0/accept_ra': (2) No such file or directory
> <debug> [139.163583] [platform/nm-linux-platform.c:2022] sysctl_set(): sysctl: failed to open '/proc/sys/net/ipv6/conf/cdc-wdm0/use_tempaddr': (2) No such file or directory
> <info> (cdc-wdm0): modem state 'enabling'
> <debug> [139.163711] [devices/nm-device.c:6685] _set_state_full(): [0x7f2db87703b0] (cdc-wdm0): device is available, will transition to DISCONNECTED
> <debug> [139.163720] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db87703b0] (cdc-wdm0): add_pending_action (1): 'queued state change to disconnected'
> <debug> [139.163732] [devices/nm-device.c:6866] nm_device_queue_state(): [0x7f2db87703b0] (cdc-wdm0): queued state change to disconnected due to none (id 33)
> <debug> [139.163790] [nm-manager.c:1149] system_create_virtual_devices(): creating virtual devices...
> <debug> [139.164067] [devices/nm-device.c:6815] queued_set_state(): [0x7f2db87703b0] (cdc-wdm0): running queued state change to disconnected (id 33)
> <info> (cdc-wdm0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
> <debug> [139.164164] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db87703b0] (cdc-wdm0): add_pending_action (2): 'autoactivate'
> <debug> [139.164178] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (1): 'queued state change to disconnected'
> <debug> [139.164258] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (0): 'autoactivate'
> <info> (cdc-wdm0): modem state changed, 'enabling' --> 'registered' (reason: user-requested)
> <debug> [139.468346] [nm-dbus-manager.c:160] private_server_new_connection(): (private) accepted connection 0x7f2db876e180 on private socket.
> <debug> [139.468433] [nm-dbus-manager.c:452] private_connection_new(): (private) registered 0x7f2db871f080 (NMAgentManager) at '/org/freedesktop/NetworkManager/AgentManager' on private socket.
> <debug> [139.468470] [nm-dbus-manager.c:452] private_connection_new(): (private) registered 0x7f2db870ada0 (NMSettings) at '/org/freedesktop/NetworkManager/Settings' on private socket.
> <debug> [139.468503] [nm-dbus-manager.c:452] private_connection_new(): (private) registered 0x7f2db8764280 (NMDeviceGeneric) at '/org/freedesktop/NetworkManager/Devices/0' on private socket.
> <debug> [139.468530] [nm-dbus-manager.c:452] private_connection_new(): (private) registered 0x7f2db8744150 (NMManager) at '/org/freedesktop/NetworkManager' on private socket.
> <debug> [139.468564] [nm-dbus-manager.c:452] private_connection_new(): (private) registered 0x7f2db87703b0 (NMDeviceModem) at '/org/freedesktop/NetworkManager/Devices/1' on private socket.
> <debug> [139.468592] [nm-dbus-manager.c:452] private_connection_new(): (private) registered 0x7f2db87238e0 (NMKeyfileConnection) at '/org/freedesktop/NetworkManager/Settings/0' on private socket.
> <debug> [139.478080] [nm-dbus-manager.c:117] private_server_message_filter(): (private) closed connection 0x7f2db876e180 on private socket.
> <debug> [173.572626] [settings/nm-agent-manager.c:333] impl_agent_manager_register_with_capabilities(): (:1.30/org.freedesktop.nm-applet/1000) requesting permissions
> <debug> [173.588119] [settings/nm-agent-manager.c:245] agent_register_permissions_done(): (:1.30/org.freedesktop.nm-applet/1000) agent registered
> <debug> [173.588248] [nm-policy.c:1183] reset_autoconnect_for_failed_secrets(): Re-enabling autoconnect for all connections with failed secrets
> <debug> [173.588291] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db8764280] (lo): add_pending_action (1): 'autoactivate'
> <debug> [173.588349] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db87703b0] (cdc-wdm0): add_pending_action (1): 'autoactivate'
> <debug> [173.588417] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db8764280] (lo): remove_pending_action (0): 'autoactivate'
> <debug> [173.588457] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (0): 'autoactivate'
>
>
> $ nmcli connection up MBB
>
>
> <debug> [427.061373] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db87703b0] (cdc-wdm0): add_pending_action (1): 'activation::0x7f2db87239a0'
> <info> (cdc-wdm0): Activation: starting connection 'MBB'
> <info> (cdc-wdm0): Activation: Stage 1 of 5 (Device Prepare) scheduled...
> <info> (cdc-wdm0): Activation: Stage 1 of 5 (Device Prepare) started...
> <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
> <info> NetworkManager state is now CONNECTING
> <debug> [427.077347] [nm-active-connection.c:447] check_master_ready(): (0x7f2db87239a0): not signalling master-ready (no master)
> <debug> [427.077365] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db8764280] (lo): add_pending_action (1): 'autoactivate'
> <debug> [427.077384] [nm-manager.c:4371] policy_activating_device_changed(): ActivatingConnection now MBB
> <info> (cdc-wdm0): Activation: Stage 1 of 5 (Device Prepare) complete.
> <debug> [427.077613] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db8764280] (lo): remove_pending_action (0): 'autoactivate'
> <info> (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
> <info> (cdc-wdm0): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
> <debug> [430.417149] [platform/nm-platform.c:806] nm_platform_link_set_up(): link: setting up 'wwan0' (2)
> <debug> [430.417169] [platform/nm-linux-platform.c:2378] link_change_flags(): link: change 2: flags set 'up' (1)
> <debug> [430.417348] [platform/nm-linux-platform.c:414] get_kernel_object(): get_kernel_object for link: wwan0 (2, family 0)
> <debug> [430.417465] [platform/nm-platform.c:2565] log_link(): signal: link changed: 2: wwan0 <UP,LOWER_UP> mtu 1500 wwan driver 'qmi_wwan' udi '/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3/2-3:1.1/net/wwan0'
> <debug> [430.417535] [platform/nm-linux-platform.c:1989] _log_dbg_sysctl_set_impl(): sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/disable_ipv6' to '1' (current value is '0')
> <info> (cdc-wdm0): Activation: Stage 2 of 5 (Device Configure) scheduled...
> <debug> [430.417628] [platform/nm-linux-platform.c:1872] event_notification(): netlink event (type 16) for link: wwan0 (2, family 0)
> <debug> [430.440491] [platform/nm-linux-platform.c:414] get_kernel_object(): get_kernel_object for link: wwan0 (2, family 0)
> <debug> [430.441292] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 24)
> <debug> [430.442167] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 5 had no result
> <debug> [430.442580] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 24)
> <debug> [430.469608] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 5 had no result
> <debug> [430.477126] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 25)
> <debug> [430.478049] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 5 had no result
> <debug> [430.478507] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 25)
> <debug> [430.479298] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 5 had no result
> <debug> [430.479765] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 21)
> <debug> [430.480492] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 3 had no result
> <info> (cdc-wdm0): Activation: Stage 2 of 5 (Device Configure) starting...
> <info> (cdc-wdm0): device state change: prepare -> config (reason 'none') [40 50 0]
> <debug> [430.482729] [devices/nm-device.c:5546] nm_device_bring_up(): [0x7f2db87703b0] (cdc-wdm0): bringing up device.
> <debug> [430.483436] [platform/nm-platform.c:806] nm_platform_link_set_up(): link: setting up 'wwan0' (2)
> <debug> [430.490442] [platform/nm-linux-platform.c:2378] link_change_flags(): link: change 2: flags set 'up' (1)
> <debug> [430.491119] [platform/nm-linux-platform.c:414] get_kernel_object(): get_kernel_object for link: wwan0 (2, family 0)
> <debug> [430.491412] [platform/nm-platform.c:2565] log_link(): signal: link changed: 2: wwan0 <UP,LOWER_UP> mtu 1500 wwan driver 'qmi_wwan' udi '/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3/2-3:1.1/net/wwan0'
> <info> (cdc-wdm0): Activation: Stage 2 of 5 (Device Configure) successful.
> <debug> [430.491761] [devices/nm-device.c:4101] nm_device_activate_schedule_stage3_ip_config_start(): [0x7f2db87703b0] (cdc-wdm0): Activation: setting firewall zone 'block'
> <debug> [430.491999] [firewall-manager/nm-firewall-manager.c:147] nm_firewall_manager_add_or_change_zone(): (wwan0) firewall zone change -> "block" [1]
> <info> (cdc-wdm0): Activation: Stage 2 of 5 (Device Configure) complete.
> <debug> [430.500353] [firewall-manager/nm-firewall-manager.c:117] add_or_change_cb(): (wwan0) firewall zone add/change succeeded [1]
> <info> (cdc-wdm0): Activation: Stage 3 of 5 (IP Configure Start) scheduled.
> <info> (cdc-wdm0): Activation: Stage 3 of 5 (IP Configure Start) started...
> <info> (cdc-wdm0): device state change: config -> ip-config (reason 'none') [50 70 0]
> <info> (cdc-wdm0): Activation: Stage 3 of 5 (IP Configure Start) complete.
> <info> (cdc-wdm0): IPv4 static configuration:
> <info> address 01.234.56.78/30
> <info> gateway 01.234.56.77
> <info> DNS 8.8.8.8
> <info> DNS 8.8.4.4
> <debug> [430.509587] [NetworkManagerUtils.c:1425] monotonic_timestamp_get(): monotonic timestamp started counting 1.678712852 seconds ago with an offset of 314.0 seconds to CLOCK_BOOTTIME (local time is 2014-08-06 20:40:30)
> <debug> [430.509791] [platform/nm-platform.c:1445] nm_platform_ip4_address_add(): address: adding or updating IPv4 address: 01.234.56.78/30 lft forever pref forever lifetime 1-0[4294967295,4294967295] dev wwan0 src unknown
> <debug> [430.510184] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 2 returned 0x7f2db8777500
> <debug> [430.510398] [platform/nm-platform.c:2571] log_ip4_address(): signal: address 4 added: 01.234.56.78/30 lft forever pref forever lifetime 1-0[4294967295,4294967295] dev wwan0 src kernel
> <debug> [430.510633] [devices/nm-device.c:5889] device_ip_changed(): [0x7f2db87703b0] (cdc-wdm0): queued IP config change
> <debug> [430.510910] [devices/nm-device.c:5080] nm_device_set_ip4_config(): [0x7f2db87703b0] (cdc-wdm0): set IP4Config instance (/org/freedesktop/NetworkManager/IP4Config/0)
> <debug> [430.511147] [dns-manager/nm-dns-manager.c:1013] nm_dns_manager_begin_updates(): (device_ip4_config_changed): queueing DNS updates (1)
> <debug> [430.511366] [dns-manager/nm-dns-manager.c:1031] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration did not change
> <debug> [430.511603] [dns-manager/nm-dns-manager.c:1035] nm_dns_manager_end_updates(): (device_ip4_config_changed): no DNS changes to commit (0)
> <info> (cdc-wdm0): Activation: Stage 5 of 5 (IPv4 Configure Commit) scheduled...
> <debug> [430.512197] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 20)
> <debug> [430.512478] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 2 returned 0x7f2db876c780
> <debug> [430.512833] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 24)
> <debug> [430.513122] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 4 returned 0x7f2db876c690
> <debug> [430.513501] [platform/nm-linux-platform.c:1621] announce_object(): skip announce unmatching IP4 route 01.234.56.78/32 via 0.0.0.0 dev wwan0 metric 0 mss 0 src kernel
> <debug> [430.514995] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 24)
> <debug> [430.515369] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 4 returned 0x7f2db877bb70
> <debug> [430.515629] [platform/nm-linux-platform.c:1621] announce_object(): skip announce unmatching IP4 route 01.234.56.79/32 via 0.0.0.0 dev wwan0 metric 0 mss 0 src kernel
> <debug> [430.524623] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 24)
> <debug> [430.524715] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 4 returned 0x7f2db876c650
> <debug> [430.524734] [platform/nm-linux-platform.c:1621] announce_object(): skip announce unmatching IP4 route 01.234.56.76/30 via 0.0.0.0 dev wwan0 metric 0 mss 0 src kernel
> <debug> [430.524799] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 24)
> <debug> [430.524886] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 4 returned 0x7f2db8778a00
> <debug> [430.524901] [platform/nm-linux-platform.c:1621] announce_object(): skip announce unmatching IP4 route 01.234.56.76/32 via 0.0.0.0 dev wwan0 metric 0 mss 0 src kernel
> <debug> [430.525139] [devices/nm-device.c:5203] nm_device_set_ip6_config(): [0x7f2db87703b0] (cdc-wdm0): set IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/0)
> <debug> [430.525165] [dns-manager/nm-dns-manager.c:1013] nm_dns_manager_begin_updates(): (device_ip6_config_changed): queueing DNS updates (1)
> <debug> [430.525176] [dns-manager/nm-dns-manager.c:1031] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration did not change
> <debug> [430.525184] [dns-manager/nm-dns-manager.c:1035] nm_dns_manager_end_updates(): (device_ip6_config_changed): no DNS changes to commit (0)
> <info> (cdc-wdm0): Activation: Stage 5 of 5 (IPv4 Commit) started...
> <debug> [430.525274] [platform/nm-platform.c:1445] nm_platform_ip4_address_add(): address: adding or updating IPv4 address: 01.234.56.78/30 lft forever pref forever lifetime 1-0[4294967295,4294967295] dev wwan0 src unknown
> <debug> [430.539813] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 2 returned 0x7f2db8774400
> <debug> [430.540094] [platform/nm-platform.c:2571] log_ip4_address(): signal: address 4 changed: 01.234.56.78/30 lft forever pref forever lifetime 1-0[4294967295,4294967295] dev wwan0 src kernel
> <debug> [430.540299] [devices/nm-device.c:5889] device_ip_changed(): [0x7f2db87703b0] (cdc-wdm0): queued IP config change
> <debug> [430.540607] [devices/nm-device.c:6386] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (1): 'dhcp4' not pending (expected)
> <info> (cdc-wdm0): device state change: ip-config -> ip-check (reason 'none') [70 80 0]
> <debug> [430.542992] [nm-dispatcher.c:441] _dispatcher_call(): (1) (cdc-wdm0) dispatching action 'pre-up' (with callback)
> <debug> [430.543023] [nm-dispatcher.c:456] _dispatcher_call(): (1) simulate request; no scripts in /etc/NetworkManager/dispatcher.d/pre-up.d
> <info> (cdc-wdm0): Activation: Stage 5 of 5 (IPv4 Commit) complete.
> <debug> [430.543187] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 20)
> <debug> [430.543313] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 2 returned 0x7f2db8774540
> <debug> [430.543968] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db87703b0] (cdc-wdm0): add_pending_action (2): 'queued state change to secondaries'
> <debug> [430.544005] [devices/nm-device.c:6866] nm_device_queue_state(): [0x7f2db87703b0] (cdc-wdm0): queued state change to secondaries due to none (id 219)
> <debug> [430.554776] [devices/nm-device.c:6815] queued_set_state(): [0x7f2db87703b0] (cdc-wdm0): running queued state change to secondaries (id 219)
> <info> (cdc-wdm0): device state change: ip-check -> secondaries (reason 'none') [80 90 0]
> <debug> [430.554842] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db87703b0] (cdc-wdm0): add_pending_action (3): 'queued state change to activated'
> <debug> [430.554961] [devices/nm-device.c:6866] nm_device_queue_state(): [0x7f2db87703b0] (cdc-wdm0): queued state change to activated due to none (id 221)
> <debug> [430.555597] [nm-manager.c:4371] policy_activating_device_changed(): ActivatingConnection now (none)
> <debug> [430.556373] [devices/nm-device.c:6766] _set_state_full(): [0x7f2db87703b0] (cdc-wdm0): device entered SECONDARIES state
> <debug> [430.556974] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (2): 'queued state change to secondaries'
> <debug> [430.562342] [devices/nm-device.c:6815] queued_set_state(): [0x7f2db87703b0] (cdc-wdm0): running queued state change to activated (id 221)
> <info> (cdc-wdm0): device state change: secondaries -> activated (reason 'none') [90 100 0]
> <debug> [430.563502] [nm-connectivity.c:158] run_check(): Connectivity check with uri 'http://fedoraproject.org/static/hotspot.txt' started.
> <info> NetworkManager state is now CONNECTED_LOCAL
> <debug> [430.563658] [nm-active-connection.c:443] check_master_ready(): (0x7f2db87239a0): not signalling master-ready (not activating)
> <debug> [430.626608] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (1): 'activation::0x7f2db87239a0'
> <debug> [430.627227] [dns-manager/nm-dns-manager.c:1013] nm_dns_manager_begin_updates(): (device_state_changed): queueing DNS updates (1)
> <debug> [430.627266] [dns-manager/nm-dns-manager.c:1013] nm_dns_manager_begin_updates(): (update_routing_and_dns): queueing DNS updates (2)
> <debug> [430.627325] [platform/nm-platform.c:1786] nm_platform_ip4_route_add(): route: adding or updating IPv4 route: 0.0.0.0/0 via 01.234.56.77 dev wwan0 metric 1024 mss 0 src user
> <debug> [430.627648] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 4 returned 0x7f2db8784c80
> <debug> [430.627705] [platform/nm-platform.c:2583] log_ip4_route(): signal: route 4 added: 0.0.0.0/0 via 01.234.56.77 dev wwan0 metric 1024 mss 0 src user
> <debug> [430.627738] [devices/nm-device.c:5889] device_ip_changed(): [0x7f2db87703b0] (cdc-wdm0): queued IP config change
> <info> Policy set 'MBB' (wwan0) as default for IPv4 routing and DNS.
> <debug> [430.627965] [nm-manager.c:4338] policy_default_device_changed(): PrimaryConnection now MBB
> <debug> [430.628027] [dns-manager/nm-dns-manager.c:1031] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration changed
> <debug> [430.628050] [dns-manager/nm-dns-manager.c:1035] nm_dns_manager_end_updates(): (update_routing_and_dns): no DNS changes to commit (1)
> <debug> [430.628071] [dns-manager/nm-dns-manager.c:1031] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration changed
> <debug> [430.628089] [dns-manager/nm-dns-manager.c:1040] nm_dns_manager_end_updates(): (device_state_changed): committing DNS changes (0)
> <debug> [430.628106] [dns-manager/nm-dns-manager.c:595] update_dns(): updating resolv.conf
> <info> (cdc-wdm0): Activation: successful, device activated.
> <debug> [430.628895] [nm-dispatcher.c:441] _dispatcher_call(): (2) (cdc-wdm0) dispatching action 'up'
> <debug> [430.630337] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (0): 'queued state change to activated'
> <debug> [430.630430] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 24)
> <debug> [430.630862] [platform/nm-linux-platform.c:458] get_kernel_object(): get_kernel_object for type 4 returned 0x7f2db875fbe0
> <debug> [430.636232] [firewall-manager/nm-firewall-manager.c:147] nm_firewall_manager_add_or_change_zone(): (wwan0) firewall zone change -> "block" [2]
> <debug> [430.636296] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db8764280] (lo): add_pending_action (1): 'autoactivate'
> <debug> [430.636460] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db8764280] (lo): remove_pending_action (0): 'autoactivate'
> <debug> [430.643952] [firewall-manager/nm-firewall-manager.c:117] add_or_change_cb(): (wwan0) firewall zone add/change succeeded [2]
>
>
> $ nmcli connection down MBB
>
>
> <info> Connectivity check for uri 'http://fedoraproject.org/static/hotspot.txt' failed with 'Error resolving 'fedoraproject.org': Name or service not known'.
> <debug> [470.675449] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates LIMITED
> <info> Connectivity check for uri 'http://fedoraproject.org/static/hotspot.txt' failed with 'Error resolving 'fedoraproject.org': Name or service not known'.
> <debug> [484.378233] [nm-connectivity.c:158] run_check(): Connectivity check with uri 'http://fedoraproject.org/static/hotspot.txt' started.
> <info> (cdc-wdm0): device state change: activated -> deactivating (reason 'user-requested') [100 110 39]
> <debug> [484.527007] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates NONE
> <info> NetworkManager state is now DISCONNECTING
> <debug> [484.527048] [nm-active-connection.c:443] check_master_ready(): (0x7f2db87239a0): not signalling master-ready (not activating)
> <debug> [484.574407] [nm-policy.c:1522] device_state_changed(): Blocking autoconnect of connection 'MBB' by user request
> <debug> [484.574497] [nm-dispatcher.c:441] _dispatcher_call(): (3) (cdc-wdm0) dispatching action 'pre-down' (with callback)
> <debug> [484.574528] [nm-dispatcher.c:456] _dispatcher_call(): (3) simulate request; no scripts in /etc/NetworkManager/dispatcher.d/pre-down.d
> <debug> [484.575694] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db87703b0] (cdc-wdm0): add_pending_action (1): 'queued state change to disconnected'
> <debug> [484.575744] [devices/nm-device.c:6866] nm_device_queue_state(): [0x7f2db87703b0] (cdc-wdm0): queued state change to disconnected due to user-requested (id 323)
> <debug> [484.575783] [devices/nm-device.c:6815] queued_set_state(): [0x7f2db87703b0] (cdc-wdm0): running queued state change to disconnected (id 323)
> <info> (cdc-wdm0): device state change: deactivating -> disconnected (reason 'user-requested') [110 30 39]
> <info> (cdc-wdm0): deactivating device (reason 'user-requested') [39]
> <debug> [484.575840] [firewall-manager/nm-firewall-manager.c:204] nm_firewall_manager_remove_from_zone(): (wwan0) firewall zone remove -> * [3]
> <debug> [484.575988] [devices/nm-device.c:6386] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (1): 'autoconf6' not pending (expected)
> <debug> [484.576110] [platform/nm-linux-platform.c:1987] _log_dbg_sysctl_set_impl(): sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/disable_ipv6' to '1' (current value is identical)
> <debug> [484.576215] [platform/nm-linux-platform.c:1989] _log_dbg_sysctl_set_impl(): sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/accept_ra' to '0' (current value is '1')
> <debug> [484.576296] [platform/nm-linux-platform.c:1987] _log_dbg_sysctl_set_impl(): sysctl: setting '/proc/sys/net/ipv6/conf/wwan0/use_tempaddr' to '0' (current value is identical)
> <debug> [484.576722] [platform/nm-platform.c:1493] nm_platform_ip4_address_delete(): address: deleting IPv4 address 01.234.56.78/30
> <debug> [484.579965] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 2 had no result
> <debug> [484.580234] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 4 had no result
> <debug> [484.580280] [platform/nm-linux-platform.c:1621] announce_object(): skip announce unmatching IP4 route 01.234.56.78/32 via 0.0.0.0 dev wwan0 metric 0 mss 0 src kernel
> <debug> [484.580433] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 4 had no result
> <debug> [484.580468] [platform/nm-linux-platform.c:1621] announce_object(): skip announce unmatching IP4 route 01.234.56.79/32 via 0.0.0.0 dev wwan0 metric 0 mss 0 src kernel
> <debug> [484.580609] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 4 had no result
> <debug> [484.580642] [platform/nm-linux-platform.c:1621] announce_object(): skip announce unmatching IP4 route 01.234.56.76/30 via 0.0.0.0 dev wwan0 metric 0 mss 0 src kernel
> <debug> [484.580782] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 4 had no result
> <debug> [484.580853] [platform/nm-linux-platform.c:1621] announce_object(): skip announce unmatching IP4 route 01.234.56.76/32 via 0.0.0.0 dev wwan0 metric 0 mss 0 src kernel
> <debug> [484.581212] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 4 had no result
> <debug> [484.581610] [platform/nm-platform.c:2583] log_ip4_route(): signal: route 4 removed: 0.0.0.0/0 via 01.234.56.77 dev wwan0 metric 1024 mss 0 src user
> <debug> [484.581820] [devices/nm-device.c:5889] device_ip_changed(): [0x7f2db87703b0] (cdc-wdm0): queued IP config change
> <debug> [484.582129] [platform/nm-platform.c:2571] log_ip4_address(): signal: address 4 removed: 01.234.56.78/30 lft forever pref forever lifetime 55-0[4294967295,4294967295] dev wwan0 src kernel
> <debug> [484.582469] [devices/nm-device.c:5889] device_ip_changed(): [0x7f2db87703b0] (cdc-wdm0): queued IP config change
> <debug> [484.582716] [platform/nm-platform.c:824] nm_platform_link_set_down(): link: setting down 'wwan0' (2)
> <debug> [484.582971] [platform/nm-linux-platform.c:2378] link_change_flags(): link: change 2: flags unset 'up' (1)
> <debug> [484.583852] [platform/nm-linux-platform.c:414] get_kernel_object(): get_kernel_object for link: wwan0 (2, family 0)
> <debug> [484.584031] [platform/nm-platform.c:2565] log_link(): signal: link changed: 2: wwan0 <DOWN> mtu 1500 wwan driver 'qmi_wwan' udi '/sys/devices/pci0000:00/0000:00:04.1/usb2/2-3/2-3:1.1/net/wwan0'
> <debug> [484.584069] [devices/nm-device.c:5086] nm_device_set_ip4_config(): [0x7f2db87703b0] (cdc-wdm0): clear IP4Config instance (/org/freedesktop/NetworkManager/IP4Config/0)
> <debug> [484.584107] [dns-manager/nm-dns-manager.c:1013] nm_dns_manager_begin_updates(): (device_ip4_config_changed): queueing DNS updates (1)
> <debug> [484.584222] [nm-manager.c:4338] policy_default_device_changed(): PrimaryConnection now (none)
> <debug> [484.584474] [dns-manager/nm-dns-manager.c:1031] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration changed
> <debug> [484.584704] [dns-manager/nm-dns-manager.c:1040] nm_dns_manager_end_updates(): (device_ip4_config_changed): committing DNS changes (0)
> <debug> [484.584959] [dns-manager/nm-dns-manager.c:595] update_dns(): updating resolv.conf
> <debug> [484.599553] [devices/nm-device.c:5209] nm_device_set_ip6_config(): [0x7f2db87703b0] (cdc-wdm0): clear IP6Config instance (/org/freedesktop/NetworkManager/IP6Config/0)
> <debug> [484.600005] [dns-manager/nm-dns-manager.c:1013] nm_dns_manager_begin_updates(): (device_ip6_config_changed): queueing DNS updates (1)
> <debug> [484.600328] [dns-manager/nm-dns-manager.c:1031] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration did not change
> <debug> [484.600566] [dns-manager/nm-dns-manager.c:1035] nm_dns_manager_end_updates(): (device_ip6_config_changed): no DNS changes to commit (0)
> <info> NetworkManager state is now DISCONNECTED
> <debug> [484.601084] [nm-active-connection.c:443] check_master_ready(): (0x7f2db87239a0): not signalling master-ready (not activating)
> <debug> [484.601435] [dns-manager/nm-dns-manager.c:1013] nm_dns_manager_begin_updates(): (update_routing_and_dns): queueing DNS updates (1)
> <debug> [484.601596] [dns-manager/nm-dns-manager.c:1031] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration did not change
> <debug> [484.601914] [dns-manager/nm-dns-manager.c:1035] nm_dns_manager_end_updates(): (update_routing_and_dns): no DNS changes to commit (0)
> <debug> [484.602211] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f2db87703b0] (cdc-wdm0): add_pending_action (2): 'autoactivate'
> <debug> [484.602506] [nm-dispatcher.c:441] _dispatcher_call(): (4) (cdc-wdm0) dispatching action 'down'
> <debug> [484.603478] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (1): 'queued state change to disconnected'
> <debug> [484.604394] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 21)
> <debug> [484.605150] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 2 had no result
> <debug> [484.606128] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 25)
> <debug> [484.606222] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 4 had no result
> <info> (cdc-wdm0): modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
> <debug> [484.608498] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 25)
> <debug> [484.608980] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 4 had no result
> <debug> [484.609608] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 25)
> <debug> [484.610223] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 4 had no result
> <debug> [484.610413] [platform/nm-linux-platform.c:1874] event_notification(): netlink event (type 25)
> <debug> [484.610636] [platform/nm-linux-platform.c:460] get_kernel_object(): get_kernel_object for type 4 had no result
> <debug> [484.610971] [platform/nm-linux-platform.c:1872] event_notification(): netlink event (type 16) for link: wwan0 (2, family 0)
> <debug> [484.611301] [platform/nm-linux-platform.c:414] get_kernel_object(): get_kernel_object for link: wwan0 (2, family 0)
> <debug> [484.611775] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f2db87703b0] (cdc-wdm0): remove_pending_action (0): 'autoactivate'
> <info> (cdc-wdm0): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
> <debug> [484.625621] [firewall-manager/nm-firewall-manager.c:180] remove_cb(): (wwan0) firewall zone remove succeeded [3]
> <info> Connectivity check for uri 'http://fedoraproject.org/static/hotspot.txt' failed with 'Error resolving 'fedoraproject.org': Name or service not known'.
> <debug> [485.691604] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates LIMITED
> <debug> [485.691658] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates NONE
> <info> Connectivity check for uri 'http://fedoraproject.org/static/hotspot.txt' failed with 'Error resolving 'fedoraproject.org': Name or service not known'.
> <debug> [485.692350] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates LIMITED
> <debug> [485.692392] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates NONE
> <info> Connectivity check for uri 'http://fedoraproject.org/static/hotspot.txt' failed with 'Error resolving 'fedoraproject.org': Name or service not known'.
> <debug> [485.693503] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates LIMITED
> <debug> [485.693546] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates NONE
> <info> Connectivity check for uri 'http://fedoraproject.org/static/hotspot.txt' failed with 'Error resolving 'fedoraproject.org': Name or service not known'.
> <debug> [485.694019] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates LIMITED
> <debug> [485.694054] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates NONE
> <info> Connectivity check for uri 'http://fedoraproject.org/static/hotspot.txt' failed with 'Error resolving 'fedoraproject.org': Name or service not known'.
> <debug> [485.696092] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates LIMITED
> <debug> [485.696114] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates NONE
>
And significant difference in comparison
/src/mm-bearer-qmi.c
...
mm_dbg ("QMI IPv4 Settings:");
...
config = mm_bearer_ip_config_new ();
- mm_bearer_ip_config_set_method (config, MM_BEARER_IP_METHOD_STATIC);
+ mm_bearer_ip_config_set_method (config, MM_BEARER_IP_METHOD_DHCP);
$ nmcli connection up MBB
...
<info> Activation (wwan0) Beginning DHCPv4 transaction (timeout in 15 seconds)
<debug> [260.540398] [dhcp-manager/nm-dhcp-dhclient.c:287] create_dhclient_config(): (wwan0): creating composite dhclient config /var/lib/NetworkManager/dhclient-wwan0.conf
<debug> [260.540405] [dhcp-manager/nm-dhcp-dhclient.c:210] find_existing_config(): (wwan0) looking for existing config /etc/NetworkManager/dhclient-12345678-1234-1234-1234-123456789abc.conf
<debug> [260.540414] [dhcp-manager/nm-dhcp-dhclient.c:217] find_existing_config(): (wwan0) looking for existing config /etc/NetworkManager/dhclient-wwan0.conf
<debug> [260.547616] [dhcp-manager/nm-dhcp-dhclient.c:223] find_existing_config(): (wwan0) looking for existing config /etc/NetworkManager/dhclient.conf
<debug> [260.547845] [dhcp-manager/nm-dhcp-dhclient.c:237] find_existing_config(): (wwan0) looking for existing config /etc/dhcp/dhclient-wwan0.conf
<debug> [260.556440] [dhcp-manager/nm-dhcp-dhclient.c:243] find_existing_config(): (wwan0) looking for existing config /etc/dhclient-wwan0.conf
<debug> [260.556473] [dhcp-manager/nm-dhcp-dhclient.c:249] find_existing_config(): (wwan0) looking for existing config /etc/dhcp/dhclient.conf
<debug> [260.556486] [dhcp-manager/nm-dhcp-dhclient.c:255] find_existing_config(): (wwan0) looking for existing config /etc/dhclient.conf
<debug> [260.556499] [dhcp-manager/nm-dhcp-dhclient.c:297] create_dhclient_config(): (wwan0): no existing dhclient configuration to merge
<debug> [260.556681] [dhcp-manager/nm-dhcp-dhclient.c:457] dhclient_start(): running: /sbin/dhclient -d -sf /usr/libexec/nm-dhcp-helper -pf /var/run/dhclient-wwan0.pid -lf /var/lib/NetworkManager/dhclient-12345678-1234-1234-1234-123456789abc-wwan0.lease -cf /var/lib/NetworkManager/dhclient-wwan0.conf wwan0
<info> dhclient started with pid 2084
<debug> [260.592806] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f6727ec2410] (cdc-wdm0): add_pending_action (2): 'dhcp4'
Internet Systems Consortium DHCP Client 4.3.1b1
Copyright 2004-2014 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/
Internet Systems Consortium DHCP Client 4.3.1b1
Copyright 2004-2014 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/
<debug> [260.689656] [nm-dbus-manager.c:160] private_server_new_connection(): (dhcp) accepted connection 0x7f6727ec8cd0 on private socket.
<debug> [260.690446] [dhcp-manager/nm-dhcp-client.c:184] reason_to_state(): (wwan0): unmapped DHCP state 'PREINIT'
<debug> [260.691001] [nm-dbus-manager.c:117] private_server_message_filter(): (dhcp) closed connection 0x7f6727ec8cd0 on private socket.
Listening on LPF/wwan0/00:01:02:03:04:05
Sending on LPF/wwan0/00:01:02:03:04:05
Sending on Socket/fallback
Created duid \000\001\000\001\033u\253\214\000\001\002\003\004\005.
DHCPDISCOVER on wwan0 to 255.255.255.255 port 67 interval 8 (xid=0x473bb898)
Listening on LPF/wwan0/00:01:02:03:04:05
Sending on LPF/wwan0/00:01:02:03:04:05
Sending on Socket/fallback
Created duid \000\001\000\001\033u\253\214\000\001\002\003\004\005.
DHCPDISCOVER on wwan0 to 255.255.255.255 port 67 interval 8 (xid=0x473bb898)
DHCPREQUEST on wwan0 to 255.255.255.255 port 67 (xid=0x473bb898)
DHCPOFFER from 01.234.56.1
DHCPREQUEST on wwan0 to 255.255.255.255 port 67 (xid=0x473bb898)
DHCPOFFER from 01.234.56.1
DHCPACK from 01.234.56.1 (xid=0x473bb898)
DHCPACK from 01.234.56.1 (xid=0x473bb898)
<debug> [260.731185] [nm-dbus-manager.c:160] private_server_new_connection(): (dhcp) accepted connection 0x7f6727ec1b80 on private socket.
<debug> [260.732499] [NetworkManagerUtils.c:1425] monotonic_timestamp_get(): monotonic timestamp started counting 1.902183544 seconds ago with an offset of 252.0 seconds to CLOCK_BOOTTIME (local time is 2014-08-07 25:14:20)
<info> address 01.234.56.4
<info> plen 29 (255.255.255.248)
<info> gateway 01.234.56.1
<info> server identifier 01.234.56.1
<info> lease time 7200
<info> nameserver '8.8.8.8'
<info> nameserver '8.8.4.4'
<info> (wwan0): DHCPv4 state changed unknown -> bound
<debug> [260.732771] [devices/nm-device.c:2712] dhcp4_state_changed(): [0x7f6727ec2410] (cdc-wdm0): new DHCPv4 client state 1
bound to 01.234.56.4 -- renewal in 3469 seconds.
<debug> [260.819044] [devices/nm-device.c:6372] nm_device_remove_pending_action(): [0x7f6727ec2410] (cdc-wdm0): remove_pending_action (1): 'dhcp4'
bound to 01.234.56.4 -- renewal in 3469 seconds.
<debug> [260.875018] [devices/nm-device.c:6339] nm_device_add_pending_action(): [0x7f6727ec2410] (cdc-wdm0): add_pending_action (2): 'queued state change to secondaries'
<debug> [260.875026] [devices/nm-device.c:6866] nm_device_queue_state(): [0x7f6727ec2410] (cdc-wdm0): queued state change to secondaries due to none (id 203)
<debug> [260.875039] [devices/nm-device.c:6815] queued_set_state(): [0x7f6727ec2410] (cdc-wdm0): running queued state change to secondaries (id 203)
<debug> [261.658197] [nm-connectivity.c:113] nm_connectivity_check_cb(): Connectivity check for uri 'http://fedoraproject.org/static/hotspot.txt' successful.
<debug> [261.658280] [nm-manager.c:4281] connectivity_changed(): connectivity checking indicates FULL
<info> NetworkManager state is now CONNECTED_GLOBAL
<debug> [261.897584] [nm-connectivity.c:113] nm_connectivity_check_cb(): Connectivity check for uri 'http://fedoraproject.org/static/hotspot.txt' successful.
...
poma
More information about the ModemManager-devel
mailing list