Understanding Modem Manager
Marc Murphy
marcmltd at marcm.co.uk
Wed Dec 18 15:48:01 PST 2013
For the Sierra I have the commands required and the MC8705 uses the AT interface on USB3 and the NMEA stream on USB2
I will have a look at the AT commands for the Huawei as well. I haven't tried to initiate a tracking session for it yet.
When I'm back at my PC tomorrow I will have more of a look.
I have access to the at reference material for both modems.
Regards
Marc
> On 18 Dec 2013, at 23:33, "Dan Williams" <dcbw at redhat.com> wrote:
>
>> On Wed, 2013-12-18 at 22:54 +0000, Marc Murphy wrote:
>> Hi Dan
>> Thanks for all the assistance guys. I found that I had to up Network Manager from 0.9.8.0 to 0.9.8.2 to get it to work with the newer version on Modem Manager.
>> I didn't rebuild NetworkManager and found that when I did it caused a build error as there was an undefined reference to `mm_simple_connect_properties_set_allowed_modes'
>> A quick google later and I needed to update to 0.9.8.2 :)
>>
>> I can now initialise and obtain an IP connection "woohooo"
>>
>> Next is to get the GPS/NMEA going.
>> When I look at
>> # mmcli -m 0 --location-status
>>
>> /org/freedesktop/ModemManager1/Modem/0
>> ----------------------------
>> Location | capabilities: '3gpp-lac-ci'
>> | enabled: '3gpp-lac-ci'
>> | signals: 'no'
>>
>> But the modem apparently supports NMEA. Is there anything I should look at or something I need to enable to be able to get the NMEA output ?
>> I thought I would at least get the 3GPP location as its enabled and connected;
>> # mmcli -m 0 --location-get
>>
>> /org/freedesktop/ModemManager1/Modem/0
>> -------------------------
>> 3GPP location | Not available
>> -------------------------
>> GPS NMEA traces | Not available
>> -------------------------
>> Raw GPS | Not available
>> -------------------------
>> CDMA BS | Not available
>>
>> I am happy to change code to try and get it to work and then push the updates. Just need a pointer of where to start looking.
>>
>> The modem is Huawei EM820W
>> I also have a SierraWireless MC8705 which shows exactly the same symptoms. I know this modem has NMEA as I can query it on the USB3 AT interface to activate a tracking session.
>
> For NMEA, are we talking about the Sierra or the Huawei? And how are
> you querying it to start the tracking session?
>
> The Huawei plugin doesn't specifically support NMEA output, unless the
> device uses QMI and supports the PDS service. So if you are talking
> about the Huawei device, knowing which AT commands to use would be
> great.
>
> Sierra devices are the same; if they don't support QMI then there are
> Sierra-specific AT commands for GPS, but those aren't implemented yet.
> However, we're very interested in doing that for Sierra devices, as long
> as we can figure out which port is the NMEA port, which isn't always
> easy with Sierra.
>
> Dan
>
>> Thanks
>> Marc
>>
>>
>> -----Original Message-----
>> From: modemmanager-devel-bounces at lists.freedesktop.org [mailto:modemmanager-devel-bounces at lists.freedesktop.org] On Behalf Of Dan Williams
>> Sent: 17 December 2013 23:00
>> To: Marc Murphy
>> Cc: 'Aleksander Morgado'; modemmanager-devel at lists.freedesktop.org
>> Subject: Re: Understanding Modem Manager
>>
>>> On Tue, 2013-12-17 at 22:31 +0000, Marc Murphy wrote:
>>> 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;
>>
>> What's the NetworkManager error? At least the ModemManager output is
>> looking good now.
>>
>> Dan
>>
>>> 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
>>> _______________________________________________
>>> ModemManager-devel mailing list
>>> ModemManager-devel at lists.freedesktop.org
>>> http://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
>>
>>
>> _______________________________________________
>> ModemManager-devel mailing list
>> ModemManager-devel at lists.freedesktop.org
>> http://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
>
>
More information about the ModemManager-devel
mailing list