<info>  [1516814805.293942] [main.c:158] main(): ModemManager (version 1.6.12) starting in system bus...
<debug> [1516814805.304257] [mm-sleep-monitor.c:245] mm_sleep_monitor_get(): create MMSleepMonitor singleton (0x565457565a90)
<debug> [1516814805.311599] [main.c:83] bus_acquired_cb(): Bus acquired, creating manager...
<debug> [1516814805.321512] [mm-plugin-manager.c:1565] load_plugins(): [plugin manager] looking for plugins in '/usr/lib/ModemManager'
<debug> [1516814805.328479] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Sierra'
<debug> [1516814805.335786] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Nokia (Icera)'
<debug> [1516814805.349186] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Dell'
<debug> [1516814805.356630] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Nokia'
<debug> [1516814805.364046] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Pantech'
<debug> [1516814805.372453] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Longcheer'
<debug> [1516814805.379688] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Telit'
<debug> [1516814805.387073] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Via CBP7'
<debug> [1516814805.394327] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Altair LTE'
<debug> [1516814805.401825] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Novatel LTE'
<debug> [1516814805.409999] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Huawei'
<debug> [1516814805.417284] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Option'
<debug> [1516814805.424552] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Haier'
<debug> [1516814805.432951] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'ZTE'
<debug> [1516814805.440540] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Samsung'
<debug> [1516814805.447525] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Sierra (legacy)'
<debug> [1516814805.455118] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'MTK'
<debug> [1516814805.462431] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'AnyDATA'
<debug> [1516814805.470430] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Cinterion'
<debug> [1516814805.477878] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Linktop'
<debug> [1516814805.485399] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Wavecom'
<debug> [1516814805.493251] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'SimTech'
<debug> [1516814805.500588] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Thuraya'
<debug> [1516814805.507686] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'X22X'
<debug> [1516814805.514837] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Novatel'
<debug> [1516814805.522168] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Ericsson MBM'
<debug> [1516814805.530201] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Generic'
<debug> [1516814805.537305] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Iridium'
<debug> [1516814805.544917] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Motorola'
<debug> [1516814805.553206] [mm-plugin-manager.c:1590] load_plugins(): [plugin manager] loaded plugin 'Option High-Speed'
<debug> [1516814805.560273] [mm-plugin-manager.c:1615] load_plugins(): [plugin manager] successfully loaded 30 plugins
<debug> [1516814805.567952] [main.c:105] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
<debug> [1516814805.574790] [mm-base-manager.c:502] mm_base_manager_start(): Starting automatic device scan...
<debug> [1516814805.589794] [mm-base-manager.c:541] mm_base_manager_start(): Finished device scan...
<debug> [1516814805.596700] [mm-sleep-monitor.c:108] take_inhibitor(): [sleep-monitor] taking systemd sleep inhibitor
<debug> [1516814805.610119] [mm-plugin-manager.c:1448] mm_plugin_manager_device_support_check(): [plugin manager] task 0: new support task for device: /sys/devices/pci0000:00/0000:00:14.0/usb2/2-2
<debug> [1516814805.617291] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: ttyUSB0
<debug> [1516814805.624452] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 0,ttyUSB0: new support task for port
<debug> [1516814805.631787] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 0,ttyUSB0: deferred until min wait time elapsed
<debug> [1516814805.639042] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: ttyUSB1
<debug> [1516814805.647136] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 0,ttyUSB1: new support task for port
<debug> [1516814805.659744] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 0,ttyUSB1: deferred until min wait time elapsed
<debug> [1516814805.666889] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: ttyUSB2
<debug> [1516814805.675134] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 0,ttyUSB2: new support task for port
<debug> [1516814805.687038] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 0,ttyUSB2: deferred until min wait time elapsed
<debug> [1516814805.694370] [mm-base-manager.c:392] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
<debug> [1516814805.702049] [mm-base-manager.c:392] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
<debug> [1516814805.709340] [mm-base-manager.c:392] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
<debug> [1516814805.717416] [mm-base-manager.c:392] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
<debug> [1516814805.725294] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: wwp0s20u2i8
<debug> [1516814805.732471] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 0,wwp0s20u2i8: new support task for port
<debug> [1516814805.740490] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 0,wwp0s20u2i8: deferred until min wait time elapsed
<debug> [1516814805.747604] [mm-plugin-manager.c:1448] mm_plugin_manager_device_support_check(): [plugin manager] task 1: new support task for device: /sys/devices/pci0000:00/0000:00:19.0
<debug> [1516814805.754744] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 1: port grabbed: eno1
<debug> [1516814805.761825] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 1,eno1: new support task for port
<debug> [1516814805.774288] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 1,eno1: deferred until min wait time elapsed
<debug> [1516814805.782755] [mm-plugin-manager.c:1448] mm_plugin_manager_device_support_check(): [plugin manager] task 2: new support task for device: /sys/devices/pci0000:00/0000:00:1c.3/0000:02:00.0
<debug> [1516814805.789722] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 2: port grabbed: wlp2s0
<debug> [1516814805.796992] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 2,wlp2s0: new support task for port
<debug> [1516814805.805111] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 2,wlp2s0: deferred until min wait time elapsed
<debug> [1516814805.812282] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: cdc-wdm0
<debug> [1516814805.820573] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 0,cdc-wdm0: new support task for port
<debug> [1516814805.828530] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 0,cdc-wdm0: deferred until min wait time elapsed
<debug> [1516814805.835451] [mm-sleep-monitor.c:97] inhibit_done(): [sleep-monitor] inhibitor fd is 10
<debug> [1516814807.119030] [mm-plugin-manager.c:1116] device_context_min_wait_time_elapsed(): [plugin manager] task 0: min wait time elapsed
<debug> [1516814807.128998] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 0,cdc-wdm0: found '2' plugins to try
<debug> [1516814807.136053] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,cdc-wdm0: will try with plugin 'Sierra'
<debug> [1516814807.144017] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,cdc-wdm0: will try with plugin 'Generic'
<debug> [1516814807.151328] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 0,cdc-wdm0: started
<debug> [1516814807.158351] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 0,cdc-wdm0: checking with plugin 'Sierra'
<debug> [1516814807.165432] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [cdc-wdm0] probe required: 'qmi'
<debug> [1516814807.172792] [mm-port-probe.c:1388] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi'
<debug> [1516814807.180665] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 0,wwp0s20u2i8: found '2' plugins to try
<debug> [1516814807.187751] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,wwp0s20u2i8: will try with plugin 'Sierra'
<debug> [1516814807.194889] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,wwp0s20u2i8: will try with plugin 'Generic'
<debug> [1516814807.202915] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 0,wwp0s20u2i8: started
<debug> [1516814807.210004] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 0,wwp0s20u2i8: checking with plugin 'Sierra'
<debug> [1516814807.216975] [mm-plugin.c:730] mm_plugin_supports_port(): (Sierra) [wwp0s20u2i8] probing deferred until result suggested
<debug> [1516814807.224026] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 0,ttyUSB2: found '2' plugins to try
<debug> [1516814807.231660] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,ttyUSB2: will try with plugin 'Sierra'
<debug> [1516814807.239585] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,ttyUSB2: will try with plugin 'Generic'
<debug> [1516814807.246522] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 0,ttyUSB2: started
<debug> [1516814807.253857] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 0,ttyUSB2: checking with plugin 'Sierra'
<debug> [1516814807.261755] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [ttyUSB2] probe required: 'at, qcdm'
<debug> [1516814807.268708] [mm-port-probe.c:1388] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm'
<debug> [1516814807.276022] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 0,ttyUSB1: found '2' plugins to try
<debug> [1516814807.283007] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,ttyUSB1: will try with plugin 'Sierra'
<debug> [1516814807.290001] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,ttyUSB1: will try with plugin 'Generic'
<debug> [1516814807.298197] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 0,ttyUSB1: started
<debug> [1516814807.305533] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 0,ttyUSB1: checking with plugin 'Sierra'
<debug> [1516814807.312840] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [ttyUSB1] probe required: 'at, qcdm'
<debug> [1516814807.320964] [mm-port-probe.c:1388] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm'
<debug> [1516814807.328771] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 0,ttyUSB0: found '2' plugins to try
<debug> [1516814807.336582] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,ttyUSB0: will try with plugin 'Sierra'
<debug> [1516814807.343503] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 0,ttyUSB0: will try with plugin 'Generic'
<debug> [1516814807.350555] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 0,ttyUSB0: started
<debug> [1516814807.358349] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 0,ttyUSB0: checking with plugin 'Sierra'
<debug> [1516814807.365500] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [ttyUSB0] probe required: 'at, qcdm'
<debug> [1516814807.377094] [mm-port-probe.c:1388] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, qcdm'
<debug> [1516814807.384662] [mm-plugin-manager.c:1116] device_context_min_wait_time_elapsed(): [plugin manager] task 1: min wait time elapsed
<debug> [1516814807.391833] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [eno1] filtered by drivers
<debug> [1516814807.403589] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [eno1] filtered by vendor/product IDs
<debug> [1516814807.410785] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [eno1] filtered by vendor/product IDs
<debug> [1516814807.417707] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [eno1] filtered by subsystem
<debug> [1516814807.425511] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [eno1] filtered by vendor/product IDs
<debug> [1516814807.432635] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [eno1] filtered by subsystem
<debug> [1516814807.439496] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [eno1] filtered by subsystem
<debug> [1516814807.446325] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [eno1] filtered by subsystem
<debug> [1516814807.453909] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [eno1] filtered by vendor/product IDs
<debug> [1516814807.461866] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [eno1] filtered by vendor/product IDs
<debug> [1516814807.468995] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [eno1] filtered by vendor/product IDs
<debug> [1516814807.476161] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [eno1] filtered by subsystem
<debug> [1516814807.484478] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [eno1] filtered by subsystem
<debug> [1516814807.491433] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [eno1] filtered by vendor/product IDs
<debug> [1516814807.498459] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [eno1] filtered by vendor/product IDs
<debug> [1516814807.505486] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [eno1] filtered by drivers
<debug> [1516814807.512285] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [eno1] filtered by subsystem
<debug> [1516814807.520545] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [eno1] filtered by vendor/product IDs
<debug> [1516814807.533003] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [eno1] filtered by vendor/product IDs
<debug> [1516814807.540118] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [eno1] filtered by subsystem
<debug> [1516814807.548247] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [eno1] filtered by subsystem
<debug> [1516814807.555739] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [eno1] filtered by vendor/product IDs
<debug> [1516814807.562765] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [eno1] filtered by subsystem
<debug> [1516814807.569880] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [eno1] filtered by vendor/product IDs
<debug> [1516814807.576891] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [eno1] filtered by vendor/product IDs
<debug> [1516814807.584645] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [eno1] filtered by udev tags
<debug> [1516814807.598168] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [eno1] filtered by subsystem
<debug> [1516814807.605374] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [eno1] filtered by subsystem
<debug> [1516814807.612369] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [eno1] filtered by drivers
<debug> [1516814807.619495] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 1,eno1: found '1' plugins to try
<debug> [1516814807.627568] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 1,eno1: will try with plugin 'Generic'
<debug> [1516814807.634952] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 1,eno1: started
<debug> [1516814807.642086] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 1,eno1: checking with plugin 'Generic'
<debug> [1516814807.654814] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [eno1] probing deferred until result suggested
<debug> [1516814807.661980] [mm-plugin-manager.c:1116] device_context_min_wait_time_elapsed(): [plugin manager] task 2: min wait time elapsed
<debug> [1516814807.668970] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [wlp2s0] filtered by drivers
<debug> [1516814807.675922] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.682947] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.690737] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlp2s0] filtered by subsystem
<debug> [1516814807.697555] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.710037] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlp2s0] filtered by subsystem
<debug> [1516814807.718342] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlp2s0] filtered by subsystem
<debug> [1516814807.725667] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlp2s0] filtered by subsystem
<debug> [1516814807.737904] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.745132] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.752013] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.760001] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlp2s0] filtered by subsystem
<debug> [1516814807.767138] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [wlp2s0] filtered by subsystem
<debug> [1516814807.774064] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.781991] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.789283] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [wlp2s0] filtered by drivers
<debug> [1516814807.796025] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlp2s0] filtered by subsystem
<debug> [1516814807.803527] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.810648] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.818296] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlp2s0] filtered by subsystem
<debug> [1516814807.825640] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlp2s0] filtered by subsystem
<debug> [1516814807.832395] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.840195] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [wlp2s0] filtered by subsystem
<debug> [1516814807.847394] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.854429] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [wlp2s0] filtered by vendor/product IDs
<debug> [1516814807.862785] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [wlp2s0] filtered by udev tags
<debug> [1516814807.869779] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlp2s0] filtered by subsystem
<debug> [1516814807.877686] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlp2s0] filtered by subsystem
<debug> [1516814807.890724] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [wlp2s0] filtered by drivers
<debug> [1516814807.898023] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 2,wlp2s0: found '1' plugins to try
<debug> [1516814807.905073] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 2,wlp2s0: will try with plugin 'Generic'
<debug> [1516814807.912146] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 2,wlp2s0: started
<debug> [1516814807.920129] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 2,wlp2s0: checking with plugin 'Generic'
<debug> [1516814807.932007] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [wlp2s0] probing deferred until result suggested
<debug> [1516814807.938902] [mm-plugin-manager.c:456] port_context_defer_until_suggested(): [plugin manager] task 0,wwp0s20u2i8: deferring support check until result suggested
<debug> [1516814807.947342] [mm-plugin-manager.c:456] port_context_defer_until_suggested(): [plugin manager] task 1,eno1: deferring support check until result suggested
<debug> [1516814807.954478] [mm-plugin-manager.c:456] port_context_defer_until_suggested(): [plugin manager] task 2,wlp2s0: deferring support check until result suggested
<debug> [1516814807.961548] [mm-port-probe.c:475] wdm_probe_qmi(): (usbmisc/cdc-wdm0) probing QMI...
<debug> [1516814807.969017] [mm-port-qmi.c:338] port_open_context_step(): Opening QMI device...
<debug> [1516814807.976046] [mm-port-qmi.c:343] port_open_context_step(): Checking if QMI device already opening...
<debug> [1516814807.984149] [mm-port-qmi.c:356] port_open_context_step(): Checking if QMI device already open...
<debug> [1516814807.992244] [mm-port-qmi.c:377] port_open_context_step(): Creating QMI device...
<debug> [1516814807.999339] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB2) opening serial port...
<warn>  [1516814808.007628] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
<debug> [1516814808.014705] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
<debug> [1516814808.021770] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB1) opening serial port...
<warn>  [1516814808.029164] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set
<debug> [1516814808.036231] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
<debug> [1516814808.049016] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB0) opening serial port...
<warn>  [1516814808.056351] [mm-port-serial.c:513] real_config_fd(): (ttyUSB0): port attributes not fully set
<debug> [1516814808.063561] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
<debug> [1516814808.071745] [mm-port-qmi.c:390] port_open_context_step(): Opening device without data format update...
[/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...[/dev/cdc-wdm0] loaded driver of cdc-wdm port: qmi_wwancannot connect to proxy: Could not connect: Connection refusedspawning new qmi-proxy (try 1)...<debug> [1516814808.118283] [mm-plugin-manager.c:1064] device_context_min_probing_time_elapsed(): [plugin manager] task 0: min probing time elapsed
<debug> [1516814808.125232] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 0: still 5 running probes (4 active): cdc-wdm0, wwp0s20u2i8, ttyUSB2, ttyUSB1, ttyUSB0
<debug> [1516814808.132496] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT<CR>'
<debug> [1516814808.149551] [mm-port-serial-at.c:460] debug_log(): (ttyUSB1): --> 'AT<CR>'
<debug> [1516814808.172089] [mm-port-serial-at.c:460] debug_log(): (ttyUSB0): --> 'AT<CR>'
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<<   translated = /dev/cdc-wdm0
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
[/dev/cdc-wdm0] Checking version info (10 retries)...[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 11
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Version Info" (0x0021)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 188
<<<<<<   data   = 01:BB:00:80:00:00:01:02:21:00:B0:00:02:04:00:00:00:00:00:01:A6:00:21:00:01:00:05:00:01:01:00:77:00:02:01:00:00:00:03:01:00:19:00:04:01:00:0C:00:05:01:00:0A:00:07:01:00:03:00:08:01:00:04:00:09:02:00:01:00:0A:02:00:18:00:0B:01:00:4D:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:10:00:1D:01:00:01:00:22:01:00:00:00:24:01:00:00:00:29:01:00:00:00:2A:01:00:00:00:30:01:00:00:00:31:01:00:00:00:36:01:00:00:00:44:01:00:00:00:47:01:00:00:00:F0:01:00:00:00:F3:01:00:00:00:F5:01:00:00:00:F6:01:00:00:00:FC:01:00:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 187
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 176
<<<<<<   message     = "Get Version Info" (0x0021)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Service list" (0x01)
<<<<<<   length     = 166
<<<<<<   value      = 21:00:01:00:05:00:01:01:00:77:00:02:01:00:00:00:03:01:00:19:00:04:01:00:0C:00:05:01:00:0A:00:07:01:00:03:00:08:01:00:04:00:09:02:00:01:00:0A:02:00:18:00:0B:01:00:4D:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:10:00:1D:01:00:01:00:22:01:00:00:00:24:01:00:00:00:29:01:00:00:00:2A:01:00:00:00:30:01:00:00:00:31:01:00:00:00:36:01:00:00:00:44:01:00:00:00:47:01:00:00:00:F0:01:00:00:00:F3:01:00:00:00:F5:01:00:00:00:F6:01:00:00:00:FC:01:00:00:00
<<<<<<   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '5' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '119' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '0' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '25' ] ' [4] = '[ service = 'qos' major_version = '1' minor_version = '12' ] ' [5] = '[ service = 'wms' major_version = '1' minor_version = '10' ] ' [6] = '[ service = 'auth' major_version = '1' minor_version = '3' ] ' [7] = '[ service = 'at' major_version = '1' minor_version = '4' ] ' [8] = '[ service = 'voice' major_version = '2' minor_version = '1' ] ' [9] = '[ service = 'cat2' major_version = '2' minor_version = '24' ] ' [10] = '[ service = 'uim' major_version = '1' minor_version = '77' ] ' [11] = '[ service = 'pbm' major_version = '1' minor_version = '4' ] ' [12] = '[ service = 'test' major_version = '1' minor_version = '0' ] ' [13] = '[ service = 'loc' major_version = '2' minor_version = '0' ] ' [14] = '[ service = 'sar' major_version = '1' minor_version = '0' ] ' [15] = '[ service = 'ts' major_version = '1' minor_version = '0' ] ' [16] = '[ service = 'tmd' major_version = '1' minor_version = '0' ] ' [17] = '[ service = 'wda' major_version = '1' minor_version = '16' ] ' [18] = '[ service = 'csvt' major_version = '1' minor_version = '1' ] ' [19] = '[ service = 'coex' major_version = '1' minor_version = '0' ] ' [20] = '[ service = 'pdc' major_version = '1' minor_version = '0' ] ' [21] = '[ service = 'rfrpe' major_version = '1' minor_version = '0' ] ' [22] = '[ service = 'dsd' major_version = '1' minor_version = '0' ] ' [23] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [24] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [25] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [26] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [27] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [28] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [29] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [30] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [31] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [32] = '[ service = '(null)' major_version = '1' minor_version = '0' ] '}
[/dev/cdc-wdm0] QMI Device supports 33 services:[/dev/cdc-wdm0]    ctl (1.5)[/dev/cdc-wdm0]    wds (1.119)[/dev/cdc-wdm0]    dms (1.0)[/dev/cdc-wdm0]    nas (1.25)[/dev/cdc-wdm0]    qos (1.12)[/dev/cdc-wdm0]    wms (1.10)[/dev/cdc-wdm0]    auth (1.3)[/dev/cdc-wdm0]    at (1.4)[/dev/cdc-wdm0]    voice (2.1)[/dev/cdc-wdm0]    cat2 (2.24)[/dev/cdc-wdm0]    uim (1.77)[/dev/cdc-wdm0]    pbm (1.4)[/dev/cdc-wdm0]    test (1.0)[/dev/cdc-wdm0]    loc (2.0)[/dev/cdc-wdm0]    sar (1.0)[/dev/cdc-wdm0]    ts (1.0)[/dev/cdc-wdm0]    tmd (1.0)[/dev/cdc-wdm0]    wda (1.16)[/dev/cdc-wdm0]    csvt (1.1)[/dev/cdc-wdm0]    coex (1.0)[/dev/cdc-wdm0]    pdc (1.0)[/dev/cdc-wdm0]    rfrpe (1.0)[/dev/cdc-wdm0]    dsd (1.0)[/dev/cdc-wdm0]    unknown [0x30] (1.0)[/dev/cdc-wdm0]    unknown [0x31] (1.0)[/dev/cdc-wdm0]    unknown [0x36] (1.0)[/dev/cdc-wdm0]    unknown [0x44] (1.0)[/dev/cdc-wdm0]    unknown [0x47] (1.0)[/dev/cdc-wdm0]    unknown [0xf0] (1.0)[/dev/cdc-wdm0]    unknown [0xf3] (1.0)[/dev/cdc-wdm0]    unknown [0xf5] (1.0)[/dev/cdc-wdm0]    unknown [0xf6] (1.0)[/dev/cdc-wdm0]    unknown [0xfc] (1.0)<debug> [1516814808.217489] [mm-port-qmi.c:506] port_open_context_step(): QMI port open operation finished
<debug> [1516814808.231076] [mm-port-probe.c:274] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable
<debug> [1516814808.238301] [mm-plugin-manager.c:286] port_context_supported(): [plugin manager] task 0,cdc-wdm0: found best plugin for port (Sierra)
<debug> [1516814808.245715] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 0,cdc-wdm0: finished in '2.425142' seconds
<debug> [1516814808.253813] [mm-plugin-manager.c:919] device_context_set_best_plugin(): [plugin manager] task 0,cdc-wdm0: found best plugin: Sierra
<debug> [1516814808.260451] [mm-plugin-manager.c:329] port_context_set_suggestion(): [plugin manager] task 0,wwp0s20u2i8: deferred task completed, got suggested plugin (Sierra)
<debug> [1516814808.267553] [mm-plugin-manager.c:369] port_context_set_suggestion(): [plugin manager] task 0,ttyUSB2: got suggested plugin (Sierra)
<debug> [1516814808.274398] [mm-plugin-manager.c:369] port_context_set_suggestion(): [plugin manager] task 0,ttyUSB1: got suggested plugin (Sierra)
<debug> [1516814808.280764] [mm-plugin-manager.c:369] port_context_set_suggestion(): [plugin manager] task 0,ttyUSB0: got suggested plugin (Sierra)
<debug> [1516814808.288787] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 0: still 4 running probes (4 active): wwp0s20u2i8, ttyUSB2, ttyUSB1, ttyUSB0
<debug> [1516814808.295864] [mm-plugin-manager.c:1064] device_context_min_probing_time_elapsed(): [plugin manager] task 1: min probing time elapsed
<debug> [1516814808.302629] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 1: still 1 running probes (0 active): eno1
<debug> [1516814808.309624] [mm-plugin-manager.c:1015] device_context_continue(): [plugin manager] task 1: no active tasks to probe
<debug> [1516814808.316827] [mm-plugin-manager.c:340] port_context_set_suggestion(): [plugin manager] task 1,eno1: deferred task completed, no suggested plugin
<debug> [1516814808.324528] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 1,eno1: finished in '2.562706' seconds
<debug> [1516814808.331266] [mm-plugin-manager.c:898] device_context_set_best_plugin(): [plugin manager] task 1,eno1: not supported by any plugin
<debug> [1516814808.338502] [mm-device.c:374] mm_device_ignore_port(): Fully ignoring port 'net/eno1' from now on
<debug> [1516814808.346463] [mm-plugin-manager.c:985] device_context_continue(): [plugin manager] task 1: no more ports to probe
<debug> [1516814808.353418] [mm-plugin-manager.c:819] device_context_complete(): [plugin manager] task 1: finished in '2.605810' seconds
<info>  [1516814808.360653] [mm-base-manager.c:155] device_support_check_ready(): Couldn't check support for device at '/sys/devices/pci0000:00/0000:00:19.0': not supported by any plugin
<debug> [1516814808.367520] [mm-plugin-manager.c:1064] device_context_min_probing_time_elapsed(): [plugin manager] task 2: min probing time elapsed
<debug> [1516814808.374731] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 2: still 1 running probes (0 active): wlp2s0
<debug> [1516814808.382748] [mm-plugin-manager.c:1015] device_context_continue(): [plugin manager] task 2: no active tasks to probe
<debug> [1516814808.394891] [mm-plugin-manager.c:340] port_context_set_suggestion(): [plugin manager] task 2,wlp2s0: deferred task completed, no suggested plugin
<debug> [1516814808.402263] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 2,wlp2s0: finished in '2.605273' seconds
<debug> [1516814808.409988] [mm-plugin-manager.c:898] device_context_set_best_plugin(): [plugin manager] task 2,wlp2s0: not supported by any plugin
<debug> [1516814808.416871] [mm-device.c:374] mm_device_ignore_port(): Fully ignoring port 'net/wlp2s0' from now on
<debug> [1516814808.424271] [mm-plugin-manager.c:985] device_context_continue(): [plugin manager] task 2: no more ports to probe
<debug> [1516814808.431244] [mm-plugin-manager.c:819] device_context_complete(): [plugin manager] task 2: finished in '2.648489' seconds
<info>  [1516814808.438158] [mm-base-manager.c:155] device_support_check_ready(): Couldn't check support for device at '/sys/devices/pci0000:00/0000:00:1c.3/0000:02:00.0': not supported by any plugin
<debug> [1516814808.446616] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 0,wwp0s20u2i8: checking with plugin 'Sierra'
<debug> [1516814808.453840] [mm-plugin.c:730] mm_plugin_supports_port(): (Sierra) [wwp0s20u2i8] probing deferred until result suggested
<debug> [1516814808.460827] [mm-plugin-manager.c:437] port_context_defer_until_suggested(): [plugin manager] task 0,wwp0s20u2i8: completed, got suggested plugin (Sierra)
<debug> [1516814808.468819] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 0,wwp0s20u2i8: finished in '2.736350' seconds
<debug> [1516814808.475929] [mm-plugin-manager.c:972] device_context_set_best_plugin(): [plugin manager] task 0,wwp0s20u2i8: best plugin matches device reported one: Sierra
<debug> [1516814808.483464] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 0: still 3 running probes (3 active): ttyUSB2, ttyUSB1, ttyUSB0
<debug> [1516814808.551191] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [1516814808.558939] [mm-port-probe.c:155] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable
<debug> [1516814808.566845] [mm-plugin-manager.c:286] port_context_supported(): [plugin manager] task 0,ttyUSB2: found best plugin for port (Sierra)
<debug> [1516814808.573767] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 0,ttyUSB2: finished in '2.898635' seconds
<debug> [1516814808.581357] [mm-plugin-manager.c:972] device_context_set_best_plugin(): [plugin manager] task 0,ttyUSB2: best plugin matches device reported one: Sierra
<debug> [1516814808.588390] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 0: still 2 running probes (2 active): ttyUSB1, ttyUSB0
<debug> [1516814808.595532] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 0 (close)
<debug> [1516814808.603544] [mm-port-serial.c:1369] _close_internal(): (ttyUSB2) closing serial port...
<debug> [1516814808.611148] [mm-port-serial.c:1417] _close_internal(): (ttyUSB2) serial port closed
<debug> [1516814808.618270] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB2) forced to close port
<debug> [1516814812.177933] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [1516814812.187048] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [1516814812.194574] [mm-port-serial-at.c:460] debug_log(): (ttyUSB1): --> 'AT<CR>'
<debug> [1516814812.201653] [mm-port-serial-at.c:460] debug_log(): (ttyUSB0): --> 'AT<CR>'
<debug> [1516814815.176699] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [1516814815.186682] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [1516814815.193669] [mm-port-serial-at.c:460] debug_log(): (ttyUSB1): --> 'AT<CR>'
<debug> [1516814815.200982] [mm-port-serial-at.c:460] debug_log(): (ttyUSB0): --> 'AT<CR>'
<debug> [1516814818.175206] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [1516814818.186011] [mm-port-probe.c:165] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is not AT-capable
<debug> [1516814818.193300] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [1516814818.200430] [mm-port-probe.c:165] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
<debug> [1516814818.208379] [mm-port-probe.c:691] serial_probe_qcdm(): (tty/ttyUSB1) probing QCDM...
<debug> [1516814818.215435] [mm-port-serial.c:1353] _close_internal(): (ttyUSB1) device open count is 0 (close)
<debug> [1516814818.222759] [mm-port-serial.c:1369] _close_internal(): (ttyUSB1) closing serial port...
<debug> [1516814818.231340] [mm-port-serial.c:1417] _close_internal(): (ttyUSB1) serial port closed
<debug> [1516814818.238516] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB1) forced to close port
<debug> [1516814818.245862] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB1) opening serial port...
<debug> [1516814818.253177] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
<debug> [1516814818.260586] [mm-port-probe.c:691] serial_probe_qcdm(): (tty/ttyUSB0) probing QCDM...
<debug> [1516814818.268503] [mm-port-serial.c:1353] _close_internal(): (ttyUSB0) device open count is 0 (close)
<debug> [1516814818.275836] [mm-port-serial.c:1369] _close_internal(): (ttyUSB0) closing serial port...
<debug> [1516814818.283519] [mm-port-serial.c:1417] _close_internal(): (ttyUSB0) serial port closed
<debug> [1516814818.291400] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB0) forced to close port
<debug> [1516814818.298880] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB0) opening serial port...
<debug> [1516814818.307415] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
<debug> [1516814818.314593] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB1): --> 7e 00 78 f0 7e
<debug> [1516814818.322526] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
<debug> [1516814818.329954] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 13 41 54 0d 41 54 0d 41 54 0d 00 78 f0 7d 5e cd 5f 7e
<warn>  [1516814818.337232] [mm-port-probe.c:628] serial_probe_qcdm_parse_response(): (tty/ttyUSB0) failed to parse QCDM version info command result: -7
<debug> [1516814818.344357] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
<debug> [1516814818.353102] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 00 53 65 70 20 32 39 20 32 30 31 37 30 36 3a 30 34 3a 32 33 41 75 67 20 33 30 20 32 30 31 37 30 38 3a 30 30 3a 30 30 43 31 30 30 30 32 30 30 3a 06 ff 64 00 02 00 32 58 a0 7e
<debug> [1516814818.361270] [mm-port-probe.c:243] mm_port_probe_set_result_qcdm(): (tty/ttyUSB0) port is QCDM-capable
<debug> [1516814818.368198] [mm-plugin-manager.c:286] port_context_supported(): [plugin manager] task 0,ttyUSB0: found best plugin for port (Sierra)
<debug> [1516814818.375583] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 0,ttyUSB0: finished in '12.751133' seconds
<debug> [1516814818.383816] [mm-plugin-manager.c:972] device_context_set_best_plugin(): [plugin manager] task 0,ttyUSB0: best plugin matches device reported one: Sierra
<debug> [1516814818.390683] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 0: still 1 running probes (1 active): ttyUSB1
<debug> [1516814818.398030] [mm-port-serial.c:1353] _close_internal(): (ttyUSB0) device open count is 0 (close)
<debug> [1516814818.405014] [mm-port-serial.c:1369] _close_internal(): (ttyUSB0) closing serial port...
<debug> [1516814818.412137] [mm-port-serial.c:1417] _close_internal(): (ttyUSB0) serial port closed
<debug> [1516814818.425442] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB0) forced to close port
<debug> [1516814821.176819] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB1): --> 7e 00 78 f0 7e
<debug> [1516814824.173960] [mm-port-probe.c:261] mm_port_probe_set_result_qcdm(): (tty/ttyUSB1) port is not QCDM-capable
<debug> [1516814824.183032] [mm-plugin-manager.c:286] port_context_supported(): [plugin manager] task 0,ttyUSB1: found best plugin for port (Sierra)
<debug> [1516814824.190345] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 0,ttyUSB1: finished in '18.543199' seconds
<debug> [1516814824.198501] [mm-plugin-manager.c:972] device_context_set_best_plugin(): [plugin manager] task 0,ttyUSB1: best plugin matches device reported one: Sierra
<debug> [1516814824.205318] [mm-plugin-manager.c:985] device_context_continue(): [plugin manager] task 0: no more ports to probe
<debug> [1516814824.212657] [mm-plugin-manager.c:819] device_context_complete(): [plugin manager] task 0: finished in '18.602522' seconds
<info>  [1516814824.219750] [mm-device.c:538] mm_device_create_modem(): Creating modem with plugin 'Sierra' and '5' ports
<debug> [1516814824.226450] [sierra/mm-plugin-sierra.c:55] create_modem(): QMI-powered Sierra modem found...
<debug> [1516814824.235409] [mm-base-modem.c:280] mm_base_modem_grab_port(): (cdc-wdm0) type 'qmi' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb2/2-2
<debug> [1516814824.242230] [mm-base-modem.c:280] mm_base_modem_grab_port(): (wwp0s20u2i8) type 'net' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb2/2-2
<debug> [1516814824.249399] [mm-base-modem.c:280] mm_base_modem_grab_port(): (ttyUSB2) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb2/2-2
<warn>  [1516814824.257883] [mm-plugin.c:957] mm_plugin_create_modem(): Could not grab port (tty/ttyUSB1): 'Cannot add port 'tty/ttyUSB1', unhandled serial type'
<debug> [1516814824.270880] [mm-base-modem.c:280] mm_base_modem_grab_port(): (ttyUSB0) type 'qcdm' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb2/2-2
<debug> [1516814824.279299] [mm-base-modem.c:875] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2) tty/ttyUSB2 at (primary)
<debug> [1516814824.286260] [mm-base-modem.c:875] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2) net/wwp0s20u2i8 data (primary)
<debug> [1516814824.293568] [mm-base-modem.c:875] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2) tty/ttyUSB0 qcdm
<debug> [1516814824.302198] [mm-base-modem.c:875] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2) usb/cdc-wdm0 qmi (primary)
<debug> [1516814824.309429] [mm-port-qmi.c:338] port_open_context_step(): Opening QMI device...
<debug> [1516814824.322509] [mm-port-qmi.c:343] port_open_context_step(): Checking if QMI device already opening...
<debug> [1516814824.329615] [mm-port-qmi.c:356] port_open_context_step(): Checking if QMI device already open...
<debug> [1516814824.341364] [mm-port-qmi.c:377] port_open_context_step(): Creating QMI device...
<info>  [1516814824.349900] [mm-base-manager.c:175] device_support_check_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2' successfully created
<debug> [1516814824.362757] [mm-port-serial.c:1353] _close_internal(): (ttyUSB1) device open count is 0 (close)
<debug> [1516814824.370358] [mm-port-serial.c:1369] _close_internal(): (ttyUSB1) closing serial port...
<debug> [1516814824.378701] [mm-port-serial.c:1417] _close_internal(): (ttyUSB1) serial port closed
<debug> [1516814824.385907] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB1) forced to close port
<debug> [1516814824.393183] [mm-port-qmi.c:390] port_open_context_step(): Opening device without data format update...
[/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...[/dev/cdc-wdm0] loaded driver of cdc-wdm port: qmi_wwan[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<<   translated = /dev/cdc-wdm0
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
[/dev/cdc-wdm0] Checking version info (10 retries)...[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 11
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Version Info" (0x0021)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 188
<<<<<<   data   = 01:BB:00:80:00:00:01:02:21:00:B0:00:02:04:00:00:00:00:00:01:A6:00:21:00:01:00:05:00:01:01:00:77:00:02:01:00:00:00:03:01:00:19:00:04:01:00:0C:00:05:01:00:0A:00:07:01:00:03:00:08:01:00:04:00:09:02:00:01:00:0A:02:00:18:00:0B:01:00:4D:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:10:00:1D:01:00:01:00:22:01:00:00:00:24:01:00:00:00:29:01:00:00:00:2A:01:00:00:00:30:01:00:00:00:31:01:00:00:00:36:01:00:00:00:44:01:00:00:00:47:01:00:00:00:F0:01:00:00:00:F3:01:00:00:00:F5:01:00:00:00:F6:01:00:00:00:FC:01:00:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 187
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 176
<<<<<<   message     = "Get Version Info" (0x0021)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Service list" (0x01)
<<<<<<   length     = 166
<<<<<<   value      = 21:00:01:00:05:00:01:01:00:77:00:02:01:00:00:00:03:01:00:19:00:04:01:00:0C:00:05:01:00:0A:00:07:01:00:03:00:08:01:00:04:00:09:02:00:01:00:0A:02:00:18:00:0B:01:00:4D:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:10:00:1D:01:00:01:00:22:01:00:00:00:24:01:00:00:00:29:01:00:00:00:2A:01:00:00:00:30:01:00:00:00:31:01:00:00:00:36:01:00:00:00:44:01:00:00:00:47:01:00:00:00:F0:01:00:00:00:F3:01:00:00:00:F5:01:00:00:00:F6:01:00:00:00:FC:01:00:00:00
<<<<<<   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '5' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '119' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '0' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '25' ] ' [4] = '[ service = 'qos' major_version = '1' minor_version = '12' ] ' [5] = '[ service = 'wms' major_version = '1' minor_version = '10' ] ' [6] = '[ service = 'auth' major_version = '1' minor_version = '3' ] ' [7] = '[ service = 'at' major_version = '1' minor_version = '4' ] ' [8] = '[ service = 'voice' major_version = '2' minor_version = '1' ] ' [9] = '[ service = 'cat2' major_version = '2' minor_version = '24' ] ' [10] = '[ service = 'uim' major_version = '1' minor_version = '77' ] ' [11] = '[ service = 'pbm' major_version = '1' minor_version = '4' ] ' [12] = '[ service = 'test' major_version = '1' minor_version = '0' ] ' [13] = '[ service = 'loc' major_version = '2' minor_version = '0' ] ' [14] = '[ service = 'sar' major_version = '1' minor_version = '0' ] ' [15] = '[ service = 'ts' major_version = '1' minor_version = '0' ] ' [16] = '[ service = 'tmd' major_version = '1' minor_version = '0' ] ' [17] = '[ service = 'wda' major_version = '1' minor_version = '16' ] ' [18] = '[ service = 'csvt' major_version = '1' minor_version = '1' ] ' [19] = '[ service = 'coex' major_version = '1' minor_version = '0' ] ' [20] = '[ service = 'pdc' major_version = '1' minor_version = '0' ] ' [21] = '[ service = 'rfrpe' major_version = '1' minor_version = '0' ] ' [22] = '[ service = 'dsd' major_version = '1' minor_version = '0' ] ' [23] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [24] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [25] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [26] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [27] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [28] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [29] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [30] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [31] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [32] = '[ service = '(null)' major_version = '1' minor_version = '0' ] '}
[/dev/cdc-wdm0] QMI Device supports 33 services:[/dev/cdc-wdm0]    ctl (1.5)[/dev/cdc-wdm0]    wds (1.119)[/dev/cdc-wdm0]    dms (1.0)[/dev/cdc-wdm0]    nas (1.25)[/dev/cdc-wdm0]    qos (1.12)[/dev/cdc-wdm0]    wms (1.10)[/dev/cdc-wdm0]    auth (1.3)[/dev/cdc-wdm0]    at (1.4)[/dev/cdc-wdm0]    voice (2.1)[/dev/cdc-wdm0]    cat2 (2.24)[/dev/cdc-wdm0]    uim (1.77)[/dev/cdc-wdm0]    pbm (1.4)[/dev/cdc-wdm0]    test (1.0)[/dev/cdc-wdm0]    loc (2.0)[/dev/cdc-wdm0]    sar (1.0)[/dev/cdc-wdm0]    ts (1.0)[/dev/cdc-wdm0]    tmd (1.0)[/dev/cdc-wdm0]    wda (1.16)[/dev/cdc-wdm0]    csvt (1.1)[/dev/cdc-wdm0]    coex (1.0)[/dev/cdc-wdm0]    pdc (1.0)[/dev/cdc-wdm0]    rfrpe (1.0)[/dev/cdc-wdm0]    dsd (1.0)[/dev/cdc-wdm0]    unknown [0x30] (1.0)[/dev/cdc-wdm0]    unknown [0x31] (1.0)[/dev/cdc-wdm0]    unknown [0x36] (1.0)[/dev/cdc-wdm0]    unknown [0x44] (1.0)[/dev/cdc-wdm0]    unknown [0x47] (1.0)[/dev/cdc-wdm0]    unknown [0xf0] (1.0)[/dev/cdc-wdm0]    unknown [0xf3] (1.0)[/dev/cdc-wdm0]    unknown [0xf5] (1.0)[/dev/cdc-wdm0]    unknown [0xf6] (1.0)[/dev/cdc-wdm0]    unknown [0xfc] (1.0)<debug> [1516814824.441648] [mm-port-qmi.c:401] port_open_context_step(): Querying kernel data format...
[/dev/cdc-wdm0] Reading expected data format from: /sys/class/net/wwp0s20u2i8/qmi/raw_ip<debug> [1516814824.450159] [mm-port-qmi.c:415] port_open_context_step(): Allocating WDA client...
[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:03:22:00:04:00:01:01:00:1A
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 1A
<<<<<<   translated = wda
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:03:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:1A:01
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 1A:01
<<<<<<   translated = [ service = 'wda' cid = '1' ]
[/dev/cdc-wdm0] Registered 'wda' (version 1.16) client with ID '1'<debug> [1516814824.505099] [mm-port-qmi.c:428] port_open_context_step(): Querying device data format...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:1A:01:00:01:00:21:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "wda"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Data Format" (0x0021)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 91
<<<<<<   data   = 01:5A:00:80:1A:01:02:01:00:21:00:4E:00:02:04:00:00:00:00:00:10:01:00:00:11:04:00:02:00:00:00:12:04:00:00:00:00:00:13:04:00:00:00:00:00:14:04:00:00:00:00:00:15:04:00:00:00:00:00:16:04:00:00:00:00:00:17:04:00:10:00:00:00:18:04:00:00:0C:00:00:1A:04:00:00:00:00:00:1B:01:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 90
<<<<<<   flags   = 0x80
<<<<<<   service = "wda"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 78
<<<<<<   message     = "Get Data Format" (0x0021)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "QoS Format" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = no
<<<<<< TLV:
<<<<<<   type       = "Link Layer Protocol" (0x11)
<<<<<<   length     = 4
<<<<<<   value      = 02:00:00:00
<<<<<<   translated = raw-ip
<<<<<< TLV:
<<<<<<   type       = "Uplink Data Aggregation Protocol" (0x12)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = disabled
<<<<<< TLV:
<<<<<<   type       = "Downlink Data Aggregation Protocol" (0x13)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = disabled
<<<<<< TLV:
<<<<<<   type       = "NDP Signature" (0x14)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = 0
<<<<<< TLV:
<<<<<<   type       = "Uplink Data Aggregation Max Size" (0x15)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = 0
<<<<<< TLV:
<<<<<<   type       = "Downlink Data Aggregation Max Size" (0x16)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = 0
<<<<<< TLV:
<<<<<<   type   = 0x17
<<<<<<   length = 4
<<<<<<   value  = 10:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x18
<<<<<<   length = 4
<<<<<<   value  = 00:0C:00:00
<<<<<< TLV:
<<<<<<   type   = 0x1a
<<<<<<   length = 4
<<<<<<   value  = 00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x1b
<<<<<<   length = 1
<<<<<<   value  = 00
<debug> [1516814824.569106] [mm-port-qmi.c:442] port_open_context_step(): Checking data format: kernel raw-ip, device raw-ip
<debug> [1516814824.580944] [mm-port-qmi.c:506] port_open_context_step(): QMI port open operation finished
[/dev/cdc-wdm0] Releasing 'wda' client with flags 'release-cid'...[/dev/cdc-wdm0] Unregistered 'wda' client with ID '1'[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:04:23:00:05:00:01:02:00:1A:01
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 1A:01
<<<<<<   translated = [ service = 'wda' cid = '1' ]
[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:05:22:00:04:00:01:01:00:02
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:04:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:1A:01
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 1A:01
<<<<<<   translated = [ service = 'wda' cid = '1' ]
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:05:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:02
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:02
<<<<<<   translated = [ service = 'dms' cid = '2' ]
[/dev/cdc-wdm0] Registered 'dms' (version 1.0) client with ID '2'[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:06:22:00:04:00:01:01:00:03
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 03
<<<<<<   translated = nas
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:06:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:03:02
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 03:02
<<<<<<   translated = [ service = 'nas' cid = '2' ]
[/dev/cdc-wdm0] Registered 'nas' (version 1.25) client with ID '2'[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:07:22:00:04:00:01:01:00:05
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 05
<<<<<<   translated = wms
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:07:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:05:02
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 05:02
<<<<<<   translated = [ service = 'wms' cid = '2' ]
[/dev/cdc-wdm0] Registered 'wms' (version 1.10) client with ID '2'<debug> [1516814824.825173] [mm-broadband-modem-qmi.c:11224] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'pds': Couldn't create client for service 'pds': Service 'pds' not supported by the device
<debug> [1516814824.835277] [mm-broadband-modem-qmi.c:11224] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'oma': Couldn't create client for service 'oma': Service 'oma' not supported by the device
[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:08:22:00:04:00:01:01:00:0B
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 8
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 0B
<<<<<<   translated = uim
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:08:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:0B:02
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 8
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 0B:02
<<<<<<   translated = [ service = 'uim' cid = '2' ]
[/dev/cdc-wdm0] Registered 'uim' (version 1.77) client with ID '2'<debug> [1516814824.889154] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB2) opening serial port...
<warn>  [1516814824.898269] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
<debug> [1516814824.906306] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
<debug> [1516814824.913335] [mm-port-serial-at.c:496] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
<debug> [1516814824.920565] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
<debug> [1516814824.927646] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
<debug> [1516814824.935145] [mm-broadband-modem-qmi.c:534] modem_load_current_capabilities(): loading current capabilities...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:02:00:01:00:34:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get System Selection Preference" (0x0034)
<debug> [1516814824.943285] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 155
<<<<<<   data   = 01:9A:00:80:03:02:02:01:00:34:00:8E:00:02:04:00:00:00:00:00:10:01:00:00:11:02:00:3F:00:12:08:00:00:00:C0:0F:00:00:06:10:14:02:00:FF:00:15:08:00:DF:19:0F:BA:00:A7:00:00:16:01:00:00:18:04:00:02:00:00:00:19:04:00:00:00:00:00:1A:08:00:00:00:00:00:00:00:00:00:1C:06:00:05:08:05:04:01:02:1D:04:00:00:00:00:00:1F:04:00:02:00:00:00:20:04:00:02:00:00:00:22:02:00:00:00:23:20:00:DF:19:0F:BA:00:A7:00:00:02:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:48
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 154
<<<<<<   flags   = 0x80
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 142
<<<<<<   message     = "Get System Selection Preference" (0x0034)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Emergency mode" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = no
<<<<<< TLV:
<<<<<<   type       = "Mode Preference" (0x11)
<<<<<<   length     = 2
<<<<<<   value      = 3F:00
<<<<<<   translated = cdma-1x, cdma-1xevdo, gsm, umts, lte, td-scdma
<<<<<< TLV:
<<<<<<   type       = "Band Preference" (0x12)
<<<<<<   length     = 8
<<<<<<   value      = 00:00:C0:0F:00:00:06:10
<<<<<<   translated = wcdma-2100, wcdma-pcs-1900, wcdma-dcs-1800, wcdma-1700-us, wcdma-850-us, wcdma-800, wcdma-900, wcdma-1700-japan
<<<<<< TLV:
<<<<<<   type       = "Roaming Preference" (0x14)
<<<<<<   length     = 2
<<<<<<   value      = FF:00
<<<<<<   translated = any
<<<<<< TLV:
<<<<<<   type       = "LTE Band Preference" (0x15)
<<<<<<   length     = 8
<<<<<<   value      = DF:19:0F:BA:00:A7:00:00
<<<<<<   translated = 1, 2, 3, 4, 5, 7, 8, 9, 12, 13, 17, 18, 19, 20, 41, 42, 43
<<<<<< TLV:
<<<<<<   type       = "Network Selection Preference" (0x16)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = automatic
<<<<<< TLV:
<<<<<<   type       = "Service Domain Preference" (0x18)
<<<<<<   length     = 4
<<<<<<   value      = 02:00:00:00
<<<<<<   translated = cs-ps
<<<<<< TLV:
<<<<<<   type       = "GSM WCDMA Acquisition Order Preference" (0x19)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = automatic
<<<<<< TLV:
<<<<<<   type       = "TD SCDMA Band Preference" (0x1a)
<<<<<<   length     = 8
<<<<<<   value      = 00:00:00:00:00:00:00:00
<<<<<<   translated = (null)
<<<<<< TLV:
<<<<<<   type   = 0x1c
<<<<<<   length = 6
<<<<<<   value  = 05:08:05:04:01:02
<<<<<< TLV:
<<<<<<   type   = 0x1d
<<<<<<   length = 4
<<<<<<   value  = 00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x1f
<<<<<<   length = 4
<<<<<<   value  = 02:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x20
<<<<<<   length = 4
<<<<<<   value  = 02:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x22
<<<<<<   length = 2
<<<<<<   value  = 00:00
<<<<<< TLV:
<<<<<<   type   = 0x23
<<<<<<   length = 32
<<<<<<   value  = DF:19:0F:BA:00:A7:00:00:02:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:48
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:02:00:02:00:2B:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Technology Preference" (0x002B)
<debug> [1516814824.957591] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [1516814824.971133] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
<debug> [1516814824.984332] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [1516814824.991542] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
<debug> [1516814825.011373] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 26
<<<<<<   data   = 01:19:00:80:03:02:02:02:00:2B:00:0D:00:01:03:00:00:00:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 25
<<<<<<   flags   = 0x80
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 13
<<<<<<   message     = "Get Technology Preference" (0x002B)
<<<<<< TLV:
<<<<<<   type       = "Active" (0x01)
<<<<<<   length     = 3
<<<<<<   value      = 00:00:00
<<<<<<   translated = [ technology_preference = 'auto' technology_preference_duration = 'permanent' ]
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:01:00:20:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Capabilities" (0x0020)
<debug> [1516814825.018859] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
<debug> [1516814825.032783] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [1516814825.039822] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
<debug> [1516814825.054437] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [1516814825.062476] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
<debug> [1516814825.075479] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [1516814825.082821] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 2 (close)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 151
<<<<<<   data   = 01:96:00:80:02:02:02:01:00:20:00:8A:00:02:04:00:00:00:00:00:01:0D:00:80:F0:FA:02:00:E1:F5:05:04:02:02:05:08:10:04:00:04:00:00:00:11:08:00:03:00:00:00:00:00:00:00:12:08:00:00:00:00:00:00:00:00:00:13:0C:00:01:01:01:01:30:00:00:00:00:00:00:00:14:02:00:01:01:15:09:00:01:30:00:00:00:00:00:00:00:16:06:00:01:01:00:00:00:00:17:05:00:01:00:00:00:00:18:01:00:01:19:09:00:01:30:00:00:00:00:00:00:00:1A:0D:00:01:01:01:01:30:00:00:00:00:00:00:00:00:1B:02:00:FF:FF
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 150
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 138
<<<<<<   message     = "Get Capabilities" (0x0020)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Info" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 80:F0:FA:02:00:E1:F5:05:04:02:02:05:08
<<<<<<   translated = [ max_tx_channel_rate = '50000000' max_rx_channel_rate = '100000000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'umts ' [1] = 'lte '}' ]
<<<<<< TLV:
<<<<<<   type   = 0x10
<<<<<<   length = 4
<<<<<<   value  = 04:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 8
<<<<<<   value  = 03:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x12
<<<<<<   length = 8
<<<<<<   value  = 00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x13
<<<<<<   length = 12
<<<<<<   value  = 01:01:01:01:30:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x14
<<<<<<   length = 2
<<<<<<   value  = 01:01
<<<<<< TLV:
<<<<<<   type   = 0x15
<<<<<<   length = 9
<<<<<<   value  = 01:30:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x16
<<<<<<   length = 6
<<<<<<   value  = 01:01:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x17
<<<<<<   length = 5
<<<<<<   value  = 01:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x18
<<<<<<   length = 1
<<<<<<   value  = 01
<<<<<< TLV:
<<<<<<   type   = 0x19
<<<<<<   length = 9
<<<<<<   value  = 01:30:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x1a
<<<<<<   length = 13
<<<<<<   value  = 01:01:01:01:30:00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x1b
<<<<<<   length = 2
<<<<<<   value  = FF:FF
<debug> [1516814825.096389] [mm-modem-helpers-qmi.c:1289] mm_modem_capability_from_qmi_capabilities_context(): Current capabilities built: 'gsm-umts, lte'
  SSP mode preference: 'cdma-1x, cdma-1xevdo, gsm, umts, lte, td-scdma'
  TP: 'auto'
  DMS Capabilities: 'gsm-umts, lte'
<debug> [1516814825.104304] [mm-iface-modem.c:3902] load_current_capabilities_ready(): Setting EPS network as supported
<debug> [1516814825.112228] [mm-broadband-modem-qmi.c:696] modem_load_supported_capabilities(): loading supported capabilities...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:02:00:20:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Capabilities" (0x0020)
<debug> [1516814825.119284] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
<debug> [1516814825.137980] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [1516814825.145420] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 1 (close)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 151
<<<<<<   data   = 01:96:00:80:02:02:02:02:00:20:00:8A:00:02:04:00:00:00:00:00:01:0D:00:80:F0:FA:02:00:E1:F5:05:04:02:02:05:08:10:04:00:04:00:00:00:11:08:00:03:00:00:00:00:00:00:00:12:08:00:00:00:00:00:00:00:00:00:13:0C:00:01:01:01:01:30:00:00:00:00:00:00:00:14:02:00:01:01:15:09:00:01:30:00:00:00:00:00:00:00:16:06:00:01:01:00:00:00:00:17:05:00:01:00:00:00:00:18:01:00:01:19:09:00:01:30:00:00:00:00:00:00:00:1A:0D:00:01:01:01:01:30:00:00:00:00:00:00:00:00:1B:02:00:FF:FF
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 150
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 138
<<<<<<   message     = "Get Capabilities" (0x0020)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Info" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 80:F0:FA:02:00:E1:F5:05:04:02:02:05:08
<<<<<<   translated = [ max_tx_channel_rate = '50000000' max_rx_channel_rate = '100000000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'umts ' [1] = 'lte '}' ]
<<<<<< TLV:
<<<<<<   type   = 0x10
<<<<<<   length = 4
<<<<<<   value  = 04:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 8
<<<<<<   value  = 03:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x12
<<<<<<   length = 8
<<<<<<   value  = 00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x13
<<<<<<   length = 12
<<<<<<   value  = 01:01:01:01:30:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x14
<<<<<<   length = 2
<<<<<<   value  = 01:01
<<<<<< TLV:
<<<<<<   type   = 0x15
<<<<<<   length = 9
<<<<<<   value  = 01:30:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x16
<<<<<<   length = 6
<<<<<<   value  = 01:01:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x17
<<<<<<   length = 5
<<<<<<   value  = 01:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x18
<<<<<<   length = 1
<<<<<<   value  = 01
<<<<<< TLV:
<<<<<<   type   = 0x19
<<<<<<   length = 9
<<<<<<   value  = 01:30:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x1a
<<<<<<   length = 13
<<<<<<   value  = 01:01:01:01:30:00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x1b
<<<<<<   length = 2
<<<<<<   value  = FF:FF
<debug> [1516814825.152234] [mm-iface-modem.c:4360] interface_initialization_step(): Modem allows up to 1 bearers
<debug> [1516814825.160667] [mm-bearer-list.c:270] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
<debug> [1516814825.167839] [mm-broadband-modem-qmi.c:998] modem_load_manufacturer(): loading manufacturer...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:03:00:21:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Manufacturer" (0x0021)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 52
<<<<<<   data   = 01:33:00:80:02:02:02:03:00:21:00:27:00:02:04:00:00:00:00:00:01:1D:00:53:69:65:72:72:61:20:57:69:72:65:6C:65:73:73:2C:20:49:6E:63:6F:72:70:6F:72:61:74:65:64
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 51
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 39
<<<<<<   message     = "Get Manufacturer" (0x0021)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Manufacturer" (0x01)
<<<<<<   length     = 29
<<<<<<   value      = 53:69:65:72:72:61:20:57:69:72:65:6C:65:73:73:2C:20:49:6E:63:6F:72:70:6F:72:61:74:65:64
<<<<<<   translated = Sierra Wireless, Incorporated
<debug> [1516814825.209169] [mm-broadband-modem-qmi.c:945] modem_load_manufacturer_finish(): loaded manufacturer: Sierra Wireless, Incorporated
<debug> [1516814825.216286] [mm-broadband-modem-qmi.c:1074] modem_load_model(): loading model...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:04:00:22:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Model" (0x0022)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 29
<<<<<<   data   = 01:1C:00:80:02:02:02:04:00:22:00:10:00:02:04:00:00:00:00:00:01:06:00:45:4D:37:35:36:35
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 28
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Get Model" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Model" (0x01)
<<<<<<   length     = 6
<<<<<<   value      = 45:4D:37:35:36:35
<<<<<<   translated = EM7565
<debug> [1516814825.273052] [mm-broadband-modem-qmi.c:1021] modem_load_model_finish(): loaded model: EM7565
<debug> [1516814825.281233] [mm-broadband-modem-qmi.c:1150] modem_load_revision(): loading revision...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:05:00:23:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Revision" (0x0023)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 78
<<<<<<   data   = 01:4D:00:80:02:02:02:05:00:23:00:41:00:02:04:00:00:00:00:00:01:37:00:53:57:49:39:58:35:30:43:5F:30:31:2E:30:30:2E:30:32:2E:30:30:20:36:66:66:34:38:61:20:6A:65:6E:6B:69:6E:73:20:32:30:31:37:2F:30:39:2F:32:39:20:30:35:3A:35:34:3A:32:36
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 77
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 65
<<<<<<   message     = "Get Revision" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Revision" (0x01)
<<<<<<   length     = 55
<<<<<<   value      = 53:57:49:39:58:35:30:43:5F:30:31:2E:30:30:2E:30:32:2E:30:30:20:36:66:66:34:38:61:20:6A:65:6E:6B:69:6E:73:20:32:30:31:37:2F:30:39:2F:32:39:20:30:35:3A:35:34:3A:32:36
<<<<<<   translated = SWI9X50C_01.00.02.00 6ff48a jenkins 2017/09/29 05:54:26
<debug> [1516814825.337196] [mm-broadband-modem-qmi.c:1097] modem_load_revision_finish(): loaded revision: SWI9X50C_01.00.02.00 6ff48a jenkins 2017/09/29 05:54:26
<debug> [1516814825.345171] [mm-broadband-modem-qmi.c:1292] modem_load_equipment_identifier(): loading equipment identifier...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:06:00:25:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get IDs" (0x0025)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 59
<<<<<<   data   = 01:3A:00:80:02:02:02:06:00:25:00:2E:00:02:04:00:00:00:00:00:10:01:00:30:11:0F:00:33:35:39:32:36:30:30:38:30:31:30:32:38:32:32:12:0E:00:33:35:39:32:36:30:30:38:30:31:30:32:38:32
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 58
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 46
<<<<<<   message     = "Get IDs" (0x0025)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Esn" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 30
<<<<<<   translated = 0
<<<<<< TLV:
<<<<<<   type       = "Imei" (0x11)
<<<<<<   length     = 15
<<<<<<   value      = 33:35:39:32:36:30:30:38:30:31:30:32:38:32:32
<<<<<<   translated = 359260080102822
<<<<<< TLV:
<<<<<<   type       = "Meid" (0x12)
<<<<<<   length     = 14
<<<<<<   value      = 33:35:39:32:36:30:30:38:30:31:30:32:38:32
<<<<<<   translated = 35926008010282
<debug> [1516814825.401192] [mm-broadband-modem-qmi.c:1241] dms_get_ids_ready(): Invalid ESN reported: '0' (unexpected length)
<debug> [1516814825.409545] [mm-broadband-modem-qmi.c:1189] modem_load_equipment_identifier_finish(): loaded equipment identifier: 359260080102822
<debug> [1516814825.416702] [mm-broadband-modem-qmi.c:1327] modem_load_device_identifier(): loading device identifier...
<debug> [1516814825.423860] [mm-modem-helpers.c:310] mm_create_device_identifier(): Device ID source '0000119900009091359260080102822SWI9X50C_01.00.02.006ff48ajenkins2017/09/2905:54:26EM7565SierraWireless,Incorporated'
<debug> [1516814825.430907] [mm-modem-helpers.c:311] mm_create_device_identifier(): Device ID '6725e89adc4bee4038b9b017721e88ded2668f96'
<debug> [1516814825.438158] [mm-broadband-modem-qmi.c:1315] modem_load_device_identifier_finish(): loaded device identifier: 6725e89adc4bee4038b9b017721e88ded2668f96
<debug> [1516814825.446422] [mm-broadband-modem-qmi.c:2171] modem_load_supported_bands(): loading band capabilities...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:07:00:45:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Band Capabilities" (0x0045)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 112
<<<<<<   data   = 01:6F:00:80:02:02:02:07:00:45:00:63:00:02:04:00:00:00:00:00:01:08:00:00:00:C0:0F:00:00:06:10:10:08:00:DF:19:0F:BA:00:A7:00:00:11:08:00:00:00:00:00:00:00:00:00:12:38:00:1B:00:01:00:02:00:03:00:04:00:05:00:07:00:08:00:09:00:0C:00:0D:00:11:00:12:00:13:00:14:00:1A:00:1C:00:1D:00:1E:00:20:00:29:00:2A:00:2B:00:2E:00:30:00:42:00:FC:00:FF:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 111
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 99
<<<<<<   message     = "Get Band Capabilities" (0x0045)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Band Capability" (0x01)
<<<<<<   length     = 8
<<<<<<   value      = 00:00:C0:0F:00:00:06:10
<<<<<<   translated = wcdma-2100, wcdma-pcs-1900, wcdma-dcs-1800, wcdma-1700-us, wcdma-850-us, wcdma-800, wcdma-900, wcdma-1700-japan, wcdma-850-japan
<<<<<< TLV:
<<<<<<   type       = "LTE Band Capability" (0x10)
<<<<<<   length     = 8
<<<<<<   value      = DF:19:0F:BA:00:A7:00:00
<<<<<<   translated = 1, 2, 3, 4, 5, 7, 8, 9, 12, 13, 17, 18, 19, 20, 41, 42, 43
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 8
<<<<<<   value  = 00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x12
<<<<<<   length = 56
<<<<<<   value  = 1B:00:01:00:02:00:03:00:04:00:05:00:07:00:08:00:09:00:0C:00:0D:00:11:00:12:00:13:00:14:00:1A:00:1C:00:1D:00:1E:00:20:00:29:00:2A:00:2B:00:2E:00:30:00:42:00:FC:00:FF:00
<debug> [1516814825.465159] [mm-broadband-modem-qmi.c:3284] load_power_state(): Getting device operating mode...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:08:00:2D:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 8
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Operating Mode" (0x002D)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:02:02:02:08:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 8
<<<<<<   tlv_length  = 11
<<<<<<   message     = "Get Operating Mode" (0x002D)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Mode" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = online
<debug> [1516814825.529187] [mm-broadband-modem-qmi.c:1845] load_unlock_required_context_step(): loading unlock required (DMS)...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:09:00:2B:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 9
<<<<<<   tlv_length  = 0
<<<<<<   message     = "UIM Get PIN Status" (0x002B)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:80:02:02:02:09:00:2B:00:07:00:02:04:00:01:00:5E:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 9
<<<<<<   tlv_length  = 7
<<<<<<   message     = "UIM Get PIN Status" (0x002B)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 01:00:5E:00
<<<<<<   translated = FAILURE: NotSupported
<debug> [1516814825.593303] [mm-broadband-modem-qmi.c:1866] load_unlock_required_context_step(): loading unlock required (UIM)...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:0B:02:00:01:00:2F:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Card Status" (0x002F)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 98
<<<<<<   data   = 01:61:00:80:0B:02:02:01:00:2F:00:55:00:02:04:00:00:00:00:00:16:09:00:02:00:00:00:00:00:00:00:00:15:03:00:02:00:00:11:03:00:02:02:02:10:33:00:00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 97
<<<<<<   flags   = 0x80
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 85
<<<<<<   message     = "Get Card Status" (0x002F)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type   = 0x16
<<<<<<   length = 9
<<<<<<   value  = 02:00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x15
<<<<<<   length = 3
<<<<<<   value  = 02:00:00
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 3
<<<<<<   value  = 02:02:02
<<<<<< TLV:
<<<<<<   type       = "Card Status" (0x10)
<<<<<<   length     = 51
<<<<<<   value      = 00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
<<<<<<   translated = [ index_gw_primary = '0' index_1x_primary = '65535' index_gw_secondary_ = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'present' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'unknown' applications = '{ [0] = '[ type = 'usim' state = 'pin1-or-upin-pin-required' personalization_state = 'unknown' personalization_feature = 'unknown' personalization_retries = '0' personalization_unblock_retries = '0' application_identifier_value = '{ [0] = '160 ' [1] = '0 ' [2] = '0 ' [3] = '0 ' [4] = '135 ' [5] = '16 ' [6] = '2 ' [7] = '255 ' [8] = '255 ' [9] = '255 ' [10] = '255 ' [11] = '137 ' [12] = '4 ' [13] = '3 ' [14] = '0 ' [15] = '255 '}' upin_replaces_pin1 = 'no' pin1_state = 'enabled-not-verified' pin1_retries = '3' puk1_retries = '10' pin2_state = 'enabled-not-verified' pin2_retries = '3' puk2_retries = '10' ] '}' ] ' [1] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
<debug> [1516814825.657374] [mm-broadband-modem-qmi.c:1507] uim_get_card_status_output_parse(): Multiple cards reported: 2
<debug> [1516814825.665512] [mm-broadband-modem-qmi.c:1538] uim_get_card_status_output_parse(): Application 'usim' [0] in card [0]: pin1-or-upin-pin-required
<warn>  [1516814825.672646] [mm-broadband-modem-qmi.c:1569] uim_get_card_status_output_parse(): Card '1' is unusable: no-atr-received
<debug> [1516814825.680576] [mm-broadband-modem-qmi.c:2072] modem_load_unlock_retries(): loading unlock retries...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:0B:02:00:02:00:2F:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Card Status" (0x002F)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 98
<<<<<<   data   = 01:61:00:80:0B:02:02:02:00:2F:00:55:00:02:04:00:00:00:00:00:16:09:00:02:00:00:00:00:00:00:00:00:15:03:00:02:00:00:11:03:00:02:02:02:10:33:00:00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 97
<<<<<<   flags   = 0x80
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 85
<<<<<<   message     = "Get Card Status" (0x002F)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type   = 0x16
<<<<<<   length = 9
<<<<<<   value  = 02:00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x15
<<<<<<   length = 3
<<<<<<   value  = 02:00:00
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 3
<<<<<<   value  = 02:02:02
<<<<<< TLV:
<<<<<<   type       = "Card Status" (0x10)
<<<<<<   length     = 51
<<<<<<   value      = 00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
<<<<<<   translated = [ index_gw_primary = '0' index_1x_primary = '65535' index_gw_secondary_ = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'present' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'unknown' applications = '{ [0] = '[ type = 'usim' state = 'pin1-or-upin-pin-required' personalization_state = 'unknown' personalization_feature = 'unknown' personalization_retries = '0' personalization_unblock_retries = '0' application_identifier_value = '{ [0] = '160 ' [1] = '0 ' [2] = '0 ' [3] = '0 ' [4] = '135 ' [5] = '16 ' [6] = '2 ' [7] = '255 ' [8] = '255 ' [9] = '255 ' [10] = '255 ' [11] = '137 ' [12] = '4 ' [13] = '3 ' [14] = '0 ' [15] = '255 '}' upin_replaces_pin1 = 'no' pin1_state = 'enabled-not-verified' pin1_retries = '3' puk1_retries = '10' pin2_state = 'enabled-not-verified' pin2_retries = '3' puk2_retries = '10' ] '}' ] ' [1] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
<debug> [1516814825.721131] [mm-broadband-modem-qmi.c:1507] uim_get_card_status_output_parse(): Multiple cards reported: 2
<debug> [1516814825.729216] [mm-broadband-modem-qmi.c:1538] uim_get_card_status_output_parse(): Application 'usim' [0] in card [0]: pin1-or-upin-pin-required
<warn>  [1516814825.736264] [mm-broadband-modem-qmi.c:1569] uim_get_card_status_output_parse(): Card '1' is unusable: no-atr-received
<info>  [1516814825.743191] [mm-iface-modem.c:1433] __iface_modem_update_state_internal(): Modem: state changed (unknown -> locked)
<debug> [1516814825.750513] [mm-sim-qmi.c:144] load_sim_identifier(): loading SIM identifier...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:0A:00:3C:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 10
<<<<<<   tlv_length  = 0
<<<<<<   message     = "UIM Get ICCID" (0x003C)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:80:02:02:02:0A:00:3C:00:07:00:02:04:00:01:00:5E:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 10
<<<<<<   tlv_length  = 7
<<<<<<   message     = "UIM Get ICCID" (0x003C)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 01:00:5E:00
<<<<<<   translated = FAILURE: NotSupported
<debug> [1516814825.785201] [mm-sim-qmi.c:144] load_sim_identifier(): loading SIM identifier...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:0B:00:3C:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 11
<<<<<<   tlv_length  = 0
<<<<<<   message     = "UIM Get ICCID" (0x003C)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:80:02:02:02:0B:00:3C:00:07:00:02:04:00:01:00:5E:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 11
<<<<<<   tlv_length  = 7
<<<<<<   message     = "UIM Get ICCID" (0x003C)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 01:00:5E:00
<<<<<<   translated = FAILURE: NotSupported
<warn>  [1516814825.849077] [mm-base-sim.c:1387] load_sim_identifier_ready(): couldn't load SIM identifier: 'Couldn't get UIM ICCID: QMI protocol error (94): 'NotSupported''
<debug> [1516814825.855936] [mm-sim-qmi.c:206] load_imsi(): loading IMSI...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:0C:00:43:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 12
<<<<<<   tlv_length  = 0
<<<<<<   message     = "UIM Get IMSI" (0x0043)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:80:02:02:02:0C:00:43:00:07:00:02:04:00:01:00:5E:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 12
<<<<<<   tlv_length  = 7
<<<<<<   message     = "UIM Get IMSI" (0x0043)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 01:00:5E:00
<<<<<<   translated = FAILURE: NotSupported
<warn>  [1516814825.913166] [mm-base-sim.c:1423] load_imsi_ready(): couldn't load IMSI: 'Couldn't get UIM IMSI: QMI protocol error (94): 'NotSupported''
<debug> [1516814825.921524] [mm-sim-qmi.c:325] load_operator_identifier(): loading SIM operator identifier...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:02:00:03:00:25:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Home Network" (0x0025)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:80:03:02:02:03:00:25:00:0F:00:02:04:00:01:00:25:00:01:05:00:00:00:00:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x80
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 15
<<<<<<   message     = "Get Home Network" (0x0025)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 01:00:25:00
<<<<<<   translated = FAILURE: UimUninitialized
<<<<<< TLV:
<<<<<<   type       = "Home Network" (0x01)
<<<<<<   length     = 5
<<<<<<   value      = 00:00:00:00:00
<<<<<<   translated = [ mcc = '0' mnc = '0' description = '' ]
<warn>  [1516814825.977157] [mm-base-sim.c:1424] load_operator_identifier_ready(): couldn't load Operator identifier: 'Couldn't get home network: QMI protocol error (37): 'UimUninitialized''
<debug> [1516814825.990970] [mm-sim-qmi.c:374] load_operator_name(): loading SIM operator name...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:02:00:04:00:25:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Home Network" (0x0025)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:80:03:02:02:04:00:25:00:0F:00:02:04:00:01:00:25:00:01:05:00:00:00:00:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x80
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 15
<<<<<<   message     = "Get Home Network" (0x0025)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 01:00:25:00
<<<<<<   translated = FAILURE: UimUninitialized
<<<<<< TLV:
<<<<<<   type       = "Home Network" (0x01)
<<<<<<   length     = 5
<<<<<<   value      = 00:00:00:00:00
<<<<<<   translated = [ mcc = '0' mnc = '0' description = '' ]
<warn>  [1516814826.041178] [mm-base-sim.c:1425] load_operator_name_ready(): couldn't load Operator name: 'Couldn't get home network: QMI protocol error (37): 'UimUninitialized''
<debug> [1516814826.048014] [mm-broadband-modem-qmi.c:1411] modem_load_own_numbers(): loading own numbers...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:0D:00:24:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 13
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get MSISDN" (0x0024)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 23
<<<<<<   data   = 01:16:00:80:02:02:02:0D:00:24:00:0A:00:02:04:00:01:00:25:00:01:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 22
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 13
<<<<<<   tlv_length  = 10
<<<<<<   message     = "Get MSISDN" (0x0024)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 01:00:25:00
<<<<<<   translated = FAILURE: UimUninitialized
<<<<<< TLV:
<<<<<<   type       = "MSISDN" (0x01)
<<<<<<   length     = 0
<<<<<<   value      = (null)
<<<<<<   translated = 
<warn>  [1516814826.105176] [mm-iface-modem.c:4145] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (37): 'UimUninitialized''
<debug> [1516814826.113349] [mm-broadband-modem-qmi.c:2319] modem_load_current_bands(): loading current bands...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:02:00:05:00:34:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get System Selection Preference" (0x0034)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 155
<<<<<<   data   = 01:9A:00:80:03:02:02:05:00:34:00:8E:00:02:04:00:00:00:00:00:10:01:00:00:11:02:00:3F:00:12:08:00:00:00:C0:0F:00:00:06:10:14:02:00:FF:00:15:08:00:DF:19:0F:BA:00:A7:00:00:16:01:00:00:18:04:00:02:00:00:00:19:04:00:00:00:00:00:1A:08:00:00:00:00:00:00:00:00:00:1C:06:00:05:08:05:04:01:02:1D:04:00:00:00:00:00:1F:04:00:02:00:00:00:20:04:00:02:00:00:00:22:02:00:00:00:23:20:00:DF:19:0F:BA:00:A7:00:00:02:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:48
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 154
<<<<<<   flags   = 0x80
<<<<<<   service = "nas"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 142
<<<<<<   message     = "Get System Selection Preference" (0x0034)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Emergency mode" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = no
<<<<<< TLV:
<<<<<<   type       = "Mode Preference" (0x11)
<<<<<<   length     = 2
<<<<<<   value      = 3F:00
<<<<<<   translated = cdma-1x, cdma-1xevdo, gsm, umts, lte, td-scdma
<<<<<< TLV:
<<<<<<   type       = "Band Preference" (0x12)
<<<<<<   length     = 8
<<<<<<   value      = 00:00:C0:0F:00:00:06:10
<<<<<<   translated = wcdma-2100, wcdma-pcs-1900, wcdma-dcs-1800, wcdma-1700-us, wcdma-850-us, wcdma-800, wcdma-900, wcdma-1700-japan
<<<<<< TLV:
<<<<<<   type       = "Roaming Preference" (0x14)
<<<<<<   length     = 2
<<<<<<   value      = FF:00
<<<<<<   translated = any
<<<<<< TLV:
<<<<<<   type       = "LTE Band Preference" (0x15)
<<<<<<   length     = 8
<<<<<<   value      = DF:19:0F:BA:00:A7:00:00
<<<<<<   translated = 1, 2, 3, 4, 5, 7, 8, 9, 12, 13, 17, 18, 19, 20, 41, 42, 43
<<<<<< TLV:
<<<<<<   type       = "Network Selection Preference" (0x16)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = automatic
<<<<<< TLV:
<<<<<<   type       = "Service Domain Preference" (0x18)
<<<<<<   length     = 4
<<<<<<   value      = 02:00:00:00
<<<<<<   translated = cs-ps
<<<<<< TLV:
<<<<<<   type       = "GSM WCDMA Acquisition Order Preference" (0x19)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = automatic
<<<<<< TLV:
<<<<<<   type       = "TD SCDMA Band Preference" (0x1a)
<<<<<<   length     = 8
<<<<<<   value      = 00:00:00:00:00:00:00:00
<<<<<<   translated = (null)
<<<<<< TLV:
<<<<<<   type   = 0x1c
<<<<<<   length = 6
<<<<<<   value  = 05:08:05:04:01:02
<<<<<< TLV:
<<<<<<   type   = 0x1d
<<<<<<   length = 4
<<<<<<   value  = 00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x1f
<<<<<<   length = 4
<<<<<<   value  = 02:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x20
<<<<<<   length = 4
<<<<<<   value  = 02:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x22
<<<<<<   length = 2
<<<<<<   value  = 00:00
<<<<<< TLV:
<<<<<<   type   = 0x23
<<<<<<   length = 32
<<<<<<   value  = DF:19:0F:BA:00:A7:00:00:02:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:48
<debug> [1516814826.169271] [mm-modem-helpers-qmi.c:421] nas_add_qmi_bands(): Cannot add the following bands: '(null)'
<debug> [1516814826.177340] [mm-broadband-modem-qmi.c:2285] load_bands_get_system_selection_preference_ready(): Bands reported in system selection preference: 'wcdma-2100, wcdma-pcs-1900, wcdma-dcs-1800, wcdma-1700-us, wcdma-850-us, wcdma-800, wcdma-900, wcdma-1700-japan'
<debug> [1516814826.185091] [mm-broadband-modem-qmi.c:10319] firmware_check_support(): loading firmware images...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:0E:00:49:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 14
<<<<<<   tlv_length  = 0
<<<<<<   message     = "List Stored Images" (0x0049)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 102
<<<<<<   data   = 01:65:00:80:02:02:02:0E:00:49:00:59:00:02:04:00:00:00:00:00:01:4F:00:02:00:04:01:01:01:00:3F:5F:3F:00:00:00:00:00:00:00:00:00:00:00:00:00:0D:30:31:2E:30:30:2E:30:32:2E:30:30:5F:3F:01:32:00:01:FF:FF:30:30:31:2E:30:31:32:5F:30:30:30:00:00:00:00:00:13:30:31:2E:30:30:2E:30:32:2E:30:30:5F:47:45:4E:45:52:49:43
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 101
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 14
<<<<<<   tlv_length  = 89
<<<<<<   message     = "List Stored Images" (0x0049)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "List" (0x01)
<<<<<<   length     = 79
<<<<<<   value      = 02:00:04:01:01:01:00:3F:5F:3F:00:00:00:00:00:00:00:00:00:00:00:00:00:0D:30:31:2E:30:30:2E:30:32:2E:30:30:5F:3F:01:32:00:01:FF:FF:30:30:31:2E:30:31:32:5F:30:30:30:00:00:00:00:00:13:30:31:2E:30:30:2E:30:32:2E:30:30:5F:47:45:4E:45:52:49:43
<<<<<<   translated = { [0] = '[ type = 'modem' maximum_images = '4' index_of_running_image = '1' sublist = '{ [0] = '[ storage_index = '1' failure_count = '0' unique_id = '{ [0] = '63 ' [1] = '95 ' [2] = '63 ' [3] = '0 ' [4] = '0 ' [5] = '0 ' [6] = '0 ' [7] = '0 ' [8] = '0 ' [9] = '0 ' [10] = '0 ' [11] = '0 ' [12] = '0 ' [13] = '0 ' [14] = '0 ' [15] = '0 '}' build_id = '01.00.02.00_?' ] '}' ] ' [1] = '[ type = 'pri' maximum_images = '50' index_of_running_image = '0' sublist = '{ [0] = '[ storage_index = '255' failure_count = '255' unique_id = '{ [0] = '48 ' [1] = '48 ' [2] = '49 ' [3] = '46 ' [4] = '48 ' [5] = '49 ' [6] = '50 ' [7] = '95 ' [8] = '48 ' [9] = '48 ' [10] = '48 ' [11] = '0 ' [12] = '0 ' [13] = '0 ' [14] = '0 ' [15] = '0 '}' build_id = '01.00.02.00_GENERIC' ] '}' ] '}
<debug> [1516814826.233245] [mm-broadband-modem-qmi.c:10267] list_stored_images_ready(): Found pairing PRI+MODEM images with build ID '01.00.02.00_GENERIC'
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 53
<<<<<<   data   = 01:34:00:00:02:02:00:0F:00:4C:00:28:00:01:25:00:01:30:30:31:2E:30:31:32:5F:30:30:30:00:00:00:00:00:13:30:31:2E:30:30:2E:30:32:2E:30:30:5F:47:45:4E:45:52:49:43
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 52
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 15
<<<<<<   tlv_length  = 40
<<<<<<   message     = "Get Stored Image Info" (0x004C)
<<<<<< TLV:
<<<<<<   type       = "Image" (0x01)
<<<<<<   length     = 37
<<<<<<   value      = 01:30:30:31:2E:30:31:32:5F:30:30:30:00:00:00:00:00:13:30:31:2E:30:30:2E:30:32:2E:30:30:5F:47:45:4E:45:52:49:43
<<<<<<   translated = [ type = 'pri' unique_id = '{ [0] = '48 ' [1] = '48 ' [2] = '49 ' [3] = '46 ' [4] = '48 ' [5] = '49 ' [6] = '50 ' [7] = '95 ' [8] = '48 ' [9] = '48 ' [10] = '48 ' [11] = '0 ' [12] = '0 ' [13] = '0 ' [14] = '0 ' [15] = '0 '}' build_id = '01.00.02.00_GENERIC' ]
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:80:02:02:02:0F:00:4C:00:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 15
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Get Stored Image Info" (0x004C)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<debug> [1516814826.297270] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 0 (close)
<debug> [1516814826.305448] [mm-port-serial.c:1369] _close_internal(): (ttyUSB2) closing serial port...
<debug> [1516814826.317937] [mm-port-serial.c:1417] _close_internal(): (ttyUSB2) serial port closed
<debug> [1516814826.325108] [mm-base-modem.c:855] initialize_ready(): Couldn't finish initialization in the current state: 'Modem is currently locked, cannot fully initialize'
<debug> [1516814826.332481] [mm-device.c:453] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2' at path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [1516814826.340599] [mm-device.c:463] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Sierra' modem, VID 0x1199 PID 0x9091 (usb)
<debug> [1516814833.977898] [mm-sim-qmi.c:548] send_pin(): Verifying PIN...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 27
<<<<<<   data   = 01:1A:00:00:0B:02:00:03:00:26:00:0E:00:02:06:00:01:04:30:30:36:39:01:02:00:06:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 26
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 14
<<<<<<   message     = "Verify PIN" (0x0026)
<<<<<< TLV:
<<<<<<   type       = "Info" (0x02)
<<<<<<   length     = 6
<<<<<<   value      = 01:04:30:30:36:39
<<<<<<   translated = [ pin_id = 'pin1' pin_value = '0069' ]
<<<<<< TLV:
<<<<<<   type       = "Session Information" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 06:00
<<<<<<   translated = [ session_type = 'card-slot-1' application_identifier = '' ]
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:80:0B:02:02:03:00:26:00:0C:00:02:04:00:01:00:03:00:10:02:00:03:0A
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x80
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Verify PIN" (0x0026)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 01:00:03:00
<<<<<<   translated = FAILURE: Internal
<<<<<< TLV:
<<<<<<   type       = "Retries Remaining" (0x10)
<<<<<<   length     = 2
<<<<<<   value      = 03:0A
<<<<<<   translated = [ verify_retries_left = '3' unblock_retries_left = '10' ]
<debug> [1516814835.322000] [mm-broadband-modem-qmi.c:1866] load_unlock_required_context_step(): loading unlock required (UIM)...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:0B:02:00:04:00:2F:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Card Status" (0x002F)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 98
<<<<<<   data   = 01:61:00:80:0B:02:02:04:00:2F:00:55:00:02:04:00:00:00:00:00:16:09:00:02:00:00:00:00:00:00:00:00:15:03:00:02:00:00:11:03:00:02:02:02:10:33:00:00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 97
<<<<<<   flags   = 0x80
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 85
<<<<<<   message     = "Get Card Status" (0x002F)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type   = 0x16
<<<<<<   length = 9
<<<<<<   value  = 02:00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x15
<<<<<<   length = 3
<<<<<<   value  = 02:00:00
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 3
<<<<<<   value  = 02:02:02
<<<<<< TLV:
<<<<<<   type       = "Card Status" (0x10)
<<<<<<   length     = 51
<<<<<<   value      = 00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
<<<<<<   translated = [ index_gw_primary = '0' index_1x_primary = '65535' index_gw_secondary_ = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'present' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'unknown' applications = '{ [0] = '[ type = 'usim' state = 'pin1-or-upin-pin-required' personalization_state = 'unknown' personalization_feature = 'unknown' personalization_retries = '0' personalization_unblock_retries = '0' application_identifier_value = '{ [0] = '160 ' [1] = '0 ' [2] = '0 ' [3] = '0 ' [4] = '135 ' [5] = '16 ' [6] = '2 ' [7] = '255 ' [8] = '255 ' [9] = '255 ' [10] = '255 ' [11] = '137 ' [12] = '4 ' [13] = '3 ' [14] = '0 ' [15] = '255 '}' upin_replaces_pin1 = 'no' pin1_state = 'enabled-not-verified' pin1_retries = '3' puk1_retries = '10' pin2_state = 'enabled-not-verified' pin2_retries = '3' puk2_retries = '10' ] '}' ] ' [1] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
<debug> [1516814835.385426] [mm-broadband-modem-qmi.c:1507] uim_get_card_status_output_parse(): Multiple cards reported: 2
<debug> [1516814835.395575] [mm-broadband-modem-qmi.c:1538] uim_get_card_status_output_parse(): Application 'usim' [0] in card [0]: pin1-or-upin-pin-required
<warn>  [1516814835.402500] [mm-broadband-modem-qmi.c:1569] uim_get_card_status_output_parse(): Card '1' is unusable: no-atr-received
<debug> [1516814835.410794] [mm-broadband-modem-qmi.c:2072] modem_load_unlock_retries(): loading unlock retries...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:0B:02:00:05:00:2F:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Card Status" (0x002F)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 98
<<<<<<   data   = 01:61:00:80:0B:02:02:05:00:2F:00:55:00:02:04:00:00:00:00:00:16:09:00:02:00:00:00:00:00:00:00:00:15:03:00:02:00:00:11:03:00:02:02:02:10:33:00:00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 97
<<<<<<   flags   = 0x80
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 85
<<<<<<   message     = "Get Card Status" (0x002F)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type   = 0x16
<<<<<<   length = 9
<<<<<<   value  = 02:00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x15
<<<<<<   length = 3
<<<<<<   value  = 02:00:00
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 3
<<<<<<   value  = 02:02:02
<<<<<< TLV:
<<<<<<   type       = "Card Status" (0x10)
<<<<<<   length     = 51
<<<<<<   value      = 00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
<<<<<<   translated = [ index_gw_primary = '0' index_1x_primary = '65535' index_gw_secondary_ = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'present' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'unknown' applications = '{ [0] = '[ type = 'usim' state = 'pin1-or-upin-pin-required' personalization_state = 'unknown' personalization_feature = 'unknown' personalization_retries = '0' personalization_unblock_retries = '0' application_identifier_value = '{ [0] = '160 ' [1] = '0 ' [2] = '0 ' [3] = '0 ' [4] = '135 ' [5] = '16 ' [6] = '2 ' [7] = '255 ' [8] = '255 ' [9] = '255 ' [10] = '255 ' [11] = '137 ' [12] = '4 ' [13] = '3 ' [14] = '0 ' [15] = '255 '}' upin_replaces_pin1 = 'no' pin1_state = 'enabled-not-verified' pin1_retries = '3' puk1_retries = '10' pin2_state = 'enabled-not-verified' pin2_retries = '3' puk2_retries = '10' ] '}' ] ' [1] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
<debug> [1516814835.449302] [mm-broadband-modem-qmi.c:1507] uim_get_card_status_output_parse(): Multiple cards reported: 2
<debug> [1516814835.457888] [mm-broadband-modem-qmi.c:1538] uim_get_card_status_output_parse(): Application 'usim' [0] in card [0]: pin1-or-upin-pin-required
<warn>  [1516814835.464929] [mm-broadband-modem-qmi.c:1569] uim_get_card_status_output_parse(): Card '1' is unusable: no-atr-received
<debug> [1516814839.190861] [mm-sim-qmi.c:548] send_pin(): Verifying PIN...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 27
<<<<<<   data   = 01:1A:00:00:0B:02:00:06:00:26:00:0E:00:02:06:00:01:04:30:30:36:39:01:02:00:06:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 26
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 14
<<<<<<   message     = "Verify PIN" (0x0026)
<<<<<< TLV:
<<<<<<   type       = "Info" (0x02)
<<<<<<   length     = 6
<<<<<<   value      = 01:04:30:30:36:39
<<<<<<   translated = [ pin_id = 'pin1' pin_value = '0069' ]
<<<<<< TLV:
<<<<<<   type       = "Session Information" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 06:00
<<<<<<   translated = [ session_type = 'card-slot-1' application_identifier = '' ]
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:80:0B:02:02:06:00:26:00:0C:00:02:04:00:01:00:03:00:10:02:00:03:0A
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x80
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Verify PIN" (0x0026)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 01:00:03:00
<<<<<<   translated = FAILURE: Internal
<<<<<< TLV:
<<<<<<   type       = "Retries Remaining" (0x10)
<<<<<<   length     = 2
<<<<<<   value      = 03:0A
<<<<<<   translated = [ verify_retries_left = '3' unblock_retries_left = '10' ]
<debug> [1516814840.537131] [mm-broadband-modem-qmi.c:1866] load_unlock_required_context_step(): loading unlock required (UIM)...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:0B:02:00:07:00:2F:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Card Status" (0x002F)
[/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 98
<<<<<<   data   = 01:61:00:80:0B:02:02:07:00:2F:00:55:00:02:04:00:00:00:00:00:16:09:00:02:00:00:00:00:00:00:00:00:15:03:00:02:00:00:11:03:00:02:02:02:10:33:00:00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
[/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 97
<<<<<<   flags   = 0x80
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 85
<<<<<<   message     = "Get Card Status" (0x002F)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type   = 0x16
<<<<<<   length = 9
<<<<<<   value  = 02:00:00:00:00:00:00:00:00
<<<<<< TLV:
<<<<<<   type   = 0x15
<<<<<<   length = 3
<<<<<<   value  = 02:00:00
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 3
<<<<<<   value  = 02:02:02
<<<<<< TLV:
<<<<<<   type       = "Card Status" (0x10)
<<<<<<   length     = 51
<<<<<<   value      = 00:00:FF:FF:FF:FF:FF:FF:02:01:00:00:00:00:01:02:02:00:0B:00:00:10:A0:00:00:00:87:10:02:FF:FF:FF:FF:89:04:03:00:FF:00:01:03:0A:01:03:0A:02:00:00:00:03:00
<<<<<<   translated = [ index_gw_primary = '0' index_1x_primary = '65535' index_gw_secondary_ = '65535' index_1x_secondary = '65535' cards = '{ [0] = '[ card_state = 'present' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'unknown' applications = '{ [0] = '[ type = 'usim' state = 'pin1-or-upin-pin-required' personalization_state = 'unknown' personalization_feature = 'unknown' personalization_retries = '0' personalization_unblock_retries = '0' application_identifier_value = '{ [0] = '160 ' [1] = '0 ' [2] = '0 ' [3] = '0 ' [4] = '135 ' [5] = '16 ' [6] = '2 ' [7] = '255 ' [8] = '255 ' [9] = '255 ' [10] = '255 ' [11] = '137 ' [12] = '4 ' [13] = '3 ' [14] = '0 ' [15] = '255 '}' upin_replaces_pin1 = 'no' pin1_state = 'enabled-not-verified' pin1_retries = '3' puk1_retries = '10' pin2_state = 'enabled-not-verified' pin2_retries = '3' puk2_retries = '10' ] '}' ] ' [1] = '[ card_state = 'error' upin_state = 'not-initialized' upin_retries = '0' upuk_retries = '0' error_code = 'no-atr-received' applications = '{}' ] '}' ]
<debug> [1516814840.601272] [mm-broadband-modem-qmi.c:1507] uim_get_card_status_output_parse(): Multiple cards reported: 2
<debug> [1516814840.609731] [mm-broadband-modem-qmi.c:1538] uim_get_card_status_output_parse(): Application 'usim' [0] in card [0]: pin1-or-upin-pin-required
<warn>  [1516814840.616836] [mm-broadband-modem-qmi.c:1569] uim_get_card_status_output_parse(): Card '1' is unusable: no-atr-received
<debug> [1516814840.623942] [mm-broadband-modem-qmi.c:2072] modem_load_unlock_retries(): loading unlock retries...
[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:0B:02:00:08:00:2F:00:00:00
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "uim"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 8
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Card Status" (0x002F)
<info>  [1516814840.634582] [main.c:46] quit_cb(): Caught signal, shutting down...
<debug> [1516814840.642307] [mm-base-manager.c:853] set_property(): Stopping connection in object manager server
Cannot read from istream: connection broken<debug> [1516814840.649534] [mm-device.c:399] unexport_modem(): Unexported modem '/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2' from path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [1516814840.656966] [mm-port-qmi.c:578] mm_port_qmi_close(): Releasing client for service 'uim'...
[/dev/cdc-wdm0] Releasing 'uim' client with flags 'release-cid'...[/dev/cdc-wdm0] Unregistered 'uim' client with ID '2'[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:09:23:00:05:00:01:02:00:0B:02
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 9
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 0B:02
<<<<<<   translated = [ service = 'uim' cid = '2' ]
<debug> [1516814840.664996] [mm-port-qmi.c:578] mm_port_qmi_close(): Releasing client for service 'wms'...
[/dev/cdc-wdm0] Releasing 'wms' client with flags 'release-cid'...[/dev/cdc-wdm0] Unregistered 'wms' client with ID '2'[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:0A:23:00:05:00:01:02:00:05:02
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 10
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 05:02
<<<<<<   translated = [ service = 'wms' cid = '2' ]
<debug> [1516814840.672994] [mm-port-qmi.c:578] mm_port_qmi_close(): Releasing client for service 'nas'...
[/dev/cdc-wdm0] Releasing 'nas' client with flags 'release-cid'...[/dev/cdc-wdm0] Unregistered 'nas' client with ID '2'[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:0B:23:00:05:00:01:02:00:03:02
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 11
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 03:02
<<<<<<   translated = [ service = 'nas' cid = '2' ]
<debug> [1516814840.680956] [mm-port-qmi.c:578] mm_port_qmi_close(): Releasing client for service 'dms'...
[/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...[/dev/cdc-wdm0] Unregistered 'dms' client with ID '2'[/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:0C:23:00:05:00:01:02:00:02:02
[/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 12
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:02
<<<<<<   translated = [ service = 'dms' cid = '2' ]
