Attachment 110746 Details for Bug 87206 – ModemManager debug logs #2

ModemManager debug logs #2

mm_2.log (text/plain), 150.56 KB, created by norbig on 2014-12-11 19:07:03 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: norbig

Created: 2014-12-11 19:07:03 UTC

Size: 150.56 KB

patch

obsolete

>ModemManager[1516]: <info>  [1418324031.334533] [main.c:140] main(): ModemManager (version 1.4.0) starting in system bus...
>ModemManager[1516]: <debug> [1418324031.337220] [main.c:65] bus_acquired_cb(): Bus acquired, creating manager...
>ModemManager[1516]: <debug> [1418324031.340991] [mm-plugin-manager.c:852] load_plugins(): Looking for plugins in '/usr/lib/ModemManager'
>ModemManager[1516]: <debug> [1418324031.341794] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Gobi'
>ModemManager[1516]: <debug> [1418324031.342384] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Via CBP7'
>ModemManager[1516]: <debug> [1418324031.343012] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Iridium'
>ModemManager[1516]: <debug> [1418324031.343414] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Pantech'
>ModemManager[1516]: <debug> [1418324031.344831] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Sierra'
>ModemManager[1516]: <debug> [1418324031.345678] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Cinterion'
>ModemManager[1516]: <debug> [1418324031.346428] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel'
>ModemManager[1516]: <debug> [1418324031.347104] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'AnyDATA'
>ModemManager[1516]: <debug> [1418324031.870695] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'SimTech'
>ModemManager[1516]: <debug> [1418324031.871814] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Samsung'
>ModemManager[1516]: <debug> [1418324032.362389] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Altair LTE'
>ModemManager[1516]: <debug> [1418324032.363047] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia'
>ModemManager[1516]: <debug> [1418324032.363729] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Longcheer'
>ModemManager[1516]: <debug> [1418324032.364148] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Generic'
>ModemManager[1516]: <debug> [1418324032.365623] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Huawei'
>ModemManager[1516]: <debug> [1418324032.366900] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Ericsson MBM'
>ModemManager[1516]: <debug> [1418324032.367755] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option'
>ModemManager[1516]: <debug> [1418324032.368556] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel LTE'
>ModemManager[1516]: <debug> [1418324032.369072] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Linktop'
>ModemManager[1516]: <debug> [1418324032.378291] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia (Icera)'
>ModemManager[1516]: <debug> [1418324032.379163] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'MTK'
>ModemManager[1516]: <debug> [1418324032.518923] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'ZTE'
>ModemManager[1516]: <debug> [1418324032.520001] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option High-Speed'
>ModemManager[1516]: <debug> [1418324032.520526] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Motorola'
>ModemManager[1516]: <debug> [1418324032.521261] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'X22X'
>ModemManager[1516]: <debug> [1418324032.521779] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Telit'
>ModemManager[1516]: <debug> [1418324032.522711] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Wavecom'
>ModemManager[1516]: <debug> [1418324032.522743] [mm-plugin-manager.c:902] load_plugins(): Successfully loaded 27 plugins
>ModemManager[1516]: <debug> [1418324032.523461] [main.c:87] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
>ModemManager[1516]: <debug> [1418324032.523522] [mm-base-manager.c:479] mm_base_manager_start(): Starting automatic device scan...
>ModemManager[1516]: <debug> [1418324032.525407] [mm-base-manager.c:518] mm_base_manager_start(): Finished device scan...
>ModemManager[1516]: <debug> [1418324032.525897] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:16.3] Checking device support...
>ModemManager[1516]: <debug> [1418324032.526006] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyS1] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.526027] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.526060] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyS1] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.526079] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.526093] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.526107] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.526132] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.526144] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.526159] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.526173] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.526188] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyS1] filtered by udev tags
>ModemManager[1516]: <debug> [1418324032.526202] [mm-plugin.c:253] apply_pre_probing_filters(): (Option) [ttyS1] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.541553] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.541783] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.541983] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.554337] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyS1] filtered by udev tags
>ModemManager[1516]: <debug> [1418324032.554566] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.566099] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyS1] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.566353] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.566545] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.566751] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.566956] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyS1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.567159] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyS1] Found '5' plugins to try...
>ModemManager[1516]: <debug> [1418324032.567357] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1]   Will try with plugin 'Via CBP7'
>ModemManager[1516]: <debug> [1418324032.567557] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1]   Will try with plugin 'Iridium'
>ModemManager[1516]: <debug> [1418324032.567758] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1]   Will try with plugin 'Cinterion'
>ModemManager[1516]: <debug> [1418324032.567962] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1]   Will try with plugin 'Nokia'
>ModemManager[1516]: <debug> [1418324032.568162] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1]   Will try with plugin 'Generic'
>ModemManager[1516]: <debug> [1418324032.569293] [mm-plugin.c:767] mm_plugin_supports_port(): (Via CBP7) [ttyS1] probe required: 'at, at-vendor, at-product'
>ModemManager[1516]: <debug> [1418324032.569322] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyS1) launching port probing: 'at, at-vendor, at-product'
>ModemManager[1516]: <debug> [1418324032.577346] [mm-base-manager.c:314] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
>ModemManager[1516]: <debug> [1418324032.577577] [mm-base-manager.c:314] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
>ModemManager[1516]: <debug> [1418324032.577806] [mm-base-manager.c:314] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
>ModemManager[1516]: <debug> [1418324032.578564] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Checking device support...
>ModemManager[1516]: <debug> [1418324032.578640] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [eno1] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.578658] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.578666] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.578673] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.578792] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [eno1] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.579001] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.579208] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.579390] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.579588] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.579782] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.579977] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.580173] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.580371] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.580570] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.580767] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [eno1] filtered by udev tags
>ModemManager[1516]: <debug> [1418324032.580959] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.581156] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.581354] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.581545] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.581737] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.581935] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.582133] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [eno1] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.582392] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.582599] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [eno1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.582793] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.582987] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [eno1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.583180] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [eno1] Found '1' plugins to try...
>ModemManager[1516]: <debug> [1418324032.583374] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [eno1]   Will try with plugin 'Generic'
>ModemManager[1516]: <debug> [1418324032.583575] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [eno1] probing deferred until result suggested
>ModemManager[1516]: <debug> [1418324032.583914] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0] Checking device support...
>ModemManager[1516]: <debug> [1418324032.583979] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wlp2s0] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.584146] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.584341] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.584541] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.584735] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wlp2s0] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.584923] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.708627] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.708911] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.709134] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.709337] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.709538] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.709736] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.709937] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.710123] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.710320] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wlp2s0] filtered by udev tags
>ModemManager[1516]: <debug> [1418324032.710510] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.710703] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.710897] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.711093] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.711285] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.711477] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.711671] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wlp2s0] filtered by drivers
>ModemManager[1516]: <debug> [1418324032.711869] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.712060] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wlp2s0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324032.712261] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.712465] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlp2s0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324032.712659] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wlp2s0] Found '1' plugins to try...
>ModemManager[1516]: <debug> [1418324032.712849] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wlp2s0]   Will try with plugin 'Generic'
>ModemManager[1516]: <debug> [1418324032.713049] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [wlp2s0] probing deferred until result suggested
>ModemManager[1516]: <debug> [1418324032.713366] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [eno1] deferring support check until result suggested
>ModemManager[1516]: <debug> [1418324032.713421] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wlp2s0] deferring support check until result suggested
>ModemManager[1516]: <debug> [1418324032.713831] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyS1) opening serial port...
>ModemManager[1516]: <debug> [1418324032.714017] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyS1) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324032.814288] [mm-port-serial-at.c:440] debug_log(): (ttyS1): --> 'AT<CR>'
>ModemManager[1516]: <debug> [1418324034.414160] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:16.3] Minimum probing time consumed
>ModemManager[1516]: <debug> [1418324034.414230] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Minimum probing time consumed
>ModemManager[1516]: <debug> [1418324034.414264] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [eno1] deferred task cancelled, no suggested plugin
>ModemManager[1516]: <debug> [1418324034.414328] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0] Minimum probing time consumed
>ModemManager[1516]: <debug> [1418324034.414341] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [wlp2s0] deferred task cancelled, no suggested plugin
>ModemManager[1516]: <debug> [1418324034.414359] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [eno1] not supported by any plugin
>ModemManager[1516]: <debug> [1418324034.414378] [mm-device.c:357] mm_device_ignore_port(): Fully ignoring port 'net/eno1' from now on
>ModemManager[1516]: <debug> [1418324034.414404] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'eno1' port probe finished, last one in device
>ModemManager[1516]: <debug> [1418324034.414420] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] device support check finished in '1.835818' seconds
>ModemManager[1516]: <warn>  [1418324034.414483] [mm-base-manager.c:153] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:19.0': not supported by any plugin
>ModemManager[1516]: <debug> [1418324034.414515] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [wlp2s0] not supported by any plugin
>ModemManager[1516]: <debug> [1418324034.414529] [mm-device.c:357] mm_device_ignore_port(): Fully ignoring port 'net/wlp2s0' from now on
>ModemManager[1516]: <debug> [1418324034.414556] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'wlp2s0' port probe finished, last one in device
>ModemManager[1516]: <debug> [1418324034.414755] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0] device support check finished in '1.830808' seconds
>ModemManager[1516]: <warn>  [1418324034.414966] [mm-base-manager.c:153] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.1/0000:02:00.0': not supported by any plugin
>ModemManager[1516]: <debug> [1418324036.415311] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1516]: <debug> [1418324036.415395] [mm-port-serial-at.c:440] debug_log(): (ttyS1): --> 'AT<CR>'
>ModemManager[1516]: <debug> [1418324039.415699] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1516]: <debug> [1418324039.415812] [mm-port-serial-at.c:440] debug_log(): (ttyS1): --> 'AT<CR>'
>ModemManager[1516]: <debug> [1418324042.415222] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1516]: <debug> [1418324042.415294] [mm-port-probe.c:162] mm_port_probe_set_result_at(): (tty/ttyS1) port is not AT-capable
>ModemManager[1516]: <debug> [1418324042.415365] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyS1) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324042.415375] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyS1) closing serial port...
>ModemManager[1516]: <debug> [1418324042.415461] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyS1) serial port closed
>ModemManager[1516]: <debug> [1418324042.415481] [mm-port-serial.c:1415] port_serial_close_force(): (ttyS1) forced to close port
>ModemManager[1516]: <debug> [1418324042.415522] [mm-plugin.c:454] apply_post_probing_filters(): (Via CBP7) [ttyS1] filtered as no vendor/product strings given
>ModemManager[1516]: <debug> [1418324042.415567] [mm-plugin.c:767] mm_plugin_supports_port(): (Iridium) [ttyS1] probe required: 'at, at-vendor, at-product'
>ModemManager[1516]: <debug> [1418324042.415596] [mm-plugin.c:454] apply_post_probing_filters(): (Iridium) [ttyS1] filtered as no vendor/product strings given
>ModemManager[1516]: <debug> [1418324042.415628] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyS1] probe required: 'at, at-vendor'
>ModemManager[1516]: <debug> [1418324042.415669] [mm-plugin.c:429] apply_post_probing_filters(): (Cinterion) [ttyS1] filtered by vendor strings
>ModemManager[1516]: <debug> [1418324042.415700] [mm-plugin.c:767] mm_plugin_supports_port(): (Nokia) [ttyS1] probe required: 'at, at-vendor, at-icera'
>ModemManager[1516]: <debug> [1418324042.415725] [mm-plugin.c:429] apply_post_probing_filters(): (Nokia) [ttyS1] filtered by vendor strings
>ModemManager[1516]: <debug> [1418324042.415838] [mm-plugin.c:767] mm_plugin_supports_port(): (Generic) [ttyS1] probe required: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324042.416033] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyS1) launching port probing: 'qcdm'
>ModemManager[1516]: <debug> [1418324042.416233] [mm-port-probe.c:658] serial_probe_qcdm(): (tty/ttyS1) probing QCDM...
>ModemManager[1516]: <debug> [1418324042.416468] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyS1) opening serial port...
>ModemManager[1516]: <debug> [1418324042.416769] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyS1) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324042.416843] [mm-port-serial-qcdm.c:201] debug_log(): (ttyS1): --> 7e 00 78 f0 7e
>ModemManager[1516]: <debug> [1418324045.415928] [mm-port-serial-qcdm.c:201] debug_log(): (ttyS1): --> 7e 00 78 f0 7e
>ModemManager[1516]: <debug> [1418324048.413953] [mm-port-probe.c:258] mm_port_probe_set_result_qcdm(): (tty/ttyS1) port is not QCDM-capable
>ModemManager[1516]: <debug> [1418324048.414055] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyS1) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324048.414071] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyS1) closing serial port...
>ModemManager[1516]: <debug> [1418324048.414150] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyS1) serial port closed
>ModemManager[1516]: <debug> [1418324048.414166] [mm-port-serial.c:1415] port_serial_close_force(): (ttyS1) forced to close port
>ModemManager[1516]: <debug> [1418324048.414196] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Generic) [ttyS1] found best plugin for port
>ModemManager[1516]: <debug> [1418324048.414226] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyS1' port probe finished, last one in device
>ModemManager[1516]: <debug> [1418324048.414239] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:16.3] device support check finished in '15.888303' seconds
>ModemManager[1516]: <info>  [1418324048.414260] [mm-device.c:509] mm_device_create_modem(): Creating modem with plugin 'Generic' and '1' ports
>ModemManager[1516]: <warn>  [1418324048.419008] [mm-plugin.c:905] mm_plugin_create_modem(): Could not grab port (tty/ttyS1): 'Cannot add port 'tty/ttyS1', unhandled serial type'
>ModemManager[1516]: <debug> [1418324048.419051] [mm-base-modem.c:1485] finalize(): Modem (Generic) '/sys/devices/pci0000:00/0000:00:16.3' completely disposed
>ModemManager[1516]: <warn>  [1418324048.419063] [mm-base-manager.c:158] find_device_support_ready(): Couldn't create modem for device at '/sys/devices/pci0000:00/0000:00:16.3': Failed to find primary AT port
>ModemManager[1516]: <debug> [1418324073.613393] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2] Checking device support...
>ModemManager[1516]: <debug> [1418324073.613549] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB2] filtered by drivers
>ModemManager[1516]: <debug> [1418324073.613591] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.613623] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB2] filtered by drivers
>ModemManager[1516]: <debug> [1418324073.613656] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.613687] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.613716] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.613747] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.613777] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.613810] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.613845] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB2] filtered by udev tags
>ModemManager[1516]: <debug> [1418324073.613874] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.776179] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.776586] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.779011] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.779049] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB2] filtered by udev tags
>ModemManager[1516]: <debug> [1418324073.779063] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.779074] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB2] filtered by drivers
>ModemManager[1516]: <debug> [1418324073.779083] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.779093] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.779134] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.779226] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.780007] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB2] Found '6' plugins to try...
>ModemManager[1516]: <debug> [1418324073.780762] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2]   Will try with plugin 'Huawei'
>ModemManager[1516]: <debug> [1418324073.781492] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2]   Will try with plugin 'Via CBP7'
>ModemManager[1516]: <debug> [1418324073.781829] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2]   Will try with plugin 'Iridium'
>ModemManager[1516]: <debug> [1418324073.782167] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2]   Will try with plugin 'Cinterion'
>ModemManager[1516]: <debug> [1418324073.782514] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2]   Will try with plugin 'Nokia'
>ModemManager[1516]: <debug> [1418324073.782862] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2]   Will try with plugin 'Generic'
>ModemManager[1516]: <debug> [1418324073.783212] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB2] probe required: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324073.783555] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324073.784261] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB1] filtered by drivers
>ModemManager[1516]: <debug> [1418324073.784615] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.784840] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB1] filtered by drivers
>ModemManager[1516]: <debug> [1418324073.785070] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.785296] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.785516] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.785754] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.828390] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.828476] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.828578] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB1] filtered by udev tags
>ModemManager[1516]: <debug> [1418324073.828849] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.829115] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324073.829391] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.311039] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.311448] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB1] filtered by udev tags
>ModemManager[1516]: <debug> [1418324074.311794] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.312458] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB1] filtered by drivers
>ModemManager[1516]: <debug> [1418324074.313677] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.313910] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.314311] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.314730] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.315132] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB1] Found '6' plugins to try...
>ModemManager[1516]: <debug> [1418324074.315350] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Huawei'
>ModemManager[1516]: <debug> [1418324074.315586] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Via CBP7'
>ModemManager[1516]: <debug> [1418324074.316010] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Iridium'
>ModemManager[1516]: <debug> [1418324074.316231] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Cinterion'
>ModemManager[1516]: <debug> [1418324074.316444] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Nokia'
>ModemManager[1516]: <debug> [1418324074.316658] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Generic'
>ModemManager[1516]: <debug> [1418324074.316896] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB1] probe required: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324074.317119] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324074.317660] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers
>ModemManager[1516]: <debug> [1418324074.317706] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.317847] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.318064] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.318283] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [cdc-wdm0] filtered by drivers
>ModemManager[1516]: <debug> [1418324074.318498] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.318712] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.318926] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.319168] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.319384] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.319604] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.319833] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.320605] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.320654] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags
>ModemManager[1516]: <debug> [1418324074.320852] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.321069] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.321285] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.321593] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.321941] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.322471] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.322798] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.323106] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.323408] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.323936] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.324263] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.324571] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try...
>ModemManager[1516]: <debug> [1418324074.324871] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'Huawei'
>ModemManager[1516]: <debug> [1418324074.325216] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'Generic'
>ModemManager[1516]: <debug> [1418324074.325561] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [cdc-wdm0] probe required: 'qmi'
>ModemManager[1516]: <debug> [1418324074.340141] [mm-port-probe.c:1353] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi'
>ModemManager[1516]: <debug> [1418324074.342901] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wwp0s29u1u2i1] filtered by drivers
>ModemManager[1516]: <debug> [1418324074.342936] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.342948] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.342958] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.342990] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wwp0s29u1u2i1] filtered by drivers
>ModemManager[1516]: <debug> [1418324074.343021] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.343053] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.343085] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.343115] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.343146] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.343176] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.343217] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.343243] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.343273] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wwp0s29u1u2i1] filtered by udev tags
>ModemManager[1516]: <debug> [1418324074.343485] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.343691] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.343895] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.344095] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.344304] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.344517] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.344694] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wwp0s29u1u2i1] filtered by drivers
>ModemManager[1516]: <debug> [1418324074.344866] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.345039] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wwp0s29u1u2i1] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.345214] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.345441] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwp0s29u1u2i1] filtered by subsystem
>ModemManager[1516]: <debug> [1418324074.345613] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wwp0s29u1u2i1] Found '2' plugins to try...
>ModemManager[1516]: <debug> [1418324074.345813] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwp0s29u1u2i1]   Will try with plugin 'Huawei'
>ModemManager[1516]: <debug> [1418324074.345983] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwp0s29u1u2i1]   Will try with plugin 'Generic'
>ModemManager[1516]: <debug> [1418324074.346178] [mm-plugin.c:689] mm_plugin_supports_port(): (Huawei) [wwp0s29u1u2i1] probing deferred until result suggested
>ModemManager[1516]: <debug> [1418324074.346419] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wwp0s29u1u2i1] deferring support check until result suggested
>ModemManager[1516]: <debug> [1418324074.346608] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1516]: <warn>  [1418324074.347241] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1516]: <debug> [1418324074.347274] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324074.353426] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[1516]: <warn>  [1418324074.353988] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[1516]: <debug> [1418324074.354017] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324074.354066] [mm-port-probe.c:456] wdm_probe_qmi(): (usbmisc/cdc-wdm0) probing QMI...
>ModemManager[1516]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
>ModemManager[1516]: cannot connect to proxy: Could not connect: Connection refused
>ModemManager[1516]: spawning new qmi-proxy (try 1)...
>ModemManager[1516]: <debug> [1418324074.453765] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324074.453821] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port...
>ModemManager[1516]: <debug> [1418324074.455623] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed
>ModemManager[1516]: <debug> [1418324074.455663] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB2) forced to close port
>ModemManager[1516]: <debug> [1418324074.455726] [mm-plugin-manager.c:514] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB2] deferring support check
>ModemManager[1516]: <debug> [1418324074.455793] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324074.455812] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port...
>ModemManager[1516]: <debug> [1418324074.457500] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed
>ModemManager[1516]: <debug> [1418324074.457536] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB1) forced to close port
>ModemManager[1516]: <debug> [1418324074.457585] [mm-plugin-manager.c:514] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB1] deferring support check
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 28
><<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 27
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 16
><<<<<<   message     = "Internal Proxy Open" (0xFF00)
><<<<<< TLV:
><<<<<<   type       = "Device Path" (0x01)
><<<<<<   length     = 13
><<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
><<<<<<   translated = /dev/cdc-wdm0
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 19
>>>>>>>   data   = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 18
>>>>>>>   flags   = 0x00
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Internal Proxy Open" (0xFF00)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[1516]: [/dev/cdc-wdm0] Checking version info (10 retries)...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 12
><<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 11
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Version Info" (0x0021)
>ModemManager[1516]: <debug> [1418324074.573542] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB0] filtered by drivers
>ModemManager[1516]: <debug> [1418324074.573588] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.573601] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB0] filtered by drivers
>ModemManager[1516]: <debug> [1418324074.573613] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.573624] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.573638] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.573649] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.573658] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.573669] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.573681] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags
>ModemManager[1516]: <debug> [1418324074.573691] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.573701] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.573710] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.574037] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.574259] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB0] filtered by udev tags
>ModemManager[1516]: <debug> [1418324074.574479] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.574706] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers
>ModemManager[1516]: <debug> [1418324074.574930] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.575154] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.575374] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.575592] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1516]: <debug> [1418324074.575825] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '6' plugins to try...
>ModemManager[1516]: <debug> [1418324074.576041] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Huawei'
>ModemManager[1516]: <debug> [1418324074.576262] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Via CBP7'
>ModemManager[1516]: <debug> [1418324074.576485] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Iridium'
>ModemManager[1516]: <debug> [1418324074.576700] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Cinterion'
>ModemManager[1516]: <debug> [1418324074.577022] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Nokia'
>ModemManager[1516]: <debug> [1418324074.577341] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Generic'
>ModemManager[1516]: <debug> [1418324074.577614] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB0] probe required: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324074.577839] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324074.578205] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[1516]: <warn>  [1418324074.580918] [mm-port-serial.c:515] real_config_fd(): (ttyUSB0): port attributes not fully set
>ModemManager[1516]: <debug> [1418324074.580977] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324074.681254] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT^CURC=0<CR>'
>ModemManager[1516]: <debug> [1418324075.412320] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2] Minimum probing time consumed
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 12
><<<<<<   data   = 01:0B:00:00:00:00:00:03:21:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 11
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 3
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Version Info" (0x0021)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 48
>>>>>>>   data   = 01:2F:00:80:00:00:01:03:21:00:24:00:02:04:00:00:00:00:00:01:1A:00:05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 47
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 3
>>>>>>>   tlv_length  = 36
>>>>>>>   message     = "Get Version Info" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Service list" (0x01)
>>>>>>>   length     = 26
>>>>>>>   value      = 05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>>>>>>>   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '2' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '3' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '2' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] ' [4] = '[ service = 'cat' major_version = '0' minor_version = '0' ] '}
>ModemManager[1516]: [/dev/cdc-wdm0] QMI Device supports 5 services:
>ModemManager[1516]: [/dev/cdc-wdm0]    ctl (1.2)
>ModemManager[1516]: [/dev/cdc-wdm0]    wds (1.3)
>ModemManager[1516]: [/dev/cdc-wdm0]    dms (1.2)
>ModemManager[1516]: [/dev/cdc-wdm0]    nas (1.0)
>ModemManager[1516]: [/dev/cdc-wdm0]    cat (0.0)
>ModemManager[1516]: <debug> [1418324075.418825] [mm-port-probe.c:271] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable
>ModemManager[1516]: <debug> [1418324075.581543] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- 'AT^CURC=0'
>ModemManager[1516]: <debug> [1418324075.581703] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [cdc-wdm0] found best plugin for port
>ModemManager[1516]: <debug> [1418324075.581726] [mm-plugin-manager.c:232] port_probe_context_finished(): (Plugin Manager) (Huawei) [cdc-wdm0]: found best plugin for device (/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2)
>ModemManager[1516]: <debug> [1418324075.581762] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB0] suggested plugin for port
>ModemManager[1516]: <debug> [1418324075.581776] [mm-plugin-manager.c:361] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [wwp0s29u1u2i1] deferred task completed, got suggested plugin
>ModemManager[1516]: <debug> [1418324075.581793] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB1] suggested plugin for port
>ModemManager[1516]: <debug> [1418324075.581809] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB2] suggested plugin for port
>ModemManager[1516]: <debug> [1418324075.581826] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 4 running probes in this device (ttyUSB0, wwp0s29u1u2i1, ttyUSB1, ttyUSB2)
>ModemManager[1516]: <debug> [1418324075.581856] [mm-plugin.c:689] mm_plugin_supports_port(): (Huawei) [wwp0s29u1u2i1] probing deferred until result suggested
>ModemManager[1516]: <debug> [1418324075.581879] [mm-plugin-manager.c:549] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [wwp0s29u1u2i1] task completed, got suggested plugin
>ModemManager[1516]: <debug> [1418324075.581897] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'wwp0s29u1u2i1' port probe finished, still 3 running probes in this device (ttyUSB0, ttyUSB1, ttyUSB2)
>ModemManager[1516]: <debug> [1418324075.582903] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR>'
>ModemManager[1516]: <debug> [1418324075.584156] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324075.584216] [huawei/mm-plugin-huawei.c:197] curc_ready(): (Huawei) unsolicited messages in secondary ports turned off
>ModemManager[1516]: <debug> [1418324075.584263] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT^GETPORTMODE<CR>'
>ModemManager[1516]: <debug> [1418324076.984692] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- 'AT^GETPORTMOD'
>ModemManager[1516]: <debug> [1418324077.413318] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB2] probe required: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324077.413395] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324077.413435] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB1] probe required: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324077.413454] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324077.413613] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1516]: <warn>  [1418324077.414307] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1516]: <debug> [1418324077.414340] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324077.414433] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[1516]: <warn>  [1418324077.415341] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[1516]: <debug> [1418324077.415397] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324077.415462] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>^BOOT:42668426,0,0,0,66<CR><LF>'
>ModemManager[1516]: <debug> [1418324077.514788] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324077.514836] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port...
>ModemManager[1516]: <debug> [1418324077.516712] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed
>ModemManager[1516]: <debug> [1418324077.516753] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB2) forced to close port
>ModemManager[1516]: <debug> [1418324077.516851] [mm-plugin-manager.c:508] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB2] deferring support check, suggested: Huawei
>ModemManager[1516]: <debug> [1418324077.516942] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324077.516956] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port...
>ModemManager[1516]: <debug> [1418324077.518708] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed
>ModemManager[1516]: <debug> [1418324077.518743] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB1) forced to close port
>ModemManager[1516]: <debug> [1418324077.518800] [mm-plugin-manager.c:508] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB1] deferring support check, suggested: Huawei
>ModemManager[1516]: <debug> [1418324079.263539] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- 'E'
>ModemManager[1516]: <debug> [1418324079.264077] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR>'
>ModemManager[1516]: <debug> [1418324079.265133] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>^GETPORTMODE:TYPE:WCDMA:Qualcomm ,MDM:0,NDIS:1 ,DIAG:2,PCUI:3,CDROM:4,SD:5<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324079.265240] [huawei/mm-plugin-huawei.c:154] getportmode_ready(): (Huawei) port mode layout retrieved
>ModemManager[1516]: <debug> [1418324079.265313] [mm-port-probe.c:152] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is AT-capable
>ModemManager[1516]: <debug> [1418324079.265369] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324079.265387] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB0) closing serial port...
>ModemManager[1516]: <debug> [1418324079.269922] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB0) serial port closed
>ModemManager[1516]: <debug> [1418324079.269964] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB0) forced to close port
>ModemManager[1516]: <debug> [1418324079.270044] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB0] found best plugin for port
>ModemManager[1516]: <debug> [1418324079.270083] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, still 2 running probes in this device (ttyUSB1, ttyUSB2)
>ModemManager[1516]: <debug> [1418324080.414360] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB2] probe required: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324080.414440] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324080.414488] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB1] probe required: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324080.414503] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm'
>ModemManager[1516]: <debug> [1418324080.414642] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1516]: <warn>  [1418324080.415227] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1516]: <debug> [1418324080.415267] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324080.415366] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[1516]: <warn>  [1418324080.416127] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[1516]: <debug> [1418324080.416189] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324080.516489] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT<CR>'
>ModemManager[1516]: <debug> [1418324080.516558] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
>ModemManager[1516]: <debug> [1418324080.716626] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'AT'
>ModemManager[1516]: <debug> [1418324080.717609] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR>'
>ModemManager[1516]: <debug> [1418324080.718776] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324080.718868] [mm-port-probe.c:152] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable
>ModemManager[1516]: <debug> [1418324080.718928] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324080.718950] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port...
>ModemManager[1516]: <debug> [1418324080.721382] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed
>ModemManager[1516]: <debug> [1418324080.721426] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB2) forced to close port
>ModemManager[1516]: <debug> [1418324080.721512] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB2] found best plugin for port
>ModemManager[1516]: <debug> [1418324080.721548] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB2' port probe finished, still 1 running probes in this device (ttyUSB1)
>ModemManager[1516]: <debug> [1418324084.415704] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1516]: <debug> [1418324084.415805] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
>ModemManager[1516]: <debug> [1418324087.415692] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1516]: <debug> [1418324087.415784] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
>ModemManager[1516]: <debug> [1418324090.416124] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1516]: <debug> [1418324090.416191] [mm-port-probe.c:162] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is not AT-capable
>ModemManager[1516]: <debug> [1418324090.416246] [mm-port-probe.c:658] serial_probe_qcdm(): (tty/ttyUSB1) probing QCDM...
>ModemManager[1516]: <debug> [1418324090.416270] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324090.416288] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port...
>ModemManager[1516]: <debug> [1418324090.418054] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed
>ModemManager[1516]: <debug> [1418324090.418094] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB1) forced to close port
>ModemManager[1516]: <debug> [1418324090.418202] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[1516]: <debug> [1418324090.418757] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324090.418798] [mm-port-serial-qcdm.c:201] debug_log(): (ttyUSB1): --> 7e 00 78 f0 7e
>ModemManager[1516]: <debug> [1418324090.420213] [mm-port-serial-qcdm.c:201] debug_log(): (ttyUSB1): <-- 00 44 65 63 20 32 33 20 32 30 31 30 31 31 3a 30 37 3a 31 35 44 65 63 20 32 33 20 32 30 31 30 31 31 3a 30 37 3a 31 35 4b 50 52 42 4c 00 44 65 00 00 21 00 00 00 01 20 3e cb 7e
>ModemManager[1516]: <debug> [1418324090.420290] [mm-port-probe.c:240] mm_port_probe_set_result_qcdm(): (tty/ttyUSB1) port is QCDM-capable
>ModemManager[1516]: <debug> [1418324090.420341] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324090.420359] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port...
>ModemManager[1516]: <debug> [1418324090.423029] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed
>ModemManager[1516]: <debug> [1418324090.423063] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB1) forced to close port
>ModemManager[1516]: <debug> [1418324090.423115] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB1] found best plugin for port
>ModemManager[1516]: <debug> [1418324090.423153] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyUSB1' port probe finished, last one in device
>ModemManager[1516]: <debug> [1418324090.423171] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2] device support check finished in '16.809674' seconds
>ModemManager[1516]: <info>  [1418324090.423196] [mm-device.c:509] mm_device_create_modem(): Creating modem with plugin 'Huawei' and '5' ports
>ModemManager[1516]: <debug> [1418324090.423225] [huawei/mm-plugin-huawei.c:491] create_modem(): QMI-powered Huawei modem found...
>ModemManager[1516]: <debug> [1418324090.423512] [huawei/mm-plugin-huawei.c:554] grab_port(): (tty/ttyUSB0) Port will have AT flags 'ppp'
>ModemManager[1516]: <debug> [1418324090.423580] [mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyUSB0) type 'at' claimed by /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2
>ModemManager[1516]: <debug> [1418324090.423597] [huawei/mm-plugin-huawei.c:554] grab_port(): (net/wwp0s29u1u2i1) Port will have AT flags 'none'
>ModemManager[1516]: <debug> [1418324090.423610] [mm-base-modem.c:304] mm_base_modem_grab_port(): (wwp0s29u1u2i1) type 'net' claimed by /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2
>ModemManager[1516]: <debug> [1418324090.423621] [huawei/mm-plugin-huawei.c:554] grab_port(): (usbmisc/cdc-wdm0) Port will have AT flags 'ppp'
>ModemManager[1516]: <debug> [1418324090.423638] [mm-base-modem.c:304] mm_base_modem_grab_port(): (cdc-wdm0) type 'qmi' claimed by /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2
>ModemManager[1516]: <debug> [1418324090.423649] [huawei/mm-plugin-huawei.c:554] grab_port(): (tty/ttyUSB1) Port will have AT flags 'primary'
>ModemManager[1516]: <debug> [1418324090.423666] [mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyUSB1) type 'qcdm' claimed by /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2
>ModemManager[1516]: <debug> [1418324090.423802] [huawei/mm-plugin-huawei.c:554] grab_port(): (tty/ttyUSB2) Port will have AT flags 'none'
>ModemManager[1516]: <debug> [1418324090.424021] [mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyUSB2) type 'at' claimed by /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2
>ModemManager[1516]: <debug> [1418324090.424155] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2) tty/ttyUSB2 at (primary)
>ModemManager[1516]: <debug> [1418324090.424323] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2) net/wwp0s29u1u2i1 data (primary)
>ModemManager[1516]: <debug> [1418324090.424495] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2) tty/ttyUSB0 data (secondary)
>ModemManager[1516]: <debug> [1418324090.424698] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2) tty/ttyUSB1 qcdm
>ModemManager[1516]: <debug> [1418324090.424862] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2) usb/cdc-wdm0 qmi (primary)
>ModemManager[1516]: <info>  [1418324090.425245] [mm-base-manager.c:162] find_device_support_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2' successfully created
>ModemManager[1516]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 28
><<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 27
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 16
><<<<<<   message     = "Internal Proxy Open" (0xFF00)
><<<<<< TLV:
><<<<<<   type       = "Device Path" (0x01)
><<<<<<   length     = 13
><<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
><<<<<<   translated = /dev/cdc-wdm0
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 19
>>>>>>>   data   = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 18
>>>>>>>   flags   = 0x00
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Internal Proxy Open" (0xFF00)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[1516]: [/dev/cdc-wdm0] Checking version info (10 retries)...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 12
><<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 11
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Version Info" (0x0021)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 48
>>>>>>>   data   = 01:2F:00:80:00:00:01:02:21:00:24:00:02:04:00:00:00:00:00:01:1A:00:05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 47
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 36
>>>>>>>   message     = "Get Version Info" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Service list" (0x01)
>>>>>>>   length     = 26
>>>>>>>   value      = 05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>>>>>>>   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '2' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '3' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '2' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] ' [4] = '[ service = 'cat' major_version = '0' minor_version = '0' ] '}
>ModemManager[1516]: [/dev/cdc-wdm0] QMI Device supports 5 services:
>ModemManager[1516]: [/dev/cdc-wdm0]    ctl (1.2)
>ModemManager[1516]: [/dev/cdc-wdm0]    wds (1.3)
>ModemManager[1516]: [/dev/cdc-wdm0]    dms (1.2)
>ModemManager[1516]: [/dev/cdc-wdm0]    nas (1.0)
>ModemManager[1516]: [/dev/cdc-wdm0]    cat (0.0)
>ModemManager[1516]: [/dev/cdc-wdm0] Setting network port data format...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 21
><<<<<<   data   = 01:14:00:00:00:00:00:03:26:00:09:00:10:02:00:01:00:01:01:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 20
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 3
><<<<<<   tlv_length  = 9
><<<<<<   message     = "Set Data Format" (0x0026)
><<<<<< TLV:
><<<<<<   type       = "Protocol" (0x10)
><<<<<<   length     = 2
><<<<<<   value      = 01:00
><<<<<<   translated = 802-3
><<<<<< TLV:
><<<<<<   type       = "Format" (0x01)
><<<<<<   length     = 1
><<<<<<   value      = 00
><<<<<<   translated = absent
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 19
>>>>>>>   data   = 01:12:00:80:00:00:01:03:26:00:07:00:02:04:00:01:00:2D:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 18
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 3
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Set Data Format" (0x0026)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 01:00:2D:00
>>>>>>>   translated = FAILURE: (null)
>ModemManager[1516]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 28
><<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 27
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 16
><<<<<<   message     = "Internal Proxy Open" (0xFF00)
><<<<<< TLV:
><<<<<<   type       = "Device Path" (0x01)
><<<<<<   length     = 13
><<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
><<<<<<   translated = /dev/cdc-wdm0
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 19
>>>>>>>   data   = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 18
>>>>>>>   flags   = 0x00
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Internal Proxy Open" (0xFF00)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[1516]: [/dev/cdc-wdm0] Checking version info (10 retries)...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 12
><<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 11
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Version Info" (0x0021)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 48
>>>>>>>   data   = 01:2F:00:80:00:00:01:02:21:00:24:00:02:04:00:00:00:00:00:01:1A:00:05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 47
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 36
>>>>>>>   message     = "Get Version Info" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Service list" (0x01)
>>>>>>>   length     = 26
>>>>>>>   value      = 05:00:01:00:02:00:01:01:00:03:00:02:01:00:02:00:03:01:00:00:00:E0:00:00:00:00
>>>>>>>   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '2' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '3' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '2' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] ' [4] = '[ service = 'cat' major_version = '0' minor_version = '0' ] '}
>ModemManager[1516]: [/dev/cdc-wdm0] QMI Device supports 5 services:
>ModemManager[1516]: [/dev/cdc-wdm0]    ctl (1.2)
>ModemManager[1516]: [/dev/cdc-wdm0]    wds (1.3)
>ModemManager[1516]: [/dev/cdc-wdm0]    dms (1.2)
>ModemManager[1516]: [/dev/cdc-wdm0]    nas (1.0)
>ModemManager[1516]: [/dev/cdc-wdm0]    cat (0.0)
>ModemManager[1516]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[1516]: [/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[1516]: [/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[1516]: [/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[1516]: [/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[1516]: [/dev/cdc-wdm0] Registered 'dms' (version 1.2) client with ID '1'
>ModemManager[1516]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[1516]: [/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[1516]: [/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[1516]: [/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[1516]: [/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[1516]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '1'
>ModemManager[1516]: <debug> [1418324090.590278] [mm-broadband-modem-qmi.c:9995] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'wms': Couldn't create client for service 'wms': Service 'wms' not supported by the device
>ModemManager[1516]: <debug> [1418324090.590295] [mm-broadband-modem-qmi.c:9995] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'pds': Couldn't create client for service 'pds': Service 'pds' not supported by the device
>ModemManager[1516]: <debug> [1418324091.745932] [mm-broadband-modem-qmi.c:9995] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'oma': Couldn't create client for service 'oma': Service 'oma' not supported by the device
>ModemManager[1516]: <debug> [1418324091.746200] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1516]: <warn>  [1418324091.747089] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1516]: <debug> [1418324091.747141] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324091.747155] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
>ModemManager[1516]: <debug> [1418324091.747409] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1516]: <debug> [1418324091.747628] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1516]: <debug> [1418324091.748702] [mm-broadband-modem-qmi.c:531] modem_load_current_capabilities(): loading current capabilities...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:01:00:2B:00:00:00
>ModemManager[1516]: [/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[1516]: <debug> [1418324091.748931] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1516]: <debug> [1418324091.753264] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'A'
>ModemManager[1516]: <debug> [1418324091.753339] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'T'
>ModemManager[1516]: <debug> [1418324091.754410] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'E'
>ModemManager[1516]: [/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[1516]: [/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[1516]: <debug> [1418324092.367347] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '0<CR><CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324092.367565] [mm-broadband-modem-qmi.c:426] load_current_capabilities_get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:01:00:20:00:00:00
>ModemManager[1516]: [/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[1516]: <debug> [1418324092.367733] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
>ModemManager[1516]: <debug> [1418324092.417128] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324092.417256] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
>ModemManager[1516]: [/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[1516]: [/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[1516]: <debug> [1418324092.431614] [mm-modem-helpers-qmi.c:1268] mm_modem_capability_from_qmi_capabilities_context(): Current capabilities built: 'gsm-umts'
>  SSP mode preference: 'unknown'
>  TP: 'auto'
>  DMS Capabilities: 'gsm-umts'
>ModemManager[1516]: <debug> [1418324092.431731] [mm-broadband-modem-qmi.c:693] modem_load_supported_capabilities(): loading supported capabilities...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:02:00:20:00:00:00
>ModemManager[1516]: [/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[1516]: <debug> [1418324092.437585] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324092.437718] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
>ModemManager[1516]: [/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[1516]: [/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[1516]: <debug> [1418324092.441298] [mm-iface-modem.c:4327] interface_initialization_step(): Modem allows up to 2 bearers
>ModemManager[1516]: <debug> [1418324092.441314] [mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 2, max active: 2)
>ModemManager[1516]: <debug> [1418324092.441388] [mm-broadband-modem-qmi.c:995] modem_load_manufacturer(): loading manufacturer...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:03:00:21:00:00:00
>ModemManager[1516]: [/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[1516]: <debug> [1418324092.445051] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324092.445137] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 43
>>>>>>>   data   = 01:2A:00:80:02:01:02:03:00:21:00:1E:00:02:04:00:00:00:00:00:01:14:00:48:55:41:57:45:49:20:43:4F:4D:4D:55:4E:49:43:41:54:49:4F:4E
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 42
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 3
>>>>>>>   tlv_length  = 30
>>>>>>>   message     = "Get Manufacturer" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Manufacturer" (0x01)
>>>>>>>   length     = 20
>>>>>>>   value      = 48:55:41:57:45:49:20:43:4F:4D:4D:55:4E:49:43:41:54:49:4F:4E
>>>>>>>   translated = HUAWEI COMMUNICATION
>ModemManager[1516]: <debug> [1418324092.449236] [mm-broadband-modem-qmi.c:942] modem_load_manufacturer_finish(): loaded manufacturer: HUAWEI COMMUNICATION
>ModemManager[1516]: <debug> [1418324092.449278] [mm-broadband-modem-qmi.c:1071] modem_load_model(): loading model...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:04:00:22:00:00:00
>ModemManager[1516]: [/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[1516]: <debug> [1418324092.923127] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324092.923257] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 25
>>>>>>>   data   = 01:18:00:80:02:01:02:04:00:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:33:33
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 24
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 4
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Get Model" (0x0022)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Model" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 33:33
>>>>>>>   translated = 33
>ModemManager[1516]: <debug> [1418324092.925518] [mm-broadband-modem-qmi.c:1018] modem_load_model_finish(): loaded model: 33
>ModemManager[1516]: <debug> [1418324092.925586] [mm-broadband-modem-qmi.c:1147] modem_load_revision(): loading revision...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:05:00:23:00:00:00
>ModemManager[1516]: [/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[1516]: <debug> [1418324092.929983] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324092.930071] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1516]: <debug> [1418324092.930136] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 75
>>>>>>>   data   = 01:4A:00:80:02:01:02:05:00:23:00:3E:00:02:04:00:00:00:00:00:01:34:00:4D:36:32:34:36:41:2D:4B:50:52:42:4C:2D:31:2E:32:2E:30:30:32:32:5F:33:48:20:20:20:31:20:20:5B:44:65:63:20:32:33:20:32:30:31:30:20:31:31:3A:30:37:3A:31:35:5D
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 74
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 5
>>>>>>>   tlv_length  = 62
>>>>>>>   message     = "Get Revision" (0x0023)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Revision" (0x01)
>>>>>>>   length     = 52
>>>>>>>   value      = 4D:36:32:34:36:41:2D:4B:50:52:42:4C:2D:31:2E:32:2E:30:30:32:32:5F:33:48:20:20:20:31:20:20:5B:44:65:63:20:32:33:20:32:30:31:30:20:31:31:3A:30:37:3A:31:35:5D
>>>>>>>   translated = M6246A-KPRBL-1.2.0022_3H   1  [Dec 23 2010 11:07:15]
>ModemManager[1516]: <debug> [1418324092.932576] [mm-broadband-modem-qmi.c:1094] modem_load_revision_finish(): loaded revision: M6246A-KPRBL-1.2.0022_3H   1  [Dec 23 2010 11:07:15]
>ModemManager[1516]: <debug> [1418324092.932616] [mm-broadband-modem-qmi.c:1289] modem_load_equipment_identifier(): loading equipment identifier...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:06:00:25:00:00:00
>ModemManager[1516]: [/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[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 40
>>>>>>>   data   = 01:27:00:80:02:01:02:06:00:25:00:1B:00:02:04:00:00:00:00:00:11:11:00:33:35:32:33:37:35:30:34:32:36:36:38:34:32:36:78:0C
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 39
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 6
>>>>>>>   tlv_length  = 27
>>>>>>>   message     = "Get IDs" (0x0025)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Imei" (0x11)
>>>>>>>   length     = 17
>>>>>>>   value      = xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx:xx
>>>>>>>   translated = xxxxxxxxxxxxxxx
>ModemManager[1516]: <debug> [1418324092.940436] [mm-broadband-modem-qmi.c:1186] modem_load_equipment_identifier_finish(): loaded equipment identifier: xxxxxxxxxxxxxxx
>ModemManager[1516]: <debug> [1418324092.940470] [mm-broadband-modem-qmi.c:1324] modem_load_device_identifier(): loading device identifier...
>ModemManager[1516]: <debug> [1418324092.940521] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '000012d1000014acxxxxxxxxxxxxxxxM6246A-KPRBL-1.2.0022_3H1[Dec23201011:07:15]33HUAWEICOMMUNICATION'
>ModemManager[1516]: <debug> [1418324092.940534] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '28bc528013753cafc554b705ffcbf4ac0d3e32f7'
>ModemManager[1516]: <debug> [1418324092.940563] [mm-broadband-modem-qmi.c:1312] modem_load_device_identifier_finish(): loaded device identifier: 28bc528013753cafc554b705ffcbf4ac0d3e32f7
>ModemManager[1516]: <debug> [1418324093.178426] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324093.178616] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1516]: <debug> [1418324093.178865] [mm-broadband-modem-qmi.c:1727] modem_load_supported_bands(): loading band capabilities...
>ModemManager[1516]: <warn>  [1418324093.179097] [mm-iface-modem.c:3982] load_supported_bands_ready(): couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>ModemManager[1516]: <debug> [1418324093.179307] [mm-broadband-modem-qmi.c:2682] load_power_state(): Getting device operating mode...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:08:00:2D:00:00:00
>ModemManager[1516]: [/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[1516]: [/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[1516]: [/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[1516]: <warn>  [1418324093.184517] [mm-iface-modem.c:4014] load_power_state_ready(): couldn't load Power State: 'Unhandled power state: 'unknown' (255)'
>ModemManager[1516]: <debug> [1418324093.184547] [mm-broadband-modem-qmi.c:1533] modem_load_unlock_required(): loading unlock required...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:09:00:2B:00:00:00
>ModemManager[1516]: [/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[1516]: [/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:01:03:0A
>ModemManager[1516]: [/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      = 01:03:0A
>>>>>>>   translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[1516]: <debug> [1418324093.364501] [mm-broadband-modem-qmi.c:1626] modem_load_unlock_retries(): loading unlock retries...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:0A:00:2B:00:00:00
>ModemManager[1516]: [/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[1516]: [/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:01:03:0A
>ModemManager[1516]: [/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      = 01:03:0A
>>>>>>>   translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[1516]: <info>  [1418324093.371440] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem: state changed (unknown -> locked)
>ModemManager[1516]: <debug> [1418324093.371762] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>ModemManager[1516]: <debug> [1418324093.371829] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>ModemManager[1516]: <warn>  [1418324093.371868] [mm-base-sim.c:1428] load_sim_identifier_ready(): couldn't load SIM identifier: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>ModemManager[1516]: <debug> [1418324093.371902] [mm-sim-qmi.c:224] load_imsi(): loading IMSI...
>ModemManager[1516]: <warn>  [1418324093.371955] [mm-base-sim.c:1464] load_imsi_ready(): couldn't load IMSI: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>ModemManager[1516]: <debug> [1418324093.372038] [mm-broadband-modem-qmi.c:1408] modem_load_own_numbers(): loading own numbers...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:0E:00:24:00:00:00
>ModemManager[1516]: [/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[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:02:01:02:0E:00:24:00:07:00:02:04:00:01:00:10:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 14
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Get MSISDN" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 01:00:10:00
>>>>>>>   translated = FAILURE: NotProvisioned
>ModemManager[1516]: <warn>  [1418324093.376955] [mm-iface-modem.c:4112] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:02:00:2B:00:00:00
>ModemManager[1516]: [/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[1516]: [/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[1516]: [/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[1516]: <debug> [1418324093.471131] [mm-broadband-modem-qmi.c:2853] get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
>ModemManager[1516]: <warn>  [1418324093.471158] [mm-iface-modem.c:4141] load_current_modes_ready(): couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
>ModemManager[1516]: <debug> [1418324093.471192] [mm-broadband-modem-qmi.c:1875] modem_load_current_bands(): loading current bands...
>ModemManager[1516]: <warn>  [1418324093.471875] [mm-iface-modem.c:4162] load_current_bands_ready(): couldn't load current Bands: 'QMI operation failed: Cannot send message: QMI service 'nas' version '1.1' required, got version '1.0''
>ModemManager[1516]: <debug> [1418324093.472111] [mm-broadband-modem-qmi.c:9090] firmware_check_support(): loading firmware images...
>ModemManager[1516]: <debug> [1418324093.472174] [mm-broadband-modem.c:9005] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
>ModemManager[1516]: <debug> [1418324093.472260] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324093.472274] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port...
>ModemManager[1516]: <debug> [1418324093.474200] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed
>ModemManager[1516]: <debug> [1418324093.474259] [mm-base-modem.c:950] initialize_ready(): Couldn't finish initialization in the current state: 'Modem is currently locked, cannot fully initialize'
>ModemManager[1516]: <debug> [1418324093.474704] [mm-device.c:436] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2' at path '/org/freedesktop/ModemManager1/Modem/0'
>ModemManager[1516]: <debug> [1418324093.474814] [mm-device.c:446] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Huawei' modem, VID 0x12D1 PID 0x14AC (usb)
>ModemManager[1516]: <debug> [1418324099.666692] [mm-sim-qmi.c:318] send_pin(): Sending PIN...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 22
><<<<<<   data   = 01:15:00:00:02:01:00:10:00:28:00:09:00:01:06:00:01:04:33:33:30:38
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 21
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 16
><<<<<<   tlv_length  = 9
><<<<<<   message     = "UIM Verify PIN" (0x0028)
><<<<<< TLV:
><<<<<<   type       = "Info" (0x01)
><<<<<<   length     = 6
><<<<<<   value      = xx:xx:xx:xx:xx:xx
><<<<<<   translated = [ pin_id = 'pin' pin = 'xxxx' ]
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:02:01:02:10:00:28:00:07:00:02:04:00:00:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 16
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "UIM Verify PIN" (0x0028)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[1516]: <debug> [1418324099.725135] [mm-broadband-modem-qmi.c:1533] modem_load_unlock_required(): loading unlock required...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:11:00:2B:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 17
><<<<<<   tlv_length  = 0
><<<<<<   message     = "UIM Get PIN Status" (0x002B)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 32
>>>>>>>   data   = 01:1F:00:80:02:01:02:11:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:02:03:0A
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 31
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 17
>>>>>>>   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      = 02:03:0A
>>>>>>>   translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[1516]: <debug> [1418324103.361193] [mm-broadband-modem-qmi.c:1626] modem_load_unlock_retries(): loading unlock retries...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:12:00:2B:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 18
><<<<<<   tlv_length  = 0
><<<<<<   message     = "UIM Get PIN Status" (0x002B)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 26
>>>>>>>   data   = 01:19:00:80:03:FF:04:00:00:24:00:0D:00:01:06:00:02:02:02:00:01:00:11:01:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 25
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 255
>>>>>>> QMI:
>>>>>>>   flags       = "indication"
>>>>>>>   transaction = 0
>>>>>>>   tlv_length  = 13
>>>>>>>   message     = "Serving System" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Serving System" (0x01)
>>>>>>>   length     = 6
>>>>>>>   value      = 02:02:02:00:01:00
>>>>>>>   translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = 'unknown' radio_interfaces = '{ [0] = 'none '}' ]
>>>>>>> TLV:
>>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>>   length     = 1
>>>>>>>   value      = 00
>>>>>>>   translated = {}
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 26
>>>>>>>   data   = 01:19:00:80:03:FF:04:00:00:24:00:0D:00:01:06:00:02:02:02:02:01:05:11:01:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 25
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 255
>>>>>>> QMI:
>>>>>>>   flags       = "indication"
>>>>>>>   transaction = 0
>>>>>>>   tlv_length  = 13
>>>>>>>   message     = "Serving System" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Serving System" (0x01)
>>>>>>>   length     = 6
>>>>>>>   value      = 02:02:02:02:01:05
>>>>>>>   translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>>>>>>> TLV:
>>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>>   length     = 1
>>>>>>>   value      = 00
>>>>>>>   translated = {}
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 27
>>>>>>>   data   = 01:1A:00:80:03:FF:04:00:00:24:00:0E:00:01:06:00:02:02:02:02:01:05:11:02:00:01:05
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 26
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 255
>>>>>>> QMI:
>>>>>>>   flags       = "indication"
>>>>>>>   transaction = 0
>>>>>>>   tlv_length  = 14
>>>>>>>   message     = "Serving System" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Serving System" (0x01)
>>>>>>>   length     = 6
>>>>>>>   value      = 02:02:02:02:01:05
>>>>>>>   translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>>>>>>> TLV:
>>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>>   length     = 2
>>>>>>>   value      = 01:05
>>>>>>>   translated = { [0] = 'wcdma '}
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 32
>>>>>>>   data   = 01:1F:00:80:02:01:02:12:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:02:03:0A
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 31
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 18
>>>>>>>   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      = 02:03:0A
>>>>>>>   translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[1516]: <debug> [1418324107.719295] [mm-iface-modem.c:3076] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
>ModemManager[1516]: <info>  [1418324107.719594] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (locked -> initializing)
>ModemManager[1516]: <debug> [1418324107.720042] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1516]: <warn>  [1418324107.721121] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1516]: <debug> [1418324107.721221] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324107.721255] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
>ModemManager[1516]: <debug> [1418324107.721321] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1516]: <debug> [1418324107.721368] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1516]: <debug> [1418324107.721476] [mm-broadband-modem-qmi.c:1727] modem_load_supported_bands(): loading band capabilities...
>ModemManager[1516]: <warn>  [1418324107.721552] [mm-iface-modem.c:3982] load_supported_bands_ready(): couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>ModemManager[1516]: <debug> [1418324107.721593] [mm-broadband-modem-qmi.c:2682] load_power_state(): Getting device operating mode...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:14:00:2D:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 20
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Operating Mode" (0x002D)
>ModemManager[1516]: <debug> [1418324107.721812] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 24
>>>>>>>   data   = 01:17:00:80:02:01:02:14:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:FF
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 23
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 20
>>>>>>>   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[1516]: <warn>  [1418324107.727564] [mm-iface-modem.c:4014] load_power_state_ready(): couldn't load Power State: 'Unhandled power state: 'unknown' (255)'
>ModemManager[1516]: <debug> [1418324107.727623] [mm-broadband-modem-qmi.c:1533] modem_load_unlock_required(): loading unlock required...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:15:00:2B:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 21
><<<<<<   tlv_length  = 0
><<<<<<   message     = "UIM Get PIN Status" (0x002B)
>ModemManager[1516]: <debug> [1418324107.731382] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324107.731512] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
>ModemManager[1516]: <debug> [1418324107.737113] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324107.737211] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
>ModemManager[1516]: <debug> [1418324107.749006] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324107.749107] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
>ModemManager[1516]: <debug> [1418324107.755262] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324107.755374] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
>ModemManager[1516]: <debug> [1418324107.762793] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324107.762899] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1516]: <debug> [1418324107.768886] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324107.768986] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1516]: <debug> [1418324107.769045] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
>ModemManager[1516]: <debug> [1418324107.780144] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324107.780237] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 32
>>>>>>>   data   = 01:1F:00:80:02:01:02:15:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:02:03:0A
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 31
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 21
>>>>>>>   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      = 02:03:0A
>>>>>>>   translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[1516]: <debug> [1418324108.843282] [mm-broadband-modem-qmi.c:1626] modem_load_unlock_retries(): loading unlock retries...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:16:00:2B:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 22
><<<<<<   tlv_length  = 0
><<<<<<   message     = "UIM Get PIN Status" (0x002B)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 32
>>>>>>>   data   = 01:1F:00:80:02:01:02:16:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:02:03:0A
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 31
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 22
>>>>>>>   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      = 02:03:0A
>>>>>>>   translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[1516]: <debug> [1418324108.913125] [mm-iface-modem.c:3076] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
>ModemManager[1516]: <debug> [1418324108.913199] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>ModemManager[1516]: <debug> [1418324108.913248] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>ModemManager[1516]: <warn>  [1418324108.913279] [mm-base-sim.c:1428] load_sim_identifier_ready(): couldn't load SIM identifier: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>ModemManager[1516]: <debug> [1418324108.913310] [mm-sim-qmi.c:224] load_imsi(): loading IMSI...
>ModemManager[1516]: <warn>  [1418324108.913350] [mm-base-sim.c:1464] load_imsi_ready(): couldn't load IMSI: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>ModemManager[1516]: <debug> [1418324108.913378] [mm-broadband-modem-qmi.c:1408] modem_load_own_numbers(): loading own numbers...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:1A:00:24:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 26
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get MSISDN" (0x0024)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:02:01:02:1A:00:24:00:07:00:02:04:00:01:00:10:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 26
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Get MSISDN" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 01:00:10:00
>>>>>>>   translated = FAILURE: NotProvisioned
>ModemManager[1516]: <warn>  [1418324108.919324] [mm-iface-modem.c:4112] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:04:00:2B:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 4
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Technology Preference" (0x002B)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:03:01:02:04:00:2B:00:07:00:02:04:00:01:00:47:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 4
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Get Technology Preference" (0x002B)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 01:00:47:00
>>>>>>>   translated = FAILURE: InvalidQmiCommand
>ModemManager[1516]: <debug> [1418324108.925310] [mm-broadband-modem-qmi.c:2853] get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
>ModemManager[1516]: <warn>  [1418324108.925338] [mm-iface-modem.c:4141] load_current_modes_ready(): couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
>ModemManager[1516]: <debug> [1418324108.925354] [mm-broadband-modem-qmi.c:1875] modem_load_current_bands(): loading current bands...
>ModemManager[1516]: <warn>  [1418324108.925376] [mm-iface-modem.c:4162] load_current_bands_ready(): couldn't load current Bands: 'QMI operation failed: Cannot send message: QMI service 'nas' version '1.1' required, got version '1.0''
>ModemManager[1516]: <debug> [1418324113.385039] [mm-broadband-modem-qmi.c:3281] modem_3gpp_load_imei_finish(): loaded IMEI: xxxxxxxxxxxxxxx
>ModemManager[1516]: <debug> [1418324113.385136] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'ph-sim' status, assuming disabled
>ModemManager[1516]: <debug> [1418324113.385202] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-pers' status, assuming disabled
>ModemManager[1516]: <debug> [1418324113.385423] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-sub-pers' status, assuming disabled
>ModemManager[1516]: <debug> [1418324113.385653] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'provider-pers' status, assuming disabled
>ModemManager[1516]: <debug> [1418324113.385857] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'corp-pers' status, assuming disabled
>ModemManager[1516]: <debug> [1418324113.386382] [mm-broadband-modem.c:8998] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
>ModemManager[1516]: <debug> [1418324113.386886] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1516]: <debug> [1418324113.386960] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CNMI=?<CR>'
>ModemManager[1516]: <debug> [1418324113.398902] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CNMI: (0,1,2),(0,1,2,3),(0,2),(0,1,2),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.399071] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1516]: <debug> [1418324113.399111] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1516]: <debug> [1418324113.399138] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS=?<CR>'
>ModemManager[1516]: <debug> [1418324113.410625] [mm-port-serial-at.c:440] 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[1516]: <debug> [1418324113.410856] [mm-iface-modem-messaging.c:1148] load_supported_storages_ready(): Supported storages loaded:
>ModemManager[1516]: <debug> [1418324113.410896] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready():   mem1 (list/read/delete) storages: 'me, mt, sm, sr'
>ModemManager[1516]: <debug> [1418324113.410925] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready():   mem2 (write/send) storages:       'me, mt, sm, sr'
>ModemManager[1516]: <debug> [1418324113.410950] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready():   mem3 (reception) storages:        'me, mt, sm, sr'
>ModemManager[1516]: <debug> [1418324113.411210] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1516]: <debug> [1418324113.411388] [mm-broadband-modem.c:9002] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
>ModemManager[1516]: <debug> [1418324113.411524] [mm-broadband-modem-qmi.c:9424] signal_check_support(): Extended signal capabilities supported
>ModemManager[1516]: <debug> [1418324113.411880] [mm-broadband-modem-qmi.c:8138] oma_check_support(): OMA capabilities not supported
>ModemManager[1516]: <debug> [1418324113.411952] [mm-broadband-modem.c:9004] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
>ModemManager[1516]: <debug> [1418324113.412039] [mm-broadband-modem.c:9005] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
>ModemManager[1516]: <info>  [1418324113.412107] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (initializing -> disabled)
>ModemManager[1516]: <debug> [1418324113.412351] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1516]: <debug> [1418324113.412392] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port...
>ModemManager[1516]: <debug> [1418324113.415202] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed
>ModemManager[1516]: <info>  [1418324113.417557] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
>ModemManager[1516]: <debug> [1418324113.417884] [mm-broadband-modem.c:8000] enabling_started(): Skipping modem initialization: device hotplugged
>ModemManager[1516]: <debug> [1418324113.417907] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1516]: <warn>  [1418324113.419078] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1516]: <debug> [1418324113.419129] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324113.419145] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
>ModemManager[1516]: <debug> [1418324113.419182] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[1516]: <debug> [1418324113.419570] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1516]: <debug> [1418324113.419592] [mm-broadband-modem.c:8016] enabling_started(): Flashing primary AT port before enabling...
>ModemManager[1516]: <debug> [1418324113.419629] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1516]: <debug> [1418324113.425433] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.425554] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
>ModemManager[1516]: <debug> [1418324113.431595] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.431653] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
>ModemManager[1516]: <debug> [1418324113.443266] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.443361] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
>ModemManager[1516]: <debug> [1418324113.449396] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.450006] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
>ModemManager[1516]: <debug> [1418324113.456973] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.520255] [mm-broadband-modem-qmi.c:2682] load_power_state(): Getting device operating mode...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:20:00:2D:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 32
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Operating Mode" (0x002D)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 24
>>>>>>>   data   = 01:17:00:80:02:01:02:20:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:FF
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 23
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 32
>>>>>>>   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[1516]: Couldn't reload current power state: Unhandled power state: 'unknown' (255)
>ModemManager[1516]: <debug> [1418324113.525016] [mm-broadband-modem-qmi.c:2557] common_power_up_down_off(): Setting device operating mode...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 17
><<<<<<   data   = 01:10:00:00:02:01:00:21:00:2E:00:04:00:01:01:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 16
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 33
><<<<<<   tlv_length  = 4
><<<<<<   message     = "Set Operating Mode" (0x002E)
><<<<<< TLV:
><<<<<<   type       = "Mode" (0x01)
><<<<<<   length     = 1
><<<<<<   value      = 00
><<<<<<   translated = online
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:02:01:02:21:00:2E:00:07:00:02:04:00:00:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 33
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Set Operating Mode" (0x002E)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[1516]: <debug> [1418324113.531323] [mm-iface-modem.c:3191] modem_power_up_ready(): Modem set in full-power mode...
>ModemManager[1516]: <debug> [1418324113.531461] [mm-broadband-modem.c:8592] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 23
><<<<<<   data   = 01:16:00:00:03:01:00:06:00:02:00:0A:00:10:07:00:01:05:B0:D8:00:28:50
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 22
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 6
><<<<<<   tlv_length  = 10
><<<<<<   message     = "Set Event Report" (0x0002)
><<<<<< TLV:
><<<<<<   type       = "Signal Strength Indicator" (0x10)
><<<<<<   length     = 7
><<<<<<   value      = 01:05:B0:D8:00:28:50
><<<<<<   translated = [ report = 'yes' thresholds = '{ [0] = '-80 ' [1] = '-40 ' [2] = '0 ' [3] = '40 ' [4] = '80 '}' ]
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:03:01:02:06:00:02:00:07:00:02:04:00:00:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 6
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Set Event Report" (0x0002)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[1516]: <debug> [1418324113.537380] [mm-broadband-modem-qmi.c:4769] modem_3gpp_enable_unsolicited_registration_events(): Assuming serving system indications are always enabled
>ModemManager[1516]: <debug> [1418324113.544590] [mm-iface-modem-3gpp.c:751] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:07:00:24:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 7
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Serving System" (0x0024)
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 34
>>>>>>>   data   = 01:21:00:80:03:01:02:07:00:24:00:15:00:02:04:00:00:00:00:00:01:06:00:02:02:02:02:01:05:11:02:00:01:05
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 33
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 7
>>>>>>>   tlv_length  = 21
>>>>>>>   message     = "Get Serving System" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Serving System" (0x01)
>>>>>>>   length     = 6
>>>>>>>   value      = 02:02:02:02:01:05
>>>>>>>   translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>>>>>>> TLV:
>>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>>   length     = 2
>>>>>>>   value      = 01:05
>>>>>>>   translated = { [0] = 'wcdma '}
>ModemManager[1516]: <debug> [1418324113.551314] [mm-broadband-modem-qmi.c:3907] common_process_serving_system_3gpp(): Processing 3GPP info...
>ModemManager[1516]: <info>  [1418324113.551353] [mm-iface-modem-3gpp.c:1169] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> idle)
>ModemManager[1516]: <debug> [1418324113.551456] [mm-iface-modem.c:1561] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
>ModemManager[1516]: <debug> [1418324113.551588] [mm-broadband-modem.c:8633] enabling_step(): Modem has location capabilities, enabling the Location interface...
>ModemManager[1516]: <debug> [1418324113.551613] [mm-iface-modem-location.c:759] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
>ModemManager[1516]: <debug> [1418324113.551663] [mm-iface-modem-3gpp.c:751] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:08:00:24:00:00:00
>ModemManager[1516]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 8
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Serving System" (0x0024)
>ModemManager[1516]: <debug> [1418324113.551806] [mm-broadband-modem.c:8646] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
>ModemManager[1516]: <debug> [1418324113.551867] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1516]: <debug> [1418324113.551893] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMGF=?<CR>'
>ModemManager[1516]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 34
>>>>>>>   data   = 01:21:00:80:03:01:02:08:00:24:00:15:00:02:04:00:00:00:00:00:01:06:00:02:02:02:02:01:05:11:02:00:01:05
>ModemManager[1516]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 33
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 8
>>>>>>>   tlv_length  = 21
>>>>>>>   message     = "Get Serving System" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Serving System" (0x01)
>>>>>>>   length     = 6
>>>>>>>   value      = 02:02:02:02:01:05
>>>>>>>   translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>>>>>>> TLV:
>>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>>   length     = 2
>>>>>>>   value      = 01:05
>>>>>>>   translated = { [0] = 'wcdma '}
>ModemManager[1516]: <debug> [1418324113.557579] [mm-broadband-modem-qmi.c:3907] common_process_serving_system_3gpp(): Processing 3GPP info...
>ModemManager[1516]: <debug> [1418324113.563442] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.563585] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1516]: <debug> [1418324113.563619] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1516]: <debug> [1418324113.563649] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMGF=0<CR>'
>ModemManager[1516]: <debug> [1418324113.574830] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.574910] [mm-broadband-modem.c:5485] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU'
>ModemManager[1516]: <debug> [1418324113.575017] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1516]: <debug> [1418324113.575050] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1516]: <debug> [1418324113.575182] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS="","MT","MT"<CR>'
>ModemManager[1516]: <debug> [1418324113.644668] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMS ERROR: 500<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.644846] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown
>ModemManager[1516]: <debug> [1418324113.644905] [mm-iface-modem-messaging.c:792] set_default_storage_ready(): Couldn't set default storage: 'Unknown'
>ModemManager[1516]: <debug> [1418324113.644927] [mm-broadband-modem.c:6184] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me'
>ModemManager[1516]: <debug> [1418324113.644945] [mm-broadband-modem.c:5379] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)...
>ModemManager[1516]: <debug> [1418324113.644978] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1516]: <debug> [1418324113.645011] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1516]: <debug> [1418324113.645038] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS="ME"<CR>'
>ModemManager[1516]: <debug> [1418324113.659842] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMS ERROR: 500<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.659920] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown
>ModemManager[1516]: <debug> [1418324113.659951] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'me': 'Unknown'
>ModemManager[1516]: <debug> [1418324113.659970] [mm-broadband-modem.c:6184] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
>ModemManager[1516]: <debug> [1418324113.659983] [mm-broadband-modem.c:5379] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)...
>ModemManager[1516]: <debug> [1418324113.660010] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1516]: <debug> [1418324113.660045] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1516]: <debug> [1418324113.660071] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS="SM"<CR>'
>ModemManager[1516]: <debug> [1418324113.675142] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMS ERROR: 500<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.675252] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown
>ModemManager[1516]: <debug> [1418324113.675295] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'sm': 'Unknown'
>ModemManager[1516]: <debug> [1418324113.675322] [mm-broadband-modem.c:6184] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sr'
>ModemManager[1516]: <debug> [1418324113.675338] [mm-broadband-modem.c:5379] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SR), mem2 (none)...
>ModemManager[1516]: <debug> [1418324113.675369] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1516]: <debug> [1418324113.675391] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1516]: <debug> [1418324113.675410] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS="SR"<CR>'
>ModemManager[1516]: <debug> [1418324113.690128] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMS ERROR: 500<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.690246] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown
>ModemManager[1516]: <debug> [1418324113.690295] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'sr': 'Unknown'
>ModemManager[1516]: <debug> [1418324113.690351] [mm-broadband-modem.c:5791] set_messaging_unsolicited_events_handlers(): (ttyUSB2) Setting messaging unsolicited events handlers
>ModemManager[1516]: <debug> [1418324113.690382] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1516]: <debug> [1418324113.690425] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1516]: <debug> [1418324113.690466] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,1,0<CR>'
>ModemManager[1516]: <debug> [1418324113.710681] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.710811] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
>ModemManager[1516]: <debug> [1418324113.710871] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,2,0<CR>'
>ModemManager[1516]: <debug> [1418324113.735597] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1516]: <debug> [1418324113.735762] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1516]: <debug> [1418324113.735790] [mm-broadband-modem.c:8672] enabling_step(): Modem has extended signal reporting capabilities, enabling the Signal interface...
>ModemManager[1516]: <debug> [1418324113.735831] [mm-iface-modem-signal.c:206] setup_refresh_context(): Extended signal information reporting disabled (rate: 0 seconds)
>ModemManager[1516]: <info>  [1418324113.735923] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)

Actions: View

Attachments on bug 87206: 110692 | 110693 | 110746 | 110747 | 110750 | 110751

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