Understanding Modem Manager
Dan Williams
dcbw at redhat.com
Wed Dec 18 15:32:37 PST 2013
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