Attachment 127675 Details for Bug 91836 – Output of 'ModemManager --debug' version 1.6.2 with reverted commit 905ffa67 and successful connection

Output of 'ModemManager --debug' version 1.6.2 with reverted commit 905ffa67 and successful connection

ModemManager-1.6.2-patched.log (text/plain), 138.43 KB, created by Balló György on 2016-11-01 20:54:02 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Balló György

Created: 2016-11-01 20:54:02 UTC

Size: 138.43 KB

patch

obsolete

>ModemManager[1398]: <info>  [1478032513.339760] [main.c:158] main(): ModemManager (version 1.6.2) starting in system bus...
>ModemManager[1398]: <debug> [1478032513.343009] [mm-sleep-monitor.c:245] mm_sleep_monitor_get(): create MMSleepMonitor singleton (0x8378cc0)
>ModemManager[1398]: <debug> [1478032513.351201] [main.c:83] bus_acquired_cb(): Bus acquired, creating manager...
>ModemManager[1398]: <debug> [1478032513.362000] [mm-plugin-manager.c:1559] load_plugins(): [plugin manager] looking for plugins in '/usr/lib/ModemManager'
>ModemManager[1398]: <debug> [1478032513.363869] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Longcheer'
>ModemManager[1398]: <debug> [1478032513.364816] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Novatel LTE'
>ModemManager[1398]: <debug> [1478032513.365646] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Linktop'
>ModemManager[1398]: <debug> [1478032513.366583] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Altair LTE'
>ModemManager[1398]: <debug> [1478032513.367626] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Telit'
>ModemManager[1398]: <debug> [1478032513.368486] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'MTK'
>ModemManager[1398]: <debug> [1478032513.369600] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Cinterion'
>ModemManager[1398]: <debug> [1478032513.370639] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Option'
>ModemManager[1398]: <debug> [1478032513.371574] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Generic'
>ModemManager[1398]: <debug> [1478032513.372666] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'ZTE'
>ModemManager[1398]: <debug> [1478032513.373490] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'X22X'
>ModemManager[1398]: <debug> [1478032513.374401] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Via CBP7'
>ModemManager[1398]: <debug> [1478032513.375368] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'AnyDATA'
>ModemManager[1398]: <debug> [1478032513.378345] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Ericsson MBM'
>ModemManager[1398]: <debug> [1478032513.379252] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Nokia'
>ModemManager[1398]: <debug> [1478032513.380131] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Pantech'
>ModemManager[1398]: <debug> [1478032513.381118] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Samsung'
>ModemManager[1398]: <debug> [1478032513.382356] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Huawei'
>ModemManager[1398]: <debug> [1478032513.383193] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Haier'
>ModemManager[1398]: <debug> [1478032513.384035] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Motorola'
>ModemManager[1398]: <debug> [1478032513.384993] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Iridium'
>ModemManager[1398]: <debug> [1478032513.385891] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Wavecom'
>ModemManager[1398]: <debug> [1478032513.386917] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Novatel'
>ModemManager[1398]: <debug> [1478032513.388208] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Dell'
>ModemManager[1398]: <debug> [1478032513.389090] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'SimTech'
>ModemManager[1398]: <debug> [1478032513.390221] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Nokia (Icera)'
>ModemManager[1398]: <debug> [1478032513.391482] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Option High-Speed'
>ModemManager[1398]: <debug> [1478032513.392605] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Thuraya'
>ModemManager[1398]: <debug> [1478032513.393780] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Sierra (legacy)'
>ModemManager[1398]: <debug> [1478032513.394794] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Sierra'
>ModemManager[1398]: <debug> [1478032513.394966] [mm-plugin-manager.c:1609] load_plugins(): [plugin manager] successfully loaded 30 plugins
>ModemManager[1398]: <debug> [1478032513.409577] [main.c:105] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
>ModemManager[1398]: <debug> [1478032513.409814] [mm-base-manager.c:498] mm_base_manager_start(): Starting automatic device scan...
>ModemManager[1398]: <debug> [1478032513.451942] [mm-base-manager.c:537] mm_base_manager_start(): Finished device scan...
>ModemManager[1398]: <debug> [1478032513.454336] [mm-sleep-monitor.c:108] take_inhibitor(): [sleep-monitor] taking systemd sleep inhibitor
>ModemManager[1398]: <debug> [1478032513.457254] [mm-base-manager.c:388] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
>ModemManager[1398]: <debug> [1478032513.462472] [mm-base-manager.c:388] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
>ModemManager[1398]: <debug> [1478032513.464813] [mm-base-manager.c:388] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
>ModemManager[1398]: <debug> [1478032513.467013] [mm-base-manager.c:388] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
>ModemManager[1398]: <debug> [1478032513.476152] [mm-plugin-manager.c:1442] mm_plugin_manager_device_support_check(): [plugin manager] task 0: new support task for device: /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0
>ModemManager[1398]: <debug> [1478032513.479533] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: enp2s0
>ModemManager[1398]: <debug> [1478032513.479706] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 0,enp2s0: new support task for port
>ModemManager[1398]: <debug> [1478032513.479784] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 0,enp2s0: deferred until min wait time elapsed
>ModemManager[1398]: <debug> [1478032513.481267] [mm-plugin-manager.c:1442] mm_plugin_manager_device_support_check(): [plugin manager] task 1: new support task for device: /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0
>ModemManager[1398]: <debug> [1478032513.481782] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 1: port grabbed: wlan0
>ModemManager[1398]: <debug> [1478032513.481931] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 1,wlan0: new support task for port
>ModemManager[1398]: <debug> [1478032513.482007] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 1,wlan0: deferred until min wait time elapsed
>ModemManager[1398]: <debug> [1478032513.485253] [mm-sleep-monitor.c:97] inhibit_done(): [sleep-monitor] inhibitor fd is 10
>ModemManager[1398]: <debug> [1478032514.981138] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 0: min wait time elapsed
>ModemManager[1398]: <debug> [1478032514.981489] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.981651] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.981770] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.981889] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.982010] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.982127] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.982293] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.982413] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.982535] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.982653] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.982768] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.982886] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.983013] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [enp2s0] filtered by udev tags
>ModemManager[1398]: <debug> [1478032514.983129] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.983249] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.983364] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.983484] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.983601] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.983718] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.983830] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.983948] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.984068] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.984187] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.984305] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.984423] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [enp2s0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.984535] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [enp2s0] filtered by drivers
>ModemManager[1398]: <debug> [1478032514.984650] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [enp2s0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.984841] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [enp2s0] filtered by drivers
>ModemManager[1398]: <debug> [1478032514.984965] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [enp2s0] filtered by drivers
>ModemManager[1398]: <debug> [1478032514.985105] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 0,enp2s0: found '1' plugins to try
>ModemManager[1398]: <debug> [1478032514.985226] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,enp2s0: will try with plugin 'Generic'
>ModemManager[1398]: <debug> [1478032514.985372] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 0,enp2s0: started
>ModemManager[1398]: <debug> [1478032514.985490] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,enp2s0: checking with plugin 'Generic'
>ModemManager[1398]: <debug> [1478032514.985664] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [enp2s0] probing deferred until result suggested
>ModemManager[1398]: <debug> [1478032514.985929] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 1: min wait time elapsed
>ModemManager[1398]: <debug> [1478032514.986067] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.986182] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.986296] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.986413] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.986531] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.986651] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.986775] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.986892] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.987003] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.987119] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.987238] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.987356] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.987488] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [wlan0] filtered by udev tags
>ModemManager[1398]: <debug> [1478032514.987602] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.987714] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.987833] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.987950] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.988175] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.988310] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.988428] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.988540] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.988659] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.988777] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.988895] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.989012] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [wlan0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032514.989126] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [wlan0] filtered by drivers
>ModemManager[1398]: <debug> [1478032514.989240] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [wlan0] filtered by subsystem
>ModemManager[1398]: <debug> [1478032514.989359] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [wlan0] filtered by drivers
>ModemManager[1398]: <debug> [1478032514.989531] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [wlan0] filtered by drivers
>ModemManager[1398]: <debug> [1478032514.989660] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 1,wlan0: found '1' plugins to try
>ModemManager[1398]: <debug> [1478032514.989776] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 1,wlan0: will try with plugin 'Generic'
>ModemManager[1398]: <debug> [1478032514.989917] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 1,wlan0: started
>ModemManager[1398]: <debug> [1478032514.990026] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 1,wlan0: checking with plugin 'Generic'
>ModemManager[1398]: <debug> [1478032514.990186] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [wlan0] probing deferred until result suggested
>ModemManager[1398]: <debug> [1478032514.990368] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 0,enp2s0: deferring support check until result suggested
>ModemManager[1398]: <debug> [1478032514.990560] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 1,wlan0: deferring support check until result suggested
>ModemManager[1398]: <debug> [1478032515.980870] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 0: min probing time elapsed
>ModemManager[1398]: <debug> [1478032515.981145] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 0: still 1 running probes (0 active): enp2s0
>ModemManager[1398]: <debug> [1478032515.981293] [mm-plugin-manager.c:1009] device_context_continue(): [plugin manager] task 0: no active tasks to probe
>ModemManager[1398]: <debug> [1478032515.981529] [mm-plugin-manager.c:337] port_context_set_suggestion(): [plugin manager] task 0,enp2s0: deferred task completed, no suggested plugin
>ModemManager[1398]: <debug> [1478032515.981673] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 0,enp2s0: finished in '2.501973' seconds
>ModemManager[1398]: <debug> [1478032515.981992] [mm-plugin-manager.c:892] device_context_set_best_plugin(): [plugin manager] task 0,enp2s0: not supported by any plugin
>ModemManager[1398]: <debug> [1478032515.982152] [mm-device.c:373] mm_device_ignore_port(): Fully ignoring port 'net/enp2s0' from now on
>ModemManager[1398]: <debug> [1478032515.982301] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 0: no more ports to probe
>ModemManager[1398]: <debug> [1478032515.982421] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 0: finished in '2.506294' seconds
>ModemManager[1398]: <info>  [1478032515.982602] [mm-base-manager.c:155] device_support_check_ready(): Couldn't check support for device at '/sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0': not supported by any plugin
>ModemManager[1398]: <debug> [1478032515.982841] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 1: min probing time elapsed
>ModemManager[1398]: <debug> [1478032515.982978] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 1: still 1 running probes (0 active): wlan0
>ModemManager[1398]: <debug> [1478032515.983097] [mm-plugin-manager.c:1009] device_context_continue(): [plugin manager] task 1: no active tasks to probe
>ModemManager[1398]: <debug> [1478032515.983214] [mm-plugin-manager.c:337] port_context_set_suggestion(): [plugin manager] task 1,wlan0: deferred task completed, no suggested plugin
>ModemManager[1398]: <debug> [1478032515.983333] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 1,wlan0: finished in '2.501408' seconds
>ModemManager[1398]: <debug> [1478032515.983480] [mm-plugin-manager.c:892] device_context_set_best_plugin(): [plugin manager] task 1,wlan0: not supported by any plugin
>ModemManager[1398]: <debug> [1478032515.983616] [mm-device.c:373] mm_device_ignore_port(): Fully ignoring port 'net/wlan0' from now on
>ModemManager[1398]: <debug> [1478032515.983736] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 1: no more ports to probe
>ModemManager[1398]: <debug> [1478032515.983910] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 1: finished in '2.502651' seconds
>ModemManager[1398]: <info>  [1478032515.984079] [mm-base-manager.c:155] device_support_check_ready(): Couldn't check support for device at '/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0': not supported by any plugin
>ModemManager[1398]: <debug> [1478032534.968464] [mm-plugin-manager.c:1442] mm_plugin_manager_device_support_check(): [plugin manager] task 2: new support task for device: /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3
>ModemManager[1398]: <debug> [1478032534.969662] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 2: port grabbed: ttyUSB0
>ModemManager[1398]: <debug> [1478032534.969845] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 2,ttyUSB0: new support task for port
>ModemManager[1398]: <debug> [1478032534.969933] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 2,ttyUSB0: deferred until min wait time elapsed
>ModemManager[1398]: <debug> [1478032535.010488] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 2: port grabbed: ttyUSB1
>ModemManager[1398]: <debug> [1478032535.010716] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 2,ttyUSB1: new support task for port
>ModemManager[1398]: <debug> [1478032535.010804] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 2,ttyUSB1: deferred until min wait time elapsed
>ModemManager[1398]: <debug> [1478032535.013251] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 2: port grabbed: ttyUSB2
>ModemManager[1398]: <debug> [1478032535.013426] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 2,ttyUSB2: new support task for port
>ModemManager[1398]: <debug> [1478032535.013509] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 2,ttyUSB2: deferred until min wait time elapsed
>ModemManager[1398]: <debug> [1478032536.471174] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 2: min wait time elapsed
>ModemManager[1398]: <debug> [1478032536.471448] [mm-plugin.c:376] apply_pre_probing_filters(): (Longcheer) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.471582] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.471674] [mm-plugin.c:376] apply_pre_probing_filters(): (Linktop) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.471763] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.471852] [mm-plugin.c:376] apply_pre_probing_filters(): (Telit) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.471952] [mm-plugin.c:430] apply_pre_probing_filters(): (MTK) [ttyUSB2] filtered by udev tags
>ModemManager[1398]: <debug> [1478032536.472052] [mm-plugin.c:376] apply_pre_probing_filters(): (Option) [ttyUSB2] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.472152] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 2,ttyUSB2: found '2' plugins to try
>ModemManager[1398]: <debug> [1478032536.472237] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB2: will try with plugin 'ZTE'
>ModemManager[1398]: <debug> [1478032536.472318] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB2: will try with plugin 'Generic'
>ModemManager[1398]: <debug> [1478032536.472422] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 2,ttyUSB2: started
>ModemManager[1398]: <debug> [1478032536.472507] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 2,ttyUSB2: checking with plugin 'ZTE'
>ModemManager[1398]: <debug> [1478032536.472743] [mm-plugin.c:804] mm_plugin_supports_port(): (ZTE) [ttyUSB2] probe required: 'at, at-icera, qcdm'
>ModemManager[1398]: <debug> [1478032536.472881] [mm-port-probe.c:1377] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, at-icera, qcdm'
>ModemManager[1398]: <debug> [1478032536.473019] [mm-plugin.c:376] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.473119] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.473206] [mm-plugin.c:376] apply_pre_probing_filters(): (Linktop) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.473292] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.473376] [mm-plugin.c:376] apply_pre_probing_filters(): (Telit) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.473473] [mm-plugin.c:430] apply_pre_probing_filters(): (MTK) [ttyUSB1] filtered by udev tags
>ModemManager[1398]: <debug> [1478032536.473569] [mm-plugin.c:376] apply_pre_probing_filters(): (Option) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.473660] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 2,ttyUSB1: found '2' plugins to try
>ModemManager[1398]: <debug> [1478032536.473739] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB1: will try with plugin 'ZTE'
>ModemManager[1398]: <debug> [1478032536.473817] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB1: will try with plugin 'Generic'
>ModemManager[1398]: <debug> [1478032536.473917] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 2,ttyUSB1: started
>ModemManager[1398]: <debug> [1478032536.474000] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 2,ttyUSB1: checking with plugin 'ZTE'
>ModemManager[1398]: <debug> [1478032536.474149] [mm-plugin.c:804] mm_plugin_supports_port(): (ZTE) [ttyUSB1] probe required: 'at, at-icera, qcdm'
>ModemManager[1398]: <debug> [1478032536.474275] [mm-port-probe.c:1377] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, at-icera, qcdm'
>ModemManager[1398]: <debug> [1478032536.474400] [mm-plugin.c:376] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.474493] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.474582] [mm-plugin.c:376] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.474671] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.480913] [mm-plugin.c:376] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.481031] [mm-plugin.c:430] apply_pre_probing_filters(): (MTK) [ttyUSB0] filtered by udev tags
>ModemManager[1398]: <debug> [1478032536.481136] [mm-plugin.c:376] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1398]: <debug> [1478032536.481235] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 2,ttyUSB0: found '2' plugins to try
>ModemManager[1398]: <debug> [1478032536.481320] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB0: will try with plugin 'ZTE'
>ModemManager[1398]: <debug> [1478032536.481467] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB0: will try with plugin 'Generic'
>ModemManager[1398]: <debug> [1478032536.481580] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 2,ttyUSB0: started
>ModemManager[1398]: <debug> [1478032536.481660] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 2,ttyUSB0: checking with plugin 'ZTE'
>ModemManager[1398]: <debug> [1478032536.481871] [mm-plugin.c:804] mm_plugin_supports_port(): (ZTE) [ttyUSB0] probe required: 'at, at-icera, qcdm'
>ModemManager[1398]: <debug> [1478032536.482004] [mm-port-probe.c:1377] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, at-icera, qcdm'
>ModemManager[1398]: <debug> [1478032536.483101] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1398]: <warn>  [1478032536.488231] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1398]: <debug> [1478032536.488473] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032536.489084] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[1398]: <warn>  [1478032536.489675] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[1398]: <debug> [1478032536.489879] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032536.490411] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[1398]: <warn>  [1478032536.491072] [mm-port-serial.c:513] real_config_fd(): (ttyUSB0): port attributes not fully set
>ModemManager[1398]: <debug> [1478032536.491290] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032536.590020] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032536.590525] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATE0+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032537.469415] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 2: min probing time elapsed
>ModemManager[1398]: <debug> [1478032537.471075] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 2: still 3 running probes (3 active): ttyUSB2, ttyUSB1, ttyUSB0
>ModemManager[1398]: <debug> [1478032537.591115] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'ATE0+CPMS?'
>ModemManager[1398]: <debug> [1478032537.592068] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ATE0+CPMS?'
>ModemManager[1398]: <debug> [1478032537.592400] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032537.593047] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR>'
>ModemManager[1398]: <debug> [1478032537.593544] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032537.593993] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032537.594200] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Unknown error'
>ModemManager[1398]: <debug> [1478032537.594428] [mm-port-probe.c:155] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is AT-capable
>ModemManager[1398]: <debug> [1478032537.594696] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT%IPSYS?<CR>'
>ModemManager[1398]: <debug> [1478032537.600301] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032537.600597] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032537.600742] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Unknown error'
>ModemManager[1398]: <debug> [1478032537.600853] [mm-port-probe.c:155] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable
>ModemManager[1398]: <debug> [1478032537.601100] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT%IPSYS?<CR>'
>ModemManager[1398]: <debug> [1478032538.505441] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032538.505707] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032538.505942] [mm-port-probe.c:947] serial_probe_at_parse_response(): (tty/ttyUSB1) re-scheduling next command in probing group in 2 seconds...
>ModemManager[1398]: <debug> [1478032538.515008] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032538.515248] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032538.515400] [mm-port-probe.c:947] serial_probe_at_parse_response(): (tty/ttyUSB2) re-scheduling next command in probing group in 2 seconds...
>ModemManager[1398]: <debug> [1478032540.664181] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT%IPSYS?<CR>'
>ModemManager[1398]: <debug> [1478032540.664543] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT%IPSYS?<CR>'
>ModemManager[1398]: <debug> [1478032541.569653] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032541.570046] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032541.570313] [mm-port-probe.c:947] serial_probe_at_parse_response(): (tty/ttyUSB1) re-scheduling next command in probing group in 2 seconds...
>ModemManager[1398]: <debug> [1478032541.576451] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032541.576843] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032541.577113] [mm-port-probe.c:947] serial_probe_at_parse_response(): (tty/ttyUSB2) re-scheduling next command in probing group in 2 seconds...
>ModemManager[1398]: <debug> [1478032541.662677] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1398]: <debug> [1478032541.663090] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032543.663261] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT%IPSYS?<CR>'
>ModemManager[1398]: <debug> [1478032543.663572] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT%IPSYS?<CR>'
>ModemManager[1398]: <debug> [1478032544.568485] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032544.568854] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032544.569119] [mm-port-probe.c:227] mm_port_probe_set_result_at_icera(): (tty/ttyUSB1) Modem is probably not Icera-based
>ModemManager[1398]: <debug> [1478032544.569340] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 2,ttyUSB1: found best plugin for port (ZTE)
>ModemManager[1398]: <debug> [1478032544.569506] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 2,ttyUSB1: finished in '9.558795' seconds
>ModemManager[1398]: <debug> [1478032544.569699] [mm-plugin-manager.c:913] device_context_set_best_plugin(): [plugin manager] task 2,ttyUSB1: found best plugin: ZTE
>ModemManager[1398]: <debug> [1478032544.569888] [mm-plugin-manager.c:366] port_context_set_suggestion(): [plugin manager] task 2,ttyUSB2: got suggested plugin (ZTE)
>ModemManager[1398]: <debug> [1478032544.570054] [mm-plugin-manager.c:366] port_context_set_suggestion(): [plugin manager] task 2,ttyUSB0: got suggested plugin (ZTE)
>ModemManager[1398]: <debug> [1478032544.570234] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 2: still 2 running probes (2 active): ttyUSB2, ttyUSB0
>ModemManager[1398]: <debug> [1478032544.570495] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032544.570651] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port...
>ModemManager[1398]: <debug> [1478032544.585049] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed
>ModemManager[1398]: <debug> [1478032544.585477] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB1) forced to close port
>ModemManager[1398]: <debug> [1478032544.585716] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032544.585904] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032544.586055] [mm-port-probe.c:227] mm_port_probe_set_result_at_icera(): (tty/ttyUSB2) Modem is probably not Icera-based
>ModemManager[1398]: <debug> [1478032544.586165] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 2,ttyUSB2: found best plugin for port (ZTE)
>ModemManager[1398]: <debug> [1478032544.586252] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 2,ttyUSB2: finished in '9.572834' seconds
>ModemManager[1398]: <debug> [1478032544.586347] [mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin manager] task 2,ttyUSB2: best plugin matches device reported one: ZTE
>ModemManager[1398]: <debug> [1478032544.586437] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 2: still 1 running probes (1 active): ttyUSB0
>ModemManager[1398]: <debug> [1478032544.586590] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032544.586678] [mm-port-serial.c:1361] _close_internal(): (ttyUSB2) closing serial port...
>ModemManager[1398]: <debug> [1478032544.602759] [mm-port-serial.c:1409] _close_internal(): (ttyUSB2) serial port closed
>ModemManager[1398]: <debug> [1478032544.603050] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB2) forced to close port
>ModemManager[1398]: <debug> [1478032545.663487] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1398]: <debug> [1478032545.663889] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032549.664794] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1398]: <debug> [1478032549.665093] [mm-port-probe.c:165] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
>ModemManager[1398]: <debug> [1478032549.666367] [mm-port-probe.c:691] serial_probe_qcdm(): (tty/ttyUSB0) probing QCDM...
>ModemManager[1398]: <debug> [1478032549.666545] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032549.666671] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port...
>ModemManager[1398]: <debug> [1478032549.680702] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed
>ModemManager[1398]: <debug> [1478032549.680924] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB0) forced to close port
>ModemManager[1398]: <debug> [1478032549.681230] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[1398]: <debug> [1478032549.681987] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032549.682323] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
>ModemManager[1398]: <debug> [1478032549.683588] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 00 4d 61 72 20 31 39 20 32 30 31 33 31 31 3a 32 37 3a 34 37 4d 61 72 20 31 39 20 32 30 31 33 31 31 3a 32 37 3a 34 37 54 4d 4f 4d 46 36 36 37 00 00 08 00 00 00 04 e0 6f 31 7e
>ModemManager[1398]: <debug> [1478032549.683912] [mm-port-probe.c:243] mm_port_probe_set_result_qcdm(): (tty/ttyUSB0) port is QCDM-capable
>ModemManager[1398]: <debug> [1478032549.684057] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 2,ttyUSB0: found best plugin for port (ZTE)
>ModemManager[1398]: <debug> [1478032549.684161] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 2,ttyUSB0: finished in '14.714325' seconds
>ModemManager[1398]: <debug> [1478032549.684339] [mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin manager] task 2,ttyUSB0: best plugin matches device reported one: ZTE
>ModemManager[1398]: <debug> [1478032549.684445] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 2: no more ports to probe
>ModemManager[1398]: <debug> [1478032549.684541] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 2: finished in '14.716089' seconds
>ModemManager[1398]: <info>  [1478032549.684809] [mm-device.c:525] mm_device_create_modem(): Creating modem with plugin 'ZTE' and '3' ports
>ModemManager[1398]: <debug> [1478032549.690907] [zte/mm-plugin-zte.c:134] grab_port(): ZTE: AT port 'tty/ttyUSB2' flagged as primary
>ModemManager[1398]: <debug> [1478032549.691661] [mm-base-modem.c:280] mm_base_modem_grab_port(): (ttyUSB2) type 'at' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3
>ModemManager[1398]: <debug> [1478032549.691884] [zte/mm-plugin-zte.c:139] grab_port(): ZTE: AT port 'tty/ttyUSB1' flagged as secondary
>ModemManager[1398]: <debug> [1478032549.692321] [mm-base-modem.c:280] mm_base_modem_grab_port(): (ttyUSB1) type 'at' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3
>ModemManager[1398]: <debug> [1478032549.692611] [mm-base-modem.c:280] mm_base_modem_grab_port(): (ttyUSB0) type 'qcdm' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3
>ModemManager[1398]: <debug> [1478032549.692764] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3) tty/ttyUSB2 at (primary)
>ModemManager[1398]: <debug> [1478032549.692855] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3) tty/ttyUSB1 at (secondary)
>ModemManager[1398]: <debug> [1478032549.692930] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3) tty/ttyUSB2 data (primary)
>ModemManager[1398]: <debug> [1478032549.693006] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3) tty/ttyUSB0 qcdm
>ModemManager[1398]: <debug> [1478032549.694288] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1398]: <warn>  [1478032549.695252] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1398]: <debug> [1478032549.695460] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032549.695547] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
>ModemManager[1398]: <debug> [1478032549.695761] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032549.695937] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <info>  [1478032549.696108] [mm-base-manager.c:175] device_support_check_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3' successfully created
>ModemManager[1398]: <debug> [1478032549.696282] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032549.696384] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port...
>ModemManager[1398]: <debug> [1478032549.710164] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed
>ModemManager[1398]: <debug> [1478032549.710378] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB0) forced to close port
>ModemManager[1398]: <debug> [1478032549.713752] [mm-broadband-modem.c:710] modem_load_current_capabilities(): loading current capabilities...
>ModemManager[1398]: <debug> [1478032549.713922] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[1398]: <debug> [1478032549.714560] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032549.714893] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1398]: <debug> [1478032549.715418] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 26 0a 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 b2 37 7e
>ModemManager[1398]: <debug> [1478032549.717888] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 26 0a 00 00 04 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 4f 52 7e
>ModemManager[1398]: <debug> [1478032549.718265] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
>ModemManager[1398]: <debug> [1478032549.728332] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.728757] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
>ModemManager[1398]: <debug> [1478032549.739753] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.740202] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
>ModemManager[1398]: <debug> [1478032549.759760] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.760318] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
>ModemManager[1398]: <debug> [1478032549.772964] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.773274] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032549.773487] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
>ModemManager[1398]: <debug> [1478032549.781167] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.781564] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032549.781862] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1398]: <debug> [1478032549.795292] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.795770] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
>ModemManager[1398]: <debug> [1478032549.796108] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
>ModemManager[1398]: <debug> [1478032549.813677] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.814061] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032549.814288] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+GCAP<CR>'
>ModemManager[1398]: <debug> [1478032549.829004] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.829346] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032549.829678] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATI<CR>'
>ModemManager[1398]: <debug> [1478032549.839151] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>Manufacturer: ZTE CORPORATION<CR><LF>Model: MF667<CR><LF>Revision: BD_TMODEMF667V1.0.0B01<CR><LF>IMEI: 359905017792331<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.839792] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032549.840122] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032549.840348] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+WS46=?<CR>'
>ModemManager[1398]: <debug> [1478032549.860126] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.860495] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032549.860732] [mm-broadband-modem.c:364] modem_load_current_capabilities_finish(): loaded current capabilities: gsm-umts
>ModemManager[1398]: <debug> [1478032549.861181] [mm-iface-modem.c:4345] interface_initialization_step(): Modem allows up to 1 bearers
>ModemManager[1398]: <debug> [1478032549.861332] [mm-bearer-list.c:270] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
>ModemManager[1398]: <debug> [1478032549.861867] [mm-broadband-modem.c:770] modem_load_manufacturer(): loading manufacturer...
>ModemManager[1398]: <debug> [1478032549.862056] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032549.862272] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032549.862414] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port...
>ModemManager[1398]: <debug> [1478032549.876424] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed
>ModemManager[1398]: <debug> [1478032549.876710] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032549.876933] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGMI<CR>'
>ModemManager[1398]: <debug> [1478032549.894056] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ZTE CORPORATION<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.894683] [mm-broadband-modem.c:754] modem_load_manufacturer_finish(): loaded manufacturer: ZTE CORPORATION
>ModemManager[1398]: <debug> [1478032549.895294] [mm-broadband-modem.c:810] modem_load_model(): loading model...
>ModemManager[1398]: <debug> [1478032549.895532] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032549.895819] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032549.896093] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGMM<CR>'
>ModemManager[1398]: <debug> [1478032549.913590] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>MF667<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.914017] [mm-broadband-modem.c:794] modem_load_model_finish(): loaded model: MF667
>ModemManager[1398]: <debug> [1478032549.914280] [mm-broadband-modem.c:850] modem_load_revision(): loading revision...
>ModemManager[1398]: <debug> [1478032549.914463] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032549.914679] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032549.915114] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGMR<CR>'
>ModemManager[1398]: <debug> [1478032549.932351] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>BD_TMODEMF667V1.0.0B01<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.932793] [mm-broadband-modem.c:834] modem_load_revision_finish(): loaded revision: BD_TMODEMF667V1.0.0B01
>ModemManager[1398]: <debug> [1478032549.933044] [mm-broadband-modem.c:911] modem_load_equipment_identifier(): loading equipment identifier...
>ModemManager[1398]: <debug> [1478032549.933229] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032549.933476] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032549.933731] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGSN<CR>'
>ModemManager[1398]: <debug> [1478032549.952586] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>359905017792331<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.953029] [mm-broadband-modem.c:893] modem_load_equipment_identifier_finish(): loaded equipment identifier: 359905017792331
>ModemManager[1398]: <debug> [1478032549.953259] [mm-broadband-modem.c:999] modem_load_device_identifier(): loading device identifier...
>ModemManager[1398]: <debug> [1478032549.953412] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032549.953599] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032549.953775] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATI<CR>'
>ModemManager[1398]: <debug> [1478032549.966839] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>Manufacturer: ZTE CORPORATION<CR><LF>Model: MF667<CR><LF>Revision: BD_TMODEMF667V1.0.0B01<CR><LF>IMEI: 359905017792331<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.967531] [mm-modem-helpers.c:211] mm_create_device_identifier(): Device ID source '000019d200000117Manufacturer:ZTECORPORATIONModel:MF667Revision:BD_TMODEMF667V1.0.0B01IMEI:359905017792331+GCAP:+CGSM,+DS,+ES359905017792331BD_TMODEMF667V1.0.0B01MF667ZTECORPORATION'
>ModemManager[1398]: <debug> [1478032549.967766] [mm-modem-helpers.c:212] mm_create_device_identifier(): Device ID '2f59e97129882b30546f7987b4a4637e5cf5c9a2'
>ModemManager[1398]: <debug> [1478032549.967899] [mm-broadband-modem.c:962] modem_load_device_identifier_finish(): loaded device identifier: 2f59e97129882b30546f7987b4a4637e5cf5c9a2
>ModemManager[1398]: <debug> [1478032549.968416] [mm-broadband-modem.c:1613] modem_load_supported_modes(): loading supported modes...
>ModemManager[1398]: <debug> [1478032549.968701] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032549.968921] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032549.969126] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT*CNTI=2<CR>'
>ModemManager[1398]: <debug> [1478032549.986530] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032549.986925] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032549.987110] [mm-broadband-modem.c:1547] supported_modes_cnti_ready(): Generic query of supported 3GPP networks with *CNTI failed: 'Unknown error'
>ModemManager[1398]: <debug> [1478032549.987248] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032549.987405] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032549.987562] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+WS46=?<CR>'
>ModemManager[1398]: <debug> [1478032550.007220] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.007560] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032550.007758] [mm-broadband-modem.c:1495] supported_modes_ws46_test_ready(): Generic query of supported 3GPP networks with WS46=? failed: 'Unknown error'
>ModemManager[1398]: <warn>  [1478032550.007951] [mm-iface-modem.c:3974] load_supported_modes_ready(): couldn't load Supported Modes: 'Couldn't retrieve supported modes'
>ModemManager[1398]: <debug> [1478032550.008523] [mm-broadband-modem.c:1687] modem_load_supported_ip_families(): loading supported IP families...
>ModemManager[1398]: <debug> [1478032550.008776] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032550.009060] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032550.009270] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGDCONT=?<CR>'
>ModemManager[1398]: <debug> [1478032550.031428] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.031777] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <warn>  [1478032550.031959] [mm-iface-modem.c:4023] load_supported_ip_families_ready(): couldn't load Supported IP families: 'Unknown error'
>ModemManager[1398]: <debug> [1478032550.032124] [mm-broadband-modem.c:3144] load_power_state(): loading power state...
>ModemManager[1398]: <debug> [1478032550.032271] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032550.032451] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032550.032628] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CFUN?<CR>'
>ModemManager[1398]: <debug> [1478032550.048984] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.049612] [mm-broadband-modem.c:1302] modem_load_unlock_required(): checking if unlock required...
>ModemManager[1398]: <debug> [1478032550.049837] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032550.050066] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032550.050280] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPIN?<CR>'
>ModemManager[1398]: <debug> [1478032550.061284] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPIN: SIM PIN<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.061858] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032550.062130] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032550.062367] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+ZPINPUK=?<CR>'
>ModemManager[1398]: <debug> [1478032550.085645] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+ZPINPUK: 3,10<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <info>  [1478032550.086969] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem: state changed (unknown -> locked)
>ModemManager[1398]: <debug> [1478032550.087619] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032550.089437] [mm-base-sim.c:1004] load_sim_identifier(): loading SIM identifier...
>ModemManager[1398]: <debug> [1478032550.089749] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032550.090112] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRSM=176,12258,0,0,10<CR>'
>ModemManager[1398]: <debug> [1478032550.240646] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CRSM: 144,0,"986303424130117343F7"<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.241630] [mm-base-sim.c:993] load_sim_identifier_finish(): loaded SIM identifier: 8936302414031137347
>ModemManager[1398]: <debug> [1478032550.241981] [mm-base-sim.c:1074] load_imsi(): loading IMSI...
>ModemManager[1398]: <debug> [1478032550.242219] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032550.242509] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032550.242782] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CIMI<CR>'
>ModemManager[1398]: <debug> [1478032550.258989] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.259302] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <warn>  [1478032550.259509] [mm-base-sim.c:1423] load_imsi_ready(): couldn't load IMSI: 'Unknown error'
>ModemManager[1398]: <debug> [1478032550.259630] [mm-base-sim.c:1194] load_operator_identifier(): loading Operator ID...
>ModemManager[1398]: <debug> [1478032550.259763] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032550.259920] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032550.260080] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRSM=176,28589,0,0,4<CR>'
>ModemManager[1398]: <debug> [1478032550.380325] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CRSM: 103,0,""<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <warn>  [1478032550.381045] [mm-base-sim.c:1424] load_operator_identifier_ready(): couldn't load Operator identifier: 'Cannot load Operator ID without IMSI'
>ModemManager[1398]: <debug> [1478032550.381354] [mm-base-sim.c:1289] load_operator_name(): loading Operator Name...
>ModemManager[1398]: <debug> [1478032550.381684] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032550.381915] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032550.382129] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRSM=176,28486,0,0,17<CR>'
>ModemManager[1398]: <debug> [1478032550.507485] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CRSM: 144,0,"00542D4D6F62696C652048FFFFFFFFFFFF"<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.509042] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032550.510055] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[1398]: <debug> [1478032550.511120] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032550.511332] [mm-broadband-modem.c:1180] modem_load_own_numbers(): loading own numbers...
>ModemManager[1398]: <debug> [1478032550.511718] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032550.512110] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNUM<CR>'
>ModemManager[1398]: <debug> [1478032550.527223] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.527628] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032550.527836] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032550.528455] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 26 b2 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 69 6d 7e
>ModemManager[1398]: <debug> [1478032550.530867] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 26 b2 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 05 00 d1 13 7e
>ModemManager[1398]: <warn>  [1478032550.531168] [mm-iface-modem.c:4130] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Failed to parse NV MDN command result: -17'
>ModemManager[1398]: <debug> [1478032550.532176] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032550.532373] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port...
>ModemManager[1398]: <debug> [1478032550.546405] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed
>ModemManager[1398]: <debug> [1478032550.547757] [mm-broadband-modem.c:9635] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware interface not available'
>ModemManager[1398]: <debug> [1478032550.548575] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032550.548753] [mm-port-serial.c:1361] _close_internal(): (ttyUSB2) closing serial port...
>ModemManager[1398]: <debug> [1478032550.563860] [mm-port-serial.c:1409] _close_internal(): (ttyUSB2) serial port closed
>ModemManager[1398]: <debug> [1478032550.564124] [mm-base-modem.c:844] initialize_ready(): Couldn't finish initialization in the current state: 'Modem is currently locked, cannot fully initialize'
>ModemManager[1398]: <debug> [1478032550.567498] [mm-device.c:452] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-3' at path '/org/freedesktop/ModemManager1/Modem/0'
>ModemManager[1398]: <debug> [1478032550.571830] [mm-device.c:462] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'ZTE' modem, VID 0x19D2 PID 0x0117 (usb)
>ModemManager[1398]: <debug> [1478032550.819751] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1398]: <warn>  [1478032550.821509] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1398]: <debug> [1478032550.821758] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032550.822068] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPIN="0000"<CR>'
>ModemManager[1398]: <debug> [1478032550.944853] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.945308] [mm-broadband-modem.c:1302] modem_load_unlock_required(): checking if unlock required...
>ModemManager[1398]: <debug> [1478032550.945483] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032550.945654] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032550.945806] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPIN?<CR>'
>ModemManager[1398]: <debug> [1478032550.952139] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032550.961664] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.962087] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032550.962354] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032550.962562] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPIN="0000"<CR>'
>ModemManager[1398]: <debug> [1478032550.986722] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032550.987087] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032550.987374] [mm-broadband-modem.c:1302] modem_load_unlock_required(): checking if unlock required...
>ModemManager[1398]: <debug> [1478032550.987564] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032550.987771] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032550.988184] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+ZPINPUK=?<CR>'
>ModemManager[1398]: <debug> [1478032551.009863] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+ZPINPUK: 3,10<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032551.010398] [mm-iface-modem.c:3101] update_lock_info_context_step(): SIM is ready, running after SIM unlock step...
>ModemManager[1398]: <debug> [1478032551.010600] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032551.010760] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032551.010928] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPIN?<CR>'
>ModemManager[1398]: <debug> [1478032551.022404] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032551.022923] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032551.023253] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032551.023514] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032551.041754] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CME ERROR: 14<CR><LF>'
>ModemManager[1398]: <debug> [1478032551.042157] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy
>ModemManager[1398]: <debug> [1478032551.042430] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032551.042680] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+ZPINPUK=?<CR>'
>ModemManager[1398]: <debug> [1478032551.065328] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+ZPINPUK: 3,10<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032551.065991] [mm-iface-modem.c:3101] update_lock_info_context_step(): SIM is ready, running after SIM unlock step...
>ModemManager[1398]: <debug> [1478032551.066290] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032551.066629] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032551.066948] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032551.083220] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CME ERROR: 14<CR><LF>'
>ModemManager[1398]: <debug> [1478032551.083587] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy
>ModemManager[1398]: <debug> [1478032551.083817] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032551.083957] [mm-port-serial.c:1361] _close_internal(): (ttyUSB2) closing serial port...
>ModemManager[1398]: <debug> [1478032551.100131] [mm-port-serial.c:1409] _close_internal(): (ttyUSB2) serial port closed
>ModemManager[1398]: <debug> [1478032553.665221] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1398]: <warn>  [1478032553.666786] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1398]: <debug> [1478032553.667069] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032553.667346] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032553.667594] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032553.681814] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: "SM",0,20,"SM",0,20,"ME",0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032553.682602] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <info>  [1478032553.684748] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (locked -> initializing)
>ModemManager[1398]: <debug> [1478032553.688760] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032553.689036] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032553.689237] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
>ModemManager[1398]: <debug> [1478032553.689428] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032553.689763] [mm-broadband-modem.c:1613] modem_load_supported_modes(): loading supported modes...
>ModemManager[1398]: <debug> [1478032553.689983] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
>ModemManager[1398]: <debug> [1478032553.716055] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: "SM",0,20,"SM",0,20,"ME",0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032553.716546] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
>ModemManager[1398]: <debug> [1478032553.719034] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1398]: <debug> [1478032553.732222] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032553.732675] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
>ModemManager[1398]: <debug> [1478032553.732897] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
>ModemManager[1398]: <debug> [1478032553.745302] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032553.745679] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032553.745928] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT*CNTI=2<CR>'
>ModemManager[1398]: <debug> [1478032553.763826] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1398]: <debug> [1478032553.764170] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1398]: <debug> [1478032553.764371] [mm-broadband-modem.c:1547] supported_modes_cnti_ready(): Generic query of supported 3GPP networks with *CNTI failed: 'Unknown error'
>ModemManager[1398]: <debug> [1478032553.764556] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032553.764965] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032553.765229] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+WS46=?<CR>'
>ModemManager[1398]: <debug> [1478032553.779049] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+WS46: (12,22,25)<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032553.779542] [mm-broadband-modem.c:1450] supported_modes_ws46_test_ready(): Device allows (3GPP) 2G-only network mode
>ModemManager[1398]: <debug> [1478032553.779757] [mm-broadband-modem.c:1455] supported_modes_ws46_test_ready(): Device allows (3GPP) 3G-only network mode
>ModemManager[1398]: <debug> [1478032553.780018] [mm-broadband-modem.c:1484] supported_modes_ws46_test_ready(): Device allows every supported 3GPP network mode (2G/3G)
>ModemManager[1398]: <debug> [1478032553.780987] [mm-broadband-modem.c:1687] modem_load_supported_ip_families(): loading supported IP families...
>ModemManager[1398]: <debug> [1478032553.781304] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032553.781769] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032553.790397] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGDCONT=?<CR>'
>ModemManager[1398]: <debug> [1478032553.815802] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CGDCONT: (1-16),"IP",,,(0-2),(0-4)<CR><LF>+CGDCONT: (1-16),"PPP",,,(0-2),(0-4)<CR><LF>+CGDCONT: (1-16),"IPV6",,,(0-2),(0-4)<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032553.816671] [mm-modem-helpers.c:895] mm_3gpp_parse_cgdcont_test_response(): Unhandled PDP type in CGDCONT=? reply: 'PPP'
>ModemManager[1398]: <debug> [1478032553.817145] [mm-base-sim.c:1074] load_imsi(): loading IMSI...
>ModemManager[1398]: <debug> [1478032553.817398] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032553.817691] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032553.818854] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CIMI<CR>'
>ModemManager[1398]: <debug> [1478032553.853902] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>216306021723106<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032553.854299] [mm-base-sim.c:1063] load_imsi_finish(): loaded IMSI: 216306021723106
>ModemManager[1398]: <debug> [1478032553.854528] [mm-base-sim.c:1194] load_operator_identifier(): loading Operator ID...
>ModemManager[1398]: <debug> [1478032553.854691] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032553.855193] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032553.856239] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRSM=176,28589,0,0,4<CR>'
>ModemManager[1398]: <debug> [1478032554.287737] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CRSM: 103,0,""<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <warn>  [1478032554.288503] [mm-base-sim.c:1424] load_operator_identifier_ready(): couldn't load Operator identifier: 'Failed to parse CRSM query result '+CRSM: 103,0,""''
>ModemManager[1398]: <debug> [1478032554.288769] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032554.289013] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[1398]: <debug> [1478032554.289764] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032554.289959] [mm-broadband-modem.c:1180] modem_load_own_numbers(): loading own numbers...
>ModemManager[1398]: <debug> [1478032554.290137] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032554.290423] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNUM<CR>'
>ModemManager[1398]: <debug> [1478032554.308331] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CME ERROR: 14<CR><LF>'
>ModemManager[1398]: <debug> [1478032554.308626] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy
>ModemManager[1398]: <debug> [1478032554.308835] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032554.309366] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 26 b2 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 69 6d 7e
>ModemManager[1398]: <debug> [1478032554.312432] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 26 b2 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 05 00 d1 13 7e
>ModemManager[1398]: <warn>  [1478032554.312774] [mm-iface-modem.c:4130] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Failed to parse NV MDN command result: -17'
>ModemManager[1398]: <debug> [1478032554.313048] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032554.313288] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032554.313426] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port...
>ModemManager[1398]: <debug> [1478032554.327350] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed
>ModemManager[1398]: <debug> [1478032554.327645] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+ZSNT?<CR>'
>ModemManager[1398]: <debug> [1478032554.353397] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+ZSNT: 0,0,0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032554.354412] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032554.356271] [mm-broadband-modem.c:3247] modem_3gpp_load_imei(): loading IMEI...
>ModemManager[1398]: <debug> [1478032554.356505] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032554.356771] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGSN<CR>'
>ModemManager[1398]: <debug> [1478032554.375027] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>359905017792331<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032554.375469] [mm-broadband-modem.c:3238] modem_3gpp_load_imei_finish(): loaded IMEI: 359905017792331
>ModemManager[1398]: <debug> [1478032554.375722] [mm-broadband-modem.c:3406] modem_3gpp_load_enabled_facility_locks(): loading enabled facility locks...
>ModemManager[1398]: <debug> [1478032554.375901] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032554.376101] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032554.376310] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK=?<CR>'
>ModemManager[1398]: <debug> [1478032554.390413] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: ("AB","AC","AG","AI","AO","IR","OI","OX","SC","FD","PN","PU","PP","PC","PF")<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032554.391168] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032554.391606] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032554.391878] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="SC",2<CR>'
>ModemManager[1398]: <debug> [1478032554.882042] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 1<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032554.882659] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032554.882952] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032554.883162] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="FD",2<CR>'
>ModemManager[1398]: <debug> [1478032554.944143] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032554.944611] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032554.944829] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032554.945003] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PF",2<CR>'
>ModemManager[1398]: <debug> [1478032555.077305] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.077838] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032555.078142] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032555.078334] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PN",2<CR>'
>ModemManager[1398]: <debug> [1478032555.224512] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.225083] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032555.225306] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032555.225478] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PU",2<CR>'
>ModemManager[1398]: <debug> [1478032555.374848] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.375473] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032555.375743] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032555.375953] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PP",2<CR>'
>ModemManager[1398]: <debug> [1478032555.521841] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.522618] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032555.522965] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032555.523241] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PC",2<CR>'
>ModemManager[1398]: <debug> [1478032555.671996] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.673663] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032555.679276] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032555.679580] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CUSD=?<CR>'
>ModemManager[1398]: <debug> [1478032555.699441] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CUSD: (0-2)<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.700904] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032555.709287] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032555.712697] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNMI=?<CR>'
>ModemManager[1398]: <debug> [1478032555.734770] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CNMI: (0,1,2,3),(0,1,2,3),(0,2),(0,1,2),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.735222] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032555.735439] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032555.735613] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS=?<CR>'
>ModemManager[1398]: <debug> [1478032555.755087] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: ("ME","MT","SM","SR"),("ME","MT","SM","SR"),("ME","MT","SM","SR")<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.755832] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): Supported storages loaded:
>ModemManager[1398]: <debug> [1478032555.755981] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready():   mem1 (list/read/delete) storages: 'me, mt, sm, sr'
>ModemManager[1398]: <debug> [1478032555.756059] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready():   mem2 (write/send) storages:       'me, mt, sm, sr'
>ModemManager[1398]: <debug> [1478032555.756133] [mm-iface-modem-messaging.c:1152] load_supported_storages_ready():   mem3 (reception) storages:        'me, mt, sm, sr'
>ModemManager[1398]: <debug> [1478032555.756375] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032555.756561] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032555.756729] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS?<CR>'
>ModemManager[1398]: <debug> [1478032555.770594] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: "SM",0,20,"SM",0,20,"ME",0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.771152] [mm-broadband-modem.c:5283] cpms_query_ready(): Current storages initialized:
>ModemManager[1398]: <debug> [1478032555.771287] [mm-broadband-modem.c:5285] cpms_query_ready():   mem1 (list/read/delete) storages: 'sm'
>ModemManager[1398]: <debug> [1478032555.771435] [mm-broadband-modem.c:5287] cpms_query_ready():   mem2 (write/send) storages:       'sm'
>ModemManager[1398]: <debug> [1478032555.771543] [mm-iface-modem-messaging.c:1232] init_current_storages_ready(): Current storages initialized
>ModemManager[1398]: <debug> [1478032555.775379] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032555.775969] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032555.776217] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATH<CR>'
>ModemManager[1398]: <debug> [1478032555.782718] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.785946] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032555.787196] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032555.787463] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CTZU=1<CR>'
>ModemManager[1398]: <debug> [1478032555.820081] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.820612] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CCLK?<CR>'
>ModemManager[1398]: <debug> [1478032555.836947] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CCLK: "80/01/06,00:00:22"<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.838013] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032555.838772] [mm-broadband-modem.c:9633] iface_modem_signal_initialize_ready(): Couldn't initialize interface: 'Extended Signal information not supported'
>ModemManager[1398]: <debug> [1478032555.839844] [mm-broadband-modem.c:9634] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
>ModemManager[1398]: <debug> [1478032555.840241] [mm-broadband-modem.c:9635] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware interface not available'
>ModemManager[1398]: <info>  [1478032555.840478] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (initializing -> disabled)
>ModemManager[1398]: <debug> [1478032555.841625] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032555.841840] [mm-port-serial.c:1361] _close_internal(): (ttyUSB2) closing serial port...
>ModemManager[1398]: <debug> [1478032555.861220] [mm-port-serial.c:1409] _close_internal(): (ttyUSB2) serial port closed
>ModemManager[1398]: <info>  [1478032555.883908] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
>ModemManager[1398]: <debug> [1478032555.885051] [mm-broadband-modem.c:8536] enabling_started(): Skipping modem initialization: device hotplugged
>ModemManager[1398]: <debug> [1478032555.885212] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1398]: <warn>  [1478032555.886038] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1398]: <debug> [1478032555.886241] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032555.886338] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
>ModemManager[1398]: <debug> [1478032555.886524] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[1398]: <warn>  [1478032555.887176] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[1398]: <debug> [1478032555.887382] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032555.887477] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB1): running init sequence...
>ModemManager[1398]: <debug> [1478032555.887603] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[1398]: <debug> [1478032555.888255] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032555.888409] [mm-broadband-modem.c:8552] enabling_started(): Flashing primary AT port before enabling...
>ModemManager[1398]: <debug> [1478032555.888612] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1398]: <debug> [1478032555.888758] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
>ModemManager[1398]: <debug> [1478032555.896961] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.897421] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.897744] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
>ModemManager[1398]: <info>  [1478032555.909499] [mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...
>ModemManager[1398]: <debug> [1478032555.909671] [mm-iface-modem-simple.c:651] connect_auth_ready():    PIN: 0000
>ModemManager[1398]: <debug> [1478032555.909767] [mm-iface-modem-simple.c:653] connect_auth_ready():    Operator ID: unspecified
>ModemManager[1398]: <debug> [1478032555.909853] [mm-iface-modem-simple.c:655] connect_auth_ready():    Allowed roaming: yes
>ModemManager[1398]: <debug> [1478032555.909938] [mm-iface-modem-simple.c:657] connect_auth_ready():    APN: internet
>ModemManager[1398]: <debug> [1478032555.910026] [mm-iface-modem-simple.c:662] connect_auth_ready():    IP family: ipv4
>ModemManager[1398]: <debug> [1478032555.910144] [mm-iface-modem-simple.c:670] connect_auth_ready():    Allowed authentication: none, pap, chap, mschap, mschapv2, eap
>ModemManager[1398]: <debug> [1478032555.910228] [mm-iface-modem-simple.c:675] connect_auth_ready():    User: unspecified
>ModemManager[1398]: <debug> [1478032555.910307] [mm-iface-modem-simple.c:677] connect_auth_ready():    Password: unspecified
>ModemManager[1398]: <debug> [1478032555.910388] [mm-iface-modem-simple.c:679] connect_auth_ready():    Number: *99#
>ModemManager[1398]: <info>  [1478032555.910464] [mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled
>ModemManager[1398]: <debug> [1478032555.911577] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.911953] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
>ModemManager[1398]: <debug> [1478032555.930849] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.931203] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
>ModemManager[1398]: <debug> [1478032555.944895] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.945321] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
>ModemManager[1398]: <debug> [1478032555.961885] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032555.989589] [mm-broadband-modem.c:3144] load_power_state(): loading power state...
>ModemManager[1398]: <debug> [1478032555.989847] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032555.990058] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CFUN?<CR>'
>ModemManager[1398]: <debug> [1478032556.011688] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.012159] [mm-iface-modem.c:3293] set_power_state(): No need to change power state: already in 'on' power state
>ModemManager[1398]: <debug> [1478032556.012368] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.012649] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.013008] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.013281] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+IFC=1,1<CR>'
>ModemManager[1398]: <debug> [1478032556.034282] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.034603] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.034933] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CSCS=?<CR>'
>ModemManager[1398]: <debug> [1478032556.048865] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CSCS: ("IRA","GSM","UCS2")<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.049500] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.049754] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.049955] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CSCS="UCS2"<CR>'
>ModemManager[1398]: <debug> [1478032556.075027] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.075460] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.075729] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.075962] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CSCS?<CR>'
>ModemManager[1398]: <debug> [1478032556.087582] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.088154] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.088494] [mm-broadband-modem.c:9197] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
>ModemManager[1398]: <debug> [1478032556.088684] [mm-broadband-modem.c:2606] modem_3gpp_setup_unsolicited_events(): Checking indicator support...
>ModemManager[1398]: <debug> [1478032556.088910] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.089189] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CIND=?<CR>'
>ModemManager[1398]: <debug> [1478032556.108660] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CIND: ("battchg",(0-5)),("signal",(0-5)),("service",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("GPRS coverage",(0-1)),("callsetup",(0-3))<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.109680] [mm-broadband-modem.c:2559] cind_format_check_ready(): Modem supports signal quality indications via CIND at index '2'(min: 0, max: 5)
>ModemManager[1398]: <debug> [1478032556.109907] [mm-broadband-modem.c:2568] cind_format_check_ready(): Modem supports roaming indications via CIND at index '5'
>ModemManager[1398]: <debug> [1478032556.110057] [mm-broadband-modem.c:2577] cind_format_check_ready(): Modem supports service indications via CIND at index '3'
>ModemManager[1398]: <debug> [1478032556.110293] [mm-broadband-modem.c:2510] set_unsolicited_events_handlers(): (ttyUSB2) Setting 3GPP unsolicited events handlers
>ModemManager[1398]: <debug> [1478032556.110477] [mm-broadband-modem.c:2510] set_unsolicited_events_handlers(): (ttyUSB1) Setting 3GPP unsolicited events handlers
>ModemManager[1398]: <debug> [1478032556.110769] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.111055] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.111352] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMER=3,0,0,1<CR>'
>ModemManager[1398]: <debug> [1478032556.138554] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.139020] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.139318] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.139561] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMER=3,0,0,1<CR>'
>ModemManager[1398]: <debug> [1478032556.162034] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.163508] [mm-broadband-modem.c:3606] modem_3gpp_setup_unsolicited_registration_events(): (ttyUSB2) setting up 3GPP unsolicited registration messages handlers
>ModemManager[1398]: <debug> [1478032556.163737] [mm-broadband-modem.c:3606] modem_3gpp_setup_unsolicited_registration_events(): (ttyUSB1) setting up 3GPP unsolicited registration messages handlers
>ModemManager[1398]: <debug> [1478032556.164018] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.164417] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.165003] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CREG=2<CR>'
>ModemManager[1398]: <debug> [1478032556.178408] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.178876] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.179084] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.179262] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CREG=2<CR>'
>ModemManager[1398]: <debug> [1478032556.193217] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.193656] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.193954] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.194196] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGREG=2<CR>'
>ModemManager[1398]: <debug> [1478032556.213375] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.213866] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.214142] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.214387] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGREG=2<CR>'
>ModemManager[1398]: <debug> [1478032556.229665] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.230077] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.230410] [mm-broadband-modem.c:9210] enabling_step(): Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
>ModemManager[1398]: <debug> [1478032556.230650] [mm-broadband-modem.c:4920] set_unsolicited_result_code_handlers(): (ttyUSB2) Setting unsolicited result code handlers
>ModemManager[1398]: <debug> [1478032556.230794] [mm-broadband-modem.c:4920] set_unsolicited_result_code_handlers(): (ttyUSB1) Setting unsolicited result code handlers
>ModemManager[1398]: <debug> [1478032556.231071] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.231560] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CUSD=1<CR>'
>ModemManager[1398]: <debug> [1478032556.257312] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.257833] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.258744] [mm-broadband-modem.c:9238] enabling_step(): Modem has location capabilities, enabling the Location interface...
>ModemManager[1398]: <debug> [1478032556.258975] [mm-iface-modem-location.c:762] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
>ModemManager[1398]: <debug> [1478032556.259291] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>ModemManager[1398]: <debug> [1478032556.259478] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.260298] [mm-broadband-modem.c:9251] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
>ModemManager[1398]: <debug> [1478032556.260716] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.260982] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CREG?<CR>'
>ModemManager[1398]: <debug> [1478032556.289330] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 2,1, 4B0, D38519<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <info>  [1478032556.289747] [mm-iface-modem-3gpp.c:1171] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
>ModemManager[1398]: <debug> [1478032556.289937] [mm-broadband-modem.c:3442] modem_3gpp_load_operator_code(): loading Operator Code...
>ModemManager[1398]: <debug> [1478032556.290106] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
>ModemManager[1398]: <debug> [1478032556.290371] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: '4B0', Cell ID: 'D38519')
>ModemManager[1398]: <debug> [1478032556.290766] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
>ModemManager[1398]: <debug> [1478032556.290988] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
>ModemManager[1398]: <debug> [1478032556.291222] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGF=?<CR>'
>ModemManager[1398]: <debug> [1478032556.308306] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.308810] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
>ModemManager[1398]: <debug> [1478032556.309007] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
>ModemManager[1398]: <debug> [1478032556.309193] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+COPS=3,2;+COPS?<CR>'
>ModemManager[1398]: <debug> [1478032556.338282] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,2,"21630",2<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.340128] [mm-broadband-modem.c:3432] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 21630
>ModemManager[1398]: <debug> [1478032556.340499] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '216', MNC: '30', Location area code: '4B0', Cell ID: 'D38519')
>ModemManager[1398]: <debug> [1478032556.340633] [mm-broadband-modem.c:3478] modem_3gpp_load_operator_name(): loading Operator Name...
>ModemManager[1398]: <debug> [1478032556.340751] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
>ModemManager[1398]: <debug> [1478032556.340894] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
>ModemManager[1398]: <debug> [1478032556.341738] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGREG?<CR>'
>ModemManager[1398]: <debug> [1478032556.369150] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CGREG: 2,1, 4B0, D38519<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.369643] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
>ModemManager[1398]: <debug> [1478032556.369903] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGF=0<CR>'
>ModemManager[1398]: <debug> [1478032556.387341] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.387840] [mm-broadband-modem.c:5585] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU'
>ModemManager[1398]: <debug> [1478032556.388498] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
>ModemManager[1398]: <debug> [1478032556.388856] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
>ModemManager[1398]: <debug> [1478032556.389966] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+COPS=3,0;+COPS?<CR>'
>ModemManager[1398]: <debug> [1478032556.427291] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,0,"Telekom HU",2<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.442475] [mm-broadband-modem.c:3468] modem_3gpp_load_operator_name_finish(): loaded Operator Name: Telekom HU
>ModemManager[1398]: <debug> [1478032556.442889] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <info>  [1478032556.444306] [mm-iface-modem-3gpp.c:1096] update_registration_reload_current_registration_info_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
>ModemManager[1398]: <debug> [1478032556.444625] [mm-iface-modem.c:1600] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
>ModemManager[1398]: <debug> [1478032556.445606] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS="SM","MT","MT"<CR>'
>ModemManager[1398]: <debug> [1478032556.501445] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: 0,20,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.503241] [mm-broadband-modem.c:6357] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me'
>ModemManager[1398]: <debug> [1478032556.503462] [mm-broadband-modem.c:5471] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)...
>ModemManager[1398]: <debug> [1478032556.503780] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.504096] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.504394] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS="ME"<CR>'
>ModemManager[1398]: <debug> [1478032556.528022] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: 0,100,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.528642] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.528915] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.529145] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGL=4<CR>'
>ModemManager[1398]: <debug> [1478032556.543037] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.543592] [mm-broadband-modem.c:6357] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
>ModemManager[1398]: <debug> [1478032556.543768] [mm-broadband-modem.c:5471] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)...
>ModemManager[1398]: <debug> [1478032556.543957] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.544185] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.544410] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS="SM"<CR>'
>ModemManager[1398]: <debug> [1478032556.570140] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: 0,20,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.570584] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.570813] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.571008] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGL=4<CR>'
>ModemManager[1398]: <debug> [1478032556.589753] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.590282] [mm-broadband-modem.c:6357] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sr'
>ModemManager[1398]: <debug> [1478032556.590462] [mm-broadband-modem.c:5471] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SR), mem2 (none)...
>ModemManager[1398]: <debug> [1478032556.590647] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.590865] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.591091] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS="SR"<CR>'
>ModemManager[1398]: <debug> [1478032556.608481] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: 0,100,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.608980] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032556.609303] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.609584] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGL=4<CR>'
>ModemManager[1398]: <debug> [1478032556.628655] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMS ERROR: 500<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.629241] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown
>ModemManager[1398]: <debug> [1478032556.629555] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'sr': 'Unknown'
>ModemManager[1398]: <debug> [1478032556.629827] [mm-broadband-modem.c:5888] set_messaging_unsolicited_events_handlers(): (ttyUSB2) Setting messaging unsolicited events handlers
>ModemManager[1398]: <debug> [1478032556.629977] [mm-broadband-modem.c:5888] set_messaging_unsolicited_events_handlers(): (ttyUSB1) Setting messaging unsolicited events handlers
>ModemManager[1398]: <debug> [1478032556.630162] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.630391] [mm-broadband-modem.c:6061] modem_messaging_enable_unsolicited_events(): (ttyUSB2) Enabling messaging unsolicited events on primary port
>ModemManager[1398]: <debug> [1478032556.630572] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.630861] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,1,0<CR>'
>ModemManager[1398]: <debug> [1478032556.653485] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.653904] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
>ModemManager[1398]: <debug> [1478032556.654239] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,2,0<CR>'
>ModemManager[1398]: <debug> [1478032556.683641] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.684039] [mm-broadband-modem.c:6022] modem_messaging_enable_unsolicited_events_primary_ready(): (ttyUSB2) Messaging unsolicited events enabled on primary
>ModemManager[1398]: <debug> [1478032556.684191] [mm-broadband-modem.c:6027] modem_messaging_enable_unsolicited_events_primary_ready(): (ttyUSB1) Enabling messaging unsolicited events on secondary port
>ModemManager[1398]: <debug> [1478032556.684359] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.684581] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.684988] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CNMI=2,1,2,1,0<CR>'
>ModemManager[1398]: <debug> [1478032556.712298] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.712710] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
>ModemManager[1398]: <debug> [1478032556.713038] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CNMI=2,1,2,2,0<CR>'
>ModemManager[1398]: <debug> [1478032556.736697] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.737067] [mm-broadband-modem.c:5995] modem_messaging_enable_unsolicited_events_secondary_ready(): (ttyUSB1) Messaging unsolicited events enabled on secondary
>ModemManager[1398]: <debug> [1478032556.737226] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.737382] [mm-broadband-modem.c:9264] enabling_step(): Modem has voice capabilities, enabling the Voice interface...
>ModemManager[1398]: <debug> [1478032556.737782] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB2) Setting voice unsolicited events handlers
>ModemManager[1398]: <debug> [1478032556.737909] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB1) Setting voice unsolicited events handlers
>ModemManager[1398]: <debug> [1478032556.738199] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.738430] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLIP=1<CR>'
>ModemManager[1398]: <debug> [1478032556.752811] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.753455] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRC=1<CR>'
>ModemManager[1398]: <debug> [1478032556.776094] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.776486] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032556.776685] [mm-broadband-modem.c:9277] enabling_step(): Modem has time capabilities, enabling the Time interface...
>ModemManager[1398]: <info>  [1478032556.776965] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
>ModemManager[1398]: <info>  [1478032556.777252] [mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register
>ModemManager[1398]: <debug> [1478032556.777420] [mm-iface-modem-3gpp.c:400] mm_iface_modem_3gpp_register_in_network(): Already registered in network '21630', automatic registration not launched...
>ModemManager[1398]: <debug> [1478032556.779050] [mm-iface-modem.c:1355] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
>ModemManager[1398]: <debug> [1478032556.779229] [mm-broadband-modem.c:2003] modem_load_signal_quality(): loading signal quality...
>ModemManager[1398]: <debug> [1478032556.779358] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032556.779496] [mm-iface-modem.c:1075] periodic_access_technologies_check_enable(): Periodic access technology checks enabled
>ModemManager[1398]: <debug> [1478032556.779646] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
>ModemManager[1398]: <info>  [1478032556.780523] [mm-iface-modem-simple.c:501] connection_step(): Simple connect state (6/8): Bearer
>ModemManager[1398]: <debug> [1478032556.780697] [mm-iface-modem-simple.c:521] connection_step(): Creating new bearer...
>ModemManager[1398]: <debug> [1478032556.780818] [mm-broadband-modem.c:297] modem_create_bearer(): Creating Broadband bearer in broadband modem
>ModemManager[1398]: <debug> [1478032556.782632] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
>ModemManager[1398]: <debug> [1478032556.782823] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
>ModemManager[1398]: <debug> [1478032556.783146] [mm-broadband-modem.c:258] modem_create_bearer_finish(): New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[1398]: <info>  [1478032556.784185] [mm-iface-modem-simple.c:583] connection_step(): Simple connect state (7/8): Connect
>ModemManager[1398]: <debug> [1478032556.784362] [mm-base-bearer.c:703] mm_base_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[1398]: <info>  [1478032556.784514] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
>ModemManager[1398]: <debug> [1478032556.786191] [mm-broadband-bearer.c:1254] connect(): Launching 3GPP connection attempt with APN 'internet'
>ModemManager[1398]: <debug> [1478032556.786368] [mm-broadband-bearer.c:1044] connect_3gpp(): Looking for best CID...
>ModemManager[1398]: <debug> [1478032556.786484] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
>ModemManager[1398]: <debug> [1478032556.786701] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>ModemManager[1398]: <debug> [1478032556.786863] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
>ModemManager[1398]: <debug> [1478032556.787024] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CIND?<CR>'
>ModemManager[1398]: <debug> [1478032556.860591] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CIND: 5,3,1,0,0,0,1,0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.861209] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
>ModemManager[1398]: <debug> [1478032556.861800] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
>ModemManager[1398]: <debug> [1478032556.861992] [mm-iface-modem.c:1274] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s)
>ModemManager[1398]: <debug> [1478032556.866574] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+ZPAS?<CR>'
>ModemManager[1398]: <debug> [1478032556.882855] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+ZPAS: "UMTS","CS_PS"<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.883452] [mm-iface-modem.c:933] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> umts)
>ModemManager[1398]: <debug> [1478032556.883605] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
>ModemManager[1398]: <debug> [1478032556.884526] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGDCONT?<CR>'
>ModemManager[1398]: <debug> [1478032556.907123] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CGDCONT: 1,"IP","internet","0.0.0.0",0,0<CR><LF>+CGDCONT: 2,"IPV6","internet","0.0.0.0",0,0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.907702] [mm-broadband-bearer.c:961] parse_pdp_list(): Found '2' PDP contexts
>ModemManager[1398]: <debug> [1478032556.907838] [mm-broadband-bearer.c:970] parse_pdp_list():   PDP context [cid=1] [type='ipv4'] [apn='internet']
>ModemManager[1398]: <debug> [1478032556.907921] [mm-broadband-bearer.c:970] parse_pdp_list():   PDP context [cid=2] [type='ipv6'] [apn='internet']
>ModemManager[1398]: <debug> [1478032556.907999] [mm-broadband-bearer.c:994] parse_pdp_list(): Found PDP context with CID 1 and PDP type ipv4 for APN 'internet'
>ModemManager[1398]: <debug> [1478032556.908221] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
>ModemManager[1398]: <debug> [1478032556.908330] [mm-broadband-bearer.c:214] common_get_at_data_port(): Connection through a plain serial AT port (ttyUSB2)
>ModemManager[1398]: <debug> [1478032556.908454] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
>ModemManager[1398]: <debug> [1478032556.908585] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
>ModemManager[1398]: <debug> [1478032556.908763] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CREG?<CR>'
>ModemManager[1398]: <debug> [1478032556.923292] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 2,1, 4B0, D38519<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.923934] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
>ModemManager[1398]: <debug> [1478032556.924164] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
>ModemManager[1398]: <debug> [1478032556.924364] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATD*99***1#<CR>'
>ModemManager[1398]: <debug> [1478032556.941505] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>CONNECT 21600000<CR><LF>'
>ModemManager[1398]: <debug> [1478032556.941996] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
>ModemManager[1398]: <debug> [1478032556.942290] [mm-port.c:95] mm_port_set_connected(): (ttyUSB2): port now connected
>ModemManager[1398]: <debug> [1478032556.942474] [mm-base-bearer.c:601] connect_ready(): Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[1398]: <info>  [1478032556.943211] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
>ModemManager[1398]: <info>  [1478032556.955313] [mm-iface-modem-simple.c:602] connection_step(): Simple connect state (8/8): All done
>ModemManager[1398]: <debug> [1478032556.960057] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032556.960294] [mm-broadband-modem.c:8574] modem_3gpp_run_registration_checks_ready(): Initial 3GPP registration checks finished
>ModemManager[1398]: <debug> [1478032557.263880] [mm-base-manager.c:364] device_added(): (net/ppp0): could not get port's parent device
>ModemManager[1398]: <debug> [1478032561.663128] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032561.663492] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CCLK?<CR>'
>ModemManager[1398]: <debug> [1478032561.676331] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>+CCLK: "80/01/06,00:00:28"<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032561.676852] [mm-iface-modem-time.c:622] update_network_timezone_ready(): Couldn't update network timezone: 'Couldn't match +CCLK reply'
>ModemManager[1398]: <debug> [1478032561.677005] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032577.936025] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>+CREG: 1, 4B0, D3B363<CR><LF><CR><LF>+CGREG: 1, 4B0, D3B363<CR><LF>'
>ModemManager[1398]: <debug> [1478032577.947667] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '216', MNC: '30', Location area code: '4B0', Cell ID: 'D3B363')
>ModemManager[1398]: <debug> [1478032580.568498] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>+CREG: 1, 4B0, D38519<CR><LF><CR><LF>+CGREG: 1, 4B0, D38519<CR><LF>'
>ModemManager[1398]: <debug> [1478032580.569141] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '216', MNC: '30', Location area code: '4B0', Cell ID: 'D38519')
>ModemManager[1398]: <debug> [1478032586.668126] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032586.668456] [mm-broadband-modem.c:2003] modem_load_signal_quality(): loading signal quality...
>ModemManager[1398]: <debug> [1478032586.668615] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1398]: <debug> [1478032586.668813] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+ZPAS?<CR>'
>ModemManager[1398]: <debug> [1478032586.680554] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>+ZPAS: "UMTS","CS_PS"<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032586.681088] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1398]: <debug> [1478032586.681359] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CIND?<CR>'
>ModemManager[1398]: <debug> [1478032586.693146] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>+CIND: 5,3,1,1,0,0,1,0<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032586.693785] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032586.694341] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
>ModemManager[1398]: <debug> [1478032600.175609] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>+CREG: 1, 4B0, D3B363<CR><LF><CR><LF>+CGREG: 1, 4B0, D3B363<CR><LF>'
>ModemManager[1398]: <debug> [1478032600.185245] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '216', MNC: '30', Location area code: '4B0', Cell ID: 'D3B363')
>ModemManager[1398]: <debug> [1478032602.978270] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>+CREG: 1, 4B0, D38519<CR><LF><CR><LF>+CGREG: 1, 4B0, D38519<CR><LF>'
>ModemManager[1398]: <debug> [1478032602.978772] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '216', MNC: '30', Location area code: '4B0', Cell ID: 'D38519')
>ModemManager[1398]: <info>  [1478032612.200297] [main.c:46] quit_cb(): Caught signal, shutting down...
>ModemManager[1398]: <debug> [1478032612.215642] [mm-base-manager.c:849] set_property(): Stopping connection in object manager server
>ModemManager[1398]: <info>  [1478032612.216273] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disabling)
>ModemManager[1398]: <debug> [1478032612.216849] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (0)
>ModemManager[1398]: <debug> [1478032612.217019] [mm-iface-modem.c:1321] periodic_signal_quality_check_disable(): Periodic signal quality checks disabled
>ModemManager[1398]: <debug> [1478032612.217251] [mm-iface-modem.c:933] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (umts -> unknown)
>ModemManager[1398]: <debug> [1478032612.217383] [mm-iface-modem.c:1047] periodic_access_technologies_check_disable(): Periodic access technology checks disabled
>ModemManager[1398]: <debug> [1478032612.217518] [mm-base-bearer.c:874] mm_base_bearer_disconnect(): Disconnecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[1398]: <debug> [1478032612.217694] [mm-broadband-bearer.c:1639] disconnect_3gpp(): Sending PDP context deactivation in secondary port...
>ModemManager[1398]: <debug> [1478032612.217829] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032612.218650] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGACT=0,1<CR>'
>ModemManager[1398]: <debug> [1478032612.235034] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1398]: <debug> [1478032612.235364] [mm-broadband-bearer.c:1562] data_reopen_3gpp(): Reopening data port (ttyUSB2)...
>ModemManager[1398]: <debug> [1478032612.235480] [mm-port-serial.c:1608] mm_port_serial_reopen(): (ttyUSB2) reopening port (2)
>ModemManager[1398]: <debug> [1478032612.235543] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032612.235602] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 0 (close)
>ModemManager[1398]: <debug> [1478032612.235663] [mm-port-serial.c:1361] _close_internal(): (ttyUSB2) closing serial port...
>ModemManager[1398]: <debug> [1478032612.235731] [mm-port.c:95] mm_port_set_connected(): (ttyUSB2): port now disconnected
>ModemManager[1398]: <debug> [1478032612.235826] [mm-port-serial.c:1409] _close_internal(): (ttyUSB2) serial port closed
>ModemManager[1398]: <debug> [1478032612.235905] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032613.236022] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port...
>ModemManager[1398]: <warn>  [1478032613.238318] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
>ModemManager[1398]: <debug> [1478032613.239858] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
>ModemManager[1398]: <debug> [1478032613.241046] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
>ModemManager[1398]: <debug> [1478032613.241300] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032613.241702] [mm-broadband-bearer.c:1550] data_reopen_3gpp_ready(): Flashing data port (ttyUSB2)...
>ModemManager[1398]: <debug> [1478032613.242134] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
>ModemManager[1398]: <debug> [1478032614.242345] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
>ModemManager[1398]: <debug> [1478032614.244050] [mm-broadband-bearer.c:1508] data_flash_3gpp_ready(): PDP disconnection already sent
>ModemManager[1398]: <debug> [1478032614.246117] [mm-base-bearer.c:807] disconnect_ready(): Disconnected bearer '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[1398]: <debug> [1478032614.247773] [mm-broadband-modem.c:8861] disabling_step(): Modem has time capabilities, disabling the Time interface...
>ModemManager[1398]: <debug> [1478032614.253612] [mm-broadband-modem.c:8873] disabling_step(): Modem has messaging capabilities, disabling the Messaging interface...
>ModemManager[1398]: <debug> [1478032614.253964] [mm-broadband-modem.c:5888] set_messaging_unsolicited_events_handlers(): (ttyUSB2) Removing messaging unsolicited events handlers
>ModemManager[1398]: <debug> [1478032614.254115] [mm-broadband-modem.c:5888] set_messaging_unsolicited_events_handlers(): (ttyUSB1) Removing messaging unsolicited events handlers
>ModemManager[1398]: <debug> [1478032614.254844] [mm-broadband-modem.c:8885] disabling_step(): Modem has voice capabilities, disabling the Voice interface...
>ModemManager[1398]: <debug> [1478032614.255177] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB2) Removing voice unsolicited events handlers
>ModemManager[1398]: <debug> [1478032614.255317] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB1) Removing voice unsolicited events handlers
>ModemManager[1398]: <debug> [1478032614.256012] [mm-broadband-modem.c:8897] disabling_step(): Modem has location capabilities, disabling the Location interface...
>ModemManager[1398]: <debug> [1478032614.256221] [mm-iface-modem-location.c:768] setup_gathering(): Need to disable the following location sources: '3gpp-lac-ci'
>ModemManager[1398]: <debug> [1478032614.256415] [mm-iface-modem-location.c:667] setup_gathering_step(): Disabled location '3gpp-lac-ci' gathering...
>ModemManager[1398]: <debug> [1478032614.257033] [mm-broadband-modem.c:8925] disabling_step(): Modem has 3GPP/USSD capabilities, disabling the Modem 3GPP/USSD interface...
>ModemManager[1398]: <debug> [1478032614.257254] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
>ModemManager[1398]: <debug> [1478032616.791331] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
>ModemManager[1398]: <debug> [1478032620.343119] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'

Actions: View

Attachments on bug 91836: 118031 | 127674 | 127675 | 127676 | 127677

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