Understanding Modem Manager

Marc Murphy marcmltd at marcm.co.uk
Tue Dec 17 14:31:09 PST 2013


Its looking better for ModemManager but NetworkManager refuses to start.  I will attempt to get the next revision up to see if that solves the issue;

ModemManager[898]: <debug> [946684853.567140] [mm-plugin.c:704] mm_plugin_supports_port(): (Huawei) [wwan0] probing deferred until result suggested
ModemManager[898]: <debug> [946684853.571352] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags
ModemManager[898]: <debug> [946684853.571840] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.572115] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.572389] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[898]: <debug> [946684853.572633] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[898]: <debug> [946684853.572847] [mm-plugin.c:212] apply_pre_probing_filters(): (ZTE) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.573061] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.573274] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[898]: <debug> [946684853.573488] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.573701] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.573915] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.574129] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.574342] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.910371] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers
ModemManager[898]: <debug> [946684853.910616] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.910860] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.911073] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.911287] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[898]: <debug> [946684853.911501] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.911745] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [cdc-wdm0] filtered by drivers
ModemManager[898]: <debug> [946684853.911958] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.912203] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[898]: <debug> [946684853.912416] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.912630] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.912843] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem
ModemManager[898]: <debug> [946684853.913088] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try...
ModemManager[898]: <debug> [946684853.913301] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'Huawei'
ModemManager[898]: <debug> [946684853.913515] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'Generic'
ModemManager[898]: <debug> [946684853.913789] [mm-plugin.c:776] mm_plugin_supports_port(): (Huawei) [cdc-wdm0] probe required: 'qmi'
ModemManager[898]: <debug> [946684853.914095] [mm-port-probe.c:1330] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi'
ModemManager[898]: <debug> [946684853.914888] [mm-plugin-manager.c:505] plugin_supports_port_ready(): (Plugin Manager) [wwan0] deferring support check until result suggested
ModemManager[898]: <debug> [946684853.960390] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB0) opening serial port...
ModemManager[898]: <warn>  [946684853.964693] [mm-serial-port.c:416] real_config_fd(): (ttyUSB0): port attributes not fully set
ModemManager[898]: <debug> [946684853.965608] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[898]: <debug> [946684853.968294] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB1) opening serial port...
ModemManager[898]: <warn>  [946684853.970339] [mm-serial-port.c:416] real_config_fd(): (ttyUSB1): port attributes not fully set
ModemManager[898]: <debug> [946684853.970979] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[898]: <debug> [946684853.973207] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB2) opening serial port...
ModemManager[898]: <warn>  [946684853.975099] [mm-serial-port.c:416] real_config_fd(): (ttyUSB2): port attributes not fully set
ModemManager[898]: <debug> [946684853.975679] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB2) device open count is 1 (open)
ModemManager[898]: <debug> [946684853.976656] [mm-port-probe.c:453] wdm_probe_qmi(): (usbmisc/cdc-wdm0) probing QMI...
ModemManager[898]: <debug> [946684854.180483] [mm-plugin-manager.c:646] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1] Minimum probing time consumed
ModemManager[898]: [/dev/cdc-wdm0] Checking version info (10 retries)...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 01:0B:00:00:00:00:00:01:21:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 11
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Version Info" (0x0021)
ModemManager[898]: <debug> [946684854.202272] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
ModemManager[898]: <debug> [946684854.202699] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB1) closing serial port...
ModemManager[898]: <debug> [946684854.204805] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB1) serial port closed
ModemManager[898]: <debug> [946684854.205232] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB1) forced to close port
ModemManager[898]: <debug> [946684854.206178] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB2) device open count is 0 (close)
ModemManager[898]: <debug> [946684854.206575] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB2) closing serial port...
ModemManager[898]: <debug> [946684854.208070] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB2) serial port closed
ModemManager[898]: <debug> [946684854.208406] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB2) forced to close port
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 48
>>>>>>   data   = 01:2F:00:80:00:00:01:01:21:00:24:00:02:04:00:00:00:00:00:01:1A:00:05:00:01:00:03:00:01:01:00:05:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 47
>>>>>>   flags   = 0x80
>>>>>>   service = "ctl"
>>>>>>   client  = 0
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 1
>>>>>>   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:03:00:01:01:00:05: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 = '3' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '5' ] ' [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' ] '}
ModemManager[898]: <debug> [946684854.211397] [mm-plugin-manager.c:469] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB1] deferring support check
ModemManager[898]: <debug> [946684854.211885] [mm-plugin-manager.c:469] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB2] deferring support check
ModemManager[898]: [/dev/cdc-wdm0] QMI Device supports 5 services:
ModemManager[898]: [/dev/cdc-wdm0]    ctl (1.3)
ModemManager[898]: [/dev/cdc-wdm0]    wds (1.5)
ModemManager[898]: [/dev/cdc-wdm0]    dms (1.2)
ModemManager[898]: [/dev/cdc-wdm0]    nas (1.0)
ModemManager[898]: [/dev/cdc-wdm0]    cat (0.0)
ModemManager[898]: <debug> [946684854.550813] [mm-port-probe.c:270] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable
ModemManager[898]: <debug> [946684854.554567] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'AT^CURC=0<CR>'
ModemManager[898]: <debug> [946684854.556215] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [cdc-wdm0] found best plugin for port
ModemManager[898]: <debug> [946684854.556581] [mm-plugin-manager.c:201] port_probe_context_finished(): (Plugin Manager) (Huawei) [cdc-wdm0]: found best plugin for device (/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1)
ModemManager[898]: <debug> [946684854.557131] [mm-plugin-manager.c:334] suggest_port_probe_result(): (Plugin Manager) (Huawei) [wwan0] deferred task completed, got suggested plugin
ModemManager[898]: <debug> [946684854.557649] [mm-plugin-manager.c:373] suggest_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB2] suggested plugin for port
ModemManager[898]: <debug> [946684854.557985] [mm-plugin-manager.c:373] suggest_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB1] suggested plugin for port
ModemManager[898]: <debug> [946684854.558290] [mm-plugin-manager.c:373] suggest_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB0] suggested plugin for port
ModemManager[898]: <debug> [946684854.558626] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 4 running probes in this device (wwan0, ttyUSB2, ttyUSB1, ttyUSB0)
ModemManager[898]: <debug> [946684854.559175] [mm-plugin.c:704] mm_plugin_supports_port(): (Huawei) [wwan0] probing deferred until result suggested
ModemManager[898]: <debug> [946684854.559633] [mm-plugin-manager.c:485] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [wwan0] task completed, got suggested plugin
ModemManager[898]: <debug> [946684854.559938] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'wwan0' port probe finished, still 3 running probes in this device (ttyUSB2, ttyUSB1, ttyUSB0)
ModemManager[898]: <debug> [946684855.457399] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): <-- 'AT^CURC=0'
ModemManager[898]: <debug> [946684855.463930] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): <-- '<CR><CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684855.474275] [huawei/mm-plugin-huawei.c:192] curc_ready(): (Huawei) unsolicited messages in secondary ports turned off
ModemManager[898]: <debug> [946684855.476290] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'AT^GETPORTMODE<CR>'
ModemManager[898]: <debug> [946684856.879366] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): <-- 'AT^GETPORTMODE'
ModemManager[898]: <debug> [946684856.884890] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): <-- '<CR>'
ModemManager[898]: <debug> [946684856.886690] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): <-- '<CR><LF>^getportmode:type:WCDMA:Qualcomm,MDM:0,NDIS:1,DIAG:2,PCUI:3<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684856.887362] [huawei/mm-plugin-huawei.c:154] getportmode_ready(): (Huawei) port mode layout retrieved
ModemManager[898]: <debug> [946684856.888094] [mm-port-probe.c:151] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is AT-capable
ModemManager[898]: <debug> [946684856.888918] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
ModemManager[898]: <debug> [946684856.889284] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB0) closing serial port...
ModemManager[898]: <debug> [946684856.891573] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB0) serial port closed
ModemManager[898]: <debug> [946684856.891939] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB0) forced to close port
ModemManager[898]: <debug> [946684856.892641] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB0] found best plugin for port
ModemManager[898]: <debug> [946684856.893068] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, still 2 running probes in this device (ttyUSB2, ttyUSB1)
ModemManager[898]: <debug> [946684857.166811] [mm-plugin.c:776] mm_plugin_supports_port(): (Huawei) [ttyUSB1] probe required: 'at, qcdm'
ModemManager[898]: <debug> [946684857.167421] [mm-port-probe.c:1330] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm'
ModemManager[898]: <debug> [946684857.169191] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB1) opening serial port...
ModemManager[898]: <warn>  [946684857.171450] [mm-serial-port.c:416] real_config_fd(): (ttyUSB1): port attributes not fully set
ModemManager[898]: <debug> [946684857.172030] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[898]: <debug> [946684857.274843] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManager[898]: <debug> [946684858.166903] [mm-plugin.c:776] mm_plugin_supports_port(): (Huawei) [ttyUSB2] probe required: 'at, qcdm'
ModemManager[898]: <debug> [946684858.167635] [mm-port-probe.c:1330] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm'
ModemManager[898]: <debug> [946684858.169466] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB2) opening serial port...
ModemManager[898]: <warn>  [946684858.171969] [mm-serial-port.c:416] real_config_fd(): (ttyUSB2): port attributes not fully set
ModemManager[898]: <debug> [946684858.172609] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB2) device open count is 1 (open)
ModemManager[898]: <debug> [946684858.275301] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'AT<CR>'
ModemManager[898]: <debug> [946684858.476412] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- 'AT'
ModemManager[898]: <debug> [946684858.477755] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR>'
ModemManager[898]: <debug> [946684858.478548] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684858.479281] [mm-port-probe.c:151] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable
ModemManager[898]: <debug> [946684858.479891] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB2) device open count is 0 (close)
ModemManager[898]: <debug> [946684858.480227] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB2) closing serial port...
ModemManager[898]: <debug> [946684858.481692] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB2) serial port closed
ModemManager[898]: <debug> [946684858.482058] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB2) forced to close port
ModemManager[898]: <debug> [946684858.482760] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB2] found best plugin for port
ModemManager[898]: <debug> [946684858.483126] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'ttyUSB2' port probe finished, still 1 running probes in this device (ttyUSB1)
ModemManager[898]: <debug> [946684861.166567] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[898]: <debug> [946684861.167635] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManager[898]: <debug> [946684864.168551] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[898]: <debug> [946684864.169558] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManager[898]: <debug> [946684867.168520] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[898]: <debug> [946684867.169131] [mm-port-probe.c:161] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is not AT-capable
ModemManager[898]: <debug> [946684867.169619] [mm-port-probe.c:657] serial_probe_qcdm(): (tty/ttyUSB1) probing QCDM...
ModemManager[898]: <debug> [946684867.169894] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
ModemManager[898]: <debug> [946684867.170199] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB1) closing serial port...
ModemManager[898]: <debug> [946684867.171664] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB1) serial port closed
ModemManager[898]: <debug> [946684867.172030] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB1) forced to close port
ModemManager[898]: <debug> [946684867.173312] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB1) opening serial port...
ModemManager[898]: <debug> [946684867.176760] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[898]: <debug> [946684867.177737] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB1): --> 7e 00 78 f0 7e
ModemManager[898]: <debug> [946684867.179446] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB1): <-- 00 44 65 63 20 32 33 20 32 30 31 30 31 31 3a 35 32 3a 32 39 4e 6f 76 20 30 38 20 32 30 31 30 31 35 3a 30 30 3a 30 30 46 41 43 50 41 43 5a 4d 00 00 08 00 00 00 04 e0 d4 78 7e
ModemManager[898]: <debug> [946684867.180270] [mm-port-probe.c:239] mm_port_probe_set_result_qcdm(): (tty/ttyUSB1) port is QCDM-capable
ModemManager[898]: <debug> [946684867.180910] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
ModemManager[898]: <debug> [946684867.181277] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB1) closing serial port...
ModemManager[898]: <debug> [946684867.182772] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB1) serial port closed
ModemManager[898]: <debug> [946684867.183169] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB1) forced to close port
ModemManager[898]: <debug> [946684867.183810] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB1] found best plugin for port
ModemManager[898]: <debug> [946684867.184115] [mm-plugin-manager.c:285] port_probe_context_finished(): (Plugin Manager) 'ttyUSB1' port probe finished, last one in device
ModemManager[898]: <debug> [946684867.184481] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1] device support check finished in '15.033417' seconds
ModemManager[898]: <info>  [946684867.185030] [mm-device.c:486] mm_device_create_modem(): Creating modem with plugin 'Huawei' and '5' ports
ModemManager[898]: <debug> [946684867.185671] [huawei/mm-plugin-huawei.c:458] create_modem(): QMI-powered Huawei modem found...
ModemManager[898]: <debug> [946684867.263277] [huawei/mm-plugin-huawei.c:513] grab_port(): (usbmisc/cdc-wdm0) Port will have AT flags 'ppp'
ModemManager[898]: <debug> [946684867.264315] [mm-base-modem.c:275] mm_base_modem_grab_port(): (cdc-wdm0) type 'qmi' claimed by /sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1
ModemManager[898]: <debug> [946684867.264773] [huawei/mm-plugin-huawei.c:513] grab_port(): (net/wwan0) Port will have AT flags 'none'
ModemManager[898]: <debug> [946684867.265353] [mm-base-modem.c:275] mm_base_modem_grab_port(): (wwan0) type 'net' claimed by /sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1
ModemManager[898]: <debug> [946684867.265658] [huawei/mm-plugin-huawei.c:513] grab_port(): (tty/ttyUSB2) Port will have AT flags 'primary'
ModemManager[898]: <debug> [946684867.267123] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyUSB2) type 'at' claimed by /sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1
ModemManager[898]: <debug> [946684867.267611] [huawei/mm-plugin-huawei.c:513] grab_port(): (tty/ttyUSB1) Port will have AT flags 'none'
ModemManager[898]: <debug> [946684867.268374] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyUSB1) type 'qcdm' claimed by /sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1
ModemManager[898]: <debug> [946684867.268709] [huawei/mm-plugin-huawei.c:513] grab_port(): (tty/ttyUSB0) Port will have AT flags 'ppp'
ModemManager[898]: <debug> [946684867.269991] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyUSB0) type 'at' claimed by /sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1
ModemManager[898]: <debug> [946684867.270479] [mm-base-modem.c:1057] log_port(): (/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1) tty/ttyUSB2 at (primary)
ModemManager[898]: <debug> [946684867.270754] [mm-base-modem.c:1057] log_port(): (/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1) net/wwan0 data (primary)
ModemManager[898]: <debug> [946684867.270968] [mm-base-modem.c:1057] log_port(): (/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1) tty/ttyUSB0 data (secondary)
ModemManager[898]: <debug> [946684867.271212] [mm-base-modem.c:1057] log_port(): (/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1) tty/ttyUSB1 qcdm
ModemManager[898]: <debug> [946684867.607088] [mm-base-modem.c:1057] log_port(): (/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1) usb/cdc-wdm0 qmi (primary)
ModemManager[898]: <info>  [946684867.618319] [mm-manager.c:149] find_device_support_ready(): Modem for device at '/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1' successfully created
ModemManager[898]: [/dev/cdc-wdm0] Checking version info (10 retries)...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 01:0B:00:00:00:00:00:01:21:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 11
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Version Info" (0x0021)
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 48
>>>>>>   data   = 01:2F:00:80:00:00:01:01:21:00:24:00:02:04:00:00:00:00:00:01:1A:00:05:00:01:00:03:00:01:01:00:05:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 47
>>>>>>   flags   = 0x80
>>>>>>   service = "ctl"
>>>>>>   client  = 0
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 1
>>>>>>   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:03:00:01:01:00:05: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 = '3' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '5' ] ' [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' ] '}
ModemManager[898]: [/dev/cdc-wdm0] QMI Device supports 5 services:
ModemManager[898]: [/dev/cdc-wdm0]    ctl (1.3)
ModemManager[898]: [/dev/cdc-wdm0]    wds (1.5)
ModemManager[898]: [/dev/cdc-wdm0]    dms (1.2)
ModemManager[898]: [/dev/cdc-wdm0]    nas (1.0)
ModemManager[898]: [/dev/cdc-wdm0]    cat (0.0)
ModemManager[898]: [/dev/cdc-wdm0] Setting network port data format...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 21
<<<<<<   data   = 01:14:00:00:00:00:00:02:26:00:09:00:10:02:00:01:00:01:01:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 20
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   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
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 24
>>>>>>   data   = 01:17:00:80:00:00:01:02:26:00:0C:00:02:04:00:00:00:00:00:10:02:00:01:00
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 23
>>>>>>   flags   = 0x80
>>>>>>   service = "ctl"
>>>>>>   client  = 0
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 2
>>>>>>   tlv_length  = 12
>>>>>>   message     = "Set Data Format" (0x0026)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Protocol" (0x10)
>>>>>>   length     = 2
>>>>>>   value      = 01:00
>>>>>>   translated = 802-3
ModemManager[898]: [/dev/cdc-wdm0] Network port data format operation finished
ModemManager[898]: [/dev/cdc-wdm0] Allocating new client ID...
ModemManager[898]: [/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
ModemManager[898]: [/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
ModemManager[898]: [/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:01
ModemManager[898]: [/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:01
>>>>>>   translated = [ service = 'dms' cid = '1' ]
ModemManager[898]: [/dev/cdc-wdm0] Registered 'dms' (version 1.2) client with ID '1'
ModemManager[898]: [/dev/cdc-wdm0] Allocating new client ID...
ModemManager[898]: [/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
ModemManager[898]: [/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
ModemManager[898]: [/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:01
ModemManager[898]: [/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:01
>>>>>>   translated = [ service = 'nas' cid = '1' ]
ModemManager[898]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '1'
ModemManager[898]: <debug> [946684868.045626] [mm-broadband-modem-qmi.c:9971] 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
ModemManager[898]: <debug> [946684868.046542] [mm-broadband-modem-qmi.c:9971] 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
ModemManager[898]: <debug> [946684868.047244] [mm-broadband-modem-qmi.c:9971] 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
ModemManager[898]: <debug> [946684868.047610] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB2) opening serial port...
ModemManager[898]: <warn>  [946684868.049807] [mm-serial-port.c:416] real_config_fd(): (ttyUSB2): port attributes not fully set
ModemManager[898]: <debug> [946684868.050387] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB2) device open count is 1 (open)
ModemManager[898]: <debug> [946684868.050723] [mm-at-serial-port.c:473] mm_at_serial_port_run_init_sequence(): (ttyUSB2): running init sequence...
ModemManager[898]: <debug> [946684868.051424] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[898]: <debug> [946684868.052065] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[898]: <debug> [946684868.404726] [mm-broadband-modem-qmi.c:533] modem_load_current_capabilities(): loading current capabilities...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:01:00:01:00:2B:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Technology Preference" (0x002B)
ModemManager[898]: <debug> [946684868.409426] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:03:01:02:01:00:2B:00:07:00:02:04:00:01:00:47:00
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 1
>>>>>> 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
ModemManager[898]: <debug> [946684868.416811] [mm-broadband-modem-qmi.c:428] load_current_capabilities_get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:01:00:20:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Capabilities" (0x0020)
ModemManager[898]: <debug> [946684868.424502] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- 'ATE0'
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 36
>>>>>>   data   = 01:23:00:80:02:01: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
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 35
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> 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 '}' ]
ModemManager[898]: <debug> [946684868.432192] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684868.437685] [mm-modem-helpers-qmi.c:1266] mm_modem_capability_from_qmi_capabilities_context(): Current capabilities built: 'gsm-umts'
  SSP mode preference: 'unknown'
  TP: 'auto'
  DMS Capabilities: 'gsm-umts'
ModemManager[898]: <debug> [946684868.439333] [mm-broadband-modem-qmi.c:695] modem_load_supported_capabilities(): loading supported capabilities...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:02:00:20:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Capabilities" (0x0020)
ModemManager[898]: <debug> [946684868.441134] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 36
>>>>>>   data   = 01:23:00:80:02:01: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
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 35
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> 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 '}' ]
ModemManager[898]: <debug> [946684868.780673] [mm-iface-modem.c:4231] interface_initialization_step(): Modem allows up to 2 bearers
ModemManager[898]: <debug> [946684868.781100] [mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 2, max active: 2)
ModemManager[898]: <debug> [946684868.782565] [mm-broadband-modem-qmi.c:997] modem_load_manufacturer(): loading manufacturer...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:03:00:21:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Manufacturer" (0x0021)
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 44
>>>>>>   data   = 01:2B:00:80:02:01: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
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 43
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> 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
ModemManager[898]: <debug> [946684868.790499] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684868.791384] [mm-broadband-modem-qmi.c:944] modem_load_manufacturer_finish(): loaded manufacturer: QUALCOMM INCORPORATED
ModemManager[898]: <debug> [946684868.792178] [mm-broadband-modem-qmi.c:1073] modem_load_model(): loading model...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:04:00:22:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Model" (0x0022)
ModemManager[898]: <debug> [946684868.793795] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 24
>>>>>>   data   = 01:17:00:80:02:01:02:04:00:22:00:0B:00:02:04:00:00:00:00:00:01:01:00:38
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 23
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 4
>>>>>>   tlv_length  = 11
>>>>>>   message     = "Get Model" (0x0022)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Model" (0x01)
>>>>>>   length     = 1
>>>>>>   value      = 38
>>>>>>   translated = 8
ModemManager[898]: <debug> [946684869.136385] [mm-broadband-modem-qmi.c:1020] modem_load_model_finish(): loaded model: 8
ModemManager[898]: <debug> [946684869.137148] [mm-broadband-modem-qmi.c:1149] modem_load_revision(): loading revision...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:05:00:23:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Revision" (0x0023)
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 70
>>>>>>   data   = 01:45:00:80:02:01:02:05:00:23:00:39:00:02:04:00:00:00:00:00:01:2F:00:38:32:30:30:43:2D:46:41:43:50:41:43:5A:51:2D:31:30:33:38:34:20:20:31:20:20:5B:4E:6F:76:20:30:38:20:32:30:31:30:20:31:35:3A:30:30:3A:30:30:5D
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 69
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 5
>>>>>>   tlv_length  = 57
>>>>>>   message     = "Get Revision" (0x0023)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Revision" (0x01)
>>>>>>   length     = 47
>>>>>>   value      = 38:32:30:30:43:2D:46:41:43:50:41:43:5A:51:2D:31:30:33:38:34:20:20:31:20:20:5B:4E:6F:76:20:30:38:20:32:30:31:30:20:31:35:3A:30:30:3A:30:30:5D
>>>>>>   translated = 8200C-FACPACZQ-10384  1  [Nov 08 2010 15:00:00]
ModemManager[898]: <debug> [946684869.144869] [mm-broadband-modem-qmi.c:1096] modem_load_revision_finish(): loaded revision: 8200C-FACPACZQ-10384  1  [Nov 08 2010 15:00:00]
ModemManager[898]: <debug> [946684869.145602] [mm-broadband-modem-qmi.c:1291] modem_load_equipment_identifier(): loading equipment identifier...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:06:00:25:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get IDs" (0x0025)
ModemManager[898]: <debug> [946684869.150363] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 48
>>>>>>   data   = 01:2F:00:80:02:01:02:06:00:25:00:23:00:02:04:00:00:00:00:00:11:19:00:33:35:34:32:38:33:30:34:30:30:31:35:31:37:38:02:B0:1C:0E:02:E4:46:A7:01:3D
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 47
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 6
>>>>>>   tlv_length  = 35
>>>>>>   message     = "Get IDs" (0x0025)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Imei" (0x11)
>>>>>>   length     = 25
>>>>>>   value      = 33:35:34:32:38:33:30:34:30:30:31:35:31:37:38:02:B0:1C:0E:02:E4:46:A7:01:3D
>>>>>>   translated = 35428304XXXXXXX
ModemManager[898]: <debug> [946684869.489565] [mm-broadband-modem-qmi.c:1188] modem_load_equipment_identifier_finish(): loaded equipment identifier: 35428304XXXXXXX
ModemManager[898]: <debug> [946684869.490328] [mm-broadband-modem-qmi.c:1326] modem_load_device_identifier(): loading device identifier...
ModemManager[898]: <debug> [946684869.492221] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '000012d10000140c35428304XXXXXXX8200C-FACPACZQ-103841[Nov08201015:00:00]8QUALCOMMINCORPORATED'
ModemManager[898]: <debug> [946684869.493655] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '59c4abebe27451fb8e9d56b08f86f68c1eb47be4'
ModemManager[898]: <debug> [946684869.494326] [mm-broadband-modem-qmi.c:1314] modem_load_device_identifier_finish(): loaded device identifier: 59c4abebe27451fb8e9d56b08f86f68c1eb47be4
ModemManager[898]: <debug> [946684869.496371] [mm-broadband-modem-qmi.c:1729] modem_load_supported_bands(): loading band capabilities...
ModemManager[898]: <warn>  [946684869.501803] [mm-iface-modem.c:3886] 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''
ModemManager[898]: <debug> [946684869.504458] [mm-broadband-modem-qmi.c:2673] load_power_state(): Getting device operating mode...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:08:00:2D:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 8
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Operating Mode" (0x002D)
ModemManager[898]: <debug> [946684869.508242] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 24
>>>>>>   data   = 01:17:00:80:02:01:02:08:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:FF
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 23
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> 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      = FF
>>>>>>   translated = unknown
ModemManager[898]: <warn>  [946684869.513064] [mm-iface-modem.c:3918] load_power_state_ready(): couldn't load Power State: 'Unhandled power state: 'unknown' (255)'
ModemManager[898]: <debug> [946684869.513705] [mm-broadband-modem-qmi.c:1535] modem_load_unlock_required(): loading unlock required...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:09:00:2B:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 9
<<<<<<   tlv_length  = 0
<<<<<<   message     = "UIM Get PIN Status" (0x002B)
ModemManager[898]: <debug> [946684869.518710] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684869.519595] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 32
>>>>>>   data   = 01:1F:00:80:02:01: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
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 31
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> 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' ]
ModemManager[898]: <debug> [946684869.860690] [mm-broadband-modem-qmi.c:1628] modem_load_unlock_retries(): loading unlock retries...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:0A:00:2B:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 10
<<<<<<   tlv_length  = 0
<<<<<<   message     = "UIM Get PIN Status" (0x002B)
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 32
>>>>>>   data   = 01:1F:00:80:02:01: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
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 31
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> 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' ]
ModemManager[898]: <debug> [946684869.868624] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684869.876193] [mm-iface-modem.c:3033] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
ModemManager[898]: <debug> [946684869.881686] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
ModemManager[898]: <debug> [946684869.883517] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
ModemManager[898]: <warn>  [946684869.884951] [mm-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''
ModemManager[898]: <debug> [946684869.885745] [mm-sim-qmi.c:224] load_imsi(): loading IMSI...
ModemManager[898]: <warn>  [946684869.886782] [mm-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''
ModemManager[898]: <debug> [946684869.888644] [mm-broadband-modem-qmi.c:1410] modem_load_own_numbers(): loading own numbers...
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:0E:00:24:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 14
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get MSISDN" (0x0024)
ModemManager[898]: <debug> [946684870.226321] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 36
>>>>>>   data   = 01:23:00:80:02:01:02:0E:00:24:00:17:00:02:04:00:00:00:00:00:01:0D:00:2B:34:34:37:35:33:39:38:36:34:38:38:32
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 35
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 14
>>>>>>   tlv_length  = 23
>>>>>>   message     = "Get MSISDN" (0x0024)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "MSISDN" (0x01)
>>>>>>   length     = 13
>>>>>>   value      = 2B:34:34:37:35:33:39:38:36:34:38:38:32
>>>>>>   translated = +447539XXXXXX
ModemManager[898]: <debug> [946684870.232913] [mm-broadband-modem-qmi.c:1357] modem_load_own_numbers_finish(): loaded own numbers: +447539XXXXXX
ModemManager[898]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:01:00:02:00:2B:00:00:00
ModemManager[898]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Technology Preference" (0x002B)
ModemManager[898]: <debug> [946684870.237735] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684870.238681] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB2) device open count is 2 (close)
ModemManager[898]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:03:01:02:02:00:2B:00:07:00:02:04:00:01:00:47:00
ModemManager[898]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 1
>>>>>> 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
ModemManager[898]: <debug> [946684870.240481] [mm-broadband-modem-qmi.c:2844] get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
ModemManager[898]: <warn>  [946684870.241030] [mm-iface-modem.c:4045] load_current_modes_ready(): couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
ModemManager[898]: <debug> [946684870.241549] [mm-broadband-modem-qmi.c:1877] modem_load_current_bands(): loading current bands...
ModemManager[898]: <warn>  [946684870.242343] [mm-iface-modem.c:4066] 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''
ModemManager[898]: <debug> [946684870.250064] [mm-broadband-modem-qmi.c:3272] modem_3gpp_load_imei_finish(): loaded IMEI: 35428304XXXXXXX
ModemManager[898]: <debug> [946684870.251590] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'ph-sim' status, assuming disabled
ModemManager[898]: <debug> [946684870.252413] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-pers' status, assuming disabled
ModemManager[898]: <debug> [946684870.253054] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-sub-pers' status, assuming disabled
ModemManager[898]: <debug> [946684870.253634] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'provider-pers' status, assuming disabled
ModemManager[898]: <debug> [946684870.590976] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'corp-pers' status, assuming disabled
ModemManager[898]: <debug> [946684870.597049] [mm-broadband-modem.c:8952] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
ModemManager[898]: <debug> [946684870.607730] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[898]: <debug> [946684870.608828] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
ModemManager[898]: <debug> [946684870.623233] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684870.624057] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB2) device open count is 2 (close)
ModemManager[898]: <debug> [946684870.624667] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'AT+CNMI=?<CR>'
ModemManager[898]: <debug> [946684870.640078] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><LF>+CNMI: (0,1,2),(0,1,2,3),(0,2),(0,2),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684870.641360] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[898]: <debug> [946684870.642031] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB2) device open count is 2 (close)
ModemManager[898]: <debug> [946684870.642581] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): --> 'AT+CPMS=?<CR>'
ModemManager[898]: <debug> [946684870.659365] [mm-at-serial-port.c:436] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: ("ME","MT","SM","SR"),("ME","MT","SM","SR"),("ME","MT","SM","SR")<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[898]: <debug> [946684870.663180] [mm-iface-modem-messaging.c:1146] load_supported_storages_ready(): Supported storages loaded:
ModemManager[898]: <debug> [946684870.663668] [mm-iface-modem-messaging.c:1147] load_supported_storages_ready():   mem1 (list/read/delete) storages: 'me, mt, sm, sr'
ModemManager[898]: <debug> [946684870.663943] [mm-iface-modem-messaging.c:1148] load_supported_storages_ready():   mem2 (write/send) storages:       'me, mt, sm, sr'
ModemManager[898]: <debug> [946684870.664187] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready():   mem3 (reception) storages:        'me, mt, sm, sr'
ModemManager[898]: <debug> [946684870.665988] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB2) device open count is 1 (close)
ModemManager[898]: <debug> [946684870.668154] [mm-broadband-modem.c:8956] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
ModemManager[898]: <debug> [946684870.670535] [mm-broadband-modem-qmi.c:9400] signal_check_support(): Extended signal capabilities supported
ModemManager[898]: <debug> [946684870.674533] [mm-broadband-modem-qmi.c:8114] oma_check_support(): OMA capabilities not supported
ModemManager[898]: <debug> [946684870.675479] [mm-broadband-modem.c:8958] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
ModemManager[898]: <debug> [946684870.677310] [mm-broadband-modem-qmi.c:9066] firmware_check_support(): loading firmware images...
ModemManager[898]: <debug> [946684870.681979] [mm-broadband-modem.c:8959] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
ModemManager[898]: <info>  [946684870.686038] [mm-iface-modem.c:1366] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
ModemManager[898]: <debug> [946684870.688144] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB2) device open count is 0 (close)
ModemManager[898]: <debug> [946684870.688662] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB2) closing serial port...
ModemManager[898]: <debug> [946684870.690310] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB2) serial port closed
ModemManager[898]: <debug> [946684870.690921] [mm-base-modem.c:1026] initialize_ready(): modem properly initialized
ModemManager[898]: <debug> [946684870.707156] [mm-device.c:416] export_modem(): Exported modem '/sys/devices/platform/usbhs_omap/ehci-omap.0/usb1/1-1/1-1.1' at path '/org/freedesktop/ModemManager1/Modem/0'
ModemManager[898]: <debug> [946684870.707766] [mm-device.c:424] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Huawei' modem, VID 0x12D1 PID 0x140C (usb)

-----Original Message-----
From: Aleksander Morgado [mailto:aleksander at lanedo.com] 
Sent: 17 December 2013 00:35
To: Marc Murphy; 'Dan Williams'
Cc: modemmanager-devel at lists.freedesktop.org
Subject: Re: Understanding Modem Manager

On 16/12/13 23:36, Marc Murphy wrote:
> ModemManager-004924b0385a503520f26e499444f8c8c42616a6.zip
> 
> I will have the HEAD to test with and should have your fix in.
> 
> Question is will it work with NetworkManager  0.9.8.0 ?
> I thought the ModemManager dbus I/F was changed from 0.7.99 to 1.0 and they were different.

Latest stable is 1.0, although there is a tagged 1.2 release candidate that you should better use:

http://www.freedesktop.org/software/ModemManager/ModemManager-1.1.990.tar.xz

0.7.99 and 1.x have almost the same API; and both should work with NM 0.9.8.

--
Aleksander


More information about the ModemManager-devel mailing list