ModemManager[1516]: [1418324031.334533] [main.c:140] main(): ModemManager (version 1.4.0) starting in system bus... ModemManager[1516]: [1418324031.337220] [main.c:65] bus_acquired_cb(): Bus acquired, creating manager... ModemManager[1516]: [1418324031.340991] [mm-plugin-manager.c:852] load_plugins(): Looking for plugins in '/usr/lib/ModemManager' ModemManager[1516]: [1418324031.341794] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Gobi' ModemManager[1516]: [1418324031.342384] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Via CBP7' ModemManager[1516]: [1418324031.343012] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Iridium' ModemManager[1516]: [1418324031.343414] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Pantech' ModemManager[1516]: [1418324031.344831] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Sierra' ModemManager[1516]: [1418324031.345678] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Cinterion' ModemManager[1516]: [1418324031.346428] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel' ModemManager[1516]: [1418324031.347104] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'AnyDATA' ModemManager[1516]: [1418324031.870695] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'SimTech' ModemManager[1516]: [1418324031.871814] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Samsung' ModemManager[1516]: [1418324032.362389] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Altair LTE' ModemManager[1516]: [1418324032.363047] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia' ModemManager[1516]: [1418324032.363729] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Longcheer' ModemManager[1516]: [1418324032.364148] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Generic' ModemManager[1516]: [1418324032.365623] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Huawei' ModemManager[1516]: [1418324032.366900] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Ericsson MBM' ModemManager[1516]: [1418324032.367755] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option' ModemManager[1516]: [1418324032.368556] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel LTE' ModemManager[1516]: [1418324032.369072] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Linktop' ModemManager[1516]: [1418324032.378291] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia (Icera)' ModemManager[1516]: [1418324032.379163] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'MTK' ModemManager[1516]: [1418324032.518923] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'ZTE' ModemManager[1516]: [1418324032.520001] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option High-Speed' ModemManager[1516]: [1418324032.520526] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Motorola' ModemManager[1516]: [1418324032.521261] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'X22X' ModemManager[1516]: [1418324032.521779] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Telit' ModemManager[1516]: [1418324032.522711] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Wavecom' ModemManager[1516]: [1418324032.522743] [mm-plugin-manager.c:902] load_plugins(): Successfully loaded 27 plugins ModemManager[1516]: [1418324032.523461] [main.c:87] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired ModemManager[1516]: [1418324032.523522] [mm-base-manager.c:479] mm_base_manager_start(): Starting automatic device scan... ModemManager[1516]: [1418324032.525407] [mm-base-manager.c:518] mm_base_manager_start(): Finished device scan... ModemManager[1516]: [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]: [1418324032.526006] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyS1] filtered by drivers ModemManager[1516]: [1418324032.526027] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.526060] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyS1] filtered by drivers ModemManager[1516]: [1418324032.526079] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.526093] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.526107] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.526132] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.526144] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.526159] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.526173] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.526188] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyS1] filtered by udev tags ModemManager[1516]: [1418324032.526202] [mm-plugin.c:253] apply_pre_probing_filters(): (Option) [ttyS1] filtered by drivers ModemManager[1516]: [1418324032.541553] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.541783] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.541983] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.554337] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyS1] filtered by udev tags ModemManager[1516]: [1418324032.554566] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.566099] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyS1] filtered by drivers ModemManager[1516]: [1418324032.566353] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.566545] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.566751] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.566956] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyS1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.567159] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyS1] Found '5' plugins to try... ModemManager[1516]: [1418324032.567357] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1] Will try with plugin 'Via CBP7' ModemManager[1516]: [1418324032.567557] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1] Will try with plugin 'Iridium' ModemManager[1516]: [1418324032.567758] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1] Will try with plugin 'Cinterion' ModemManager[1516]: [1418324032.567962] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1] Will try with plugin 'Nokia' ModemManager[1516]: [1418324032.568162] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS1] Will try with plugin 'Generic' ModemManager[1516]: [1418324032.569293] [mm-plugin.c:767] mm_plugin_supports_port(): (Via CBP7) [ttyS1] probe required: 'at, at-vendor, at-product' ModemManager[1516]: [1418324032.569322] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyS1) launching port probing: 'at, at-vendor, at-product' ModemManager[1516]: [1418324032.577346] [mm-base-manager.c:314] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted ModemManager[1516]: [1418324032.577577] [mm-base-manager.c:314] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted ModemManager[1516]: [1418324032.577806] [mm-base-manager.c:314] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted ModemManager[1516]: [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]: [1418324032.578640] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [eno1] filtered by drivers ModemManager[1516]: [1418324032.578658] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.578666] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.578673] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.578792] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [eno1] filtered by drivers ModemManager[1516]: [1418324032.579001] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.579208] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.579390] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.579588] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.579782] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.579977] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.580173] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.580371] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.580570] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.580767] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [eno1] filtered by udev tags ModemManager[1516]: [1418324032.580959] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.581156] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.581354] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.581545] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.581737] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.581935] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.582133] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [eno1] filtered by drivers ModemManager[1516]: [1418324032.582392] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.582599] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [eno1] filtered by vendor/product IDs ModemManager[1516]: [1418324032.582793] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.582987] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [eno1] filtered by subsystem ModemManager[1516]: [1418324032.583180] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [eno1] Found '1' plugins to try... ModemManager[1516]: [1418324032.583374] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [eno1] Will try with plugin 'Generic' ModemManager[1516]: [1418324032.583575] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [eno1] probing deferred until result suggested ModemManager[1516]: [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]: [1418324032.583979] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wlp2s0] filtered by drivers ModemManager[1516]: [1418324032.584146] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.584341] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.584541] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.584735] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wlp2s0] filtered by drivers ModemManager[1516]: [1418324032.584923] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.708627] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.708911] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.709134] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.709337] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.709538] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.709736] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.709937] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.710123] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.710320] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wlp2s0] filtered by udev tags ModemManager[1516]: [1418324032.710510] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.710703] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.710897] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.711093] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.711285] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.711477] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.711671] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wlp2s0] filtered by drivers ModemManager[1516]: [1418324032.711869] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.712060] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wlp2s0] filtered by vendor/product IDs ModemManager[1516]: [1418324032.712261] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.712465] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlp2s0] filtered by subsystem ModemManager[1516]: [1418324032.712659] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wlp2s0] Found '1' plugins to try... ModemManager[1516]: [1418324032.712849] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wlp2s0] Will try with plugin 'Generic' ModemManager[1516]: [1418324032.713049] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [wlp2s0] probing deferred until result suggested ModemManager[1516]: [1418324032.713366] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [eno1] deferring support check until result suggested ModemManager[1516]: [1418324032.713421] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wlp2s0] deferring support check until result suggested ModemManager[1516]: [1418324032.713831] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyS1) opening serial port... ModemManager[1516]: [1418324032.714017] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyS1) device open count is 1 (open) ModemManager[1516]: [1418324032.814288] [mm-port-serial-at.c:440] debug_log(): (ttyS1): --> 'AT' ModemManager[1516]: [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]: [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]: [1418324034.414264] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [eno1] deferred task cancelled, no suggested plugin ModemManager[1516]: [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]: [1418324034.414341] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [wlp2s0] deferred task cancelled, no suggested plugin ModemManager[1516]: [1418324034.414359] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [eno1] not supported by any plugin ModemManager[1516]: [1418324034.414378] [mm-device.c:357] mm_device_ignore_port(): Fully ignoring port 'net/eno1' from now on ModemManager[1516]: [1418324034.414404] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'eno1' port probe finished, last one in device ModemManager[1516]: [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]: [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]: [1418324034.414515] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [wlp2s0] not supported by any plugin ModemManager[1516]: [1418324034.414529] [mm-device.c:357] mm_device_ignore_port(): Fully ignoring port 'net/wlp2s0' from now on ModemManager[1516]: [1418324034.414556] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'wlp2s0' port probe finished, last one in device ModemManager[1516]: [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]: [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]: [1418324036.415311] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out' ModemManager[1516]: [1418324036.415395] [mm-port-serial-at.c:440] debug_log(): (ttyS1): --> 'AT' ModemManager[1516]: [1418324039.415699] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out' ModemManager[1516]: [1418324039.415812] [mm-port-serial-at.c:440] debug_log(): (ttyS1): --> 'AT' ModemManager[1516]: [1418324042.415222] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out' ModemManager[1516]: [1418324042.415294] [mm-port-probe.c:162] mm_port_probe_set_result_at(): (tty/ttyS1) port is not AT-capable ModemManager[1516]: [1418324042.415365] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyS1) device open count is 0 (close) ModemManager[1516]: [1418324042.415375] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyS1) closing serial port... ModemManager[1516]: [1418324042.415461] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyS1) serial port closed ModemManager[1516]: [1418324042.415481] [mm-port-serial.c:1415] port_serial_close_force(): (ttyS1) forced to close port ModemManager[1516]: [1418324042.415522] [mm-plugin.c:454] apply_post_probing_filters(): (Via CBP7) [ttyS1] filtered as no vendor/product strings given ModemManager[1516]: [1418324042.415567] [mm-plugin.c:767] mm_plugin_supports_port(): (Iridium) [ttyS1] probe required: 'at, at-vendor, at-product' ModemManager[1516]: [1418324042.415596] [mm-plugin.c:454] apply_post_probing_filters(): (Iridium) [ttyS1] filtered as no vendor/product strings given ModemManager[1516]: [1418324042.415628] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyS1] probe required: 'at, at-vendor' ModemManager[1516]: [1418324042.415669] [mm-plugin.c:429] apply_post_probing_filters(): (Cinterion) [ttyS1] filtered by vendor strings ModemManager[1516]: [1418324042.415700] [mm-plugin.c:767] mm_plugin_supports_port(): (Nokia) [ttyS1] probe required: 'at, at-vendor, at-icera' ModemManager[1516]: [1418324042.415725] [mm-plugin.c:429] apply_post_probing_filters(): (Nokia) [ttyS1] filtered by vendor strings ModemManager[1516]: [1418324042.415838] [mm-plugin.c:767] mm_plugin_supports_port(): (Generic) [ttyS1] probe required: 'at, qcdm' ModemManager[1516]: [1418324042.416033] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyS1) launching port probing: 'qcdm' ModemManager[1516]: [1418324042.416233] [mm-port-probe.c:658] serial_probe_qcdm(): (tty/ttyS1) probing QCDM... ModemManager[1516]: [1418324042.416468] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyS1) opening serial port... ModemManager[1516]: [1418324042.416769] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyS1) device open count is 1 (open) ModemManager[1516]: [1418324042.416843] [mm-port-serial-qcdm.c:201] debug_log(): (ttyS1): --> 7e 00 78 f0 7e ModemManager[1516]: [1418324045.415928] [mm-port-serial-qcdm.c:201] debug_log(): (ttyS1): --> 7e 00 78 f0 7e ModemManager[1516]: [1418324048.413953] [mm-port-probe.c:258] mm_port_probe_set_result_qcdm(): (tty/ttyS1) port is not QCDM-capable ModemManager[1516]: [1418324048.414055] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyS1) device open count is 0 (close) ModemManager[1516]: [1418324048.414071] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyS1) closing serial port... ModemManager[1516]: [1418324048.414150] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyS1) serial port closed ModemManager[1516]: [1418324048.414166] [mm-port-serial.c:1415] port_serial_close_force(): (ttyS1) forced to close port ModemManager[1516]: [1418324048.414196] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Generic) [ttyS1] found best plugin for port ModemManager[1516]: [1418324048.414226] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyS1' port probe finished, last one in device ModemManager[1516]: [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]: [1418324048.414260] [mm-device.c:509] mm_device_create_modem(): Creating modem with plugin 'Generic' and '1' ports ModemManager[1516]: [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]: [1418324048.419051] [mm-base-modem.c:1485] finalize(): Modem (Generic) '/sys/devices/pci0000:00/0000:00:16.3' completely disposed ModemManager[1516]: [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]: [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]: [1418324073.613549] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB2] filtered by drivers ModemManager[1516]: [1418324073.613591] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.613623] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB2] filtered by drivers ModemManager[1516]: [1418324073.613656] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.613687] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.613716] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.613747] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.613777] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.613810] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.613845] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB2] filtered by udev tags ModemManager[1516]: [1418324073.613874] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.776179] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.776586] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.779011] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.779049] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB2] filtered by udev tags ModemManager[1516]: [1418324073.779063] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.779074] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB2] filtered by drivers ModemManager[1516]: [1418324073.779083] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.779093] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.779134] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.779226] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB2] filtered by vendor/product IDs ModemManager[1516]: [1418324073.780007] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB2] Found '6' plugins to try... ModemManager[1516]: [1418324073.780762] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Huawei' ModemManager[1516]: [1418324073.781492] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Via CBP7' ModemManager[1516]: [1418324073.781829] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Iridium' ModemManager[1516]: [1418324073.782167] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Cinterion' ModemManager[1516]: [1418324073.782514] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Nokia' ModemManager[1516]: [1418324073.782862] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Generic' ModemManager[1516]: [1418324073.783212] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB2] probe required: 'at, qcdm' ModemManager[1516]: [1418324073.783555] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm' ModemManager[1516]: [1418324073.784261] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB1] filtered by drivers ModemManager[1516]: [1418324073.784615] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324073.784840] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB1] filtered by drivers ModemManager[1516]: [1418324073.785070] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324073.785296] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324073.785516] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324073.785754] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324073.828390] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324073.828476] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324073.828578] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB1] filtered by udev tags ModemManager[1516]: [1418324073.828849] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324073.829115] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324073.829391] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.311039] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.311448] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB1] filtered by udev tags ModemManager[1516]: [1418324074.311794] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.312458] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB1] filtered by drivers ModemManager[1516]: [1418324074.313677] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.313910] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.314311] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.314730] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.315132] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB1] Found '6' plugins to try... ModemManager[1516]: [1418324074.315350] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Huawei' ModemManager[1516]: [1418324074.315586] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Via CBP7' ModemManager[1516]: [1418324074.316010] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Iridium' ModemManager[1516]: [1418324074.316231] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Cinterion' ModemManager[1516]: [1418324074.316444] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Nokia' ModemManager[1516]: [1418324074.316658] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Generic' ModemManager[1516]: [1418324074.316896] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB1] probe required: 'at, qcdm' ModemManager[1516]: [1418324074.317119] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm' ModemManager[1516]: [1418324074.317660] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers ModemManager[1516]: [1418324074.317706] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.317847] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.318064] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.318283] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [cdc-wdm0] filtered by drivers ModemManager[1516]: [1418324074.318498] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [cdc-wdm0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.318712] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.318926] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.319168] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.319384] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.319604] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.319833] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.320605] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.320654] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags ModemManager[1516]: [1418324074.320852] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.321069] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.321285] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.321593] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.321941] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.322471] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [cdc-wdm0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.322798] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.323106] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.323408] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.323936] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.324263] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem ModemManager[1516]: [1418324074.324571] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try... ModemManager[1516]: [1418324074.324871] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Huawei' ModemManager[1516]: [1418324074.325216] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Generic' ModemManager[1516]: [1418324074.325561] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [cdc-wdm0] probe required: 'qmi' ModemManager[1516]: [1418324074.340141] [mm-port-probe.c:1353] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi' ModemManager[1516]: [1418324074.342901] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wwp0s29u1u2i1] filtered by drivers ModemManager[1516]: [1418324074.342936] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.342948] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.342958] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.342990] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wwp0s29u1u2i1] filtered by drivers ModemManager[1516]: [1418324074.343021] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.343053] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.343085] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.343115] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.343146] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.343176] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.343217] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.343243] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.343273] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wwp0s29u1u2i1] filtered by udev tags ModemManager[1516]: [1418324074.343485] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.343691] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.343895] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.344095] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.344304] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.344517] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.344694] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wwp0s29u1u2i1] filtered by drivers ModemManager[1516]: [1418324074.344866] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.345039] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wwp0s29u1u2i1] filtered by vendor/product IDs ModemManager[1516]: [1418324074.345214] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.345441] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwp0s29u1u2i1] filtered by subsystem ModemManager[1516]: [1418324074.345613] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wwp0s29u1u2i1] Found '2' plugins to try... ModemManager[1516]: [1418324074.345813] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwp0s29u1u2i1] Will try with plugin 'Huawei' ModemManager[1516]: [1418324074.345983] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwp0s29u1u2i1] Will try with plugin 'Generic' ModemManager[1516]: [1418324074.346178] [mm-plugin.c:689] mm_plugin_supports_port(): (Huawei) [wwp0s29u1u2i1] probing deferred until result suggested ModemManager[1516]: [1418324074.346419] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wwp0s29u1u2i1] deferring support check until result suggested ModemManager[1516]: [1418324074.346608] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port... ModemManager[1516]: [1418324074.347241] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set ModemManager[1516]: [1418324074.347274] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open) ModemManager[1516]: [1418324074.353426] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1516]: [1418324074.353988] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1516]: [1418324074.354017] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1516]: [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]: [1418324074.453765] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close) ModemManager[1516]: [1418324074.453821] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port... ModemManager[1516]: [1418324074.455623] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed ModemManager[1516]: [1418324074.455663] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB2) forced to close port ModemManager[1516]: [1418324074.455726] [mm-plugin-manager.c:514] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB2] deferring support check ModemManager[1516]: [1418324074.455793] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close) ModemManager[1516]: [1418324074.455812] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port... ModemManager[1516]: [1418324074.457500] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed ModemManager[1516]: [1418324074.457536] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB1) forced to close port ModemManager[1516]: [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]: [1418324074.573542] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB0] filtered by drivers ModemManager[1516]: [1418324074.573588] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.573601] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB0] filtered by drivers ModemManager[1516]: [1418324074.573613] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.573624] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.573638] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.573649] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.573658] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.573669] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.573681] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags ModemManager[1516]: [1418324074.573691] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.573701] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.573710] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.574037] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.574259] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB0] filtered by udev tags ModemManager[1516]: [1418324074.574479] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.574706] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers ModemManager[1516]: [1418324074.574930] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.575154] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.575374] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.575592] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by vendor/product IDs ModemManager[1516]: [1418324074.575825] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '6' plugins to try... ModemManager[1516]: [1418324074.576041] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Huawei' ModemManager[1516]: [1418324074.576262] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Via CBP7' ModemManager[1516]: [1418324074.576485] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Iridium' ModemManager[1516]: [1418324074.576700] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Cinterion' ModemManager[1516]: [1418324074.577022] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Nokia' ModemManager[1516]: [1418324074.577341] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Generic' ModemManager[1516]: [1418324074.577614] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB0] probe required: 'at, qcdm' ModemManager[1516]: [1418324074.577839] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, qcdm' ModemManager[1516]: [1418324074.578205] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB0) opening serial port... ModemManager[1516]: [1418324074.580918] [mm-port-serial.c:515] real_config_fd(): (ttyUSB0): port attributes not fully set ModemManager[1516]: [1418324074.580977] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) ModemManager[1516]: [1418324074.681254] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT^CURC=0' ModemManager[1516]: [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]: [1418324075.418825] [mm-port-probe.c:271] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable ModemManager[1516]: [1418324075.581543] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- 'AT^CURC=0' ModemManager[1516]: [1418324075.581703] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [cdc-wdm0] found best plugin for port ModemManager[1516]: [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]: [1418324075.581762] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB0] suggested plugin for port ModemManager[1516]: [1418324075.581776] [mm-plugin-manager.c:361] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [wwp0s29u1u2i1] deferred task completed, got suggested plugin ModemManager[1516]: [1418324075.581793] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB1] suggested plugin for port ModemManager[1516]: [1418324075.581809] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB2] suggested plugin for port ModemManager[1516]: [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]: [1418324075.581856] [mm-plugin.c:689] mm_plugin_supports_port(): (Huawei) [wwp0s29u1u2i1] probing deferred until result suggested ModemManager[1516]: [1418324075.581879] [mm-plugin-manager.c:549] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [wwp0s29u1u2i1] task completed, got suggested plugin ModemManager[1516]: [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]: [1418324075.582903] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '' ModemManager[1516]: [1418324075.584156] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- 'OK' ModemManager[1516]: [1418324075.584216] [huawei/mm-plugin-huawei.c:197] curc_ready(): (Huawei) unsolicited messages in secondary ports turned off ModemManager[1516]: [1418324075.584263] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT^GETPORTMODE' ModemManager[1516]: [1418324076.984692] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- 'AT^GETPORTMOD' ModemManager[1516]: [1418324077.413318] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB2] probe required: 'at, qcdm' ModemManager[1516]: [1418324077.413395] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm' ModemManager[1516]: [1418324077.413435] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB1] probe required: 'at, qcdm' ModemManager[1516]: [1418324077.413454] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm' ModemManager[1516]: [1418324077.413613] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port... ModemManager[1516]: [1418324077.414307] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set ModemManager[1516]: [1418324077.414340] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open) ModemManager[1516]: [1418324077.414433] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1516]: [1418324077.415341] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1516]: [1418324077.415397] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1516]: [1418324077.415462] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '^BOOT:42668426,0,0,0,66' ModemManager[1516]: [1418324077.514788] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close) ModemManager[1516]: [1418324077.514836] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port... ModemManager[1516]: [1418324077.516712] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed ModemManager[1516]: [1418324077.516753] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB2) forced to close port ModemManager[1516]: [1418324077.516851] [mm-plugin-manager.c:508] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB2] deferring support check, suggested: Huawei ModemManager[1516]: [1418324077.516942] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close) ModemManager[1516]: [1418324077.516956] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port... ModemManager[1516]: [1418324077.518708] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed ModemManager[1516]: [1418324077.518743] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB1) forced to close port ModemManager[1516]: [1418324077.518800] [mm-plugin-manager.c:508] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB1] deferring support check, suggested: Huawei ModemManager[1516]: [1418324079.263539] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- 'E' ModemManager[1516]: [1418324079.264077] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '' ModemManager[1516]: [1418324079.265133] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '^GETPORTMODE:TYPE:WCDMA:Qualcomm ,MDM:0,NDIS:1 ,DIAG:2,PCUI:3,CDROM:4,SD:5OK' ModemManager[1516]: [1418324079.265240] [huawei/mm-plugin-huawei.c:154] getportmode_ready(): (Huawei) port mode layout retrieved ModemManager[1516]: [1418324079.265313] [mm-port-probe.c:152] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is AT-capable ModemManager[1516]: [1418324079.265369] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB0) device open count is 0 (close) ModemManager[1516]: [1418324079.265387] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB0) closing serial port... ModemManager[1516]: [1418324079.269922] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB0) serial port closed ModemManager[1516]: [1418324079.269964] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB0) forced to close port ModemManager[1516]: [1418324079.270044] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB0] found best plugin for port ModemManager[1516]: [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]: [1418324080.414360] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB2] probe required: 'at, qcdm' ModemManager[1516]: [1418324080.414440] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm' ModemManager[1516]: [1418324080.414488] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [ttyUSB1] probe required: 'at, qcdm' ModemManager[1516]: [1418324080.414503] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm' ModemManager[1516]: [1418324080.414642] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port... ModemManager[1516]: [1418324080.415227] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set ModemManager[1516]: [1418324080.415267] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open) ModemManager[1516]: [1418324080.415366] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1516]: [1418324080.416127] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1516]: [1418324080.416189] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1516]: [1418324080.516489] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT' ModemManager[1516]: [1418324080.516558] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT' ModemManager[1516]: [1418324080.716626] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'AT' ModemManager[1516]: [1418324080.717609] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '' ModemManager[1516]: [1418324080.718776] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324080.718868] [mm-port-probe.c:152] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable ModemManager[1516]: [1418324080.718928] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close) ModemManager[1516]: [1418324080.718950] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port... ModemManager[1516]: [1418324080.721382] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed ModemManager[1516]: [1418324080.721426] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB2) forced to close port ModemManager[1516]: [1418324080.721512] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB2] found best plugin for port ModemManager[1516]: [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]: [1418324084.415704] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out' ModemManager[1516]: [1418324084.415805] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT' ModemManager[1516]: [1418324087.415692] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out' ModemManager[1516]: [1418324087.415784] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT' ModemManager[1516]: [1418324090.416124] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out' ModemManager[1516]: [1418324090.416191] [mm-port-probe.c:162] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is not AT-capable ModemManager[1516]: [1418324090.416246] [mm-port-probe.c:658] serial_probe_qcdm(): (tty/ttyUSB1) probing QCDM... ModemManager[1516]: [1418324090.416270] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close) ModemManager[1516]: [1418324090.416288] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port... ModemManager[1516]: [1418324090.418054] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed ModemManager[1516]: [1418324090.418094] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB1) forced to close port ModemManager[1516]: [1418324090.418202] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1516]: [1418324090.418757] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1516]: [1418324090.418798] [mm-port-serial-qcdm.c:201] debug_log(): (ttyUSB1): --> 7e 00 78 f0 7e ModemManager[1516]: [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]: [1418324090.420290] [mm-port-probe.c:240] mm_port_probe_set_result_qcdm(): (tty/ttyUSB1) port is QCDM-capable ModemManager[1516]: [1418324090.420341] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close) ModemManager[1516]: [1418324090.420359] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port... ModemManager[1516]: [1418324090.423029] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed ModemManager[1516]: [1418324090.423063] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB1) forced to close port ModemManager[1516]: [1418324090.423115] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [ttyUSB1] found best plugin for port ModemManager[1516]: [1418324090.423153] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyUSB1' port probe finished, last one in device ModemManager[1516]: [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]: [1418324090.423196] [mm-device.c:509] mm_device_create_modem(): Creating modem with plugin 'Huawei' and '5' ports ModemManager[1516]: [1418324090.423225] [huawei/mm-plugin-huawei.c:491] create_modem(): QMI-powered Huawei modem found... ModemManager[1516]: [1418324090.423512] [huawei/mm-plugin-huawei.c:554] grab_port(): (tty/ttyUSB0) Port will have AT flags 'ppp' ModemManager[1516]: [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]: [1418324090.423597] [huawei/mm-plugin-huawei.c:554] grab_port(): (net/wwp0s29u1u2i1) Port will have AT flags 'none' ModemManager[1516]: [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]: [1418324090.423621] [huawei/mm-plugin-huawei.c:554] grab_port(): (usbmisc/cdc-wdm0) Port will have AT flags 'ppp' ModemManager[1516]: [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]: [1418324090.423649] [huawei/mm-plugin-huawei.c:554] grab_port(): (tty/ttyUSB1) Port will have AT flags 'primary' ModemManager[1516]: [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]: [1418324090.423802] [huawei/mm-plugin-huawei.c:554] grab_port(): (tty/ttyUSB2) Port will have AT flags 'none' ModemManager[1516]: [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]: [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]: [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]: [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]: [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]: [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]: [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]: [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]: [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]: [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]: [1418324091.746200] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port... ModemManager[1516]: [1418324091.747089] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set ModemManager[1516]: [1418324091.747141] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open) ModemManager[1516]: [1418324091.747155] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence... ModemManager[1516]: [1418324091.747409] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1516]: [1418324091.747628] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open) ModemManager[1516]: [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]: [1418324091.748931] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0' ModemManager[1516]: [1418324091.753264] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'A' ModemManager[1516]: [1418324091.753339] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'T' ModemManager[1516]: [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]: [1418324092.367347] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '0OK' ModemManager[1516]: [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]: [1418324092.367733] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1' ModemManager[1516]: [1418324092.417128] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324092.417256] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1' 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]: [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]: [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]: [1418324092.437585] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324092.437718] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4' 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]: [1418324092.441298] [mm-iface-modem.c:4327] interface_initialization_step(): Modem allows up to 2 bearers ModemManager[1516]: [1418324092.441314] [mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 2, max active: 2) ModemManager[1516]: [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]: [1418324092.445051] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324092.445137] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1' 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]: [1418324092.449236] [mm-broadband-modem-qmi.c:942] modem_load_manufacturer_finish(): loaded manufacturer: HUAWEI COMMUNICATION ModemManager[1516]: [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]: [1418324092.923127] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324092.923257] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0' 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]: [1418324092.925518] [mm-broadband-modem-qmi.c:1018] modem_load_model_finish(): loaded model: 33 ModemManager[1516]: [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]: [1418324092.929983] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324092.930071] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close) ModemManager[1516]: [1418324092.930136] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1' 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]: [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]: [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]: [1418324092.940436] [mm-broadband-modem-qmi.c:1186] modem_load_equipment_identifier_finish(): loaded equipment identifier: xxxxxxxxxxxxxxx ModemManager[1516]: [1418324092.940470] [mm-broadband-modem-qmi.c:1324] modem_load_device_identifier(): loading device identifier... ModemManager[1516]: [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]: [1418324092.940534] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '28bc528013753cafc554b705ffcbf4ac0d3e32f7' ModemManager[1516]: [1418324092.940563] [mm-broadband-modem-qmi.c:1312] modem_load_device_identifier_finish(): loaded device identifier: 28bc528013753cafc554b705ffcbf4ac0d3e32f7 ModemManager[1516]: [1418324093.178426] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324093.178616] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 1 (close) ModemManager[1516]: [1418324093.178865] [mm-broadband-modem-qmi.c:1727] modem_load_supported_bands(): loading band capabilities... ModemManager[1516]: [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]: [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]: [1418324093.184517] [mm-iface-modem.c:4014] load_power_state_ready(): couldn't load Power State: 'Unhandled power state: 'unknown' (255)' ModemManager[1516]: [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]: [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]: [1418324093.371440] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem: state changed (unknown -> locked) ModemManager[1516]: [1418324093.371762] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier... ModemManager[1516]: [1418324093.371829] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier... ModemManager[1516]: [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]: [1418324093.371902] [mm-sim-qmi.c:224] load_imsi(): loading IMSI... ModemManager[1516]: [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]: [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]: [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]: [1418324093.471131] [mm-broadband-modem-qmi.c:2853] get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand' ModemManager[1516]: [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]: [1418324093.471192] [mm-broadband-modem-qmi.c:1875] modem_load_current_bands(): loading current bands... ModemManager[1516]: [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]: [1418324093.472111] [mm-broadband-modem-qmi.c:9090] firmware_check_support(): loading firmware images... ModemManager[1516]: [1418324093.472174] [mm-broadband-modem.c:9005] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported' ModemManager[1516]: [1418324093.472260] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close) ModemManager[1516]: [1418324093.472274] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port... ModemManager[1516]: [1418324093.474200] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed ModemManager[1516]: [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]: [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]: [1418324093.474814] [mm-device.c:446] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Huawei' modem, VID 0x12D1 PID 0x14AC (usb) ModemManager[1516]: [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]: [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]: [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]: [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]: [1418324107.719594] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (locked -> initializing) ModemManager[1516]: [1418324107.720042] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port... ModemManager[1516]: [1418324107.721121] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set ModemManager[1516]: [1418324107.721221] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open) ModemManager[1516]: [1418324107.721255] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence... ModemManager[1516]: [1418324107.721321] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1516]: [1418324107.721368] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open) ModemManager[1516]: [1418324107.721476] [mm-broadband-modem-qmi.c:1727] modem_load_supported_bands(): loading band capabilities... ModemManager[1516]: [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]: [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]: [1418324107.721812] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0' 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]: [1418324107.727564] [mm-iface-modem.c:4014] load_power_state_ready(): couldn't load Power State: 'Unhandled power state: 'unknown' (255)' ModemManager[1516]: [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]: [1418324107.731382] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324107.731512] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1' ModemManager[1516]: [1418324107.737113] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324107.737211] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1' ModemManager[1516]: [1418324107.749006] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324107.749107] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4' ModemManager[1516]: [1418324107.755262] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324107.755374] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1' ModemManager[1516]: [1418324107.762793] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324107.762899] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0' ModemManager[1516]: [1418324107.768886] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324107.768986] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close) ModemManager[1516]: [1418324107.769045] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1' ModemManager[1516]: [1418324107.780144] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [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]: [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]: [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]: [1418324108.913199] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier... ModemManager[1516]: [1418324108.913248] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier... ModemManager[1516]: [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]: [1418324108.913310] [mm-sim-qmi.c:224] load_imsi(): loading IMSI... ModemManager[1516]: [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]: [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]: [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]: [1418324108.925310] [mm-broadband-modem-qmi.c:2853] get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand' ModemManager[1516]: [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]: [1418324108.925354] [mm-broadband-modem-qmi.c:1875] modem_load_current_bands(): loading current bands... ModemManager[1516]: [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]: [1418324113.385039] [mm-broadband-modem-qmi.c:3281] modem_3gpp_load_imei_finish(): loaded IMEI: xxxxxxxxxxxxxxx ModemManager[1516]: [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]: [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]: [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]: [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]: [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]: [1418324113.386382] [mm-broadband-modem.c:8998] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported' ModemManager[1516]: [1418324113.386886] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1516]: [1418324113.386960] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CNMI=?' ModemManager[1516]: [1418324113.398902] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '+CNMI: (0,1,2),(0,1,2,3),(0,2),(0,1,2),(0,1)OK' ModemManager[1516]: [1418324113.399071] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open) ModemManager[1516]: [1418324113.399111] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close) ModemManager[1516]: [1418324113.399138] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS=?' ModemManager[1516]: [1418324113.410625] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '+CPMS: ("ME","MT","SM","SR"),("ME","MT","SM","SR"),("ME","MT","SM","SR")OK' ModemManager[1516]: [1418324113.410856] [mm-iface-modem-messaging.c:1148] load_supported_storages_ready(): Supported storages loaded: ModemManager[1516]: [1418324113.410896] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): mem1 (list/read/delete) storages: 'me, mt, sm, sr' ModemManager[1516]: [1418324113.410925] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready(): mem2 (write/send) storages: 'me, mt, sm, sr' ModemManager[1516]: [1418324113.410950] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready(): mem3 (reception) storages: 'me, mt, sm, sr' ModemManager[1516]: [1418324113.411210] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 1 (close) ModemManager[1516]: [1418324113.411388] [mm-broadband-modem.c:9002] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported' ModemManager[1516]: [1418324113.411524] [mm-broadband-modem-qmi.c:9424] signal_check_support(): Extended signal capabilities supported ModemManager[1516]: [1418324113.411880] [mm-broadband-modem-qmi.c:8138] oma_check_support(): OMA capabilities not supported ModemManager[1516]: [1418324113.411952] [mm-broadband-modem.c:9004] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported' ModemManager[1516]: [1418324113.412039] [mm-broadband-modem.c:9005] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported' ModemManager[1516]: [1418324113.412107] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (initializing -> disabled) ModemManager[1516]: [1418324113.412351] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 0 (close) ModemManager[1516]: [1418324113.412392] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB2) closing serial port... ModemManager[1516]: [1418324113.415202] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB2) serial port closed ModemManager[1516]: [1418324113.417557] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling) ModemManager[1516]: [1418324113.417884] [mm-broadband-modem.c:8000] enabling_started(): Skipping modem initialization: device hotplugged ModemManager[1516]: [1418324113.417907] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port... ModemManager[1516]: [1418324113.419078] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set ModemManager[1516]: [1418324113.419129] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open) ModemManager[1516]: [1418324113.419145] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence... ModemManager[1516]: [1418324113.419182] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1516]: [1418324113.419570] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1516]: [1418324113.419592] [mm-broadband-modem.c:8016] enabling_started(): Flashing primary AT port before enabling... ModemManager[1516]: [1418324113.419629] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0' ModemManager[1516]: [1418324113.425433] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324113.425554] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1' ModemManager[1516]: [1418324113.431595] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324113.431653] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1' ModemManager[1516]: [1418324113.443266] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324113.443361] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4' ModemManager[1516]: [1418324113.449396] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324113.450006] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1' ModemManager[1516]: [1418324113.456973] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [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]: [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]: [1418324113.531323] [mm-iface-modem.c:3191] modem_power_up_ready(): Modem set in full-power mode... ModemManager[1516]: [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]: [1418324113.537380] [mm-broadband-modem-qmi.c:4769] modem_3gpp_enable_unsolicited_registration_events(): Assuming serving system indications are always enabled ModemManager[1516]: [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]: [1418324113.551314] [mm-broadband-modem-qmi.c:3907] common_process_serving_system_3gpp(): Processing 3GPP info... ModemManager[1516]: [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]: [1418324113.551456] [mm-iface-modem.c:1561] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp' ModemManager[1516]: [1418324113.551588] [mm-broadband-modem.c:8633] enabling_step(): Modem has location capabilities, enabling the Location interface... ModemManager[1516]: [1418324113.551613] [mm-iface-modem-location.c:759] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci' ModemManager[1516]: [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]: [1418324113.551806] [mm-broadband-modem.c:8646] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface... ModemManager[1516]: [1418324113.551867] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1516]: [1418324113.551893] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMGF=?' 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]: [1418324113.557579] [mm-broadband-modem-qmi.c:3907] common_process_serving_system_3gpp(): Processing 3GPP info... ModemManager[1516]: [1418324113.563442] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '+CMGF: (0-1)OK' ModemManager[1516]: [1418324113.563585] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open) ModemManager[1516]: [1418324113.563619] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close) ModemManager[1516]: [1418324113.563649] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMGF=0' ModemManager[1516]: [1418324113.574830] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324113.574910] [mm-broadband-modem.c:5485] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU' ModemManager[1516]: [1418324113.575017] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open) ModemManager[1516]: [1418324113.575050] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close) ModemManager[1516]: [1418324113.575182] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS="","MT","MT"' ModemManager[1516]: [1418324113.644668] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '+CMS ERROR: 500' ModemManager[1516]: [1418324113.644846] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown ModemManager[1516]: [1418324113.644905] [mm-iface-modem-messaging.c:792] set_default_storage_ready(): Couldn't set default storage: 'Unknown' ModemManager[1516]: [1418324113.644927] [mm-broadband-modem.c:6184] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me' ModemManager[1516]: [1418324113.644945] [mm-broadband-modem.c:5379] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)... ModemManager[1516]: [1418324113.644978] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open) ModemManager[1516]: [1418324113.645011] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close) ModemManager[1516]: [1418324113.645038] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS="ME"' ModemManager[1516]: [1418324113.659842] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '+CMS ERROR: 500' ModemManager[1516]: [1418324113.659920] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown ModemManager[1516]: [1418324113.659951] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'me': 'Unknown' ModemManager[1516]: [1418324113.659970] [mm-broadband-modem.c:6184] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm' ModemManager[1516]: [1418324113.659983] [mm-broadband-modem.c:5379] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)... ModemManager[1516]: [1418324113.660010] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open) ModemManager[1516]: [1418324113.660045] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close) ModemManager[1516]: [1418324113.660071] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS="SM"' ModemManager[1516]: [1418324113.675142] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '+CMS ERROR: 500' ModemManager[1516]: [1418324113.675252] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown ModemManager[1516]: [1418324113.675295] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'sm': 'Unknown' ModemManager[1516]: [1418324113.675322] [mm-broadband-modem.c:6184] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sr' ModemManager[1516]: [1418324113.675338] [mm-broadband-modem.c:5379] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SR), mem2 (none)... ModemManager[1516]: [1418324113.675369] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open) ModemManager[1516]: [1418324113.675391] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 2 (close) ModemManager[1516]: [1418324113.675410] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CPMS="SR"' ModemManager[1516]: [1418324113.690128] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '+CMS ERROR: 500' ModemManager[1516]: [1418324113.690246] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown ModemManager[1516]: [1418324113.690295] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'sr': 'Unknown' ModemManager[1516]: [1418324113.690351] [mm-broadband-modem.c:5791] set_messaging_unsolicited_events_handlers(): (ttyUSB2) Setting messaging unsolicited events handlers ModemManager[1516]: [1418324113.690382] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 1 (close) ModemManager[1516]: [1418324113.690425] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1516]: [1418324113.690466] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,1,0' ModemManager[1516]: [1418324113.710681] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '+CMS ERROR: 303' ModemManager[1516]: [1418324113.710811] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported ModemManager[1516]: [1418324113.710871] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,2,0' ModemManager[1516]: [1418324113.735597] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1516]: [1418324113.735762] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB2) device open count is 1 (close) ModemManager[1516]: [1418324113.735790] [mm-broadband-modem.c:8672] enabling_step(): Modem has extended signal reporting capabilities, enabling the Signal interface... ModemManager[1516]: [1418324113.735831] [mm-iface-modem-signal.c:206] setup_refresh_context(): Extended signal information reporting disabled (rate: 0 seconds) ModemManager[1516]: [1418324113.735923] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)