Attachment 116984 Details for Bug 91242 – Debug script of ModemManager

Debug script of ModemManager

mm.log (text/plain), 125.92 KB, created by tkteun on 2015-07-06 17:51:53 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: tkteun

Created: 2015-07-06 17:51:53 UTC

Size: 125.92 KB

patch

obsolete

>Script started on Mon Jul  6 19:38:35 2015
>[01;32mtkteun@tk-2540p[01;34m ~ $[00m sudo ModemManager --debug
>[sudo] password for tkteun: 
>sudo: ModemManager: command not found
>[01;32mtkteun@tk-2540p[01;34m ~ $[00m sudo ModemManager --debug[1@/
>ModemManager[4345]: <info>  [1436204337.449692] [main.c:140] main(): ModemManager (version 1.4.0) starting in system bus...
>ModemManager[4345]: <debug> [1436204337.453400] [main.c:65] bus_acquired_cb(): Bus acquired, creating manager...
>ModemManager[4345]: <debug> [1436204337.463390] [mm-plugin-manager.c:852] load_plugins(): Looking for plugins in '/usr/lib/x86_64-linux-gnu/ModemManager'
>ModemManager[4345]: <debug> [1436204337.476896] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Gobi'
>ModemManager[4345]: <debug> [1436204337.477895] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Cinterion'
>ModemManager[4345]: <debug> [1436204337.478837] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option High-Speed'
>ModemManager[4345]: <debug> [1436204337.491022] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Huawei'
>ModemManager[4345]: <debug> [1436204337.500810] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Altair LTE'
>ModemManager[4345]: <debug> [1436204337.501634] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'AnyDATA'
>ModemManager[4345]: <debug> [1436204337.512112] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'ZTE'
>ModemManager[4345]: <debug> [1436204337.512966] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia'
>ModemManager[4345]: <debug> [1436204337.522779] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel'
>ModemManager[4345]: <debug> [1436204337.523331] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Generic'
>ModemManager[4345]: <debug> [1436204337.524209] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel LTE'
>ModemManager[4345]: <debug> [1436204337.524966] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'X22X'
>ModemManager[4345]: <debug> [1436204337.525598] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'SimTech'
>ModemManager[4345]: <debug> [1436204337.526332] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Samsung'
>ModemManager[4345]: <debug> [1436204337.526931] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Via CBP7'
>ModemManager[4345]: <debug> [1436204337.527285] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Motorola'
>ModemManager[4345]: <debug> [1436204337.530773] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia (Icera)'
>ModemManager[4345]: <debug> [1436204337.531840] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Wavecom'
>ModemManager[4345]: <debug> [1436204337.532643] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option'
>ModemManager[4345]: <debug> [1436204337.533585] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'MTK'
>ModemManager[4345]: <debug> [1436204337.534146] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Telit'
>ModemManager[4345]: <debug> [1436204337.535456] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Sierra'
>ModemManager[4345]: <debug> [1436204337.536271] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Pantech'
>ModemManager[4345]: <debug> [1436204337.537002] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Longcheer'
>ModemManager[4345]: <debug> [1436204337.537702] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Iridium'
>ModemManager[4345]: <debug> [1436204337.538230] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Linktop'
>ModemManager[4345]: <debug> [1436204337.539224] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Ericsson MBM'
>ModemManager[4345]: <debug> [1436204337.539292] [mm-plugin-manager.c:902] load_plugins(): Successfully loaded 27 plugins
>ModemManager[4345]: <debug> [1436204337.540244] [main.c:87] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
>ModemManager[4345]: <debug> [1436204337.540275] [mm-base-manager.c:479] mm_base_manager_start(): Starting automatic device scan...
>ModemManager[4345]: <debug> [1436204337.541738] [mm-base-manager.c:518] mm_base_manager_start(): Finished device scan...
>ModemManager[4345]: <debug> [1436204337.542123] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:16.3] Checking device support...
>ModemManager[4345]: <debug> [1436204337.542211] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyS0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.542231] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyS0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.542244] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542258] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542272] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542286] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542302] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542315] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542329] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542342] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542355] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542369] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542381] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542395] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542409] [mm-plugin.c:253] apply_pre_probing_filters(): (Option) [ttyS0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.542422] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyS0] filtered by udev tags
>ModemManager[4345]: <debug> [1436204337.542435] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542448] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyS0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.542461] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542473] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542488] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyS0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.542501] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyS0] filtered by udev tags
>ModemManager[4345]: <debug> [1436204337.542513] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyS0] Found '5' plugins to try...
>ModemManager[4345]: <debug> [1436204337.542526] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS0]   Will try with plugin 'Cinterion'
>ModemManager[4345]: <debug> [1436204337.542539] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS0]   Will try with plugin 'Nokia'
>ModemManager[4345]: <debug> [1436204337.542551] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS0]   Will try with plugin 'Via CBP7'
>ModemManager[4345]: <debug> [1436204337.542563] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS0]   Will try with plugin 'Iridium'
>ModemManager[4345]: <debug> [1436204337.542575] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyS0]   Will try with plugin 'Generic'
>ModemManager[4345]: <debug> [1436204337.543250] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyS0] probe required: 'at, at-vendor'
>ModemManager[4345]: <debug> [1436204337.543378] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyS0) launching port probing: 'at, at-vendor'
>ModemManager[4345]: <debug> [1436204337.543649] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2] Checking device support...
>ModemManager[4345]: <debug> [1436204337.543749] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '2' plugins to try...
>ModemManager[4345]: <debug> [1436204337.543774] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Gobi'
>ModemManager[4345]: <debug> [1436204337.543826] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Generic'
>ModemManager[4345]: <debug> [1436204337.543860] [mm-plugin.c:767] mm_plugin_supports_port(): (Gobi) [ttyUSB0] probe required: 'at, qcdm'
>ModemManager[4345]: <debug> [1436204337.543890] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, qcdm'
>ModemManager[4345]: <debug> [1436204337.544129] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB1] Found '2' plugins to try...
>ModemManager[4345]: <debug> [1436204337.544153] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Gobi'
>ModemManager[4345]: <debug> [1436204337.544170] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Generic'
>ModemManager[4345]: <debug> [1436204337.544194] [mm-plugin.c:767] mm_plugin_supports_port(): (Gobi) [ttyUSB1] probe required: 'at, qcdm'
>ModemManager[4345]: <debug> [1436204337.544224] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm'
>ModemManager[4345]: <debug> [1436204337.544365] [mm-base-manager.c:314] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
>ModemManager[4345]: <debug> [1436204337.544495] [mm-base-manager.c:314] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
>ModemManager[4345]: <debug> [1436204337.544626] [mm-base-manager.c:314] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
>ModemManager[4345]: <debug> [1436204337.545812] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Checking device support...
>ModemManager[4345]: <debug> [1436204337.545949] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [eth0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.546008] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546055] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [eth0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.546105] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546155] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546204] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546251] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546299] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.546348] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546397] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546445] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546491] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.546540] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546588] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.546635] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.546683] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.546729] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.546777] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.546823] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.546869] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.546916] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [eth0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.546962] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [eth0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.547008] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.547056] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.547102] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [eth0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.547151] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [eth0] filtered by udev tags
>ModemManager[4345]: <debug> [1436204337.547201] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [eth0] Found '1' plugins to try...
>ModemManager[4345]: <debug> [1436204337.547248] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [eth0]   Will try with plugin 'Generic'
>ModemManager[4345]: <debug> [1436204337.547301] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [eth0] probing deferred until result suggested
>ModemManager[4345]: <debug> [1436204337.547763] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wwan0] Found '2' plugins to try...
>ModemManager[4345]: <debug> [1436204337.547811] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0]   Will try with plugin 'Gobi'
>ModemManager[4345]: <debug> [1436204337.547857] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0]   Will try with plugin 'Generic'
>ModemManager[4345]: <debug> [1436204337.547913] [mm-plugin.c:689] mm_plugin_supports_port(): (Gobi) [wwan0] probing deferred until result suggested
>ModemManager[4345]: <debug> [1436204337.548258] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.3/0000:43:00.0] Checking device support...
>ModemManager[4345]: <debug> [1436204337.548385] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wlan0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.548445] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.548495] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wlan0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.548546] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.548591] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.548638] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.548719] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.548770] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.548815] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.548839] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.548861] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.548884] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.548907] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.548931] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.548953] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.548976] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.549000] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.549022] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.549044] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.549066] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.549088] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wlan0] filtered by drivers
>ModemManager[4345]: <debug> [1436204337.549110] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wlan0] filtered by vendor/product IDs
>ModemManager[4345]: <debug> [1436204337.549133] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.549155] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.549177] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlan0] filtered by subsystem
>ModemManager[4345]: <debug> [1436204337.549201] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wlan0] filtered by udev tags
>ModemManager[4345]: <debug> [1436204337.549225] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wlan0] Found '1' plugins to try...
>ModemManager[4345]: <debug> [1436204337.549248] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wlan0]   Will try with plugin 'Generic'
>ModemManager[4345]: <debug> [1436204337.549274] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [wlan0] probing deferred until result suggested
>ModemManager[4345]: <debug> [1436204337.549531] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try...
>ModemManager[4345]: <debug> [1436204337.549556] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'Gobi'
>ModemManager[4345]: <debug> [1436204337.549577] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'Generic'
>ModemManager[4345]: <debug> [1436204337.549606] [mm-plugin.c:767] mm_plugin_supports_port(): (Gobi) [cdc-wdm0] probe required: 'qmi'
>ModemManager[4345]: <debug> [1436204337.549634] [mm-port-probe.c:1353] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi'
>ModemManager[4345]: <debug> [1436204337.549681] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [eth0] deferring support check until result suggested
>ModemManager[4345]: <debug> [1436204337.549715] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wwan0] deferring support check until result suggested
>ModemManager[4345]: <debug> [1436204337.549745] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wlan0] deferring support check until result suggested
>ModemManager[4345]: <debug> [1436204337.549961] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyS0) opening serial port...
>ModemManager[4345]: <debug> [1436204337.550166] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyS0) device open count is 1 (open)
>ModemManager[4345]: <debug> [1436204337.550278] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[4345]: <warn>  [1436204337.550348] [mm-port-serial.c:515] real_config_fd(): (ttyUSB0): port attributes not fully set
>ModemManager[4345]: <debug> [1436204337.550382] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[4345]: <debug> [1436204337.550476] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[4345]: <warn>  [1436204337.550528] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[4345]: <debug> [1436204337.550564] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[4345]: <debug> [1436204337.550602] [mm-port-probe.c:456] wdm_probe_qmi(): (usbmisc/cdc-wdm0) probing QMI...
>ModemManager[4345]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
>ModemManager[4345]: cannot connect to proxy: Could not connect: Connection refused
>ModemManager[4345]: spawning new qmi-proxy (try 1)...
>ModemManager[4345]: <debug> [1436204337.651220] [mm-port-serial-at.c:440] debug_log(): (ttyS0): --> 'AT^SQPORT?<CR>'
>ModemManager[4345]: <debug> [1436204337.651350] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
>ModemManager[4345]: <debug> [1436204337.651401] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 28
><<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 27
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 16
><<<<<<   message     = "Internal Proxy Open" (0xFF00)
><<<<<< TLV:
><<<<<<   type       = "Device Path" (0x01)
><<<<<<   length     = 13
><<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
><<<<<<   translated = /dev/cdc-wdm0
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 19
>>>>>>>   data   = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 18
>>>>>>>   flags   = 0x00
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Internal Proxy Open" (0xFF00)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[4345]: [/dev/cdc-wdm0] Checking version info (10 retries)...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 12
><<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 11
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Version Info" (0x0021)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 96
>>>>>>>   data   = 01:5F:00:80:00:00:01:02:21:00:54:00:02:04:00:00:00:00:00:01:1F:00:06:00:01:00:00:00:01:01:00:01:00:02:01:00:01:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00:10:28:00:08:55:4E:44:50:20:31:2E:30:06:00:01:00:00:00:01:01:00:00:00:02:01:00:00:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 95
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 84
>>>>>>>   message     = "Get Version Info" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Service list" (0x01)
>>>>>>>   length     = 31
>>>>>>>   value      = 06:00:01:00:00:00:01:01:00:01:00:02:01:00:01:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00
>>>>>>>   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '0' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '1' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '1' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] ' [4] = '[ service = 'wms' major_version = '1' minor_version = '0' ] ' [5] = '[ service = 'pds' major_version = '1' minor_version = '0' ] '}
>>>>>>> TLV:
>>>>>>>   type   = 0x10
>>>>>>>   length = 40
>>>>>>>   value  = 08:55:4E:44:50:20:31:2E:30:06:00:01:00:00:00:01:01:00:00:00:02:01:00:00:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] QMI Device supports 6 services:
>ModemManager[4345]: [/dev/cdc-wdm0]    ctl (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0]    wds (1.1)
>ModemManager[4345]: [/dev/cdc-wdm0]    dms (1.1)
>ModemManager[4345]: [/dev/cdc-wdm0]    nas (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0]    wms (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0]    pds (1.0)
>ModemManager[4345]: <debug> [1436204337.706837] [mm-port-probe.c:271] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable
>ModemManager[4345]: <debug> [1436204337.851521] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- 'AT'
>ModemManager[4345]: <debug> [1436204337.851650] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Gobi) [cdc-wdm0] found best plugin for port
>ModemManager[4345]: <debug> [1436204337.851695] [mm-plugin-manager.c:232] port_probe_context_finished(): (Plugin Manager) (Gobi) [cdc-wdm0]: found best plugin for device (/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2)
>ModemManager[4345]: <debug> [1436204337.851738] [mm-plugin-manager.c:361] suggest_single_port_probe_result(): (Plugin Manager) (Gobi) [wwan0] deferred task completed, got suggested plugin
>ModemManager[4345]: <debug> [1436204337.851795] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Gobi) [ttyUSB1] suggested plugin for port
>ModemManager[4345]: <debug> [1436204337.851822] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Gobi) [ttyUSB0] suggested plugin for port
>ModemManager[4345]: <debug> [1436204337.851865] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 3 running probes in this device (wwan0, ttyUSB1, ttyUSB0)
>ModemManager[4345]: <debug> [1436204337.851910] [mm-plugin.c:689] mm_plugin_supports_port(): (Gobi) [wwan0] probing deferred until result suggested
>ModemManager[4345]: <debug> [1436204337.851949] [mm-plugin-manager.c:549] plugin_supports_port_ready(): (Plugin Manager) (Gobi) [wwan0] task completed, got suggested plugin
>ModemManager[4345]: <debug> [1436204337.851997] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'wwan0' port probe finished, still 2 running probes in this device (ttyUSB1, ttyUSB0)
>ModemManager[4345]: <debug> [1436204337.852660] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR>'
>ModemManager[4345]: <debug> [1436204337.853177] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204337.853309] [mm-port-probe.c:152] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is AT-capable
>ModemManager[4345]: <debug> [1436204337.853446] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close)
>ModemManager[4345]: <debug> [1436204337.853531] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port...
>ModemManager[4345]: <debug> [1436204337.854664] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed
>ModemManager[4345]: <debug> [1436204337.854730] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB1) forced to close port
>ModemManager[4345]: <debug> [1436204337.854832] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Gobi) [ttyUSB1] found best plugin for port
>ModemManager[4345]: <debug> [1436204337.854859] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB1' port probe finished, still 1 running probes in this device (ttyUSB0)
>ModemManager[4345]: <debug> [1436204339.573695] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:16.3] Minimum probing time consumed
>ModemManager[4345]: <debug> [1436204339.573776] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2] Minimum probing time consumed
>ModemManager[4345]: <debug> [1436204339.573825] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Minimum probing time consumed
>ModemManager[4345]: <debug> [1436204339.573924] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [eth0] deferred task cancelled, no suggested plugin
>ModemManager[4345]: <debug> [1436204339.573999] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.3/0000:43:00.0] Minimum probing time consumed
>ModemManager[4345]: <debug> [1436204339.574048] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [wlan0] deferred task cancelled, no suggested plugin
>ModemManager[4345]: <debug> [1436204339.574125] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [eth0] not supported by any plugin
>ModemManager[4345]: <debug> [1436204339.574173] [mm-device.c:357] mm_device_ignore_port(): Fully ignoring port 'net/eth0' from now on
>ModemManager[4345]: <debug> [1436204339.574236] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'eth0' port probe finished, last one in device
>ModemManager[4345]: <debug> [1436204339.574287] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] device support check finished in '2.028396' seconds
>ModemManager[4345]: <warn>  [1436204339.574444] [mm-base-manager.c:153] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:19.0': not supported by any plugin
>ModemManager[4345]: <debug> [1436204339.574505] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [wlan0] not supported by any plugin
>ModemManager[4345]: <debug> [1436204339.574552] [mm-device.c:357] mm_device_ignore_port(): Fully ignoring port 'net/wlan0' from now on
>ModemManager[4345]: <debug> [1436204339.574621] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'wlan0' port probe finished, last one in device
>ModemManager[4345]: <debug> [1436204339.574665] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.3/0000:43:00.0] device support check finished in '2.026346' seconds
>ModemManager[4345]: <warn>  [1436204339.574736] [mm-base-manager.c:153] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.3/0000:43:00.0': not supported by any plugin
>ModemManager[4345]: <debug> [1436204341.574968] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[4345]: <debug> [1436204341.575136] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
>ModemManager[4345]: <debug> [1436204341.575237] [mm-port-serial-at.c:440] debug_log(): (ttyS0): --> 'AT<CR>'
>^AModemManager[4345]: <debug> [1436204344.575755] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[4345]: <debug> [1436204344.575872] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[4345]: <debug> [1436204344.576021] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
>ModemManager[4345]: <debug> [1436204344.576185] [mm-port-serial-at.c:440] debug_log(): (ttyS0): --> 'AT<CR>'
>ModemManager[4345]: <debug> [1436204347.575670] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[4345]: <debug> [1436204347.575748] [mm-port-probe.c:162] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
>ModemManager[4345]: <debug> [1436204347.575805] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[4345]: <debug> [1436204347.575885] [mm-port-probe.c:658] serial_probe_qcdm(): (tty/ttyUSB0) probing QCDM...
>ModemManager[4345]: <debug> [1436204347.575985] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB0) device open count is 0 (close)
>ModemManager[4345]: <debug> [1436204347.576018] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB0) closing serial port...
>ModemManager[4345]: <debug> [1436204347.577195] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB0) serial port closed
>ModemManager[4345]: <debug> [1436204347.577255] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB0) forced to close port
>ModemManager[4345]: <debug> [1436204347.577408] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[4345]: <debug> [1436204347.577527] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[4345]: <debug> [1436204347.577611] [mm-port-serial-qcdm.c:201] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
>ModemManager[4345]: <debug> [1436204347.577662] [mm-port-serial-at.c:440] debug_log(): (ttyS0): --> 'AT<CR>'
>ModemManager[4345]: <debug> [1436204347.579495] [mm-port-serial-qcdm.c:201] debug_log(): (ttyUSB0): <-- 00 41 70 72 20 31 34 20 32 30 30 38 31 37 3a 34 32 3a 35 36 41 70 72 20 31 34 20 32 30 30 38 31 38 3a 30 30 3a 30 30 53 55 55 41 41 53 46 4d 00 00 58 00 00 00 01 60 34 88 7e
>ModemManager[4345]: <debug> [1436204347.579590] [mm-port-probe.c:240] mm_port_probe_set_result_qcdm(): (tty/ttyUSB0) port is QCDM-capable
>ModemManager[4345]: <debug> [1436204347.579661] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB0) device open count is 0 (close)
>ModemManager[4345]: <debug> [1436204347.579703] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB0) closing serial port...
>ModemManager[4345]: <debug> [1436204347.580798] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB0) serial port closed
>ModemManager[4345]: <debug> [1436204347.580852] [mm-port-serial.c:1415] port_serial_close_force(): (ttyUSB0) forced to close port
>ModemManager[4345]: <debug> [1436204347.581056] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Gobi) [ttyUSB0] found best plugin for port
>ModemManager[4345]: <debug> [1436204347.581104] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, last one in device
>ModemManager[4345]: <debug> [1436204347.581205] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2] device support check finished in '10.037520' seconds
>ModemManager[4345]: <info>  [1436204347.581314] [mm-device.c:509] mm_device_create_modem(): Creating modem with plugin 'Gobi' and '4' ports
>ModemManager[4345]: <debug> [1436204347.581416] [gobi/mm-plugin-gobi.c:49] create_modem(): QMI-powered Gobi modem found...
>ModemManager[4345]: <debug> [1436204347.583129] [mm-base-modem.c:304] mm_base_modem_grab_port(): (cdc-wdm0) type 'qmi' claimed by /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2
>ModemManager[4345]: <debug> [1436204347.583216] [mm-base-modem.c:304] mm_base_modem_grab_port(): (wwan0) type 'net' claimed by /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2
>ModemManager[4345]: <debug> [1436204347.583482] [mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyUSB1) type 'at' claimed by /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2
>ModemManager[4345]: <debug> [1436204347.583600] [mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyUSB0) type 'qcdm' claimed by /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2
>ModemManager[4345]: <debug> [1436204347.583662] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2) tty/ttyUSB1 at (primary)
>ModemManager[4345]: <debug> [1436204347.583754] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2) net/wwan0 data (primary)
>ModemManager[4345]: <debug> [1436204347.583846] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2) tty/ttyUSB0 qcdm
>ModemManager[4345]: <debug> [1436204347.583938] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2) usb/cdc-wdm0 qmi (primary)
>ModemManager[4345]: <info>  [1436204347.584374] [mm-base-manager.c:162] find_device_support_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2' successfully created
>ModemManager[4345]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 28
><<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 27
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 16
><<<<<<   message     = "Internal Proxy Open" (0xFF00)
><<<<<< TLV:
><<<<<<   type       = "Device Path" (0x01)
><<<<<<   length     = 13
><<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
><<<<<<   translated = /dev/cdc-wdm0
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 19
>>>>>>>   data   = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 18
>>>>>>>   flags   = 0x00
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Internal Proxy Open" (0xFF00)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[4345]: [/dev/cdc-wdm0] Checking version info (10 retries)...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 12
><<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 11
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Version Info" (0x0021)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 96
>>>>>>>   data   = 01:5F:00:80:00:00:01:02:21:00:54:00:02:04:00:00:00:00:00:01:1F:00:06:00:01:00:00:00:01:01:00:01:00:02:01:00:01:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00:10:28:00:08:55:4E:44:50:20:31:2E:30:06:00:01:00:00:00:01:01:00:00:00:02:01:00:00:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 95
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 84
>>>>>>>   message     = "Get Version Info" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Service list" (0x01)
>>>>>>>   length     = 31
>>>>>>>   value      = 06:00:01:00:00:00:01:01:00:01:00:02:01:00:01:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00
>>>>>>>   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '0' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '1' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '1' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] ' [4] = '[ service = 'wms' major_version = '1' minor_version = '0' ] ' [5] = '[ service = 'pds' major_version = '1' minor_version = '0' ] '}
>>>>>>> TLV:
>>>>>>>   type   = 0x10
>>>>>>>   length = 40
>>>>>>>   value  = 08:55:4E:44:50:20:31:2E:30:06:00:01:00:00:00:01:01:00:00:00:02:01:00:00:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] QMI Device supports 6 services:
>ModemManager[4345]: [/dev/cdc-wdm0]    ctl (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0]    wds (1.1)
>ModemManager[4345]: [/dev/cdc-wdm0]    dms (1.1)
>ModemManager[4345]: [/dev/cdc-wdm0]    nas (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0]    wms (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0]    pds (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0] Setting network port data format...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 21
><<<<<<   data   = 01:14:00:00:00:00:00:03:26:00:09:00:10:02:00:01:00:01:01:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 20
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 3
><<<<<<   tlv_length  = 9
><<<<<<   message     = "Set Data Format" (0x0026)
><<<<<< TLV:
><<<<<<   type       = "Protocol" (0x10)
><<<<<<   length     = 2
><<<<<<   value      = 01:00
><<<<<<   translated = 802-3
><<<<<< TLV:
><<<<<<   type       = "Format" (0x01)
><<<<<<   length     = 1
><<<<<<   value      = 00
><<<<<<   translated = absent
>ModemManager[4345]: <debug> [1436204350.576217] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[4345]: <debug> [1436204350.576436] [mm-port-probe.c:162] mm_port_probe_set_result_at(): (tty/ttyS0) port is not AT-capable
>ModemManager[4345]: <debug> [1436204350.576532] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyS0) device open count is 0 (close)
>ModemManager[4345]: <debug> [1436204350.576594] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyS0) closing serial port...
>ModemManager[4345]: <debug> [1436204350.576745] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyS0) serial port closed
>ModemManager[4345]: <debug> [1436204350.576806] [mm-port-serial.c:1415] port_serial_close_force(): (ttyS0) forced to close port
>ModemManager[4345]: <debug> [1436204350.576882] [mm-plugin.c:429] apply_post_probing_filters(): (Cinterion) [ttyS0] filtered by vendor strings
>ModemManager[4345]: <debug> [1436204350.577035] [mm-plugin.c:767] mm_plugin_supports_port(): (Nokia) [ttyS0] probe required: 'at, at-vendor, at-icera'
>ModemManager[4345]: <debug> [1436204350.577114] [mm-plugin.c:429] apply_post_probing_filters(): (Nokia) [ttyS0] filtered by vendor strings
>ModemManager[4345]: <debug> [1436204350.577197] [mm-plugin.c:767] mm_plugin_supports_port(): (Via CBP7) [ttyS0] probe required: 'at, at-vendor, at-product'
>ModemManager[4345]: <debug> [1436204350.577270] [mm-plugin.c:454] apply_post_probing_filters(): (Via CBP7) [ttyS0] filtered as no vendor/product strings given
>ModemManager[4345]: <debug> [1436204350.577348] [mm-plugin.c:767] mm_plugin_supports_port(): (Iridium) [ttyS0] probe required: 'at, at-vendor, at-product'
>ModemManager[4345]: <debug> [1436204350.577421] [mm-plugin.c:454] apply_post_probing_filters(): (Iridium) [ttyS0] filtered as no vendor/product strings given
>ModemManager[4345]: <debug> [1436204350.577499] [mm-plugin.c:767] mm_plugin_supports_port(): (Generic) [ttyS0] probe required: 'at, qcdm'
>ModemManager[4345]: <debug> [1436204350.577561] [mm-port-probe.c:1353] mm_port_probe_run(): (tty/ttyS0) launching port probing: 'qcdm'
>ModemManager[4345]: <debug> [1436204350.577891] [mm-port-probe.c:658] serial_probe_qcdm(): (tty/ttyS0) probing QCDM...
>ModemManager[4345]: <debug> [1436204350.577995] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyS0) opening serial port...
>ModemManager[4345]: <debug> [1436204350.578221] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyS0) device open count is 1 (open)
>ModemManager[4345]: <debug> [1436204350.578309] [mm-port-serial-qcdm.c:201] debug_log(): (ttyS0): --> 7e 00 78 f0 7e
>ModemManager[4345]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 28
><<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 27
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 16
><<<<<<   message     = "Internal Proxy Open" (0xFF00)
><<<<<< TLV:
><<<<<<   type       = "Device Path" (0x01)
><<<<<<   length     = 13
><<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
><<<<<<   translated = /dev/cdc-wdm0
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 19
>>>>>>>   data   = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 18
>>>>>>>   flags   = 0x00
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Internal Proxy Open" (0xFF00)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[4345]: [/dev/cdc-wdm0] Checking version info (10 retries)...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 12
><<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 11
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Version Info" (0x0021)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 96
>>>>>>>   data   = 01:5F:00:80:00:00:01:02:21:00:54:00:02:04:00:00:00:00:00:01:1F:00:06:00:01:00:00:00:01:01:00:01:00:02:01:00:01:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00:10:28:00:08:55:4E:44:50:20:31:2E:30:06:00:01:00:00:00:01:01:00:00:00:02:01:00:00:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 95
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 84
>>>>>>>   message     = "Get Version Info" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Service list" (0x01)
>>>>>>>   length     = 31
>>>>>>>   value      = 06:00:01:00:00:00:01:01:00:01:00:02:01:00:01:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00
>>>>>>>   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '0' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '1' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '1' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] ' [4] = '[ service = 'wms' major_version = '1' minor_version = '0' ] ' [5] = '[ service = 'pds' major_version = '1' minor_version = '0' ] '}
>>>>>>> TLV:
>>>>>>>   type   = 0x10
>>>>>>>   length = 40
>>>>>>>   value  = 08:55:4E:44:50:20:31:2E:30:06:00:01:00:00:00:01:01:00:00:00:02:01:00:00:00:03:01:00:00:00:05:01:00:00:00:06:01:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] QMI Device supports 6 services:
>ModemManager[4345]: [/dev/cdc-wdm0]    ctl (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0]    wds (1.1)
>ModemManager[4345]: [/dev/cdc-wdm0]    dms (1.1)
>ModemManager[4345]: [/dev/cdc-wdm0]    nas (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0]    wms (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0]    pds (1.0)
>ModemManager[4345]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 16
><<<<<<   data   = 01:0F:00:00:00:00:00:03:22:00:04:00:01:01:00:02
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 15
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 3
><<<<<<   tlv_length  = 4
><<<<<<   message     = "Allocate CID" (0x0022)
><<<<<< TLV:
><<<<<<   type       = "Service" (0x01)
><<<<<<   length     = 1
><<<<<<   value      = 02
><<<<<<   translated = dms
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 24
>>>>>>>   data   = 01:17:00:80:00:00:01:03:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 23
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 3
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Allocate CID" (0x0022)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Allocation Info" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 02:01
>>>>>>>   translated = [ service = 'dms' cid = '1' ]
>ModemManager[4345]: [/dev/cdc-wdm0] Registered 'dms' (version 1.1) client with ID '1'
>ModemManager[4345]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 16
><<<<<<   data   = 01:0F:00:00:00:00:00:04:22:00:04:00:01:01:00:03
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 15
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 4
><<<<<<   tlv_length  = 4
><<<<<<   message     = "Allocate CID" (0x0022)
><<<<<< TLV:
><<<<<<   type       = "Service" (0x01)
><<<<<<   length     = 1
><<<<<<   value      = 03
><<<<<<   translated = nas
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 24
>>>>>>>   data   = 01:17:00:80:00:00:01:04:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:03:01
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 23
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 4
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Allocate CID" (0x0022)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Allocation Info" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 03:01
>>>>>>>   translated = [ service = 'nas' cid = '1' ]
>ModemManager[4345]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '1'
>ModemManager[4345]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 16
><<<<<<   data   = 01:0F:00:00:00:00:00:05:22:00:04:00:01:01:00:05
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (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      = 05
><<<<<<   translated = wms
>ModemManager[4345]: [/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:05:01
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (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      = 05:01
>>>>>>>   translated = [ service = 'wms' cid = '1' ]
>ModemManager[4345]: [/dev/cdc-wdm0] Registered 'wms' (version 1.0) client with ID '1'
>ModemManager[4345]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 16
><<<<<<   data   = 01:0F:00:00:00:00:00:06:22:00:04:00:01:01:00:06
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (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      = 06
><<<<<<   translated = pds
>ModemManager[4345]: [/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:06:01
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (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      = 06:01
>>>>>>>   translated = [ service = 'pds' cid = '1' ]
>ModemManager[4345]: [/dev/cdc-wdm0] Registered 'pds' (version 1.0) client with ID '1'
>ModemManager[4345]: <debug> [1436204352.649861] [mm-broadband-modem-qmi.c:9995] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'oma': Couldn't create client for service 'oma': Service 'oma' not supported by the device
>ModemManager[4345]: <debug> [1436204352.650020] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[4345]: <warn>  [1436204352.650242] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[4345]: <debug> [1436204352.650398] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[4345]: <debug> [1436204352.650523] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB1): running init sequence...
>ModemManager[4345]: <debug> [1436204352.650699] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[4345]: <debug> [1436204352.650858] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[4345]: <debug> [1436204352.652019] [mm-broadband-modem-qmi.c:531] modem_load_current_capabilities(): loading current capabilities...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:01:00:2B:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Technology Preference" (0x002B)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 18
>>>>>>>   data   = 01:11:00:80:06:FF:04:00:00:20:00:05:00:01:02:00:01:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 17
>>>>>>>   flags   = 0x80
>>>>>>>   service = "pds"
>>>>>>>   client  = 255
>>>>>>> QMI:
>>>>>>>   flags       = "indication"
>>>>>>>   transaction = 0
>>>>>>>   tlv_length  = 5
>>>>>>>   message     = (0x0020)
>>>>>>> TLV:
>>>>>>>   type   = 0x01
>>>>>>>   length = 2
>>>>>>>   value  = 01:00
>ModemManager[4345]: <debug> [1436204352.653278] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
>ModemManager[4345]: <debug> [1436204352.657734] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- 'AT'
>ModemManager[4345]: <debug> [1436204352.658020] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- 'E'
>ModemManager[4345]: <debug> [1436204352.659268] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '0'
>ModemManager[4345]: <debug> [1436204352.659765] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR>'
>ModemManager[4345]: <debug> [1436204352.661101] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204352.661274] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'ATV1<CR>'
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 26
>>>>>>>   data   = 01:19:00:80:03:01:02:01:00:2B:00:0D:00:02:04:00:00:00:00:00:01:03:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 25
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 13
>>>>>>>   message     = "Get Technology Preference" (0x002B)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Active" (0x01)
>>>>>>>   length     = 3
>>>>>>>   value      = 00:00:00
>>>>>>>   translated = [ technology_preference = 'auto' technology_preference_duration = 'permanent' ]
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:01:00:20:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Capabilities" (0x0020)
>ModemManager[4345]: <debug> [1436204352.676383] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204352.676583] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 36
>>>>>>>   data   = 01:23:00:80:02:01:02:01:00:20:00:17:00:02:04:00:00:00:00:00:01:0D:00:60:4D:2F:00:60:4D:2F:00:04:02:02:04:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 35
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 23
>>>>>>>   message     = "Get Capabilities" (0x0020)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Info" (0x01)
>>>>>>>   length     = 13
>>>>>>>   value      = 60:4D:2F:00:60:4D:2F:00:04:02:02:04:05
>>>>>>>   translated = [ max_tx_channel_rate = '3100000' max_rx_channel_rate = '3100000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'gsm ' [1] = 'umts '}' ]
>ModemManager[4345]: <debug> [1436204352.677966] [mm-modem-helpers-qmi.c:1268] mm_modem_capability_from_qmi_capabilities_context(): Current capabilities built: 'gsm-umts'
>  SSP mode preference: 'unknown'
>  TP: 'auto'
>  DMS Capabilities: 'gsm-umts'
>ModemManager[4345]: <debug> [1436204352.678184] [mm-broadband-modem-qmi.c:693] modem_load_supported_capabilities(): loading supported capabilities...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:02:00:20:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Capabilities" (0x0020)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 36
>>>>>>>   data   = 01:23:00:80:02:01:02:02:00:20:00:17:00:02:04:00:00:00:00:00:01:0D:00:60:4D:2F:00:60:4D:2F:00:04:02:02:04:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 35
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 23
>>>>>>>   message     = "Get Capabilities" (0x0020)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Info" (0x01)
>>>>>>>   length     = 13
>>>>>>>   value      = 60:4D:2F:00:60:4D:2F:00:04:02:02:04:05
>>>>>>>   translated = [ max_tx_channel_rate = '3100000' max_rx_channel_rate = '3100000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'gsm ' [1] = 'umts '}' ]
>ModemManager[4345]: <debug> [1436204352.683580] [mm-iface-modem.c:4327] interface_initialization_step(): Modem allows up to 1 bearers
>ModemManager[4345]: <debug> [1436204352.683599] [mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
>ModemManager[4345]: <debug> [1436204352.683666] [mm-broadband-modem-qmi.c:995] modem_load_manufacturer(): loading manufacturer...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:03:00:21:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 3
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Manufacturer" (0x0021)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 44
>>>>>>>   data   = 01:2B:00:80:02:01:02:03:00:21:00:1F:00:02:04:00:00:00:00:00:01:15:00:51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 43
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 3
>>>>>>>   tlv_length  = 31
>>>>>>>   message     = "Get Manufacturer" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Manufacturer" (0x01)
>>>>>>>   length     = 21
>>>>>>>   value      = 51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44
>>>>>>>   translated = QUALCOMM INCORPORATED
>ModemManager[4345]: <debug> [1436204352.689959] [mm-broadband-modem-qmi.c:942] modem_load_manufacturer_finish(): loaded manufacturer: QUALCOMM INCORPORATED
>ModemManager[4345]: <debug> [1436204352.690092] [mm-broadband-modem-qmi.c:1071] modem_load_model(): loading model...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:04:00:22:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 4
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Model" (0x0022)
>ModemManager[4345]: <debug> [1436204352.690604] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204352.690768] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'ATX4<CR>'
>ModemManager[4345]: <debug> [1436204352.697102] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 25
>>>>>>>   data   = 01:18:00:80:02:01:02:04:00:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:38:38
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 24
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 4
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Get Model" (0x0022)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Model" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 38:38
>>>>>>>   translated = 88
>ModemManager[4345]: <debug> [1436204352.697663] [mm-broadband-modem-qmi.c:1018] modem_load_model_finish(): loaded model: 88
>ModemManager[4345]: <debug> [1436204352.697773] [mm-broadband-modem-qmi.c:1147] modem_load_revision(): loading revision...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:05:00:23:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 5
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Revision" (0x0023)
>ModemManager[4345]: <debug> [1436204352.698184] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT&C1<CR>'
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 69
>>>>>>>   data   = 01:44:00:80:02:01:02:05:00:23:00:38:00:02:04:00:00:00:00:00:01:2E:00:44:31:30:32:30:2D:53:55:55:41:41:53:46:41:2D:34:33:35:32:20:20:31:20:20:5B:41:70:72:20:31:34:20:32:30:30:38:20:31:38:3A:30:30:3A:30:30:5D
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 68
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 5
>>>>>>>   tlv_length  = 56
>>>>>>>   message     = "Get Revision" (0x0023)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Revision" (0x01)
>>>>>>>   length     = 46
>>>>>>>   value      = 44:31:30:32:30:2D:53:55:55:41:41:53:46:41:2D:34:33:35:32:20:20:31:20:20:5B:41:70:72:20:31:34:20:32:30:30:38:20:31:38:3A:30:30:3A:30:30:5D
>>>>>>>   translated = D1020-SUUAASFA-4352  1  [Apr 14 2008 18:00:00]
>ModemManager[4345]: <debug> [1436204352.703899] [mm-broadband-modem-qmi.c:1094] modem_load_revision_finish(): loaded revision: D1020-SUUAASFA-4352  1  [Apr 14 2008 18:00:00]
>ModemManager[4345]: <debug> [1436204352.704029] [mm-broadband-modem-qmi.c:1289] modem_load_equipment_identifier(): loading equipment identifier...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:06:00:25:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 6
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get IDs" (0x0025)
>ModemManager[4345]: <debug> [1436204352.704840] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204352.705012] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
>ModemManager[4345]: <debug> [1436204352.723817] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204352.723962] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[4345]: <debug> [1436204352.724056] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 66
>>>>>>>   data   = 01:41:00:80:02:01:02:06:00:25:00:35:00:02:04:00:00:00:00:00:12:0E:00:41:31:30:30:30:30:31:30:39:44:39:39:36:30:10:08:00:38:30:31:44:46:43:44:35:11:0F:00:39:38:30:30:34:39:30:30:30:34:36:30:39:38:31
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 65
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 6
>>>>>>>   tlv_length  = 53
>>>>>>>   message     = "Get IDs" (0x0025)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Meid" (0x12)
>>>>>>>   length     = 14
>>>>>>>   value      = 41:31:30:30:30:30:31:30:39:44:39:39:36:30
>>>>>>>   translated = A10000109D9960
>>>>>>> TLV:
>>>>>>>   type       = "Esn" (0x10)
>>>>>>>   length     = 8
>>>>>>>   value      = 38:30:31:44:46:43:44:35
>>>>>>>   translated = 801DFCD5
>>>>>>> TLV:
>>>>>>>   type       = "Imei" (0x11)
>>>>>>>   length     = 15
>>>>>>>   value      = 39:38:30:30:34:39:30:30:30:34:36:30:39:38:31
>>>>>>>   translated = 980049000460981
>ModemManager[4345]: <debug> [1436204352.726672] [mm-broadband-modem-qmi.c:1186] modem_load_equipment_identifier_finish(): loaded equipment identifier: 980049000460981
>ModemManager[4345]: <debug> [1436204352.726775] [mm-broadband-modem-qmi.c:1324] modem_load_device_identifier(): loading device identifier...
>ModemManager[4345]: <debug> [1436204352.726872] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '000003f000001f1d980049000460981D1020-SUUAASFA-43521[Apr14200818:00:00]88QUALCOMMINCORPORATED'
>ModemManager[4345]: <debug> [1436204352.726914] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '2e4001d58dfdbab606869bcd9f58ab041eabddae'
>ModemManager[4345]: <debug> [1436204352.727043] [mm-broadband-modem-qmi.c:1312] modem_load_device_identifier_finish(): loaded device identifier: 2e4001d58dfdbab606869bcd9f58ab041eabddae
>ModemManager[4345]: <debug> [1436204352.727242] [mm-broadband-modem-qmi.c:1727] modem_load_supported_bands(): loading band capabilities...
>ModemManager[4345]: <warn>  [1436204352.727352] [mm-iface-modem.c:3982] load_supported_bands_ready(): couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
>ModemManager[4345]: <debug> [1436204352.727462] [mm-broadband-modem-qmi.c:2682] load_power_state(): Getting device operating mode...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:08:00:2D:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 8
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Operating Mode" (0x002D)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 24
>>>>>>>   data   = 01:17:00:80:02:01:02:08:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 23
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 8
>>>>>>>   tlv_length  = 11
>>>>>>>   message     = "Get Operating Mode" (0x002D)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Mode" (0x01)
>>>>>>>   length     = 1
>>>>>>>   value      = 00
>>>>>>>   translated = online
>ModemManager[4345]: <debug> [1436204352.733380] [mm-broadband-modem-qmi.c:1533] modem_load_unlock_required(): loading unlock required...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:09:00:2B:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 9
><<<<<<   tlv_length  = 0
><<<<<<   message     = "UIM Get PIN Status" (0x002B)
>ModemManager[4345]: <debug> [1436204352.736434] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204352.736544] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 1 (close)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 32
>>>>>>>   data   = 01:1F:00:80:02:01:02:09:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:03:03:0A
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 31
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 9
>>>>>>>   tlv_length  = 19
>>>>>>>   message     = "UIM Get PIN Status" (0x002B)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "PIN2 Status" (0x12)
>>>>>>>   length     = 3
>>>>>>>   value      = 01:03:0A
>>>>>>>   translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>>> TLV:
>>>>>>>   type       = "PIN1 Status" (0x11)
>>>>>>>   length     = 3
>>>>>>>   value      = 03:03:0A
>>>>>>>   translated = [ current_status = 'disabled' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[4345]: <debug> [1436204352.742373] [mm-broadband-modem-qmi.c:1626] modem_load_unlock_retries(): loading unlock retries...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:0A:00:2B:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 10
><<<<<<   tlv_length  = 0
><<<<<<   message     = "UIM Get PIN Status" (0x002B)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 32
>>>>>>>   data   = 01:1F:00:80:02:01:02:0A:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:03:03:0A
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 31
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 10
>>>>>>>   tlv_length  = 19
>>>>>>>   message     = "UIM Get PIN Status" (0x002B)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "PIN2 Status" (0x12)
>>>>>>>   length     = 3
>>>>>>>   value      = 01:03:0A
>>>>>>>   translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>>> TLV:
>>>>>>>   type       = "PIN1 Status" (0x11)
>>>>>>>   length     = 3
>>>>>>>   value      = 03:03:0A
>>>>>>>   translated = [ current_status = 'disabled' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[4345]: <debug> [1436204352.750317] [mm-iface-modem.c:3076] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
>ModemManager[4345]: <debug> [1436204352.750640] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>ModemManager[4345]: <debug> [1436204352.750752] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>ModemManager[4345]: <warn>  [1436204352.750839] [mm-base-sim.c:1428] load_sim_identifier_ready(): couldn't load SIM identifier: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
>ModemManager[4345]: <debug> [1436204352.750913] [mm-sim-qmi.c:224] load_imsi(): loading IMSI...
>ModemManager[4345]: <warn>  [1436204352.751015] [mm-base-sim.c:1464] load_imsi_ready(): couldn't load IMSI: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
>ModemManager[4345]: <debug> [1436204352.751157] [mm-broadband-modem-qmi.c:1408] modem_load_own_numbers(): loading own numbers...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:0E:00:24:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 14
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get MSISDN" (0x0024)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:02:01:02:0E:00:24:00:07:00:02:04:00:01:00:10:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 14
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Get MSISDN" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 01:00:10:00
>>>>>>>   translated = FAILURE: NotProvisioned
>ModemManager[4345]: <warn>  [1436204352.758126] [mm-iface-modem.c:4112] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:02:00:2B:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Technology Preference" (0x002B)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 26
>>>>>>>   data   = 01:19:00:80:03:01:02:02:00:2B:00:0D:00:02:04:00:00:00:00:00:01:03:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 25
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 13
>>>>>>>   message     = "Get Technology Preference" (0x002B)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Active" (0x01)
>>>>>>>   length     = 3
>>>>>>>   value      = 00:00:00
>>>>>>>   translated = [ technology_preference = 'auto' technology_preference_duration = 'permanent' ]
>ModemManager[4345]: <debug> [1436204352.764185] [mm-broadband-modem-qmi.c:2869] get_technology_preference_ready(): Unsupported modes reported: 'auto'
>ModemManager[4345]: <warn>  [1436204352.764263] [mm-iface-modem.c:4141] load_current_modes_ready(): couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
>ModemManager[4345]: <debug> [1436204352.764334] [mm-broadband-modem-qmi.c:1875] modem_load_current_bands(): loading current bands...
>ModemManager[4345]: <warn>  [1436204352.764863] [mm-iface-modem.c:4162] load_current_bands_ready(): couldn't load current Bands: 'QMI operation failed: Cannot send message: QMI service 'nas' version '1.1' required, got version '1.0''
>ModemManager[4345]: <debug> [1436204352.765440] [mm-broadband-modem-qmi.c:3281] modem_3gpp_load_imei_finish(): loaded IMEI: 980049000460981
>ModemManager[4345]: <debug> [1436204352.765672] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'ph-sim' status, assuming disabled
>ModemManager[4345]: <debug> [1436204352.765837] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-pers' status, assuming disabled
>ModemManager[4345]: <debug> [1436204352.766016] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-sub-pers' status, assuming disabled
>ModemManager[4345]: <debug> [1436204352.766124] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'provider-pers' status, assuming disabled
>ModemManager[4345]: <debug> [1436204352.766229] [mm-broadband-modem-qmi.c:3360] dms_uim_get_ck_status_ready(): Couldn't query facility 'corp-pers' status, assuming disabled
>ModemManager[4345]: <debug> [1436204352.766630] [mm-broadband-modem.c:8998] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
>ModemManager[4345]: <debug> [1436204352.767126] [mm-broadband-modem-qmi.c:6587] messaging_check_support(): Messaging capabilities supported
>ModemManager[4345]: <debug> [1436204352.767271] [mm-iface-modem-messaging.c:1148] load_supported_storages_ready(): Supported storages loaded:
>ModemManager[4345]: <debug> [1436204352.767315] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready():   mem1 (list/read/delete) storages: 'sm, me'
>ModemManager[4345]: <debug> [1436204352.767388] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready():   mem2 (write/send) storages:       'sm, me'
>ModemManager[4345]: <debug> [1436204352.767441] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready():   mem3 (reception) storages:        'sm, me'
>ModemManager[4345]: <debug> [1436204352.767702] [mm-broadband-modem.c:9002] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
>ModemManager[4345]: <debug> [1436204352.767900] [mm-broadband-modem-qmi.c:9424] signal_check_support(): Extended signal capabilities supported
>ModemManager[4345]: <debug> [1436204352.768226] [mm-broadband-modem-qmi.c:8138] oma_check_support(): OMA capabilities not supported
>ModemManager[4345]: <debug> [1436204352.768328] [mm-broadband-modem.c:9004] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
>ModemManager[4345]: <debug> [1436204352.768539] [mm-broadband-modem-qmi.c:9090] firmware_check_support(): loading firmware images...
>ModemManager[4345]: <debug> [1436204352.768659] [mm-broadband-modem.c:9005] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
>ModemManager[4345]: <info>  [1436204352.768928] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
>ModemManager[4345]: <debug> [1436204352.769168] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyUSB1) device open count is 0 (close)
>ModemManager[4345]: <debug> [1436204352.769273] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyUSB1) closing serial port...
>ModemManager[4345]: <debug> [1436204352.770383] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyUSB1) serial port closed
>ModemManager[4345]: <debug> [1436204352.770455] [mm-base-modem.c:939] initialize_ready(): modem properly initialized
>ModemManager[4345]: <debug> [1436204352.771452] [mm-device.c:436] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2' at path '/org/freedesktop/ModemManager1/Modem/0'
>ModemManager[4345]: <debug> [1436204352.771510] [mm-device.c:446] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Gobi' modem, VID 0x03F0 PID 0x1F1D (usb)
>ModemManager[4345]: <debug> [1436204353.573720] [mm-port-serial-qcdm.c:201] debug_log(): (ttyS0): --> 7e 00 78 f0 7e
>ModemManager[4345]: <debug> [1436204356.575937] [mm-port-probe.c:258] mm_port_probe_set_result_qcdm(): (tty/ttyS0) port is not QCDM-capable
>ModemManager[4345]: <debug> [1436204356.576049] [mm-port-serial.c:1296] mm_port_serial_close(): (ttyS0) device open count is 0 (close)
>ModemManager[4345]: <debug> [1436204356.576116] [mm-port-serial.c:1312] mm_port_serial_close(): (ttyS0) closing serial port...
>ModemManager[4345]: <debug> [1436204356.576271] [mm-port-serial.c:1361] mm_port_serial_close(): (ttyS0) serial port closed
>ModemManager[4345]: <debug> [1436204356.576309] [mm-port-serial.c:1415] port_serial_close_force(): (ttyS0) forced to close port
>ModemManager[4345]: <debug> [1436204356.576400] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Generic) [ttyS0] found best plugin for port
>ModemManager[4345]: <debug> [1436204356.576460] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyS0' port probe finished, last one in device
>ModemManager[4345]: <debug> [1436204356.576533] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:16.3] device support check finished in '19.034379' seconds
>ModemManager[4345]: <info>  [1436204356.576596] [mm-device.c:509] mm_device_create_modem(): Creating modem with plugin 'Generic' and '1' ports
>ModemManager[4345]: <warn>  [1436204356.576991] [mm-plugin.c:905] mm_plugin_create_modem(): Could not grab port (tty/ttyS0): 'Cannot add port 'tty/ttyS0', unhandled serial type'
>ModemManager[4345]: <debug> [1436204356.577066] [mm-base-modem.c:1485] finalize(): Modem (Generic) '/sys/devices/pci0000:00/0000:00:16.3' completely disposed
>ModemManager[4345]: <warn>  [1436204356.577151] [mm-base-manager.c:158] find_device_support_ready(): Couldn't create modem for device at '/sys/devices/pci0000:00/0000:00:16.3': Failed to find primary AT port
>ModemManager[4345]: <info>  [1436204416.226928] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
>ModemManager[4345]: <debug> [1436204416.227142] [mm-broadband-modem.c:8003] enabling_started(): Skipping modem initialization: not required
>ModemManager[4345]: <debug> [1436204416.227166] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
>ModemManager[4345]: <warn>  [1436204416.227243] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[4345]: <debug> [1436204416.227272] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[4345]: <debug> [1436204416.227286] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB1): running init sequence...
>ModemManager[4345]: <debug> [1436204416.227314] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB0) opening serial port...
>ModemManager[4345]: <debug> [1436204416.227360] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[4345]: <debug> [1436204416.227373] [mm-broadband-modem.c:8016] enabling_started(): Flashing primary AT port before enabling...
>ModemManager[4345]: <debug> [1436204416.227404] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
>ModemManager[4345]: <debug> [1436204416.233428] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204416.233624] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'ATV1<CR>'
>ModemManager[4345]: <debug> [1436204416.239322] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204416.239495] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
>ModemManager[4345]: <debug> [1436204416.250546] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204416.250758] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'ATX4<CR>'
>ModemManager[4345]: <debug> [1436204416.256419] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204416.256564] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT&C1<CR>'
>ModemManager[4345]: <debug> [1436204416.263169] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[4345]: <debug> [1436204416.328540] [mm-broadband-modem-qmi.c:2682] load_power_state(): Getting device operating mode...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:15:00:2D:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "dms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 21
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Operating Mode" (0x002D)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 24
>>>>>>>   data   = 01:17:00:80:02:01:02:15:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 23
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 21
>>>>>>>   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
>ModemManager[4345]: <debug> [1436204416.334078] [mm-iface-modem.c:3259] set_power_state(): No need to change power state: already in 'on' power state
>ModemManager[4345]: <debug> [1436204416.334218] [mm-broadband-modem.c:8592] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 23
><<<<<<   data   = 01:16:00:00:03:01:00:04:00:02:00:0A:00:10:07:00:01:05:B0:D8:00:28:50
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 22
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 4
><<<<<<   tlv_length  = 10
><<<<<<   message     = "Set Event Report" (0x0002)
><<<<<< TLV:
><<<<<<   type       = "Signal Strength Indicator" (0x10)
><<<<<<   length     = 7
><<<<<<   value      = 01:05:B0:D8:00:28:50
><<<<<<   translated = [ report = 'yes' thresholds = '{ [0] = '-80 ' [1] = '-40 ' [2] = '0 ' [3] = '40 ' [4] = '80 '}' ]
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:03:01:02:04:00:02:00:07:00:02:04:00:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 4
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Set Event Report" (0x0002)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[4345]: <debug> [1436204416.340187] [mm-broadband-modem-qmi.c:4769] modem_3gpp_enable_unsolicited_registration_events(): Assuming serving system indications are always enabled
>ModemManager[4345]: <debug> [1436204416.340303] [mm-iface-modem-3gpp.c:751] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:05:00:24:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 5
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Serving System" (0x0024)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 57
>>>>>>>   data   = 01:38:00:80:03:01:02:05:00:24:00:2C:00:02:04:00:00:00:00:00:01:06:00:01:01:01:02:01:05:10:01:00:01:12:10:00:CC:00:04:00:0B:76:6F:64:61:66:6F:6E:65:20:4E:4C:11:02:00:01:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 56
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 5
>>>>>>>   tlv_length  = 44
>>>>>>>   message     = "Get Serving System" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Serving System" (0x01)
>>>>>>>   length     = 6
>>>>>>>   value      = 01:01:01:02:01:05
>>>>>>>   translated = [ registration_state = 'registered' cs_attach_state = 'attached' ps_attach_state = 'attached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>>>>>>> TLV:
>>>>>>>   type       = "Roaming Indicator" (0x10)
>>>>>>>   length     = 1
>>>>>>>   value      = 01
>>>>>>>   translated = off
>>>>>>> TLV:
>>>>>>>   type       = "Current PLMN" (0x12)
>>>>>>>   length     = 16
>>>>>>>   value      = CC:00:04:00:0B:76:6F:64:61:66:6F:6E:65:20:4E:4C
>>>>>>>   translated = [ mcc = '204' mnc = '4' description = 'vodafone NL' ]
>>>>>>> TLV:
>>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>>   length     = 2
>>>>>>>   value      = 01:05
>>>>>>>   translated = { [0] = 'wcdma '}
>ModemManager[4345]: <debug> [1436204416.346371] [mm-broadband-modem-qmi.c:3907] common_process_serving_system_3gpp(): Processing 3GPP info...
>ModemManager[4345]: <info>  [1436204416.346511] [mm-iface-modem-3gpp.c:1154] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
>ModemManager[4345]: <debug> [1436204416.346753] [mm-broadband-modem.c:8633] enabling_step(): Modem has location capabilities, enabling the Location interface...
>ModemManager[4345]: <debug> [1436204416.346820] [mm-iface-modem-location.c:735] setup_gathering(): Location 'gps-raw' gathering is already disabled...
>ModemManager[4345]: <debug> [1436204416.346932] [mm-iface-modem-location.c:735] setup_gathering(): Location 'gps-nmea' gathering is already disabled...
>ModemManager[4345]: <debug> [1436204416.347036] [mm-iface-modem-location.c:759] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
>ModemManager[4345]: <debug> [1436204416.347240] [mm-iface-modem-3gpp.c:751] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:06:00:24:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 6
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Serving System" (0x0024)
>ModemManager[4345]: <info>  [1436204416.348054] [mm-iface-modem-3gpp.c:1079] update_registration_reload_current_registration_info_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
>ModemManager[4345]: <debug> [1436204416.348223] [mm-iface-modem.c:1561] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
>ModemManager[4345]: <debug> [1436204416.348279] [mm-broadband-modem.c:8646] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
>ModemManager[4345]: <debug> [1436204416.348691] [mm-broadband-modem-qmi.c:6775] messaging_set_default_storage(): setting default messaging routes...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 26
><<<<<<   data   = 01:19:00:00:05:01:00:01:00:32:00:0D:00:01:0A:00:02:00:00:00:01:01:00:01:01:01
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 25
><<<<<<   flags   = 0x00
><<<<<<   service = "wms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 13
><<<<<<   message     = "Set Routes" (0x0032)
><<<<<< TLV:
><<<<<<   type       = "Route List" (0x01)
><<<<<<   length     = 10
><<<<<<   value      = 02:00:00:00:01:01:00:01:01:01
><<<<<<   translated = { [0] = '[ message_type = 'point' message_class = '0' storage = 'nv' receipt_action = 'store-and-notify' ] ' [1] = '[ message_type = 'point' message_class = '1' storage = 'nv' receipt_action = 'store-and-notify' ] '}
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 57
>>>>>>>   data   = 01:38:00:80:03:01:02:06:00:24:00:2C:00:02:04:00:00:00:00:00:01:06:00:01:01:01:02:01:05:10:01:00:01:12:10:00:CC:00:04:00:0B:76:6F:64:61:66:6F:6E:65:20:4E:4C:11:02:00:01:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 56
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 6
>>>>>>>   tlv_length  = 44
>>>>>>>   message     = "Get Serving System" (0x0024)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Serving System" (0x01)
>>>>>>>   length     = 6
>>>>>>>   value      = 01:01:01:02:01:05
>>>>>>>   translated = [ registration_state = 'registered' cs_attach_state = 'attached' ps_attach_state = 'attached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>>>>>>> TLV:
>>>>>>>   type       = "Roaming Indicator" (0x10)
>>>>>>>   length     = 1
>>>>>>>   value      = 01
>>>>>>>   translated = off
>>>>>>> TLV:
>>>>>>>   type       = "Current PLMN" (0x12)
>>>>>>>   length     = 16
>>>>>>>   value      = CC:00:04:00:0B:76:6F:64:61:66:6F:6E:65:20:4E:4C
>>>>>>>   translated = [ mcc = '204' mnc = '4' description = 'vodafone NL' ]
>>>>>>> TLV:
>>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>>   length     = 2
>>>>>>>   value      = 01:05
>>>>>>>   translated = { [0] = 'wcdma '}
>ModemManager[4345]: <debug> [1436204416.351626] [mm-broadband-modem-qmi.c:3907] common_process_serving_system_3gpp(): Processing 3GPP info...
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:05:01:02:01:00:32:00:07:00:02:04:00:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "wms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Set Routes" (0x0032)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[4345]: <debug> [1436204416.372218] [mm-broadband-modem-qmi.c:7066] load_initial_sms_parts_step(): loading all 3GPP messages from storage 'sm'...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 21
><<<<<<   data   = 01:14:00:00:05:01:00:02:00:31:00:08:00:12:01:00:01:01:01:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 20
><<<<<<   flags   = 0x00
><<<<<<   service = "wms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 8
><<<<<<   message     = "List Messages" (0x0031)
><<<<<< TLV:
><<<<<<   type       = "Message Mode" (0x12)
><<<<<<   length     = 1
><<<<<<   value      = 01
><<<<<<   translated = gsm-wcdma
><<<<<< TLV:
><<<<<<   type       = "Storage Type" (0x01)
><<<<<<   length     = 1
><<<<<<   value      = 00
><<<<<<   translated = uim
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 27
>>>>>>>   data   = 01:1A:00:80:05:01:02:02:00:31:00:0E:00:02:04:00:00:00:00:00:01:04:00:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 26
>>>>>>>   flags   = 0x80
>>>>>>>   service = "wms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 14
>>>>>>>   message     = "List Messages" (0x0031)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Message List" (0x01)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = {}
>ModemManager[4345]: <debug> [1436204416.386024] [mm-broadband-modem-qmi.c:7066] load_initial_sms_parts_step(): loading all 3GPP messages from storage 'me'...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 21
><<<<<<   data   = 01:14:00:00:05:01:00:03:00:31:00:08:00:12:01:00:01:01:01:00:01
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 20
><<<<<<   flags   = 0x00
><<<<<<   service = "wms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 3
><<<<<<   tlv_length  = 8
><<<<<<   message     = "List Messages" (0x0031)
><<<<<< TLV:
><<<<<<   type       = "Message Mode" (0x12)
><<<<<<   length     = 1
><<<<<<   value      = 01
><<<<<<   translated = gsm-wcdma
><<<<<< TLV:
><<<<<<   type       = "Storage Type" (0x01)
><<<<<<   length     = 1
><<<<<<   value      = 01
><<<<<<   translated = nv
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 27
>>>>>>>   data   = 01:1A:00:80:05:01:02:03:00:31:00:0E:00:02:04:00:00:00:00:00:01:04:00:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 26
>>>>>>>   flags   = 0x80
>>>>>>>   service = "wms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 3
>>>>>>>   tlv_length  = 14
>>>>>>>   message     = "List Messages" (0x0031)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Message List" (0x01)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = {}
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 17
><<<<<<   data   = 01:10:00:00:05:01:00:04:00:01:00:04:00:10:01:00:01
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 16
><<<<<<   flags   = 0x00
><<<<<<   service = "wms"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 4
><<<<<<   tlv_length  = 4
><<<<<<   message     = "Set Event Report" (0x0001)
><<<<<< TLV:
><<<<<<   type       = "New MT Message Indicator" (0x10)
><<<<<<   length     = 1
><<<<<<   value      = 01
><<<<<<   translated = [ report = 'yes' ]
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:05:01:02:04:00:01:00:07:00:02:04:00:00:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "wms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 4
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Set Event Report" (0x0001)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>ModemManager[4345]: <debug> [1436204416.404028] [mm-broadband-modem.c:8672] enabling_step(): Modem has extended signal reporting capabilities, enabling the Signal interface...
>ModemManager[4345]: <debug> [1436204416.404073] [mm-iface-modem-signal.c:206] setup_refresh_context(): Extended signal information reporting disabled (rate: 0 seconds)
>ModemManager[4345]: <info>  [1436204416.404165] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
>ModemManager[4345]: <debug> [1436204416.404379] [mm-iface-modem.c:1316] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
>ModemManager[4345]: <debug> [1436204416.404417] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:07:00:20:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 7
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Signal Strength" (0x0020)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 25
>>>>>>>   data   = 01:18:00:80:03:01:02:07:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:9C:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 24
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 7
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 9C:05
>>>>>>>   translated = [ strength = '-100' radio_interface = 'umts' ]
>ModemManager[4345]: <debug> [1436204416.410142] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -100 dBm
>ModemManager[4345]: <debug> [1436204416.410170] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -100 dBm --> 21%
>ModemManager[4345]: <debug> [1436204416.410271] [mm-iface-modem.c:894] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> umts)
>ModemManager[4345]: <debug> [1436204416.410353] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (21)
>ModemManager[4345]: <debug> [1436204416.410389] [mm-iface-modem.c:1235] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s)
>ModemManager[4345]: <debug> [1436204446.582047] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:08:00:20:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 8
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Signal Strength" (0x0020)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 25
>>>>>>>   data   = 01:18:00:80:03:01:02:08:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:9C:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 24
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 8
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 9C:05
>>>>>>>   translated = [ strength = '-100' radio_interface = 'umts' ]
>ModemManager[4345]: <debug> [1436204446.587603] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -100 dBm
>ModemManager[4345]: <debug> [1436204446.587644] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -100 dBm --> 21%
>ModemManager[4345]: <debug> [1436204446.587772] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (21)
>ModemManager[4345]: <debug> [1436204476.581378] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:09:00:20:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 9
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Signal Strength" (0x0020)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 25
>>>>>>>   data   = 01:18:00:80:03:01:02:09:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:9C:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 24
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 9
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 9C:05
>>>>>>>   translated = [ strength = '-100' radio_interface = 'umts' ]
>ModemManager[4345]: <debug> [1436204476.588302] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -100 dBm
>ModemManager[4345]: <debug> [1436204476.588387] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -100 dBm --> 21%
>ModemManager[4345]: <debug> [1436204476.588515] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (21)
>ModemManager[4345]: <debug> [1436204506.603699] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:0A:00:20:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 10
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Signal Strength" (0x0020)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 25
>>>>>>>   data   = 01:18:00:80:03:01:02:0A:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:9C:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 24
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 10
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 9C:05
>>>>>>>   translated = [ strength = '-100' radio_interface = 'umts' ]
>ModemManager[4345]: <debug> [1436204506.610004] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -100 dBm
>ModemManager[4345]: <debug> [1436204506.610038] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -100 dBm --> 21%
>ModemManager[4345]: <debug> [1436204506.610159] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (21)
>ModemManager[4345]: <debug> [1436204536.603284] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:0B:00:20:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 11
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Signal Strength" (0x0020)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 25
>>>>>>>   data   = 01:18:00:80:03:01:02:0B:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:9C:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 24
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 11
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 9C:05
>>>>>>>   translated = [ strength = '-100' radio_interface = 'umts' ]
>ModemManager[4345]: <debug> [1436204536.607299] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -100 dBm
>ModemManager[4345]: <debug> [1436204536.607330] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -100 dBm --> 21%
>ModemManager[4345]: <debug> [1436204536.607436] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (21)
>ModemManager[4345]: <debug> [1436204566.581997] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:0C:00:20:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 12
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Signal Strength" (0x0020)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 25
>>>>>>>   data   = 01:18:00:80:03:01:02:0C:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:9C:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 24
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 12
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 9C:05
>>>>>>>   translated = [ strength = '-100' radio_interface = 'umts' ]
>ModemManager[4345]: <debug> [1436204566.587301] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -100 dBm
>ModemManager[4345]: <debug> [1436204566.587374] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -100 dBm --> 21%
>ModemManager[4345]: <debug> [1436204566.587518] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (21)
>ModemManager[4345]: <debug> [1436204596.603696] [mm-broadband-modem-qmi.c:2456] load_signal_quality(): loading signal quality...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:0D:00:20:00:00:00
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 12
><<<<<<   flags   = 0x00
><<<<<<   service = "nas"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 13
><<<<<<   tlv_length  = 0
><<<<<<   message     = "Get Signal Strength" (0x0020)
>ModemManager[4345]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 25
>>>>>>>   data   = 01:18:00:80:03:01:02:0D:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:9C:05
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 24
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 13
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>>   length     = 2
>>>>>>>   value      = 9C:05
>>>>>>>   translated = [ strength = '-100' radio_interface = 'umts' ]
>ModemManager[4345]: <debug> [1436204596.610417] [mm-broadband-modem-qmi.c:2346] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -100 dBm
>ModemManager[4345]: <debug> [1436204596.610503] [mm-broadband-modem-qmi.c:2379] signal_strength_get_quality_and_access_tech(): Signal strength: -100 dBm --> 21%
>ModemManager[4345]: <debug> [1436204596.610647] [mm-iface-modem.c:1165] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (21)
>ModemManager[4345]: <info>  [1436204597.613135] [mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...
>ModemManager[4345]: <debug> [1436204597.613199] [mm-iface-modem-simple.c:651] connect_auth_ready():    PIN: 0000
>ModemManager[4345]: <debug> [1436204597.613223] [mm-iface-modem-simple.c:653] connect_auth_ready():    Operator ID: unspecified
>ModemManager[4345]: <debug> [1436204597.613247] [mm-iface-modem-simple.c:655] connect_auth_ready():    Allowed roaming: no
>ModemManager[4345]: <debug> [1436204597.613283] [mm-iface-modem-simple.c:657] connect_auth_ready():    APN: live.vodafone.com
>ModemManager[4345]: <debug> [1436204597.613319] [mm-iface-modem-simple.c:665] connect_auth_ready():    IP family: unspecified
>ModemManager[4345]: <debug> [1436204597.613360] [mm-iface-modem-simple.c:673] connect_auth_ready():    Allowed authentication: unspecified
>ModemManager[4345]: <debug> [1436204597.613402] [mm-iface-modem-simple.c:675] connect_auth_ready():    User: vodafone
>ModemManager[4345]: <debug> [1436204597.613445] [mm-iface-modem-simple.c:677] connect_auth_ready():    Password: 
>ModemManager[4345]: <debug> [1436204597.613488] [mm-iface-modem-simple.c:679] connect_auth_ready():    Number: *99#
>ModemManager[4345]: <info>  [1436204597.613529] [mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled
>ModemManager[4345]: <info>  [1436204597.613610] [mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register
>ModemManager[4345]: <debug> [1436204597.613677] [mm-iface-modem-3gpp.c:390] mm_iface_modem_3gpp_register_in_network(): Already registered in network '20404', automatic registration not launched...
>ModemManager[4345]: <info>  [1436204597.613742] [mm-iface-modem-simple.c:501] connection_step(): Simple connect state (6/8): Bearer
>ModemManager[4345]: <debug> [1436204597.613792] [mm-iface-modem-simple.c:521] connection_step(): Creating new bearer...
>ModemManager[4345]: <debug> [1436204597.613918] [mm-broadband-modem-qmi.c:320] modem_create_bearer(): Creating QMI bearer in QMI modem
>ModemManager[4345]: <debug> [1436204597.614301] [mm-broadband-modem-qmi.c:299] modem_create_bearer_finish(): New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[4345]: <info>  [1436204597.614486] [mm-iface-modem-simple.c:583] connection_step(): Simple connect state (7/8): Connect
>ModemManager[4345]: <debug> [1436204597.614539] [mm-base-bearer.c:590] mm_base_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[4345]: <info>  [1436204597.614589] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
>ModemManager[4345]: <debug> [1436204597.614790] [mm-bearer-qmi.c:973] _connect(): Launching connection with QMI port (usb/cdc-wdm0) and data port (net/wwan0)
>ModemManager[4345]: <debug> [1436204597.615004] [mm-bearer-qmi.c:1006] _connect(): No specific IP family requested, defaulting to ipv4
>ModemManager[4345]: <debug> [1436204597.615045] [mm-bearer-qmi.c:1039] _connect(): Using default (PAP) authentication method
>ModemManager[4345]: <debug> [1436204597.615191] [mm-bearer-qmi.c:676] connect_context_step(): Running IPv4 connection setup
>ModemManager[4345]: <debug> [1436204597.615230] [mm-bearer-qmi.c:689] connect_context_step(): Allocating IPv4-specific WDS client
>ModemManager[4345]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 16
><<<<<<   data   = 01:0F:00:00:00:00:00:07:22:00:04:00:01:01:00:01
>ModemManager[4345]: [/dev/cdc-wdm0] Sent message (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      = 01
><<<<<<   translated = wds
>ModemManager[4345]: [/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:01:01
>ModemManager[4345]: [/dev/cdc-wdm0] Received message (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      = 01:01
>>>>>>>   translated = [ service = 'wds' cid = '1' ]
>ModemManager[4345]: [/dev/cdc-wdm0] Registered 'wds' (version 1.1) client with ID '1'
>ModemManager[4345]: <debug> [1436204597.652217] [mm-bearer-qmi.c:731] connect_context_step(): Starting IPv4 connection...
>ModemManager[4345]: qmi_message_add_raw_tlv: assertion 'length > 0' failed
>
>(ModemManager:4345): GLib-GIO-CRITICAL **: g_simple_async_result_take_error: assertion 'error != NULL' failed
>ModemManager[4345]: qmi_message_wds_start_network_output_ref: assertion 'self != NULL' failed
>ModemManager[4345]: qmi_message_wds_start_network_output_get_packet_data_handle: assertion 'self != NULL' failed
>
>(ModemManager:4345): GLib-GIO-CRITICAL **: g_simple_async_result_take_error: assertion 'error != NULL' failed
>[01;32mtkteun@tk-2540p[01;34m ~ $[00m 
>[01;32mtkteun@tk-2540p[01;34m ~ $[00m exit
>
>Script done on Mon Jul  6 19:50:48 2015

Actions: View

Attachments on bug 91242: 116984 | 116985

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