ModemManager[1961]: [1478028726.084486] [main.c:158] main(): ModemManager (version 1.6.2) starting in system bus... ModemManager[1961]: [1478028726.088299] [mm-sleep-monitor.c:245] mm_sleep_monitor_get(): create MMSleepMonitor singleton (0x9ca4cc0) ModemManager[1961]: [1478028726.098843] [main.c:83] bus_acquired_cb(): Bus acquired, creating manager... ModemManager[1961]: [1478028726.111526] [mm-plugin-manager.c:1559] load_plugins(): [plugin manager] looking for plugins in '/usr/lib/ModemManager' ModemManager[1961]: [1478028726.113388] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Longcheer' ModemManager[1961]: [1478028726.114393] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Novatel LTE' ModemManager[1961]: [1478028726.115211] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Linktop' ModemManager[1961]: [1478028726.116144] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Altair LTE' ModemManager[1961]: [1478028726.117327] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Telit' ModemManager[1961]: [1478028726.118147] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'MTK' ModemManager[1961]: [1478028726.119244] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Cinterion' ModemManager[1961]: [1478028726.120385] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Option' ModemManager[1961]: [1478028726.121203] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Generic' ModemManager[1961]: [1478028726.122268] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'ZTE' ModemManager[1961]: [1478028726.123136] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'X22X' ModemManager[1961]: [1478028726.124040] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Via CBP7' ModemManager[1961]: [1478028726.124896] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'AnyDATA' ModemManager[1961]: [1478028726.125943] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Ericsson MBM' ModemManager[1961]: [1478028726.126779] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Nokia' ModemManager[1961]: [1478028726.127740] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Pantech' ModemManager[1961]: [1478028726.128757] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Samsung' ModemManager[1961]: [1478028726.129920] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Huawei' ModemManager[1961]: [1478028726.130844] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Haier' ModemManager[1961]: [1478028726.131711] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Motorola' ModemManager[1961]: [1478028726.132763] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Iridium' ModemManager[1961]: [1478028726.133772] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Wavecom' ModemManager[1961]: [1478028726.134879] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Novatel' ModemManager[1961]: [1478028726.136195] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Dell' ModemManager[1961]: [1478028726.137266] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'SimTech' ModemManager[1961]: [1478028726.138494] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Nokia (Icera)' ModemManager[1961]: [1478028726.139912] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Option High-Speed' ModemManager[1961]: [1478028726.141884] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Thuraya' ModemManager[1961]: [1478028726.143144] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Sierra (legacy)' ModemManager[1961]: [1478028726.144209] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Sierra' ModemManager[1961]: [1478028726.144624] [mm-plugin-manager.c:1609] load_plugins(): [plugin manager] successfully loaded 30 plugins ModemManager[1961]: [1478028726.147347] [mm-sleep-monitor.c:108] take_inhibitor(): [sleep-monitor] taking systemd sleep inhibitor ModemManager[1961]: [1478028726.197218] [main.c:105] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired ModemManager[1961]: [1478028726.197432] [mm-base-manager.c:498] mm_base_manager_start(): Starting automatic device scan... ModemManager[1961]: [1478028726.250918] [mm-base-manager.c:537] mm_base_manager_start(): Finished device scan... ModemManager[1961]: [1478028726.252612] [mm-sleep-monitor.c:97] inhibit_done(): [sleep-monitor] inhibitor fd is 9 ModemManager[1961]: [1478028726.253887] [mm-base-manager.c:388] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted ModemManager[1961]: [1478028726.254840] [mm-base-manager.c:388] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted ModemManager[1961]: [1478028726.255788] [mm-base-manager.c:388] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted ModemManager[1961]: [1478028726.256712] [mm-base-manager.c:388] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted ModemManager[1961]: [1478028726.268666] [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[1961]: [1478028726.269274] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: enp2s0 ModemManager[1961]: [1478028726.269443] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 0,enp2s0: new support task for port ModemManager[1961]: [1478028726.270393] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 0,enp2s0: deferred until min wait time elapsed ModemManager[1961]: [1478028726.271987] [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[1961]: [1478028726.272411] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 1: port grabbed: wlan0 ModemManager[1961]: [1478028726.272551] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 1,wlan0: new support task for port ModemManager[1961]: [1478028726.272635] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 1,wlan0: deferred until min wait time elapsed ModemManager[1961]: [1478028727.771343] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 0: min wait time elapsed ModemManager[1961]: [1478028727.771643] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.771809] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.771943] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.772070] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.772196] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.772321] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.772509] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.772637] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.772762] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.772886] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.773008] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.773129] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.773264] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [enp2s0] filtered by udev tags ModemManager[1961]: [1478028727.773392] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.773522] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.773757] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.773919] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.774049] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.774167] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.774291] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.774416] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.774538] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.774662] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.774780] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.774902] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [enp2s0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.775030] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [enp2s0] filtered by drivers ModemManager[1961]: [1478028727.775155] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [enp2s0] filtered by subsystem ModemManager[1961]: [1478028727.775280] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [enp2s0] filtered by drivers ModemManager[1961]: [1478028727.775404] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [enp2s0] filtered by drivers ModemManager[1961]: [1478028727.775545] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 0,enp2s0: found '1' plugins to try ModemManager[1961]: [1478028727.775668] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,enp2s0: will try with plugin 'Generic' ModemManager[1961]: [1478028727.775820] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 0,enp2s0: started ModemManager[1961]: [1478028727.775953] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,enp2s0: checking with plugin 'Generic' ModemManager[1961]: [1478028727.776148] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [enp2s0] probing deferred until result suggested ModemManager[1961]: [1478028727.776433] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 1: min wait time elapsed ModemManager[1961]: [1478028727.776597] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.779542] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.780416] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.780784] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.780972] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.781131] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.781292] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.781456] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.781621] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.781792] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.781954] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.782116] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.782292] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [wlan0] filtered by udev tags ModemManager[1961]: [1478028727.782458] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.782625] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.782792] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.782952] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.783108] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.783270] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.783432] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.783599] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.783962] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.784140] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.784224] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.784309] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [wlan0] filtered by vendor/product IDs ModemManager[1961]: [1478028727.784395] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [wlan0] filtered by drivers ModemManager[1961]: [1478028727.784482] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [wlan0] filtered by subsystem ModemManager[1961]: [1478028727.784567] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [wlan0] filtered by drivers ModemManager[1961]: [1478028727.784691] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [wlan0] filtered by drivers ModemManager[1961]: [1478028727.784818] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 1,wlan0: found '1' plugins to try ModemManager[1961]: [1478028727.784906] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 1,wlan0: will try with plugin 'Generic' ModemManager[1961]: [1478028727.785019] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 1,wlan0: started ModemManager[1961]: [1478028727.785102] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 1,wlan0: checking with plugin 'Generic' ModemManager[1961]: [1478028727.785223] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [wlan0] probing deferred until result suggested ModemManager[1961]: [1478028727.785364] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 0,enp2s0: deferring support check until result suggested ModemManager[1961]: [1478028727.785521] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 1,wlan0: deferring support check until result suggested ModemManager[1961]: [1478028728.770774] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 0: min probing time elapsed ModemManager[1961]: [1478028728.771060] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 0: still 1 running probes (0 active): enp2s0 ModemManager[1961]: [1478028728.771236] [mm-plugin-manager.c:1009] device_context_continue(): [plugin manager] task 0: no active tasks to probe ModemManager[1961]: [1478028728.771382] [mm-plugin-manager.c:337] port_context_set_suggestion(): [plugin manager] task 0,enp2s0: deferred task completed, no suggested plugin ModemManager[1961]: [1478028728.771522] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 0,enp2s0: finished in '2.502086' seconds ModemManager[1961]: [1478028728.771857] [mm-plugin-manager.c:892] device_context_set_best_plugin(): [plugin manager] task 0,enp2s0: not supported by any plugin ModemManager[1961]: [1478028728.772026] [mm-device.c:373] mm_device_ignore_port(): Fully ignoring port 'net/enp2s0' from now on ModemManager[1961]: [1478028728.772182] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 0: no more ports to probe ModemManager[1961]: [1478028728.772317] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 0: finished in '2.503676' seconds ModemManager[1961]: [1478028728.772511] [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[1961]: [1478028728.772762] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 1: min probing time elapsed ModemManager[1961]: [1478028728.772902] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 1: still 1 running probes (0 active): wlan0 ModemManager[1961]: [1478028728.773029] [mm-plugin-manager.c:1009] device_context_continue(): [plugin manager] task 1: no active tasks to probe ModemManager[1961]: [1478028728.773151] [mm-plugin-manager.c:337] port_context_set_suggestion(): [plugin manager] task 1,wlan0: deferred task completed, no suggested plugin ModemManager[1961]: [1478028728.773281] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 1,wlan0: finished in '2.500735' seconds ModemManager[1961]: [1478028728.773434] [mm-plugin-manager.c:892] device_context_set_best_plugin(): [plugin manager] task 1,wlan0: not supported by any plugin ModemManager[1961]: [1478028728.773579] [mm-device.c:373] mm_device_ignore_port(): Fully ignoring port 'net/wlan0' from now on ModemManager[1961]: [1478028728.773849] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 1: no more ports to probe ModemManager[1961]: [1478028728.774042] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 1: finished in '2.502065' seconds ModemManager[1961]: [1478028728.774226] [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[1961]: [1478028748.900909] [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-5 ModemManager[1961]: [1478028748.906532] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 2: port grabbed: ttyUSB1 ModemManager[1961]: [1478028748.907179] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 2,ttyUSB1: new support task for port ModemManager[1961]: [1478028748.907794] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 2,ttyUSB1: deferred until min wait time elapsed ModemManager[1961]: [1478028748.915587] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 2: port grabbed: ttyUSB2 ModemManager[1961]: [1478028748.915803] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 2,ttyUSB2: new support task for port ModemManager[1961]: [1478028748.915909] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 2,ttyUSB2: deferred until min wait time elapsed ModemManager[1961]: [1478028748.927981] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 2: port grabbed: ttyUSB0 ModemManager[1961]: [1478028748.929291] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 2,ttyUSB0: new support task for port ModemManager[1961]: [1478028748.929442] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 2,ttyUSB0: deferred until min wait time elapsed ModemManager[1961]: [1478028750.407083] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 2: min wait time elapsed ModemManager[1961]: [1478028750.407355] [mm-plugin.c:376] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs ModemManager[1961]: [1478028750.407483] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs ModemManager[1961]: [1478028750.407577] [mm-plugin.c:376] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs ModemManager[1961]: [1478028750.407666] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs ModemManager[1961]: [1478028750.407757] [mm-plugin.c:376] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs ModemManager[1961]: [1478028750.407856] [mm-plugin.c:430] apply_pre_probing_filters(): (MTK) [ttyUSB0] filtered by udev tags ModemManager[1961]: [1478028750.407956] [mm-plugin.c:376] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs ModemManager[1961]: [1478028750.408058] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 2,ttyUSB0: found '2' plugins to try ModemManager[1961]: [1478028750.408148] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB0: will try with plugin 'ZTE' ModemManager[1961]: [1478028750.408228] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB0: will try with plugin 'Generic' ModemManager[1961]: [1478028750.408340] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 2,ttyUSB0: started ModemManager[1961]: [1478028750.408419] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 2,ttyUSB0: checking with plugin 'ZTE' ModemManager[1961]: [1478028750.408657] [mm-plugin.c:804] mm_plugin_supports_port(): (ZTE) [ttyUSB0] probe required: 'at, at-icera, qcdm' ModemManager[1961]: [1478028750.408805] [mm-port-probe.c:1377] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, at-icera, qcdm' ModemManager[1961]: [1478028750.408957] [mm-plugin.c:376] apply_pre_probing_filters(): (Longcheer) [ttyUSB2] filtered by vendor/product IDs ModemManager[1961]: [1478028750.409054] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB2] filtered by vendor/product IDs ModemManager[1961]: [1478028750.409143] [mm-plugin.c:376] apply_pre_probing_filters(): (Linktop) [ttyUSB2] filtered by vendor/product IDs ModemManager[1961]: [1478028750.409229] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [ttyUSB2] filtered by vendor/product IDs ModemManager[1961]: [1478028750.409312] [mm-plugin.c:376] apply_pre_probing_filters(): (Telit) [ttyUSB2] filtered by vendor/product IDs ModemManager[1961]: [1478028750.409407] [mm-plugin.c:430] apply_pre_probing_filters(): (MTK) [ttyUSB2] filtered by udev tags ModemManager[1961]: [1478028750.409500] [mm-plugin.c:376] apply_pre_probing_filters(): (Option) [ttyUSB2] filtered by vendor/product IDs ModemManager[1961]: [1478028750.409601] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 2,ttyUSB2: found '2' plugins to try ModemManager[1961]: [1478028750.409693] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB2: will try with plugin 'ZTE' ModemManager[1961]: [1478028750.409775] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB2: will try with plugin 'Generic' ModemManager[1961]: [1478028750.409878] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 2,ttyUSB2: started ModemManager[1961]: [1478028750.409963] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 2,ttyUSB2: checking with plugin 'ZTE' ModemManager[1961]: [1478028750.410107] [mm-plugin.c:804] mm_plugin_supports_port(): (ZTE) [ttyUSB2] probe required: 'at, at-icera, qcdm' ModemManager[1961]: [1478028750.410232] [mm-port-probe.c:1377] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, at-icera, qcdm' ModemManager[1961]: [1478028750.410846] [mm-plugin.c:376] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by vendor/product IDs ModemManager[1961]: [1478028750.410990] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB1] filtered by vendor/product IDs ModemManager[1961]: [1478028750.411083] [mm-plugin.c:376] apply_pre_probing_filters(): (Linktop) [ttyUSB1] filtered by vendor/product IDs ModemManager[1961]: [1478028750.411170] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [ttyUSB1] filtered by vendor/product IDs ModemManager[1961]: [1478028750.411252] [mm-plugin.c:376] apply_pre_probing_filters(): (Telit) [ttyUSB1] filtered by vendor/product IDs ModemManager[1961]: [1478028750.411348] [mm-plugin.c:430] apply_pre_probing_filters(): (MTK) [ttyUSB1] filtered by udev tags ModemManager[1961]: [1478028750.411452] [mm-plugin.c:376] apply_pre_probing_filters(): (Option) [ttyUSB1] filtered by vendor/product IDs ModemManager[1961]: [1478028750.411549] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 2,ttyUSB1: found '2' plugins to try ModemManager[1961]: [1478028750.411628] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB1: will try with plugin 'ZTE' ModemManager[1961]: [1478028750.411706] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,ttyUSB1: will try with plugin 'Generic' ModemManager[1961]: [1478028750.411813] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 2,ttyUSB1: started ModemManager[1961]: [1478028750.411900] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 2,ttyUSB1: checking with plugin 'ZTE' ModemManager[1961]: [1478028750.412111] [mm-plugin.c:804] mm_plugin_supports_port(): (ZTE) [ttyUSB1] probe required: 'at, at-icera, qcdm' ModemManager[1961]: [1478028750.412241] [mm-port-probe.c:1377] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, at-icera, qcdm' ModemManager[1961]: [1478028750.413354] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port... ModemManager[1961]: [1478028750.420541] [mm-port-serial.c:513] real_config_fd(): (ttyUSB0): port attributes not fully set ModemManager[1961]: [1478028750.420888] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) ModemManager[1961]: [1478028750.421532] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port... ModemManager[1961]: [1478028750.422420] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set ModemManager[1961]: [1478028750.422624] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open) ModemManager[1961]: [1478028750.423203] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1961]: [1478028750.423782] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1961]: [1478028750.424030] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1961]: [1478028750.521698] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?' ModemManager[1961]: [1478028750.523363] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0+CPMS?' ModemManager[1961]: [1478028750.525038] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATE0+CPMS?' ModemManager[1961]: [1478028751.406300] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 2: min probing time elapsed ModemManager[1961]: [1478028751.408317] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 2: still 3 running probes (3 active): ttyUSB0, ttyUSB2, ttyUSB1 ModemManager[1961]: [1478028751.542745] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'ATE0+CPMS?' ModemManager[1961]: [1478028751.547824] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'ERROR' ModemManager[1961]: [1478028751.548342] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028751.548551] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Unknown error' ModemManager[1961]: [1478028751.548784] [mm-port-probe.c:155] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable ModemManager[1961]: [1478028751.549085] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ATE0+CPMS?' ModemManager[1961]: [1478028751.549384] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT%IPSYS?' ModemManager[1961]: [1478028751.557318] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028751.557602] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028751.557756] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Unknown error' ModemManager[1961]: [1478028751.557877] [mm-port-probe.c:155] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is AT-capable ModemManager[1961]: [1478028751.558115] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT%IPSYS?' ModemManager[1961]: [1478028752.460751] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'ERROR' ModemManager[1961]: [1478028752.461151] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028752.461458] [mm-port-probe.c:947] serial_probe_at_parse_response(): (tty/ttyUSB2) re-scheduling next command in probing group in 2 seconds... ModemManager[1961]: [1478028752.465076] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028752.465470] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028752.465728] [mm-port-probe.c:947] serial_probe_at_parse_response(): (tty/ttyUSB1) re-scheduling next command in probing group in 2 seconds... ModemManager[1961]: [1478028755.189685] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out' ModemManager[1961]: [1478028755.190191] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT%IPSYS?' ModemManager[1961]: [1478028755.190527] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT%IPSYS?' ModemManager[1961]: [1478028755.190846] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?' ModemManager[1961]: [1478028756.096368] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028756.096877] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028756.101131] [mm-port-probe.c:947] serial_probe_at_parse_response(): (tty/ttyUSB1) re-scheduling next command in probing group in 2 seconds... ModemManager[1961]: [1478028756.102047] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'ERROR' ModemManager[1961]: [1478028756.102453] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028756.102652] [mm-port-probe.c:947] serial_probe_at_parse_response(): (tty/ttyUSB2) re-scheduling next command in probing group in 2 seconds... ModemManager[1961]: [1478028758.189458] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT%IPSYS?' ModemManager[1961]: [1478028758.189872] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT%IPSYS?' ModemManager[1961]: [1478028759.094466] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028759.094876] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028759.095176] [mm-port-probe.c:227] mm_port_probe_set_result_at_icera(): (tty/ttyUSB1) Modem is probably not Icera-based ModemManager[1961]: [1478028759.095423] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 2,ttyUSB1: found best plugin for port (ZTE) ModemManager[1961]: [1478028759.095670] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 2,ttyUSB1: finished in '10.188504' seconds ModemManager[1961]: [1478028759.095893] [mm-plugin-manager.c:913] device_context_set_best_plugin(): [plugin manager] task 2,ttyUSB1: found best plugin: ZTE ModemManager[1961]: [1478028759.096096] [mm-plugin-manager.c:366] port_context_set_suggestion(): [plugin manager] task 2,ttyUSB0: got suggested plugin (ZTE) ModemManager[1961]: [1478028759.096274] [mm-plugin-manager.c:366] port_context_set_suggestion(): [plugin manager] task 2,ttyUSB2: got suggested plugin (ZTE) ModemManager[1961]: [1478028759.096477] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 2: still 2 running probes (2 active): ttyUSB0, ttyUSB2 ModemManager[1961]: [1478028759.096756] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close) ModemManager[1961]: [1478028759.096933] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port... ModemManager[1961]: [1478028759.125963] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed ModemManager[1961]: [1478028759.126816] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB1) forced to close port ModemManager[1961]: [1478028759.127702] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'ERROR' ModemManager[1961]: [1478028759.128061] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028759.128285] [mm-port-probe.c:227] mm_port_probe_set_result_at_icera(): (tty/ttyUSB2) Modem is probably not Icera-based ModemManager[1961]: [1478028759.128442] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 2,ttyUSB2: found best plugin for port (ZTE) ModemManager[1961]: [1478028759.128564] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 2,ttyUSB2: finished in '10.212771' seconds ModemManager[1961]: [1478028759.128709] [mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin manager] task 2,ttyUSB2: best plugin matches device reported one: ZTE ModemManager[1961]: [1478028759.128845] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 2: still 1 running probes (1 active): ttyUSB0 ModemManager[1961]: [1478028759.129068] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 0 (close) ModemManager[1961]: [1478028759.129209] [mm-port-serial.c:1361] _close_internal(): (ttyUSB2) closing serial port... ModemManager[1961]: [1478028759.145383] [mm-port-serial.c:1409] _close_internal(): (ttyUSB2) serial port closed ModemManager[1961]: [1478028759.145664] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB2) forced to close port ModemManager[1961]: [1478028759.188126] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out' ModemManager[1961]: [1478028759.188576] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?' ModemManager[1961]: [1478028763.191043] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out' ModemManager[1961]: [1478028763.191322] [mm-port-probe.c:165] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable ModemManager[1961]: [1478028763.191537] [mm-port-probe.c:691] serial_probe_qcdm(): (tty/ttyUSB0) probing QCDM... ModemManager[1961]: [1478028763.191675] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close) ModemManager[1961]: [1478028763.191768] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port... ModemManager[1961]: [1478028763.205582] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed ModemManager[1961]: [1478028763.205874] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB0) forced to close port ModemManager[1961]: [1478028763.206150] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port... ModemManager[1961]: [1478028763.207049] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) ModemManager[1961]: [1478028763.207349] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e ModemManager[1961]: [1478028763.209761] [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[1961]: [1478028763.210073] [mm-port-probe.c:243] mm_port_probe_set_result_qcdm(): (tty/ttyUSB0) port is QCDM-capable ModemManager[1961]: [1478028763.210213] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 2,ttyUSB0: found best plugin for port (ZTE) ModemManager[1961]: [1478028763.210414] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 2,ttyUSB0: finished in '14.281133' seconds ModemManager[1961]: [1478028763.210563] [mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin manager] task 2,ttyUSB0: best plugin matches device reported one: ZTE ModemManager[1961]: [1478028763.210691] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 2: no more ports to probe ModemManager[1961]: [1478028763.210779] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 2: finished in '14.309880' seconds ModemManager[1961]: [1478028763.210948] [mm-device.c:525] mm_device_create_modem(): Creating modem with plugin 'ZTE' and '3' ports ModemManager[1961]: [1478028763.216299] [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-5 ModemManager[1961]: [1478028763.216850] [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-5 ModemManager[1961]: [1478028763.217463] [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-5 ModemManager[1961]: [1478028763.217666] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-5) tty/ttyUSB1 at (primary) ModemManager[1961]: [1478028763.217772] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-5) tty/ttyUSB2 at (secondary) ModemManager[1961]: [1478028763.217858] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-5) tty/ttyUSB1 data (primary) ModemManager[1961]: [1478028763.217939] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-5) tty/ttyUSB0 qcdm ModemManager[1961]: [1478028763.219226] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1961]: [1478028763.219969] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1961]: [1478028763.220259] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1961]: [1478028763.220468] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB1): running init sequence... ModemManager[1961]: [1478028763.220691] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028763.220855] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.221042] [mm-base-manager.c:175] device_support_check_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-5' successfully created ModemManager[1961]: [1478028763.221241] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close) ModemManager[1961]: [1478028763.221348] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port... ModemManager[1961]: [1478028763.235380] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed ModemManager[1961]: [1478028763.235631] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB0) forced to close port ModemManager[1961]: [1478028763.238759] [mm-broadband-modem.c:710] modem_load_current_capabilities(): loading current capabilities... ModemManager[1961]: [1478028763.238936] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port... ModemManager[1961]: [1478028763.239644] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) ModemManager[1961]: [1478028763.239906] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATE0' ModemManager[1961]: [1478028763.240673] [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[1961]: [1478028763.243551] [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[1961]: [1478028763.243943] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 4 (open) ModemManager[1961]: [1478028763.247802] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028763.248252] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATV1' ModemManager[1961]: [1478028763.254287] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028763.254737] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMEE=1' ModemManager[1961]: [1478028763.266893] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028763.267456] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATX4' ModemManager[1961]: [1478028763.273724] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028763.274087] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028763.274403] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT&C1' ModemManager[1961]: [1478028763.281711] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028763.282046] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028763.282288] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATE0' ModemManager[1961]: [1478028763.288338] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028763.288703] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 3 (close) ModemManager[1961]: [1478028763.288939] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMEE=1' ModemManager[1961]: [1478028763.301683] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028763.302086] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.302355] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+GCAP' ModemManager[1961]: [1478028763.312220] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028763.312610] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028763.312920] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATI' ModemManager[1961]: [1478028763.318707] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'Manufacturer: ZTE CORPORATIONModel: MF667Revision: BD_TMODEMF667V1.0.0B01IMEI: 359905017792331+GCAP: +CGSM,+DS,+ESOK' ModemManager[1961]: [1478028763.319280] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.319523] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.319711] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+WS46=?' ModemManager[1961]: [1478028763.332111] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028763.332607] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028763.332864] [mm-broadband-modem.c:364] modem_load_current_capabilities_finish(): loaded current capabilities: gsm-umts ModemManager[1961]: [1478028763.333314] [mm-iface-modem.c:4345] interface_initialization_step(): Modem allows up to 1 bearers ModemManager[1961]: [1478028763.333472] [mm-bearer-list.c:270] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1) ModemManager[1961]: [1478028763.334032] [mm-broadband-modem.c:770] modem_load_manufacturer(): loading manufacturer... ModemManager[1961]: [1478028763.334260] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.334499] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close) ModemManager[1961]: [1478028763.334651] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port... ModemManager[1961]: [1478028763.348577] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed ModemManager[1961]: [1478028763.348840] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.349104] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGMI' ModemManager[1961]: [1478028763.358892] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ZTE CORPORATIONOK' ModemManager[1961]: [1478028763.359377] [mm-broadband-modem.c:754] modem_load_manufacturer_finish(): loaded manufacturer: ZTE CORPORATION ModemManager[1961]: [1478028763.359653] [mm-broadband-modem.c:810] modem_load_model(): loading model... ModemManager[1961]: [1478028763.359843] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.360075] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.360469] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGMM' ModemManager[1961]: [1478028763.370414] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'MF667OK' ModemManager[1961]: [1478028763.370831] [mm-broadband-modem.c:794] modem_load_model_finish(): loaded model: MF667 ModemManager[1961]: [1478028763.371066] [mm-broadband-modem.c:850] modem_load_revision(): loading revision... ModemManager[1961]: [1478028763.371226] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.371423] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.371615] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGMR' ModemManager[1961]: [1478028763.381433] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'BD_TMODEMF667V1.0.0B01OK' ModemManager[1961]: [1478028763.381898] [mm-broadband-modem.c:834] modem_load_revision_finish(): loaded revision: BD_TMODEMF667V1.0.0B01 ModemManager[1961]: [1478028763.382138] [mm-broadband-modem.c:911] modem_load_equipment_identifier(): loading equipment identifier... ModemManager[1961]: [1478028763.382314] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.382511] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.382703] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGSN' ModemManager[1961]: [1478028763.394517] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '359905017792331OK' ModemManager[1961]: [1478028763.395027] [mm-broadband-modem.c:893] modem_load_equipment_identifier_finish(): loaded equipment identifier: 359905017792331 ModemManager[1961]: [1478028763.395322] [mm-broadband-modem.c:999] modem_load_device_identifier(): loading device identifier... ModemManager[1961]: [1478028763.395485] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.395689] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.395880] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATI' ModemManager[1961]: [1478028763.401526] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'Manufacturer: ZTE CORPORATIONModel: MF667Revision: BD_TMODEMF667V1.0.0B01IMEI: 359905017792331+GCAP: +CGSM,+DS,+ESOK' ModemManager[1961]: [1478028763.402422] [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[1961]: [1478028763.402608] [mm-modem-helpers.c:212] mm_create_device_identifier(): Device ID '2f59e97129882b30546f7987b4a4637e5cf5c9a2' ModemManager[1961]: [1478028763.402739] [mm-broadband-modem.c:962] modem_load_device_identifier_finish(): loaded device identifier: 2f59e97129882b30546f7987b4a4637e5cf5c9a2 ModemManager[1961]: [1478028763.403019] [mm-broadband-modem.c:1613] modem_load_supported_modes(): loading supported modes... ModemManager[1961]: [1478028763.403249] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.403492] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.403882] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT*CNTI=2' ModemManager[1961]: [1478028763.416330] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028763.416712] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028763.416884] [mm-broadband-modem.c:1547] supported_modes_cnti_ready(): Generic query of supported 3GPP networks with *CNTI failed: 'Unknown error' ModemManager[1961]: [1478028763.417087] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.417287] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.417449] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+WS46=?' ModemManager[1961]: [1478028763.429588] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028763.429974] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028763.430167] [mm-broadband-modem.c:1495] supported_modes_ws46_test_ready(): Generic query of supported 3GPP networks with WS46=? failed: 'Unknown error' ModemManager[1961]: [1478028763.430436] [mm-iface-modem.c:3974] load_supported_modes_ready(): couldn't load Supported Modes: 'Couldn't retrieve supported modes' ModemManager[1961]: [1478028763.430797] [mm-broadband-modem.c:1687] modem_load_supported_ip_families(): loading supported IP families... ModemManager[1961]: [1478028763.431001] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.431200] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.431377] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGDCONT=?' ModemManager[1961]: [1478028763.447323] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028763.447660] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028763.447835] [mm-iface-modem.c:4023] load_supported_ip_families_ready(): couldn't load Supported IP families: 'Unknown error' ModemManager[1961]: [1478028763.448009] [mm-broadband-modem.c:3144] load_power_state(): loading power state... ModemManager[1961]: [1478028763.448162] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.448351] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.448528] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CFUN?' ModemManager[1961]: [1478028763.460082] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CFUN: 1OK' ModemManager[1961]: [1478028763.460768] [mm-broadband-modem.c:1302] modem_load_unlock_required(): checking if unlock required... ModemManager[1961]: [1478028763.460976] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.461170] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.461365] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPIN?' ModemManager[1961]: [1478028763.472333] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CPIN: SIM PINOK' ModemManager[1961]: [1478028763.472781] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028763.473065] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028763.473309] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+ZPINPUK=?' ModemManager[1961]: [1478028763.489213] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+ZPINPUK: 3,10OK' ModemManager[1961]: [1478028763.490184] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem: state changed (unknown -> locked) ModemManager[1961]: [1478028763.490907] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028763.492250] [mm-base-sim.c:1004] load_sim_identifier(): loading SIM identifier... ModemManager[1961]: [1478028763.492494] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028763.492773] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CRSM=176,12258,0,0,10' ModemManager[1961]: [1478028763.637029] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CRSM: 144,0,"986303424130117343F7"OK' ModemManager[1961]: [1478028763.637610] [mm-base-sim.c:993] load_sim_identifier_finish(): loaded SIM identifier: 8936302414031137347 ModemManager[1961]: [1478028763.637856] [mm-base-sim.c:1074] load_imsi(): loading IMSI... ModemManager[1961]: [1478028763.638012] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.638187] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.638358] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CIMI' ModemManager[1961]: [1478028763.649593] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028763.649932] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028763.650163] [mm-base-sim.c:1423] load_imsi_ready(): couldn't load IMSI: 'Unknown error' ModemManager[1961]: [1478028763.650459] [mm-base-sim.c:1194] load_operator_identifier(): loading Operator ID... ModemManager[1961]: [1478028763.650630] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.650790] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.650956] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CRSM=176,28589,0,0,4' ModemManager[1961]: [1478028763.764511] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CRSM: 103,0,""OK' ModemManager[1961]: [1478028763.765257] [mm-base-sim.c:1424] load_operator_identifier_ready(): couldn't load Operator identifier: 'Cannot load Operator ID without IMSI' ModemManager[1961]: [1478028763.765506] [mm-base-sim.c:1289] load_operator_name(): loading Operator Name... ModemManager[1961]: [1478028763.765748] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028763.766043] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028763.766328] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CRSM=176,28486,0,0,17' ModemManager[1961]: [1478028763.888432] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CRSM: 144,0,"00542D4D6F62696C652048FFFFFFFFFFFF"OK' ModemManager[1961]: [1478028763.889603] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028763.890517] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port... ModemManager[1961]: [1478028763.891482] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) ModemManager[1961]: [1478028763.891710] [mm-broadband-modem.c:1180] modem_load_own_numbers(): loading own numbers... ModemManager[1961]: [1478028763.891940] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028763.892303] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CNUM' ModemManager[1961]: [1478028763.902774] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028763.903139] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028763.903506] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028763.904364] [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[1961]: [1478028763.907152] [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[1961]: [1478028763.907427] [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[1961]: [1478028763.908135] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close) ModemManager[1961]: [1478028763.908297] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port... ModemManager[1961]: [1478028763.922375] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed ModemManager[1961]: [1478028763.924101] [mm-broadband-modem.c:9635] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware interface not available' ModemManager[1961]: [1478028763.924930] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close) ModemManager[1961]: [1478028763.925118] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port... ModemManager[1961]: [1478028763.939207] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed ModemManager[1961]: [1478028763.939654] [mm-base-modem.c:844] initialize_ready(): Couldn't finish initialization in the current state: 'Modem is currently locked, cannot fully initialize' ModemManager[1961]: [1478028763.943070] [mm-device.c:452] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-5' at path '/org/freedesktop/ModemManager1/Modem/0' ModemManager[1961]: [1478028763.945629] [mm-device.c:462] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'ZTE' modem, VID 0x19D2 PID 0x0117 (usb) ModemManager[1961]: [1478028764.125118] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1961]: [1478028764.125934] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1961]: [1478028764.126163] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1961]: [1478028764.126497] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPIN="0000"' ModemManager[1961]: [1478028764.246555] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028764.247336] [mm-broadband-modem.c:1302] modem_load_unlock_required(): checking if unlock required... ModemManager[1961]: [1478028764.247680] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028764.247999] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028764.248280] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPIN?' ModemManager[1961]: [1478028764.259847] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CPIN: READYOK' ModemManager[1961]: [1478028764.260458] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close) ModemManager[1961]: [1478028764.260634] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port... ModemManager[1961]: [1478028764.273875] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed ModemManager[1961]: [1478028764.274244] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1961]: [1478028764.274923] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1961]: [1478028764.275170] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1961]: [1478028764.275473] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+ZPINPUK=?' ModemManager[1961]: [1478028764.291532] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+ZPINPUK: 3,10OK' ModemManager[1961]: [1478028764.292159] [mm-iface-modem.c:3101] update_lock_info_context_step(): SIM is ready, running after SIM unlock step... ModemManager[1961]: [1478028764.292426] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028764.292680] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028764.292968] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPMS?' ModemManager[1961]: [1478028764.304488] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CME ERROR: 14' ModemManager[1961]: [1478028764.304870] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy ModemManager[1961]: [1478028764.305102] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close) ModemManager[1961]: [1478028764.305226] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port... ModemManager[1961]: [1478028764.319215] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed ModemManager[1961]: [1478028766.189549] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1961]: [1478028766.190882] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1961]: [1478028766.191181] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1961]: [1478028766.191482] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPMS?' ModemManager[1961]: [1478028766.203216] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CME ERROR: 14' ModemManager[1961]: [1478028766.203745] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy ModemManager[1961]: [1478028766.204059] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close) ModemManager[1961]: [1478028766.204222] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port... ModemManager[1961]: [1478028766.217279] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed ModemManager[1961]: [1478028768.189797] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1961]: [1478028768.190768] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1961]: [1478028768.191184] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1961]: [1478028768.191584] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPMS?' ModemManager[1961]: [1478028768.206835] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CPMS: "SM",0,20,"SM",0,20,"ME",0,100OK' ModemManager[1961]: [1478028768.207592] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close) ModemManager[1961]: [1478028768.207755] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port... ModemManager[1961]: [1478028768.222084] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed ModemManager[1961]: [1478028768.223946] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (locked -> initializing) ModemManager[1961]: [1478028768.226694] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1961]: [1478028768.230750] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1961]: [1478028768.231008] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1961]: [1478028768.231108] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB1): running init sequence... ModemManager[1961]: [1478028768.231325] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028768.231496] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028768.231798] [mm-broadband-modem.c:1613] modem_load_supported_modes(): loading supported modes... ModemManager[1961]: [1478028768.232029] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 4 (open) ModemManager[1961]: [1478028768.232232] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATE0' ModemManager[1961]: [1478028768.245217] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028768.245648] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATV1' ModemManager[1961]: [1478028768.252201] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028768.252636] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMEE=1' ModemManager[1961]: [1478028768.264954] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028768.265387] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATX4' ModemManager[1961]: [1478028768.271824] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028768.272255] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT&C1' ModemManager[1961]: [1478028768.279911] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028768.280542] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATE0' ModemManager[1961]: [1478028768.290718] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028768.291044] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 3 (close) ModemManager[1961]: [1478028768.291259] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMEE=1' ModemManager[1961]: [1478028768.308493] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028768.308842] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028768.309060] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT*CNTI=2' ModemManager[1961]: [1478028768.322337] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'ERROR' ModemManager[1961]: [1478028768.322836] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error ModemManager[1961]: [1478028768.323072] [mm-broadband-modem.c:1547] supported_modes_cnti_ready(): Generic query of supported 3GPP networks with *CNTI failed: 'Unknown error' ModemManager[1961]: [1478028768.323225] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028768.323390] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028768.323555] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+WS46=?' ModemManager[1961]: [1478028768.336090] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+WS46: (12,22,25)OK' ModemManager[1961]: [1478028768.336520] [mm-broadband-modem.c:1450] supported_modes_ws46_test_ready(): Device allows (3GPP) 2G-only network mode ModemManager[1961]: [1478028768.336645] [mm-broadband-modem.c:1455] supported_modes_ws46_test_ready(): Device allows (3GPP) 3G-only network mode ModemManager[1961]: [1478028768.336765] [mm-broadband-modem.c:1484] supported_modes_ws46_test_ready(): Device allows every supported 3GPP network mode (2G/3G) ModemManager[1961]: [1478028768.337396] [mm-broadband-modem.c:1687] modem_load_supported_ip_families(): loading supported IP families... ModemManager[1961]: [1478028768.337617] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028768.337812] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028768.338764] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGDCONT=?' ModemManager[1961]: [1478028768.359845] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CGDCONT: (1-16),"IP",,,(0-2),(0-4)+CGDCONT: (1-16),"PPP",,,(0-2),(0-4)+CGDCONT: (1-16),"IPV6",,,(0-2),(0-4)OK' ModemManager[1961]: [1478028768.360551] [mm-modem-helpers.c:895] mm_3gpp_parse_cgdcont_test_response(): Unhandled PDP type in CGDCONT=? reply: 'PPP' ModemManager[1961]: [1478028768.360880] [mm-base-sim.c:1074] load_imsi(): loading IMSI... ModemManager[1961]: [1478028768.361045] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028768.361213] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028768.362176] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CIMI' ModemManager[1961]: [1478028768.388513] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '216306021723106OK' ModemManager[1961]: [1478028768.388920] [mm-base-sim.c:1063] load_imsi_finish(): loaded IMSI: 216306021723106 ModemManager[1961]: [1478028768.389135] [mm-base-sim.c:1194] load_operator_identifier(): loading Operator ID... ModemManager[1961]: [1478028768.389286] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028768.389451] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028768.390438] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CRSM=176,28589,0,0,4' ModemManager[1961]: [1478028768.817124] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CRSM: 103,0,""OK' ModemManager[1961]: [1478028768.818034] [mm-base-sim.c:1424] load_operator_identifier_ready(): couldn't load Operator identifier: 'Failed to parse CRSM query result '+CRSM: 103,0,""'' ModemManager[1961]: [1478028768.818395] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028768.818722] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port... ModemManager[1961]: [1478028768.819680] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) ModemManager[1961]: [1478028768.819970] [mm-broadband-modem.c:1180] modem_load_own_numbers(): loading own numbers... ModemManager[1961]: [1478028768.820212] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028768.820701] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CNUM' ModemManager[1961]: [1478028768.831450] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CME ERROR: 14' ModemManager[1961]: [1478028768.831900] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 14: SIM busy ModemManager[1961]: [1478028768.832141] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028768.832690] [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[1961]: [1478028768.835645] [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[1961]: [1478028768.835899] [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[1961]: [1478028768.836129] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028768.836326] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close) ModemManager[1961]: [1478028768.836431] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port... ModemManager[1961]: [1478028768.850585] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed ModemManager[1961]: [1478028768.850920] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+ZSNT?' ModemManager[1961]: [1478028768.865521] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+ZSNT: 0,0,0OK' ModemManager[1961]: [1478028768.866547] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028768.868393] [mm-broadband-modem.c:3247] modem_3gpp_load_imei(): loading IMEI... ModemManager[1961]: [1478028768.868641] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028768.868930] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGSN' ModemManager[1961]: [1478028768.881183] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '359905017792331OK' ModemManager[1961]: [1478028768.881670] [mm-broadband-modem.c:3238] modem_3gpp_load_imei_finish(): loaded IMEI: 359905017792331 ModemManager[1961]: [1478028768.882031] [mm-broadband-modem.c:3406] modem_3gpp_load_enabled_facility_locks(): loading enabled facility locks... ModemManager[1961]: [1478028768.882324] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028768.882674] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028768.883009] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CLCK=?' ModemManager[1961]: [1478028768.897138] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CLCK: ("AB","AC","AG","AI","AO","IR","OI","OX","SC","FD","PN","PU","PP","PC","PF")OK' ModemManager[1961]: [1478028768.897754] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028768.897991] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028768.898184] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CLCK="SC",2' ModemManager[1961]: [1478028768.967090] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CLCK: 1OK' ModemManager[1961]: [1478028768.968014] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028768.968363] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028768.968646] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CLCK="FD",2' ModemManager[1961]: [1478028769.043431] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CLCK: 0OK' ModemManager[1961]: [1478028769.044649] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028769.045189] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028769.045567] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CLCK="PF",2' ModemManager[1961]: [1478028769.166241] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CLCK: 0OK' ModemManager[1961]: [1478028769.166874] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028769.167292] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028769.167540] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CLCK="PN",2' ModemManager[1961]: [1478028769.310495] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CLCK: 0OK' ModemManager[1961]: [1478028769.311358] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028769.311709] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028769.311990] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CLCK="PU",2' ModemManager[1961]: [1478028769.449920] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CLCK: 0OK' ModemManager[1961]: [1478028769.450817] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028769.451205] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028769.451504] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CLCK="PP",2' ModemManager[1961]: [1478028769.578487] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CLCK: 0OK' ModemManager[1961]: [1478028769.579109] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028769.579432] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028769.579666] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CLCK="PC",2' ModemManager[1961]: [1478028769.705800] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CLCK: 0OK' ModemManager[1961]: [1478028769.711117] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028769.712663] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028769.713028] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CUSD=?' ModemManager[1961]: [1478028769.725405] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CUSD: (0-2)OK' ModemManager[1961]: [1478028769.726522] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028769.732010] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028769.732313] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CNMI=?' ModemManager[1961]: [1478028769.747284] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CNMI: (0,1,2,3),(0,1,2,3),(0,2),(0,1,2),(0,1)OK' ModemManager[1961]: [1478028769.747717] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028769.747938] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028769.748093] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPMS=?' ModemManager[1961]: [1478028769.764084] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CPMS: ("ME","MT","SM","SR"),("ME","MT","SM","SR"),("ME","MT","SM","SR")OK' ModemManager[1961]: [1478028769.764988] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): Supported storages loaded: ModemManager[1961]: [1478028769.765126] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready(): mem1 (list/read/delete) storages: 'me, mt, sm, sr' ModemManager[1961]: [1478028769.765212] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready(): mem2 (write/send) storages: 'me, mt, sm, sr' ModemManager[1961]: [1478028769.765292] [mm-iface-modem-messaging.c:1152] load_supported_storages_ready(): mem3 (reception) storages: 'me, mt, sm, sr' ModemManager[1961]: [1478028769.765530] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028769.765715] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028769.765884] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPMS?' ModemManager[1961]: [1478028769.777828] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CPMS: "SM",0,20,"SM",0,20,"ME",0,100OK' ModemManager[1961]: [1478028769.778351] [mm-broadband-modem.c:5283] cpms_query_ready(): Current storages initialized: ModemManager[1961]: [1478028769.778502] [mm-broadband-modem.c:5285] cpms_query_ready(): mem1 (list/read/delete) storages: 'sm' ModemManager[1961]: [1478028769.778593] [mm-broadband-modem.c:5287] cpms_query_ready(): mem2 (write/send) storages: 'sm' ModemManager[1961]: [1478028769.778685] [mm-iface-modem-messaging.c:1232] init_current_storages_ready(): Current storages initialized ModemManager[1961]: [1478028769.779857] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028769.780688] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028769.780977] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATH' ModemManager[1961]: [1478028769.799067] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028769.803255] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028769.804273] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028769.804535] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CTZU=1' ModemManager[1961]: [1478028769.828884] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028769.830475] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CCLK?' ModemManager[1961]: [1478028769.842630] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CCLK: "80/01/06,00:00:22"OK' ModemManager[1961]: [1478028769.844636] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028769.845525] [mm-broadband-modem.c:9633] iface_modem_signal_initialize_ready(): Couldn't initialize interface: 'Extended Signal information not supported' ModemManager[1961]: [1478028769.846660] [mm-broadband-modem.c:9634] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported' ModemManager[1961]: [1478028769.849054] [mm-broadband-modem.c:9635] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware interface not available' ModemManager[1961]: [1478028769.849326] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (initializing -> disabled) ModemManager[1961]: [1478028769.858025] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close) ModemManager[1961]: [1478028769.858228] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port... ModemManager[1961]: [1478028769.880556] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed ModemManager[1961]: [1478028769.892218] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling) ModemManager[1961]: [1478028769.893192] [mm-broadband-modem.c:8536] enabling_started(): Skipping modem initialization: device hotplugged ModemManager[1961]: [1478028769.893341] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port... ModemManager[1961]: [1478028769.897950] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set ModemManager[1961]: [1478028769.898195] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open) ModemManager[1961]: [1478028769.898306] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB1): running init sequence... ModemManager[1961]: [1478028769.898521] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port... ModemManager[1961]: [1478028769.899417] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set ModemManager[1961]: [1478028769.899650] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open) ModemManager[1961]: [1478028769.899762] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence... ModemManager[1961]: [1478028769.899896] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port... ModemManager[1961]: [1478028769.900851] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open) ModemManager[1961]: [1478028769.901041] [mm-broadband-modem.c:8552] enabling_started(): Flashing primary AT port before enabling... ModemManager[1961]: [1478028769.901263] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATE0' ModemManager[1961]: [1478028769.901461] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0' ModemManager[1961]: [1478028769.908050] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028769.908421] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATV1' ModemManager[1961]: [1478028769.914601] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1961]: [1478028769.915078] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028769.915399] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMEE=1' ModemManager[1961]: [1478028769.927812] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028769.928254] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'ATX4' ModemManager[1961]: [1478028769.936252] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028769.936731] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT&C1' ModemManager[1961]: [1478028769.944167] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.002061] [mm-broadband-modem.c:3144] load_power_state(): loading power state... ModemManager[1961]: [1478028770.002496] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.002908] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CFUN?' ModemManager[1961]: [1478028770.015516] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CFUN: 1OK' ModemManager[1961]: [1478028770.015970] [mm-iface-modem.c:3293] set_power_state(): No need to change power state: already in 'on' power state ModemManager[1961]: [1478028770.016247] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.016523] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.016882] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.017282] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+IFC=1,1' ModemManager[1961]: [1478028770.032256] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.032624] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.032860] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CSCS=?' ModemManager[1961]: [1478028770.048434] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CSCS: ("IRA","GSM","UCS2")OK' ModemManager[1961]: [1478028770.049110] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.049389] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.049614] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CSCS="UCS2"' ModemManager[1961]: [1478028770.068350] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.068807] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.069007] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.069169] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CSCS?' ModemManager[1961]: [1478028770.081335] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CSCS: "UCS2"OK' ModemManager[1961]: [1478028770.081860] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.082098] [mm-broadband-modem.c:9197] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface... ModemManager[1961]: [1478028770.082260] [mm-broadband-modem.c:2606] modem_3gpp_setup_unsolicited_events(): Checking indicator support... ModemManager[1961]: [1478028770.082411] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.082656] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CIND=?' ModemManager[1961]: [1478028770.097305] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+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))OK' ModemManager[1961]: [1478028770.097951] [mm-broadband-modem.c:2559] cind_format_check_ready(): Modem supports signal quality indications via CIND at index '2'(min: 0, max: 5) ModemManager[1961]: [1478028770.098113] [mm-broadband-modem.c:2568] cind_format_check_ready(): Modem supports roaming indications via CIND at index '5' ModemManager[1961]: [1478028770.098210] [mm-broadband-modem.c:2577] cind_format_check_ready(): Modem supports service indications via CIND at index '3' ModemManager[1961]: [1478028770.098369] [mm-broadband-modem.c:2510] set_unsolicited_events_handlers(): (ttyUSB1) Setting 3GPP unsolicited events handlers ModemManager[1961]: [1478028770.098500] [mm-broadband-modem.c:2510] set_unsolicited_events_handlers(): (ttyUSB2) Setting 3GPP unsolicited events handlers ModemManager[1961]: [1478028770.098709] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.098915] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.099178] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMER=3,0,0,1' ModemManager[1961]: [1478028770.119409] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.119832] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1961]: [1478028770.120061] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.120265] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMER=3,0,0,1' ModemManager[1961]: [1478028770.146577] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1961]: [1478028770.147994] [mm-broadband-modem.c:3606] modem_3gpp_setup_unsolicited_registration_events(): (ttyUSB1) setting up 3GPP unsolicited registration messages handlers ModemManager[1961]: [1478028770.148230] [mm-broadband-modem.c:3606] modem_3gpp_setup_unsolicited_registration_events(): (ttyUSB2) setting up 3GPP unsolicited registration messages handlers ModemManager[1961]: [1478028770.148439] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close) ModemManager[1961]: [1478028770.148726] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.148999] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CREG=2' ModemManager[1961]: [1478028770.163178] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.163585] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1961]: [1478028770.163913] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.164094] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CREG=2' ModemManager[1961]: [1478028770.176536] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1961]: [1478028770.177024] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.177259] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close) ModemManager[1961]: [1478028770.177438] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGREG=2' ModemManager[1961]: [1478028770.191007] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.191550] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1961]: [1478028770.191833] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.192065] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGREG=2' ModemManager[1961]: [1478028770.210826] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1961]: [1478028770.211244] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close) ModemManager[1961]: [1478028770.211572] [mm-broadband-modem.c:9210] enabling_step(): Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface... ModemManager[1961]: [1478028770.211819] [mm-broadband-modem.c:4920] set_unsolicited_result_code_handlers(): (ttyUSB1) Setting unsolicited result code handlers ModemManager[1961]: [1478028770.211970] [mm-broadband-modem.c:4920] set_unsolicited_result_code_handlers(): (ttyUSB2) Setting unsolicited result code handlers ModemManager[1961]: [1478028770.212255] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.212531] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CUSD=1' ModemManager[1961]: [1478028770.225034] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.225553] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.226499] [mm-broadband-modem.c:9238] enabling_step(): Modem has location capabilities, enabling the Location interface... ModemManager[1961]: [1478028770.226731] [mm-iface-modem-location.c:762] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci' ModemManager[1961]: [1478028770.227093] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') ModemManager[1961]: [1478028770.227291] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.228275] [mm-broadband-modem.c:9251] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface... ModemManager[1961]: [1478028770.228649] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.228865] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CREG?' ModemManager[1961]: [1478028770.245833] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CREG: 2,1, 4B0, D38519OK' ModemManager[1961]: [1478028770.247185] [mm-iface-modem-3gpp.c:1171] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering) ModemManager[1961]: [1478028770.247386] [mm-broadband-modem.c:3442] modem_3gpp_load_operator_code(): loading Operator Code... ModemManager[1961]: [1478028770.247541] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 4 (open) ModemManager[1961]: [1478028770.247777] [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[1961]: [1478028770.248122] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 5 (open) ModemManager[1961]: [1478028770.248318] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 4 (close) ModemManager[1961]: [1478028770.248526] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMGF=?' ModemManager[1961]: [1478028770.263150] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CMGF: (0-1)OK' ModemManager[1961]: [1478028770.263852] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 5 (open) ModemManager[1961]: [1478028770.264094] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 4 (close) ModemManager[1961]: [1478028770.264303] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' ModemManager[1961]: [1478028770.288034] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+COPS: 0,2,"21630",2OK' ModemManager[1961]: [1478028770.288757] [mm-broadband-modem.c:3432] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 21630 ModemManager[1961]: [1478028770.289252] [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[1961]: [1478028770.289459] [mm-broadband-modem.c:3478] modem_3gpp_load_operator_name(): loading Operator Name... ModemManager[1961]: [1478028770.289677] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 5 (open) ModemManager[1961]: [1478028770.289946] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 4 (close) ModemManager[1961]: [1478028770.291559] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGREG?' ModemManager[1961]: [1478028770.312437] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CGREG: 2,1, 4B0, D38519OK' ModemManager[1961]: [1478028770.312905] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 3 (close) ModemManager[1961]: [1478028770.313163] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMGF=0' ModemManager[1961]: [1478028770.325539] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.325928] [mm-broadband-modem.c:5585] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU' ModemManager[1961]: [1478028770.326361] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 4 (open) ModemManager[1961]: [1478028770.326608] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 3 (close) ModemManager[1961]: [1478028770.335184] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' ModemManager[1961]: [1478028770.358923] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+COPS: 0,0,"Telekom HU",2OK' ModemManager[1961]: [1478028770.372652] [mm-broadband-modem.c:3468] modem_3gpp_load_operator_name_finish(): loaded Operator Name: Telekom HU ModemManager[1961]: [1478028770.373044] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.373868] [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[1961]: [1478028770.374204] [mm-iface-modem.c:1600] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp' ModemManager[1961]: [1478028770.374833] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPMS="SM","MT","MT"' ModemManager[1961]: [1478028770.417465] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CPMS: 0,20,0,100,0,100OK' ModemManager[1961]: [1478028770.419162] [mm-broadband-modem.c:6357] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me' ModemManager[1961]: [1478028770.419345] [mm-broadband-modem.c:5471] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)... ModemManager[1961]: [1478028770.419543] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.419784] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.420019] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPMS="ME"' ModemManager[1961]: [1478028770.436908] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CPMS: 0,100,0,100,0,100OK' ModemManager[1961]: [1478028770.437445] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.437697] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.437890] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMGL=4' ModemManager[1961]: [1478028770.451348] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.451887] [mm-broadband-modem.c:6357] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm' ModemManager[1961]: [1478028770.452080] [mm-broadband-modem.c:5471] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)... ModemManager[1961]: [1478028770.452324] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.452560] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.452798] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPMS="SM"' ModemManager[1961]: [1478028770.469812] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CPMS: 0,20,0,100,0,100OK' ModemManager[1961]: [1478028770.470466] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.470777] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.471011] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMGL=4' ModemManager[1961]: [1478028770.484621] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.485173] [mm-broadband-modem.c:6357] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sr' ModemManager[1961]: [1478028770.485356] [mm-broadband-modem.c:5471] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SR), mem2 (none)... ModemManager[1961]: [1478028770.485519] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.485717] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.485924] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CPMS="SR"' ModemManager[1961]: [1478028770.503167] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CPMS: 0,100,0,100,0,100OK' ModemManager[1961]: [1478028770.503788] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.504099] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.504343] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMGL=4' ModemManager[1961]: [1478028770.517236] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CMS ERROR: 500' ModemManager[1961]: [1478028770.517691] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown ModemManager[1961]: [1478028770.517896] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'sr': 'Unknown' ModemManager[1961]: [1478028770.518124] [mm-broadband-modem.c:5888] set_messaging_unsolicited_events_handlers(): (ttyUSB1) Setting messaging unsolicited events handlers ModemManager[1961]: [1478028770.518259] [mm-broadband-modem.c:5888] set_messaging_unsolicited_events_handlers(): (ttyUSB2) Setting messaging unsolicited events handlers ModemManager[1961]: [1478028770.518420] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.518614] [mm-broadband-modem.c:6061] modem_messaging_enable_unsolicited_events(): (ttyUSB1) Enabling messaging unsolicited events on primary port ModemManager[1961]: [1478028770.518766] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.519011] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CNMI=2,1,2,1,0' ModemManager[1961]: [1478028770.541535] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CMS ERROR: 303' ModemManager[1961]: [1478028770.542001] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported ModemManager[1961]: [1478028770.542381] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CNMI=2,1,2,2,0' ModemManager[1961]: [1478028770.566262] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.566884] [mm-broadband-modem.c:6022] modem_messaging_enable_unsolicited_events_primary_ready(): (ttyUSB1) Messaging unsolicited events enabled on primary ModemManager[1961]: [1478028770.567300] [mm-broadband-modem.c:6027] modem_messaging_enable_unsolicited_events_primary_ready(): (ttyUSB2) Enabling messaging unsolicited events on secondary port ModemManager[1961]: [1478028770.567549] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1961]: [1478028770.567842] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.568138] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,1,0' ModemManager[1961]: [1478028770.597721] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '+CMS ERROR: 303' ModemManager[1961]: [1478028770.598139] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported ModemManager[1961]: [1478028770.598494] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,2,0' ModemManager[1961]: [1478028770.626100] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1961]: [1478028770.626588] [mm-broadband-modem.c:5995] modem_messaging_enable_unsolicited_events_secondary_ready(): (ttyUSB2) Messaging unsolicited events enabled on secondary ModemManager[1961]: [1478028770.626872] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close) ModemManager[1961]: [1478028770.627423] [mm-broadband-modem.c:9264] enabling_step(): Modem has voice capabilities, enabling the Voice interface... ModemManager[1961]: [1478028770.628138] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB1) Setting voice unsolicited events handlers ModemManager[1961]: [1478028770.628389] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB2) Setting voice unsolicited events handlers ModemManager[1961]: [1478028770.628777] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.629166] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CLIP=1' ModemManager[1961]: [1478028770.648263] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.648848] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CRC=1' ModemManager[1961]: [1478028770.659891] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028770.660725] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.661053] [mm-broadband-modem.c:9277] enabling_step(): Modem has time capabilities, enabling the Time interface... ModemManager[1961]: [1478028770.661494] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered) ModemManager[1961]: [1478028770.663968] [mm-iface-modem.c:1355] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s) ModemManager[1961]: [1478028770.664270] [mm-broadband-modem.c:2003] modem_load_signal_quality(): loading signal quality... ModemManager[1961]: [1478028770.664472] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028770.664684] [mm-iface-modem.c:1075] periodic_access_technologies_check_enable(): Periodic access technology checks enabled ModemManager[1961]: [1478028770.664905] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.666038] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') ModemManager[1961]: [1478028770.666353] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 4 (open) ModemManager[1961]: [1478028770.666605] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CIND?' ModemManager[1961]: [1478028770.686562] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CIND: 5,3,1,0,0,0,1,0OK' ModemManager[1961]: [1478028770.687111] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 3 (close) ModemManager[1961]: [1478028770.687595] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60) ModemManager[1961]: [1478028770.687759] [mm-iface-modem.c:1274] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s) ModemManager[1961]: [1478028770.688393] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+ZPAS?' ModemManager[1961]: [1478028770.700788] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+ZPAS: "UMTS","CS_PS"OK' ModemManager[1961]: [1478028770.701386] [mm-iface-modem.c:933] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> umts) ModemManager[1961]: [1478028770.701547] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.702120] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CREG?' ModemManager[1961]: [1478028770.713842] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CREG: 2,1, 4B0, D38519OK' ModemManager[1961]: [1478028770.714381] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open) ModemManager[1961]: [1478028770.714601] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 2 (close) ModemManager[1961]: [1478028770.714783] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGREG?' ModemManager[1961]: [1478028770.727674] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CGREG: 2,1, 4B0, D38519OK' ModemManager[1961]: [1478028770.728208] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028770.728437] [mm-broadband-modem.c:8574] modem_3gpp_run_registration_checks_ready(): Initial 3GPP registration checks finished ModemManager[1961]: [1478028776.193242] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028776.193614] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CCLK?' ModemManager[1961]: [1478028776.205376] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CCLK: "80/01/06,00:00:29"OK' ModemManager[1961]: [1478028776.205994] [mm-iface-modem-time.c:622] update_network_timezone_ready(): Couldn't update network timezone: 'Couldn't match +CCLK reply' ModemManager[1961]: [1478028776.206215] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028784.426025] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- '+CREG: 1, 4B0, D3A943+CGREG: 1, 4B0, D3A943' ModemManager[1961]: [1478028784.427150] [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: 'D3A943') ModemManager[1961]: [1478028791.016074] [main.c:46] quit_cb(): Caught signal, shutting down... ModemManager[1961]: [1478028791.017799] [mm-base-manager.c:849] set_property(): Stopping connection in object manager server ModemManager[1961]: [1478028791.018866] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> disabling) ModemManager[1961]: [1478028791.019741] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (0) ModemManager[1961]: [1478028791.019987] [mm-iface-modem.c:1321] periodic_signal_quality_check_disable(): Periodic signal quality checks disabled ModemManager[1961]: [1478028791.020817] [mm-iface-modem.c:933] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (umts -> unknown) ModemManager[1961]: [1478028791.021155] [mm-iface-modem.c:1047] periodic_access_technologies_check_disable(): Periodic access technology checks disabled ModemManager[1961]: [1478028791.021357] [mm-broadband-modem.c:8861] disabling_step(): Modem has time capabilities, disabling the Time interface... ModemManager[1961]: [1478028791.021790] [mm-broadband-modem.c:8873] disabling_step(): Modem has messaging capabilities, disabling the Messaging interface... ModemManager[1961]: [1478028791.022078] [mm-broadband-modem.c:5888] set_messaging_unsolicited_events_handlers(): (ttyUSB1) Removing messaging unsolicited events handlers ModemManager[1961]: [1478028791.022226] [mm-broadband-modem.c:5888] set_messaging_unsolicited_events_handlers(): (ttyUSB2) Removing messaging unsolicited events handlers ModemManager[1961]: [1478028791.023208] [mm-broadband-modem.c:8885] disabling_step(): Modem has voice capabilities, disabling the Voice interface... ModemManager[1961]: [1478028791.023866] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB1) Removing voice unsolicited events handlers ModemManager[1961]: [1478028791.024235] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB2) Removing voice unsolicited events handlers ModemManager[1961]: [1478028791.025863] [mm-broadband-modem.c:8897] disabling_step(): Modem has location capabilities, disabling the Location interface... ModemManager[1961]: [1478028791.026101] [mm-iface-modem-location.c:768] setup_gathering(): Need to disable the following location sources: '3gpp-lac-ci' ModemManager[1961]: [1478028791.026293] [mm-iface-modem-location.c:667] setup_gathering_step(): Disabled location '3gpp-lac-ci' gathering... ModemManager[1961]: [1478028791.028556] [mm-broadband-modem.c:8925] disabling_step(): Modem has 3GPP/USSD capabilities, disabling the Modem 3GPP/USSD interface... ModemManager[1961]: [1478028791.028818] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028791.029253] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CUSD=0' ModemManager[1961]: [1478028791.042090] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028791.042475] [mm-broadband-modem.c:4920] set_unsolicited_result_code_handlers(): (ttyUSB1) Removing unsolicited result code handlers ModemManager[1961]: [1478028791.042622] [mm-broadband-modem.c:4920] set_unsolicited_result_code_handlers(): (ttyUSB2) Removing unsolicited result code handlers ModemManager[1961]: [1478028791.042764] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028791.047970] [mm-broadband-modem.c:8937] disabling_step(): Modem has 3GPP capabilities, disabling the Modem 3GPP interface... ModemManager[1961]: [1478028791.048165] [mm-iface-modem-3gpp.c:1327] periodic_registration_check_disable(): Periodic 3GPP registration checks disabled ModemManager[1961]: [1478028791.048326] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028791.048760] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CREG=0' ModemManager[1961]: [1478028791.062266] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028791.062718] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1961]: [1478028791.062933] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028791.063290] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CREG=0' ModemManager[1961]: [1478028791.083736] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1961]: [1478028791.084133] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028791.084368] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close) ModemManager[1961]: [1478028791.084757] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CGREG=0' ModemManager[1961]: [1478028791.098268] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028791.098669] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1961]: [1478028791.098908] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028791.099290] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGREG=0' ModemManager[1961]: [1478028791.112829] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1961]: [1478028791.113304] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close) ModemManager[1961]: [1478028791.114648] [mm-broadband-modem.c:3660] modem_3gpp_cleanup_unsolicited_registration_events(): (ttyUSB1) cleaning up unsolicited registration messages handlers ModemManager[1961]: [1478028791.114857] [mm-broadband-modem.c:3660] modem_3gpp_cleanup_unsolicited_registration_events(): (ttyUSB2) cleaning up unsolicited registration messages handlers ModemManager[1961]: [1478028791.115414] [mm-broadband-modem.c:2510] set_unsolicited_events_handlers(): (ttyUSB1) Removing 3GPP unsolicited events handlers ModemManager[1961]: [1478028791.115582] [mm-broadband-modem.c:2510] set_unsolicited_events_handlers(): (ttyUSB2) Removing 3GPP unsolicited events handlers ModemManager[1961]: [1478028791.116020] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open) ModemManager[1961]: [1478028791.116511] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT+CMER=0' ModemManager[1961]: [1478028791.129848] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): <-- 'OK' ModemManager[1961]: [1478028791.130742] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open) ModemManager[1961]: [1478028791.131007] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 1 (close) ModemManager[1961]: [1478028791.131403] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMER=0' ModemManager[1961]: [1478028791.151347] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'OK' ModemManager[1961]: [1478028791.151742] [mm-iface-modem-3gpp.c:1186] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (home -> unknown) ModemManager[1961]: [1478028791.152342] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close) ModemManager[1961]: [1478028791.153093] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close) ModemManager[1961]: [1478028791.153269] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port... ModemManager[1961]: [1478028791.169282] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed ModemManager[1961]: [1478028791.169504] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 0 (close) ModemManager[1961]: [1478028791.169630] [mm-port-serial.c:1361] _close_internal(): (ttyUSB2) closing serial port... ModemManager[1961]: [1478028791.186239] [mm-port-serial.c:1409] _close_internal(): (ttyUSB2) serial port closed ModemManager[1961]: [1478028791.186493] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close) ModemManager[1961]: [1478028791.186679] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port... ModemManager[1961]: [1478028791.203377] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed ModemManager[1961]: [1478028791.203592] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabling -> disabled) ModemManager[1961]: [1478028791.204520] [mm-device.c:398] unexport_modem(): Unexported modem '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-5' from path '/org/freedesktop/ModemManager1/Modem/0' ModemManager[1961]: [1478028791.206007] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB0) forced to close port ModemManager[1961]: [1478028791.206278] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB1) forced to close port ModemManager[1961]: [1478028791.206511] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB2) forced to close port ModemManager[1961]: [1478028791.222655] [main.c:218] main(): ModemManager is shut down ModemManager[1961]: [1478028791.226605] [mm-sleep-monitor.c:245] _singleton_instance_weak_ref_cb(): disposing MMSleepMonitor singleton (0x9ca4cc0) ModemManager[1961]: [1478028791.228075] [mm-sleep-monitor.c:68] drop_inhibitor(): [sleep-monitor] dropping systemd sleep inhibitor