Attachment 872797 Details for Bug 817504 – zte-mm-nm-debug.txt

zte-mm-nm-debug.txt

zte-mm-nm-debug.txt (text/plain), 126.65 KB, created by Marko Myllynen on 2014-03-10 16:43:58 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Marko Myllynen

Created: 2014-03-10 16:43:58 UTC

Size: 126.65 KB

patch

obsolete

>ModemManager[1888]: <info>  [1394467096.808208] [main.c:128] main(): ModemManager (version 1.2.0-1.fc21) starting...
>ModemManager[1888]: <debug> [1394467096.824712] [main.c:61] bus_acquired_cb(): Bus acquired, creating manager...
>ModemManager[1888]: <debug> [1394467096.832200] [mm-plugin-manager.c:788] load_plugins(): Looking for plugins in '/usr/lib/ModemManager'
>ModemManager[1888]: <debug> [1394467096.835733] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Altair LTE'
>ModemManager[1888]: <debug> [1394467096.837555] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'AnyDATA'
>ModemManager[1888]: <debug> [1394467096.838313] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Cinterion'
>ModemManager[1888]: <debug> [1394467096.838999] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Generic'
>ModemManager[1888]: <debug> [1394467096.839789] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Gobi'
>ModemManager[1888]: <debug> [1394467096.840889] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Option High-Speed'
>ModemManager[1888]: <debug> [1394467096.843258] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Huawei'
>ModemManager[1888]: <debug> [1394467096.844053] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Iridium'
>ModemManager[1888]: <debug> [1394467096.845840] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Linktop'
>ModemManager[1888]: <debug> [1394467096.847646] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Longcheer'
>ModemManager[1888]: <debug> [1394467096.848419] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Ericsson MBM'
>ModemManager[1888]: <debug> [1394467096.849119] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Motorola'
>ModemManager[1888]: <debug> [1394467096.849905] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Nokia (Icera)'
>ModemManager[1888]: <debug> [1394467096.851416] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Nokia'
>ModemManager[1888]: <debug> [1394467096.852192] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Novatel LTE'
>ModemManager[1888]: <debug> [1394467096.853864] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Novatel'
>ModemManager[1888]: <debug> [1394467096.854615] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Option'
>ModemManager[1888]: <debug> [1394467096.855819] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Pantech'
>ModemManager[1888]: <debug> [1394467096.857622] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Samsung'
>ModemManager[1888]: <debug> [1394467096.858500] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Sierra'
>ModemManager[1888]: <debug> [1394467096.859236] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'SimTech'
>ModemManager[1888]: <debug> [1394467096.859946] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Telit'
>ModemManager[1888]: <debug> [1394467096.861565] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Via CBP7'
>ModemManager[1888]: <debug> [1394467096.862294] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Wavecom'
>ModemManager[1888]: <debug> [1394467096.863516] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'X22X'
>ModemManager[1888]: <debug> [1394467096.865409] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'ZTE'
>ModemManager[1888]: <debug> [1394467096.866150] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'MTK'
>ModemManager[1888]: <debug> [1394467096.866190] [mm-plugin-manager.c:838] load_plugins(): Successfully loaded 27 plugins
>ModemManager[1888]: <debug> [1394467096.870806] [main.c:79] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
>ModemManager[1888]: <debug> [1394467096.870874] [mm-manager.c:456] mm_manager_start(): Starting automatic device scan...
>ModemManager[1888]: <debug> [1394467096.877477] [mm-manager.c:495] mm_manager_start(): Finished device scan...
>ModemManager[1888]: <debug> [1394467096.881993] [mm-manager.c:294] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
>ModemManager[1888]: <debug> [1394467096.882666] [mm-manager.c:294] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
>ModemManager[1888]: <debug> [1394467096.883299] [mm-manager.c:294] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
>ModemManager[1888]: <debug> [1394467096.883926] [mm-manager.c:294] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
>ModemManager[1888]: <debug> [1394467096.887047] [mm-plugin-manager.c:675] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1e.0/0000:06:03.0] Checking device support...
>ModemManager[1888]: <debug> [1394467096.888395] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.888875] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.889551] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.889985] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wlp6s3] filtered by drivers
>ModemManager[1888]: <debug> [1394467096.890407] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wlp6s3] filtered by drivers
>ModemManager[1888]: <debug> [1394467096.890851] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.891275] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.891301] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.891320] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.891340] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wlp6s3] filtered by udev tags
>ModemManager[1888]: <debug> [1394467096.891359] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.891377] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.891395] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.891414] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.891431] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.891449] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.891466] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.891484] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.893021] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wlp6s3] filtered by drivers
>ModemManager[1888]: <debug> [1394467096.893048] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.893067] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.893563] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.895562] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.895590] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.895609] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wlp6s3] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.896887] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlp6s3] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.897313] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [wlp6s3] Found '1' plugins to try...
>ModemManager[1888]: <debug> [1394467096.897949] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [wlp6s3]   Will try with plugin 'Generic'
>ModemManager[1888]: <debug> [1394467096.898958] [mm-plugin.c:704] mm_plugin_supports_port(): (Generic) [wlp6s3] probing deferred until result suggested
>ModemManager[1888]: <debug> [1394467096.899646] [mm-plugin-manager.c:675] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1e.0/0000:06:08.0] Checking device support...
>ModemManager[1888]: <debug> [1394467096.901024] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.901456] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.902952] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.903452] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [enp6s8] filtered by drivers
>ModemManager[1888]: <debug> [1394467096.903901] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [enp6s8] filtered by drivers
>ModemManager[1888]: <debug> [1394467096.904945] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.905365] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.906252] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.907948] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.908376] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [enp6s8] filtered by udev tags
>ModemManager[1888]: <debug> [1394467096.908810] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.909946] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.910376] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.910960] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.911966] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.912954] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.913376] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.914953] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.915379] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [enp6s8] filtered by drivers
>ModemManager[1888]: <debug> [1394467096.916232] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.917576] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.918015] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.918437] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.919950] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.920393] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [enp6s8] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467096.920845] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [enp6s8] filtered by subsystem
>ModemManager[1888]: <debug> [1394467096.921952] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [enp6s8] Found '1' plugins to try...
>ModemManager[1888]: <debug> [1394467096.922377] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [enp6s8]   Will try with plugin 'Generic'
>ModemManager[1888]: <debug> [1394467096.923957] [mm-plugin.c:704] mm_plugin_supports_port(): (Generic) [enp6s8] probing deferred until result suggested
>ModemManager[1888]: <debug> [1394467096.924546] [mm-plugin-manager.c:505] plugin_supports_port_ready(): (Plugin Manager) [wlp6s3] deferring support check until result suggested
>ModemManager[1888]: <debug> [1394467096.924995] [mm-plugin-manager.c:505] plugin_supports_port_ready(): (Plugin Manager) [enp6s8] deferring support check until result suggested
>ModemManager[1888]: <debug> [1394467099.522745] [mm-plugin-manager.c:646] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1e.0/0000:06:03.0] Minimum probing time consumed
>ModemManager[1888]: <debug> [1394467099.522863] [mm-plugin-manager.c:341] suggest_port_probe_result(): (Plugin Manager) [wlp6s3] deferred task cancelled, no suggested plugin
>ModemManager[1888]: <debug> [1394467099.522916] [mm-plugin-manager.c:646] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1e.0/0000:06:08.0] Minimum probing time consumed
>ModemManager[1888]: <debug> [1394467099.522954] [mm-plugin-manager.c:341] suggest_port_probe_result(): (Plugin Manager) [enp6s8] deferred task cancelled, no suggested plugin
>ModemManager[1888]: <debug> [1394467099.523009] [mm-plugin-manager.c:164] port_probe_context_finished(): (Plugin Manager) [wlp6s3] not supported by any plugin
>ModemManager[1888]: <debug> [1394467099.523053] [mm-device.c:339] mm_device_ignore_port(): Fully ignoring port 'net/wlp6s3' from now on
>ModemManager[1888]: <debug> [1394467099.523093] [mm-plugin-manager.c:285] port_probe_context_finished(): (Plugin Manager) 'wlp6s3' port probe finished, last one in device
>ModemManager[1888]: <debug> [1394467099.523142] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1e.0/0000:06:03.0] device support check finished in '2.634828' seconds
>ModemManager[1888]: <warn>  [1394467099.523284] [mm-manager.c:140] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1e.0/0000:06:03.0': not supported by any plugin
>ModemManager[1888]: <debug> [1394467099.523356] [mm-plugin-manager.c:164] port_probe_context_finished(): (Plugin Manager) [enp6s8] not supported by any plugin
>ModemManager[1888]: <debug> [1394467099.523398] [mm-device.c:339] mm_device_ignore_port(): Fully ignoring port 'net/enp6s8' from now on
>ModemManager[1888]: <debug> [1394467099.523437] [mm-plugin-manager.c:285] port_probe_context_finished(): (Plugin Manager) 'enp6s8' port probe finished, last one in device
>ModemManager[1888]: <debug> [1394467099.524583] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1e.0/0000:06:08.0] device support check finished in '2.623610' seconds
>ModemManager[1888]: <warn>  [1394467099.527586] [mm-manager.c:140] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1e.0/0000:06:08.0': not supported by any plugin
>ModemManager[1888]: <debug> [1394467119.833182] [mm-plugin-manager.c:675] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8] Checking device support...
>ModemManager[1888]: <debug> [1394467119.833441] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.833491] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.833515] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB1] filtered by drivers
>ModemManager[1888]: <debug> [1394467119.833550] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB1] filtered by drivers
>ModemManager[1888]: <debug> [1394467119.833575] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.833596] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.833616] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.833636] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB1] filtered by udev tags
>ModemManager[1888]: <debug> [1394467119.833656] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.833675] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.833696] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.837049] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.837167] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.837264] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.837360] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.837455] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB1] filtered by drivers
>ModemManager[1888]: <debug> [1394467119.840585] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.840702] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.841211] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.847232] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB1] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467119.858476] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [ttyUSB1] Found '2' plugins to try...
>ModemManager[1888]: <debug> [1394467119.861008] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'ZTE'
>ModemManager[1888]: <debug> [1394467119.861527] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Generic'
>ModemManager[1888]: <debug> [1394467119.913907] [mm-plugin.c:776] mm_plugin_supports_port(): (ZTE) [ttyUSB1] probe required: 'at, at-icera, qcdm'
>ModemManager[1888]: <debug> [1394467119.913985] [mm-port-probe.c:1337] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, at-icera, qcdm'
>ModemManager[1888]: <debug> [1394467119.914354] [mm-serial-port.c:901] mm_serial_port_open(): (ttyUSB1) opening serial port...
>ModemManager[1888]: <warn>  [1394467119.917652] [mm-serial-port.c:416] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[1888]: <debug> [1394467119.917734] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1888]: <debug> [1394467120.019343] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATE0+CPMS?<CR>'
>ModemManager[1888]: <debug> [1394467120.075227] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.077174] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.077691] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.078152] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers
>ModemManager[1888]: <debug> [1394467120.078978] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.079973] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.080028] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.080479] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.080949] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.081403] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags
>ModemManager[1888]: <debug> [1394467120.081449] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.081491] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.081555] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.081603] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.081644] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.082562] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.082930] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.082977] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.083020] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [cdc-wdm0] filtered by drivers
>ModemManager[1888]: <debug> [1394467120.083062] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.083102] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.083142] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.083555] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.088563] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.093396] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try...
>ModemManager[1888]: <debug> [1394467120.096079] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'ZTE'
>ModemManager[1888]: <debug> [1394467120.098321] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'Generic'
>ModemManager[1888]: <debug> [1394467120.101113] [mm-plugin.c:776] mm_plugin_supports_port(): (ZTE) [cdc-wdm0] probe required: 'qmi'
>ModemManager[1888]: <debug> [1394467120.104949] [mm-port-probe.c:1337] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi'
>ModemManager[1888]: <debug> [1394467120.118604] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.121848] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.122561] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.122617] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wwp0s29f7u8i2] filtered by drivers
>ModemManager[1888]: <debug> [1394467120.125095] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wwp0s29f7u8i2] filtered by drivers
>ModemManager[1888]: <debug> [1394467120.125965] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.128025] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.136012] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.137811] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.142371] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wwp0s29f7u8i2] filtered by udev tags
>ModemManager[1888]: <debug> [1394467120.143756] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.144989] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.145516] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.146949] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.147477] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.148962] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.149474] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.150252] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.151992] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wwp0s29f7u8i2] filtered by drivers
>ModemManager[1888]: <debug> [1394467120.152509] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.153062] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.153962] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.154961] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwp0s29f7u8i2] filtered by subsystem
>ModemManager[1888]: <debug> [1394467120.155964] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wwp0s29f7u8i2] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467120.156965] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [wwp0s29f7u8i2] Found '2' plugins to try...
>ModemManager[1888]: <debug> [1394467120.157472] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [wwp0s29f7u8i2]   Will try with plugin 'ZTE'
>ModemManager[1888]: <debug> [1394467120.167576] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [wwp0s29f7u8i2]   Will try with plugin 'Generic'
>ModemManager[1888]: <debug> [1394467120.169041] [mm-plugin.c:704] mm_plugin_supports_port(): (ZTE) [wwp0s29f7u8i2] probing deferred until result suggested
>ModemManager[1888]: <debug> [1394467120.170861] [mm-plugin-manager.c:505] plugin_supports_port_ready(): (Plugin Manager) [wwp0s29f7u8i2] deferring support check until result suggested
>ModemManager[1888]: <debug> [1394467120.999065] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.006117] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.007560] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB0] filtered by drivers
>ModemManager[1888]: <debug> [1394467121.008987] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers
>ModemManager[1888]: <debug> [1394467121.009515] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.016266] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.016688] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.017272] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags
>ModemManager[1888]: <debug> [1394467121.017867] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.017967] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.018068] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.018165] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.018262] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.018360] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.018555] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.018662] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB0] filtered by drivers
>ModemManager[1888]: <debug> [1394467121.018757] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.019560] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.019672] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.019770] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467121.019871] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '2' plugins to try...
>ModemManager[1888]: <debug> [1394467121.021567] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'ZTE'
>ModemManager[1888]: <debug> [1394467121.021682] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Generic'
>ModemManager[1888]: <debug> [1394467121.021803] [mm-plugin.c:776] mm_plugin_supports_port(): (ZTE) [ttyUSB0] probe required: 'at, at-icera, qcdm'
>ModemManager[1888]: <debug> [1394467121.023270] [mm-port-probe.c:1337] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, at-icera, qcdm'
>ModemManager[1888]: <debug> [1394467121.073691] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- 'ATE0+CPMS?'
>ModemManager[1888]: <debug> [1394467121.077800] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><CR><LF>+CPMS: "SM",0,30,"SM",0,30,"ME",0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467121.086631] [mm-port-probe.c:151] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is AT-capable
>ModemManager[1888]: <debug> [1394467121.087177] [mm-port-probe.c:455] wdm_probe_qmi(): (usbmisc/cdc-wdm0) probing QMI...
>ModemManager[1888]: <debug> [1394467121.095902] [mm-serial-port.c:901] mm_serial_port_open(): (ttyUSB0) opening serial port...
>ModemManager[1888]: <warn>  [1394467121.106064] [mm-serial-port.c:416] real_config_fd(): (ttyUSB0): port attributes not fully set
>ModemManager[1888]: <debug> [1394467121.112560] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1888]: cannot connect to proxy: Could not connect: Connection refused
>ModemManager[1888]: spawning new qmi-proxy (try 1)...
>ModemManager[1888]: <debug> [1394467121.176450] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT%IPSYS?<CR>'
>ModemManager[1888]: <debug> [1394467121.216001] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
>ModemManager[1888]: [/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[1888]: [/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[1888]: [/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[1888]: [/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[1888]: [/dev/cdc-wdm0] Checking version info (10 retries)...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 12
><<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
>ModemManager[1888]: [/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[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 43
>>>>>>>   data   = 01:2A:00:80:00:00:01:02:21:00:1F:00:02:04:00:00:00:00:00:01:15:00:04:00:01:00:03:00:01:01:00:05:00:02:01:00:02:00:03:01:00:00:00
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 42
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 31
>>>>>>>   message     = "Get Version Info" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Service list" (0x01)
>>>>>>>   length     = 21
>>>>>>>   value      = 04:00:01:00:03:00:01:01:00:05:00:02:01:00:02:00:03:01:00:00:00
>>>>>>>   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '3' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '5' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '2' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] '}
>ModemManager[1888]: [/dev/cdc-wdm0] QMI Device supports 4 services:
>ModemManager[1888]: [/dev/cdc-wdm0]    ctl (1.3)
>ModemManager[1888]: [/dev/cdc-wdm0]    wds (1.5)
>ModemManager[1888]: [/dev/cdc-wdm0]    dms (1.2)
>ModemManager[1888]: [/dev/cdc-wdm0]    nas (1.0)
>ModemManager[1888]: <debug> [1394467121.306040] [mm-port-probe.c:270] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable
>ModemManager[1888]: <debug> [1394467121.306188] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (ZTE) [cdc-wdm0] found best plugin for port
>ModemManager[1888]: <debug> [1394467121.306213] [mm-plugin-manager.c:201] port_probe_context_finished(): (Plugin Manager) (ZTE) [cdc-wdm0]: found best plugin for device (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8)
>ModemManager[1888]: <debug> [1394467121.306251] [mm-plugin-manager.c:373] suggest_port_probe_result(): (Plugin Manager) (ZTE) [ttyUSB0] suggested plugin for port
>ModemManager[1888]: <debug> [1394467121.309706] [mm-plugin-manager.c:334] suggest_port_probe_result(): (Plugin Manager) (ZTE) [wwp0s29f7u8i2] deferred task completed, got suggested plugin
>ModemManager[1888]: <debug> [1394467121.310594] [mm-plugin-manager.c:373] suggest_port_probe_result(): (Plugin Manager) (ZTE) [ttyUSB1] suggested plugin for port
>ModemManager[1888]: <debug> [1394467121.311564] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 3 running probes in this device (ttyUSB0, wwp0s29f7u8i2, ttyUSB1)
>ModemManager[1888]: <debug> [1394467121.394870] [mm-plugin.c:704] mm_plugin_supports_port(): (ZTE) [wwp0s29f7u8i2] probing deferred until result suggested
>ModemManager[1888]: <debug> [1394467121.400677] [mm-plugin-manager.c:485] plugin_supports_port_ready(): (Plugin Manager) (ZTE) [wwp0s29f7u8i2] task completed, got suggested plugin
>ModemManager[1888]: <debug> [1394467121.401403] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'wwp0s29f7u8i2' port probe finished, still 2 running probes in this device (ttyUSB0, ttyUSB1)
>ModemManager[1888]: <debug> [1394467122.097512] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>ERROR<CR><LF>'
>ModemManager[1888]: <debug> [1394467122.097893] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
>ModemManager[1888]: <debug> [1394467122.097967] [mm-port-probe.c:223] mm_port_probe_set_result_at_icera(): (tty/ttyUSB1) Modem is probably not Icera-based
>ModemManager[1888]: <debug> [1394467122.098063] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
>ModemManager[1888]: <debug> [1394467122.098113] [mm-serial-port.c:1040] mm_serial_port_close(): (ttyUSB1) closing serial port...
>ModemManager[1888]: <debug> [1394467122.103363] [mm-serial-port.c:1073] mm_serial_port_close(): (ttyUSB1) serial port closed
>ModemManager[1888]: <debug> [1394467122.103667] [mm-serial-port.c:1148] mm_serial_port_close_force(): (ttyUSB1) forced to close port
>ModemManager[1888]: <debug> [1394467122.103988] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (ZTE) [ttyUSB1] found best plugin for port
>ModemManager[1888]: <debug> [1394467122.104204] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'ttyUSB1' port probe finished, still 1 running probes in this device (ttyUSB0)
>ModemManager[1888]: <debug> [1394467122.521086] [mm-plugin-manager.c:646] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8] Minimum probing time consumed
>ModemManager[1888]: <debug> [1394467125.523379] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1888]: <debug> [1394467125.523621] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
>ModemManager[1888]: <debug> [1394467129.523593] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1888]: <debug> [1394467129.523787] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
>ModemManager[1888]: <debug> [1394467133.523637] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1888]: <debug> [1394467133.523770] [mm-port-probe.c:161] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
>ModemManager[1888]: <debug> [1394467133.523944] [mm-port-probe.c:659] serial_probe_qcdm(): (tty/ttyUSB0) probing QCDM...
>ModemManager[1888]: <debug> [1394467133.523995] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1888]: <debug> [1394467133.524042] [mm-serial-port.c:1040] mm_serial_port_close(): (ttyUSB0) closing serial port...
>ModemManager[1888]: <debug> [1394467133.529367] [mm-serial-port.c:1073] mm_serial_port_close(): (ttyUSB0) serial port closed
>ModemManager[1888]: <debug> [1394467133.529447] [mm-serial-port.c:1148] mm_serial_port_close_force(): (ttyUSB0) forced to close port
>ModemManager[1888]: <debug> [1394467133.529668] [mm-serial-port.c:901] mm_serial_port_open(): (ttyUSB0) opening serial port...
>ModemManager[1888]: <debug> [1394467133.531373] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1888]: <debug> [1394467133.531713] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
>ModemManager[1888]: <debug> [1394467133.532845] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB0): <-- 00 4a 75 6e 20 20 31 20 32 30 31 31 31 35 3a 34 36 3a 33 35 4a 75 6e 20 20 31 20 32 30 31 31 31 35 3a 34 36 3a 33 35 44 4e 41 46 49 4d 46 36 00 00 08 00 00 00 04 e0 30 f0 7e
>ModemManager[1888]: <debug> [1394467133.533116] [mm-port-probe.c:239] mm_port_probe_set_result_qcdm(): (tty/ttyUSB0) port is QCDM-capable
>ModemManager[1888]: <debug> [1394467133.533360] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1888]: <debug> [1394467133.533593] [mm-serial-port.c:1040] mm_serial_port_close(): (ttyUSB0) closing serial port...
>ModemManager[1888]: <debug> [1394467133.535580] [mm-serial-port.c:1073] mm_serial_port_close(): (ttyUSB0) serial port closed
>ModemManager[1888]: <debug> [1394467133.535813] [mm-serial-port.c:1148] mm_serial_port_close_force(): (ttyUSB0) forced to close port
>ModemManager[1888]: <debug> [1394467133.536075] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (ZTE) [ttyUSB0] found best plugin for port
>ModemManager[1888]: <debug> [1394467133.537456] [mm-plugin-manager.c:285] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, last one in device
>ModemManager[1888]: <debug> [1394467133.546969] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8] device support check finished in '13.713570' seconds
>ModemManager[1888]: <info>  [1394467133.548031] [mm-device.c:486] mm_device_create_modem(): Creating modem with plugin 'ZTE' and '4' ports
>ModemManager[1888]: <debug> [1394467133.548890] [zte/mm-plugin-zte.c:69] create_modem(): QMI-powered ZTE modem found...
>ModemManager[1888]: <debug> [1394467133.629835] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyUSB0) type 'qcdm' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8
>ModemManager[1888]: <debug> [1394467133.632648] [mm-base-modem.c:275] mm_base_modem_grab_port(): (wwp0s29f7u8i2) type 'net' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8
>ModemManager[1888]: <debug> [1394467133.633896] [mm-base-modem.c:275] mm_base_modem_grab_port(): (cdc-wdm0) type 'qmi' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8
>ModemManager[1888]: <debug> [1394467133.635467] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyUSB1) type 'at' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8
>ModemManager[1888]: <debug> [1394467133.636588] [mm-base-modem.c:1057] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8) tty/ttyUSB1 at (primary)
>ModemManager[1888]: <debug> [1394467133.637595] [mm-base-modem.c:1057] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8) net/wwp0s29f7u8i2 data (primary)
>ModemManager[1888]: <debug> [1394467133.637860] [mm-base-modem.c:1057] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8) tty/ttyUSB0 qcdm
>ModemManager[1888]: <debug> [1394467133.637971] [mm-base-modem.c:1057] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8) usb/cdc-wdm0 qmi (primary)
>ModemManager[1888]: <info>  [1394467133.638477] [mm-manager.c:149] find_device_support_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8' successfully created
>ModemManager[1888]: [/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[1888]: [/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[1888]: [/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[1888]: [/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[1888]: [/dev/cdc-wdm0] Checking version info (10 retries)...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 12
><<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
>ModemManager[1888]: [/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[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 43
>>>>>>>   data   = 01:2A:00:80:00:00:01:02:21:00:1F:00:02:04:00:00:00:00:00:01:15:00:04:00:01:00:03:00:01:01:00:05:00:02:01:00:02:00:03:01:00:00:00
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 42
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 31
>>>>>>>   message     = "Get Version Info" (0x0021)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Service list" (0x01)
>>>>>>>   length     = 21
>>>>>>>   value      = 04:00:01:00:03:00:01:01:00:05:00:02:01:00:02:00:03:01:00:00:00
>>>>>>>   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '3' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '5' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '2' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '0' ] '}
>ModemManager[1888]: [/dev/cdc-wdm0] QMI Device supports 4 services:
>ModemManager[1888]: [/dev/cdc-wdm0]    ctl (1.3)
>ModemManager[1888]: [/dev/cdc-wdm0]    wds (1.5)
>ModemManager[1888]: [/dev/cdc-wdm0]    dms (1.2)
>ModemManager[1888]: [/dev/cdc-wdm0]    nas (1.0)
>ModemManager[1888]: [/dev/cdc-wdm0] Setting network port data format...
>ModemManager[1888]: [/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[1888]: [/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[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 24
>>>>>>>   data   = 01:17:00:80:00:00:01:03:26:00:0C:00:02:04:00:00:00:00:00:10:02:00:01:00
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 23
>>>>>>>   flags   = 0x80
>>>>>>>   service = "ctl"
>>>>>>>   client  = 0
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 3
>>>>>>>   tlv_length  = 12
>>>>>>>   message     = "Set Data Format" (0x0026)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Protocol" (0x10)
>>>>>>>   length     = 2
>>>>>>>   value      = 01:00
>>>>>>>   translated = 802-3
>ModemManager[1888]: [/dev/cdc-wdm0] Network port data format operation finished
>ModemManager[1888]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 16
><<<<<<   data   = 01:0F:00:00:00:00:00:04:22:00:04:00:01:01:00:02
>ModemManager[1888]: [/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      = 02
><<<<<<   translated = dms
>ModemManager[1888]: [/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:02:01
>ModemManager[1888]: [/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      = 02:01
>>>>>>>   translated = [ service = 'dms' cid = '1' ]
>ModemManager[1888]: [/dev/cdc-wdm0] Registered 'dms' (version 1.2) client with ID '1'
>ModemManager[1888]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 16
><<<<<<   data   = 01:0F:00:00:00:00:00:05:22:00:04:00:01:01:00:03
>ModemManager[1888]: [/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      = 03
><<<<<<   translated = nas
>ModemManager[1888]: [/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:03:01
>ModemManager[1888]: [/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      = 03:01
>>>>>>>   translated = [ service = 'nas' cid = '1' ]
>ModemManager[1888]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '1'
>ModemManager[1888]: <debug> [1394467133.772209] [mm-broadband-modem-qmi.c:9986] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'wms': Couldn't create client for service 'wms': Service 'wms' not supported by the device
>ModemManager[1888]: <debug> [1394467133.772270] [mm-broadband-modem-qmi.c:9986] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'pds': Couldn't create client for service 'pds': Service 'pds' not supported by the device
>ModemManager[1888]: <debug> [1394467133.772317] [mm-broadband-modem-qmi.c:9986] 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[1888]: <debug> [1394467133.772351] [mm-serial-port.c:901] mm_serial_port_open(): (ttyUSB1) opening serial port...
>ModemManager[1888]: <warn>  [1394467133.775210] [mm-serial-port.c:416] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[1888]: <debug> [1394467133.815243] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1888]: <debug> [1394467133.819845] [mm-at-serial-port.c:473] mm_at_serial_port_run_init_sequence(): (ttyUSB1): running init sequence...
>ModemManager[1888]: <debug> [1394467133.822676] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1888]: <debug> [1394467133.823289] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467133.825001] [mm-broadband-modem-qmi.c:533] modem_load_current_capabilities(): loading current capabilities...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:01:00:2B:00:00:00
>ModemManager[1888]: [/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[1888]: <debug> [1394467133.866817] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
>ModemManager[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:03:01:02:01:00:2B:00:07:00:02:04:00:01:00:47:00
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 1
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Get Technology Preference" (0x002B)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 01:00:47:00
>>>>>>>   translated = FAILURE: InvalidQmiCommand
>ModemManager[1888]: <debug> [1394467133.919144] [mm-broadband-modem-qmi.c:428] load_current_capabilities_get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:01:00:20:00:00:00
>ModemManager[1888]: [/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[1888]: <debug> [1394467133.922632] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467133.922999] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATV1<CR>'
>ModemManager[1888]: [/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[1888]: [/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[1888]: <debug> [1394467133.926717] [mm-modem-helpers-qmi.c:1266] mm_modem_capability_from_qmi_capabilities_context(): Current capabilities built: 'gsm-umts'
>  SSP mode preference: 'unknown'
>  TP: 'auto'
>  DMS Capabilities: 'gsm-umts'
>ModemManager[1888]: <debug> [1394467133.926934] [mm-broadband-modem-qmi.c:695] modem_load_supported_capabilities(): loading supported capabilities...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:02:00:20:00:00:00
>ModemManager[1888]: [/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[1888]: [/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[1888]: [/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[1888]: <debug> [1394467133.930221] [mm-iface-modem.c:4236] interface_initialization_step(): Modem allows up to 1 bearers
>ModemManager[1888]: <debug> [1394467133.930266] [mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
>ModemManager[1888]: <debug> [1394467133.930391] [mm-broadband-modem-qmi.c:997] modem_load_manufacturer(): loading manufacturer...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:03:00:21:00:00:00
>ModemManager[1888]: [/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[1888]: <debug> [1394467133.937268] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: [/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[1888]: [/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[1888]: <debug> [1394467133.985171] [mm-broadband-modem-qmi.c:944] modem_load_manufacturer_finish(): loaded manufacturer: QUALCOMM INCORPORATED
>ModemManager[1888]: <debug> [1394467133.992395] [mm-broadband-modem-qmi.c:1073] modem_load_model(): loading model...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:04:00:22:00:00:00
>ModemManager[1888]: [/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[1888]: <debug> [1394467134.032813] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
>ModemManager[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 24
>>>>>>>   data   = 01:17:00:80:02:01:02:04:00:22:00:0B:00:02:04:00:00:00:00:00:01:01:00:38
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 23
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 4
>>>>>>>   tlv_length  = 11
>>>>>>>   message     = "Get Model" (0x0022)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Model" (0x01)
>>>>>>>   length     = 1
>>>>>>>   value      = 38
>>>>>>>   translated = 8
>ModemManager[1888]: <debug> [1394467134.039127] [mm-broadband-modem-qmi.c:1020] modem_load_model_finish(): loaded model: 8
>ModemManager[1888]: <debug> [1394467134.040106] [mm-broadband-modem-qmi.c:1149] modem_load_revision(): loading revision...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:05:00:23:00:00:00
>ModemManager[1888]: [/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[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 74
>>>>>>>   data   = 01:49:00:80:02:01:02:05:00:23:00:3D:00:02:04:00:00:00:00:00:01:33:00:4D:38:32:30:30:43:2D:46:41:43:50:41:43:5A:44:2D:31:2E:30:2E:33:39:32:54:20:20:31:20:20:5B:4A:75:6E:20:20:31:20:32:30:31:31:20:31:34:3A:32:36:3A:30:37:5D
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 73
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 5
>>>>>>>   tlv_length  = 61
>>>>>>>   message     = "Get Revision" (0x0023)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Revision" (0x01)
>>>>>>>   length     = 51
>>>>>>>   value      = 4D:38:32:30:30:43:2D:46:41:43:50:41:43:5A:44:2D:31:2E:30:2E:33:39:32:54:20:20:31:20:20:5B:4A:75:6E:20:20:31:20:32:30:31:31:20:31:34:3A:32:36:3A:30:37:5D
>>>>>>>   translated = M8200C-FACPACZD-1.0.392T  1  [Jun  1 2011 14:26:07]
>ModemManager[1888]: <debug> [1394467134.085969] [mm-broadband-modem-qmi.c:1096] modem_load_revision_finish(): loaded revision: M8200C-FACPACZD-1.0.392T  1  [Jun  1 2011 14:26:07]
>ModemManager[1888]: <debug> [1394467134.087982] [mm-broadband-modem-qmi.c:1291] modem_load_equipment_identifier(): loading equipment identifier...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:06:00:25:00:00:00
>ModemManager[1888]: [/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[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 48
>>>>>>>   data   = 01:2F:00:80:02:01:02:06:00:25:00:23:00:02:04:00:00:00:00:00:11:19:00:33:35:35:35:37:32:30:34:30:32:35:38:38:34:39:03:A0:C8:EA:01:94:C4:75:01:31
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 47
>>>>>>>   flags   = 0x80
>>>>>>>   service = "dms"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 6
>>>>>>>   tlv_length  = 35
>>>>>>>   message     = "Get IDs" (0x0025)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 00:00:00:00
>>>>>>>   translated = SUCCESS
>>>>>>> TLV:
>>>>>>>   type       = "Imei" (0x11)
>>>>>>>   length     = 25
>>>>>>>   value      = 33:35:35:35:37:32:30:34:30:32:35:38:38:34:39:03:A0:C8:EA:01:94:C4:75:01:31
>>>>>>>   translated = 355572040258849
>ModemManager[1888]: <debug> [1394467134.136859] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467134.137833] [mm-broadband-modem-qmi.c:1188] modem_load_equipment_identifier_finish(): loaded equipment identifier: 355572040258849
>ModemManager[1888]: <debug> [1394467134.140742] [mm-broadband-modem-qmi.c:1326] modem_load_device_identifier(): loading device identifier...
>ModemManager[1888]: <debug> [1394467134.141349] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '000019d200001402355572040258849M8200C-FACPACZD-1.0.392T1[Jun1201114:26:07]8QUALCOMMINCORPORATED'
>ModemManager[1888]: <debug> [1394467134.141898] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '0f81772e8dbfa99f480fa421efd33e950ebcd2c8'
>ModemManager[1888]: <debug> [1394467134.142467] [mm-broadband-modem-qmi.c:1314] modem_load_device_identifier_finish(): loaded device identifier: 0f81772e8dbfa99f480fa421efd33e950ebcd2c8
>ModemManager[1888]: <debug> [1394467134.183804] [mm-broadband-modem-qmi.c:1729] modem_load_supported_bands(): loading band capabilities...
>ModemManager[1888]: <warn>  [1394467134.184849] [mm-iface-modem.c:3891] load_supported_bands_ready(): couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>ModemManager[1888]: <debug> [1394467134.186852] [mm-broadband-modem-qmi.c:2673] load_power_state(): Getting device operating mode...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:08:00:2D:00:00:00
>ModemManager[1888]: [/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[1888]: <debug> [1394467134.189680] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATX4<CR>'
>ModemManager[1888]: [/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[1888]: [/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[1888]: <debug> [1394467134.237384] [mm-broadband-modem-qmi.c:1535] modem_load_unlock_required(): loading unlock required...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:09:00:2B:00:00:00
>ModemManager[1888]: [/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[1888]: [/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:02:03:0A
>ModemManager[1888]: [/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      = 02:03:0A
>>>>>>>   translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[1888]: <debug> [1394467134.244129] [mm-broadband-modem-qmi.c:1628] modem_load_unlock_retries(): loading unlock retries...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:0A:00:2B:00:00:00
>ModemManager[1888]: [/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[1888]: <debug> [1394467134.251271] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467134.252176] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT&C1<CR>'
>ModemManager[1888]: [/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:02:03:0A
>ModemManager[1888]: [/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      = 02:03:0A
>>>>>>>   translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>ModemManager[1888]: <debug> [1394467134.302233] [mm-iface-modem.c:3038] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
>ModemManager[1888]: <debug> [1394467134.304210] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>ModemManager[1888]: <debug> [1394467134.311485] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
>ModemManager[1888]: <warn>  [1394467134.313066] [mm-sim.c:1428] load_sim_identifier_ready(): couldn't load SIM identifier: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>ModemManager[1888]: <debug> [1394467134.314674] [mm-sim-qmi.c:224] load_imsi(): loading IMSI...
>ModemManager[1888]: <warn>  [1394467134.352724] [mm-sim.c:1464] load_imsi_ready(): couldn't load IMSI: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.2''
>ModemManager[1888]: <debug> [1394467134.353796] [mm-broadband-modem-qmi.c:1410] modem_load_own_numbers(): loading own numbers...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:0E:00:24:00:00:00
>ModemManager[1888]: [/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[1888]: <debug> [1394467134.360153] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467134.361770] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
>ModemManager[1888]: [/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[1888]: [/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[1888]: <warn>  [1394467134.403658] [mm-iface-modem.c:4021] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:02:00:2B:00:00:00
>ModemManager[1888]: [/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[1888]: <debug> [1394467134.410115] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467134.410200] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467134.410244] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
>ModemManager[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 20
>>>>>>>   data   = 01:13:00:80:03:01:02:02:00:2B:00:07:00:02:04:00:01:00:47:00
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 19
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 2
>>>>>>>   tlv_length  = 7
>>>>>>>   message     = "Get Technology Preference" (0x002B)
>>>>>>> TLV:
>>>>>>>   type       = "Result" (0x02)
>>>>>>>   length     = 4
>>>>>>>   value      = 01:00:47:00
>>>>>>>   translated = FAILURE: InvalidQmiCommand
>ModemManager[1888]: <debug> [1394467134.416496] [mm-broadband-modem-qmi.c:2844] get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
>ModemManager[1888]: <warn>  [1394467134.417580] [mm-iface-modem.c:4050] load_current_modes_ready(): couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
>ModemManager[1888]: <debug> [1394467134.419109] [mm-broadband-modem-qmi.c:1877] modem_load_current_bands(): loading current bands...
>ModemManager[1888]: <warn>  [1394467134.420751] [mm-iface-modem.c:4071] 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[1888]: <debug> [1394467134.422044] [mm-broadband-modem-qmi.c:3272] modem_3gpp_load_imei_finish(): loaded IMEI: 355572040258849
>ModemManager[1888]: <debug> [1394467134.422711] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'ph-sim' status, assuming disabled
>ModemManager[1888]: <debug> [1394467134.426557] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-pers' status, assuming disabled
>ModemManager[1888]: <debug> [1394467134.435490] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-sub-pers' status, assuming disabled
>ModemManager[1888]: <debug> [1394467134.436179] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'provider-pers' status, assuming disabled
>ModemManager[1888]: <debug> [1394467134.437584] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'corp-pers' status, assuming disabled
>ModemManager[1888]: <debug> [1394467134.439469] [mm-broadband-modem.c:8955] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
>ModemManager[1888]: <debug> [1394467134.443375] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467134.444235] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467134.444850] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467134.445997] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CNMI=?<CR>'
>ModemManager[1888]: <debug> [1394467134.457244] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CNMI: (0,1,2,3),(0,1,2,3),(0,2),(0,1,2),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467134.457370] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467134.457418] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467134.457455] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS=?<CR>'
>ModemManager[1888]: <debug> [1394467134.471625] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CPMS: ("ME","MT","SM","SR"),("ME","MT","SM","SR"),("ME","MT","SM","SR")<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467134.471881] [mm-iface-modem-messaging.c:1146] load_supported_storages_ready(): Supported storages loaded:
>ModemManager[1888]: <debug> [1394467134.471905] [mm-iface-modem-messaging.c:1147] load_supported_storages_ready():   mem1 (list/read/delete) storages: 'me, mt, sm, sr'
>ModemManager[1888]: <debug> [1394467134.471925] [mm-iface-modem-messaging.c:1148] load_supported_storages_ready():   mem2 (write/send) storages:       'me, mt, sm, sr'
>ModemManager[1888]: <debug> [1394467134.471944] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready():   mem3 (reception) storages:        'me, mt, sm, sr'
>ModemManager[1888]: <debug> [1394467134.472087] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1888]: <debug> [1394467134.472290] [mm-broadband-modem.c:8959] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
>ModemManager[1888]: <debug> [1394467134.472459] [mm-broadband-modem-qmi.c:9415] signal_check_support(): Extended signal capabilities supported
>ModemManager[1888]: <debug> [1394467134.472757] [mm-broadband-modem-qmi.c:8129] oma_check_support(): OMA capabilities not supported
>ModemManager[1888]: <debug> [1394467134.472822] [mm-broadband-modem.c:8961] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
>ModemManager[1888]: <debug> [1394467134.472937] [mm-broadband-modem-qmi.c:9081] firmware_check_support(): loading firmware images...
>ModemManager[1888]: <debug> [1394467134.473029] [mm-broadband-modem.c:8962] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
>ModemManager[1888]: <info>  [1394467134.475717] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
>ModemManager[1888]: <debug> [1394467134.475894] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
>ModemManager[1888]: <debug> [1394467134.475943] [mm-serial-port.c:1040] mm_serial_port_close(): (ttyUSB1) closing serial port...
>ModemManager[1888]: <debug> [1394467134.478628] [mm-serial-port.c:1073] mm_serial_port_close(): (ttyUSB1) serial port closed
>ModemManager[1888]: <debug> [1394467134.478746] [mm-base-modem.c:1026] initialize_ready(): modem properly initialized
>ModemManager[1888]: <debug> [1394467134.485234] [mm-device.c:416] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8' at path '/org/freedesktop/ModemManager1/Modem/0'
>ModemManager[1888]: <debug> [1394467134.485398] [mm-device.c:424] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'ZTE' modem, VID 0x19D2 PID 0x1402 (usb)
>ModemManager[1888]: <info>  [1394467150.536896] [mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...
>ModemManager[1888]: <debug> [1394467150.537635] [mm-iface-modem-simple.c:651] connect_auth_ready():    PIN: unspecified
>ModemManager[1888]: <debug> [1394467150.537681] [mm-iface-modem-simple.c:653] connect_auth_ready():    Operator ID: unspecified
>ModemManager[1888]: <debug> [1394467150.537721] [mm-iface-modem-simple.c:655] connect_auth_ready():    Allowed roaming: yes
>ModemManager[1888]: <debug> [1394467150.537760] [mm-iface-modem-simple.c:657] connect_auth_ready():    APN: internet
>ModemManager[1888]: <debug> [1394467150.537799] [mm-iface-modem-simple.c:665] connect_auth_ready():    IP family: unspecified
>ModemManager[1888]: <debug> [1394467150.537848] [mm-iface-modem-simple.c:670] connect_auth_ready():    Allowed authentication: none, pap, chap, mschap, mschapv2, eap
>ModemManager[1888]: <debug> [1394467150.538869] [mm-iface-modem-simple.c:675] connect_auth_ready():    User: unspecified
>ModemManager[1888]: <debug> [1394467150.539467] [mm-iface-modem-simple.c:677] connect_auth_ready():    Password: unspecified
>ModemManager[1888]: <debug> [1394467150.542115] [mm-iface-modem-simple.c:679] connect_auth_ready():    Number: *99#
>ModemManager[1888]: <info>  [1394467150.543949] [mm-iface-modem-simple.c:461] connection_step(): Simple connect state (3/8): Enable
>ModemManager[1888]: <info>  [1394467150.544605] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
>ModemManager[1888]: <debug> [1394467150.555006] [mm-broadband-modem.c:7957] enabling_started(): Skipping modem initialization: device hotplugged
>ModemManager[1888]: <debug> [1394467150.557309] [mm-serial-port.c:901] mm_serial_port_open(): (ttyUSB1) opening serial port...
>ModemManager[1888]: <warn>  [1394467150.560699] [mm-serial-port.c:416] real_config_fd(): (ttyUSB1): port attributes not fully set
>ModemManager[1888]: <debug> [1394467150.561857] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
>ModemManager[1888]: <debug> [1394467150.563012] [mm-at-serial-port.c:473] mm_at_serial_port_run_init_sequence(): (ttyUSB1): running init sequence...
>ModemManager[1888]: <debug> [1394467150.566331] [mm-serial-port.c:901] mm_serial_port_open(): (ttyUSB0) opening serial port...
>ModemManager[1888]: <debug> [1394467150.570858] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1888]: <debug> [1394467150.570907] [mm-broadband-modem.c:7973] enabling_started(): Flashing primary AT port before enabling...
>ModemManager[1888]: <debug> [1394467150.575255] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
>ModemManager[1888]: <debug> [1394467150.584474] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467150.584600] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATV1<CR>'
>ModemManager[1888]: <debug> [1394467150.590221] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467150.590332] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
>ModemManager[1888]: <debug> [1394467150.601846] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467150.601955] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATX4<CR>'
>ModemManager[1888]: <debug> [1394467150.607218] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467150.607324] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT&C1<CR>'
>ModemManager[1888]: <debug> [1394467150.615588] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467150.676328] [mm-broadband-modem-qmi.c:2673] load_power_state(): Getting device operating mode...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:02:01:00:15:00:2D:00:00:00
>ModemManager[1888]: [/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[1888]: [/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[1888]: [/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[1888]: <debug> [1394467150.680378] [mm-iface-modem.c:3199] set_power_state(): No need to change power state: already in 'on' power state
>ModemManager[1888]: <debug> [1394467150.680506] [mm-broadband-modem.c:8549] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
>ModemManager[1888]: [/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[1888]: [/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[1888]: [/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[1888]: [/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[1888]: <debug> [1394467150.684821] [mm-broadband-modem-qmi.c:4760] modem_3gpp_enable_unsolicited_registration_events(): Assuming serving system indications are always enabled
>ModemManager[1888]: <debug> [1394467150.685096] [mm-iface-modem-3gpp.c:749] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:05:00:24:00:00:00
>ModemManager[1888]: [/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[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 59
>>>>>>>   data   = 01:3A:00:80:03:01:02:05:00:24:00:2E:00:02:04:00:00:00:00:00:01:06:00:01:01:01:02:01:05:10:01:00:01:12:11:00:F4:00:0C:00:0C:53:75:6F:6D:65:6E:20:32:47:20:4F:79:11:03:00:02:03:04
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 58
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 5
>>>>>>>   tlv_length  = 46
>>>>>>>   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     = 17
>>>>>>>   value      = F4:00:0C:00:0C:53:75:6F:6D:65:6E:20:32:47:20:4F:79
>>>>>>>   translated = [ mcc = '244' mnc = '12' description = 'Suomen 2G Oy' ]
>>>>>>> TLV:
>>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>>   length     = 3
>>>>>>>   value      = 02:03:04
>>>>>>>   translated = { [0] = 'hsdpa ' [1] = 'hsupa '}
>ModemManager[1888]: <debug> [1394467150.730571] [mm-broadband-modem-qmi.c:3898] common_process_serving_system_3gpp(): Processing 3GPP info...
>ModemManager[1888]: <info>  [1394467150.731477] [mm-iface-modem-3gpp.c:1152] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
>ModemManager[1888]: <debug> [1394467150.734326] [mm-broadband-modem.c:8590] enabling_step(): Modem has location capabilities, enabling the Location interface...
>ModemManager[1888]: <debug> [1394467150.736017] [mm-iface-modem-location.c:739] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
>ModemManager[1888]: <debug> [1394467150.790447] [mm-iface-modem-3gpp.c:749] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:06:00:24:00:00:00
>ModemManager[1888]: [/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[1888]: <debug> [1394467150.795527] [mm-broadband-modem.c:3476] modem_3gpp_load_subscription_state(): Load subscription state: Marking the SIM as provisioned.
>ModemManager[1888]: [/dev/cdc-wdm0] Received message...
>>>>>>> RAW:
>>>>>>>   length = 59
>>>>>>>   data   = 01:3A:00:80:03:01:02:06:00:24:00:2E:00:02:04:00:00:00:00:00:01:06:00:01:01:01:02:01:05:10:01:00:01:12:11:00:F4:00:0C:00:0C:53:75:6F:6D:65:6E:20:32:47:20:4F:79:11:03:00:02:03:04
>ModemManager[1888]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>>> QMUX:
>>>>>>>   length  = 58
>>>>>>>   flags   = 0x80
>>>>>>>   service = "nas"
>>>>>>>   client  = 1
>>>>>>> QMI:
>>>>>>>   flags       = "response"
>>>>>>>   transaction = 6
>>>>>>>   tlv_length  = 46
>>>>>>>   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     = 17
>>>>>>>   value      = F4:00:0C:00:0C:53:75:6F:6D:65:6E:20:32:47:20:4F:79
>>>>>>>   translated = [ mcc = '244' mnc = '12' description = 'Suomen 2G Oy' ]
>>>>>>> TLV:
>>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>>   length     = 3
>>>>>>>   value      = 02:03:04
>>>>>>>   translated = { [0] = 'hsdpa ' [1] = 'hsupa '}
>ModemManager[1888]: <debug> [1394467150.806458] [mm-broadband-modem-qmi.c:3898] common_process_serving_system_3gpp(): Processing 3GPP info...
>ModemManager[1888]: <info>  [1394467150.808276] [mm-iface-modem-3gpp.c:1077] update_registration_reload_current_registration_info_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
>ModemManager[1888]: <debug> [1394467150.860130] [mm-iface-modem.c:1540] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
>ModemManager[1888]: <debug> [1394467150.860943] [mm-broadband-modem.c:8603] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
>ModemManager[1888]: <debug> [1394467150.863119] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1888]: <debug> [1394467150.865306] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGF=?<CR>'
>ModemManager[1888]: <debug> [1394467150.880130] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467150.880330] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467150.880377] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467150.880419] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGF=0<CR>'
>ModemManager[1888]: <debug> [1394467150.893487] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467150.893672] [mm-broadband-modem.c:5460] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU'
>ModemManager[1888]: <debug> [1394467150.945348] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467150.946237] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467150.948253] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS="","MT","MT"<CR>'
>ModemManager[1888]: <debug> [1394467150.980363] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CPMS: 0,30,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467150.980503] [mm-broadband-modem.c:6158] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me'
>ModemManager[1888]: <debug> [1394467150.980540] [mm-broadband-modem.c:5354] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)...
>ModemManager[1888]: <debug> [1394467150.980589] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467150.980635] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467150.980674] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS="ME"<CR>'
>ModemManager[1888]: <debug> [1394467150.998494] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CPMS: 0,100,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467150.998691] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467150.998745] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467150.998784] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGL=4<CR>'
>ModemManager[1888]: <debug> [1394467151.011978] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467151.012151] [mm-broadband-modem.c:6158] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
>ModemManager[1888]: <debug> [1394467151.012180] [mm-broadband-modem.c:5354] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)...
>ModemManager[1888]: <debug> [1394467151.012221] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467151.012267] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467151.012305] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS="SM"<CR>'
>ModemManager[1888]: <debug> [1394467151.029873] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CPMS: 0,30,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467151.030023] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467151.030076] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467151.030117] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGL=4<CR>'
>ModemManager[1888]: <debug> [1394467151.043108] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467151.043271] [mm-broadband-modem.c:6158] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sr'
>ModemManager[1888]: <debug> [1394467151.043300] [mm-broadband-modem.c:5354] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SR), mem2 (none)...
>ModemManager[1888]: <debug> [1394467151.043342] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467151.043386] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467151.043425] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS="SR"<CR>'
>ModemManager[1888]: <debug> [1394467151.060495] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CPMS: 0,100,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467151.060684] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
>ModemManager[1888]: <debug> [1394467151.060738] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
>ModemManager[1888]: <debug> [1394467151.060803] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGL=4<CR>'
>ModemManager[1888]: <debug> [1394467151.072359] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CMS ERROR: 500<CR><LF>'
>ModemManager[1888]: <debug> [1394467151.072580] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown
>ModemManager[1888]: <debug> [1394467151.072642] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'sr': 'Unknown'
>ModemManager[1888]: <debug> [1394467151.072702] [mm-broadband-modem.c:5766] set_messaging_unsolicited_events_handlers(): (ttyUSB1) Setting messaging unsolicited events handlers
>ModemManager[1888]: <debug> [1394467151.072740] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1888]: <debug> [1394467151.072799] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB1) device open count is 2 (open)
>ModemManager[1888]: <debug> [1394467151.072854] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CNMI=2,1,2,1,0<CR>'
>ModemManager[1888]: <debug> [1394467151.095904] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
>ModemManager[1888]: <debug> [1394467151.096022] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
>ModemManager[1888]: <debug> [1394467151.096081] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CNMI=2,1,2,2,0<CR>'
>ModemManager[1888]: <debug> [1394467151.119401] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
>ModemManager[1888]: <debug> [1394467151.119662] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 1 (close)
>ModemManager[1888]: <debug> [1394467151.119755] [mm-broadband-modem.c:8629] enabling_step(): Modem has extended signal reporting capabilities, enabling the Signal interface...
>ModemManager[1888]: <debug> [1394467151.119834] [mm-iface-modem-signal.c:206] setup_refresh_context(): Extended signal information reporting disabled (rate: 0 seconds)
>ModemManager[1888]: <info>  [1394467151.120118] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
>ModemManager[1888]: <debug> [1394467151.127030] [mm-iface-modem.c:1295] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
>ModemManager[1888]: <debug> [1394467151.128425] [mm-broadband-modem-qmi.c:2458] load_signal_quality(): loading signal quality...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:07:00:20:00:00:00
>ModemManager[1888]: [/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[1888]: <info>  [1394467151.131754] [mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled
>ModemManager[1888]: <info>  [1394467151.131854] [mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register
>ModemManager[1888]: <debug> [1394467151.131924] [mm-iface-modem-3gpp.c:388] mm_iface_modem_3gpp_register_in_network(): Already registered in network '24412', automatic registration not launched...
>ModemManager[1888]: [/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:B6:05
>ModemManager[1888]: [/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      = B6:05
>>>>>>>   translated = [ strength = '-74' radio_interface = 'umts' ]
>ModemManager[1888]: <debug> [1394467151.141636] [mm-broadband-modem-qmi.c:2348] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -74 dBm
>ModemManager[1888]: <debug> [1394467151.142266] [mm-broadband-modem-qmi.c:2381] signal_strength_get_quality_and_access_tech(): Signal strength: -74 dBm --> 63%
>ModemManager[1888]: <info>  [1394467151.146141] [mm-iface-modem.c:894] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> umts)
>ModemManager[1888]: <info>  [1394467151.147088] [mm-iface-modem.c:1144] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (63)
>ModemManager[1888]: <debug> [1394467151.149012] [mm-iface-modem.c:1214] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s)
>ModemManager[1888]: <info>  [1394467151.150020] [mm-iface-modem-simple.c:501] connection_step(): Simple connect state (6/8): Bearer
>ModemManager[1888]: <debug> [1394467151.155267] [mm-iface-modem-simple.c:521] connection_step(): Creating new bearer...
>ModemManager[1888]: <debug> [1394467151.157114] [mm-broadband-modem-qmi.c:320] modem_create_bearer(): Creating QMI bearer in QMI modem
>ModemManager[1888]: <debug> [1394467151.159485] [mm-broadband-modem-qmi.c:299] modem_create_bearer_finish(): New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[1888]: <info>  [1394467151.161976] [mm-iface-modem-simple.c:583] connection_step(): Simple connect state (7/8): Connect
>ModemManager[1888]: <debug> [1394467151.163434] [mm-bearer.c:591] mm_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[1888]: <info>  [1394467151.165035] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
>ModemManager[1888]: <debug> [1394467151.236394] [mm-bearer-qmi.c:868] _connect(): Launching connection with QMI port (usb/cdc-wdm0) and data port (net/wwp0s29f7u8i2)
>ModemManager[1888]: <debug> [1394467151.242032] [mm-bearer-qmi.c:901] _connect(): No specific IP family requested, defaulting to ipv4
>ModemManager[1888]: <debug> [1394467151.243015] [mm-bearer-qmi.c:561] connect_context_step(): Running IPv4 connection setup
>ModemManager[1888]: <debug> [1394467151.245004] [mm-bearer-qmi.c:574] connect_context_step(): Allocating IPv4-specific WDS client
>ModemManager[1888]: [/dev/cdc-wdm0] Allocating new client ID...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 16
><<<<<<   data   = 01:0F:00:00:00:00:00:06:22:00:04:00:01:01:00:01
>ModemManager[1888]: [/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      = 01
><<<<<<   translated = wds
>ModemManager[1888]: [/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:01:01
>ModemManager[1888]: [/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      = 01:01
>>>>>>>   translated = [ service = 'wds' cid = '1' ]
>ModemManager[1888]: [/dev/cdc-wdm0] Registered 'wds' (version 1.5) client with ID '1'
>ModemManager[1888]: <debug> [1394467151.274420] [mm-bearer-qmi.c:616] connect_context_step(): Starting IPv4 connection...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 28
><<<<<<   data   = 01:1B:00:00:01:01:00:01:00:20:00:0F:00:16:01:00:03:14:08:00:69:6E:74:65:72:6E:65:74
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 27
><<<<<<   flags   = 0x00
><<<<<<   service = "wds"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 1
><<<<<<   tlv_length  = 15
><<<<<<   message     = "Start Network" (0x0020)
><<<<<< TLV:
><<<<<<   type       = "Authentication Preference" (0x16)
><<<<<<   length     = 1
><<<<<<   value      = 03
><<<<<<   translated = pap, chap
><<<<<< TLV:
><<<<<<   type       = "APN" (0x14)
><<<<<<   length     = 8
><<<<<<   value      = 69:6E:74:65:72:6E:65:74
><<<<<<   translated = internet
>ModemManager[1888]: <debug> [1394467181.550183] [mm-broadband-modem-qmi.c:2458] load_signal_quality(): loading signal quality...
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 13
><<<<<<   data   = 01:0C:00:00:03:01:00:08:00:20:00:00:00
>ModemManager[1888]: [/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[1888]: [/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:B6:05
>ModemManager[1888]: [/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      = B6:05
>>>>>>>   translated = [ strength = '-74' radio_interface = 'umts' ]
>ModemManager[1888]: <debug> [1394467181.554502] [mm-broadband-modem-qmi.c:2348] signal_strength_get_quality_and_access_tech(): Signal strength (umts): -74 dBm
>ModemManager[1888]: <debug> [1394467181.554583] [mm-broadband-modem-qmi.c:2381] signal_strength_get_quality_and_access_tech(): Signal strength: -74 dBm --> 63%
>ModemManager[1888]: <info>  [1394467181.554773] [mm-iface-modem.c:1144] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (63)
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 18
><<<<<<   data   = 01:11:00:00:01:01:00:02:00:02:00:05:00:01:02:00:01:00
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 17
><<<<<<   flags   = 0x00
><<<<<<   service = "wds"
><<<<<<   client  = 1
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 2
><<<<<<   tlv_length  = 5
><<<<<<   message     = "Abort" (0x0002)
><<<<<< TLV:
><<<<<<   type       = "Transaction ID" (0x01)
><<<<<<   length     = 2
><<<<<<   value      = 01:00
><<<<<<   translated = 1
>ModemManager[1888]: <debug> [1394467196.535511] [mm-bearer.c:466] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'Transaction timed out'
>ModemManager[1888]: <info>  [1394467196.535647] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
>ModemManager[1888]: <debug> [1394467196.538485] [mm-iface-modem-simple.c:221] connect_bearer_ready(): Couldn't connect bearer: 'Transaction timed out'
>ModemManager[1888]: <debug> [1394467197.547178] [mm-serial-port.c:752] data_available(): (ttyUSB0) unexpected port hangup!
>ModemManager[1888]: <debug> [1394467197.547286] [mm-serial-port.c:1148] mm_serial_port_close_force(): (ttyUSB0) forced to close port
>ModemManager[1888]: <debug> [1394467197.547337] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1888]: <debug> [1394467197.547387] [mm-serial-port.c:1040] mm_serial_port_close(): (ttyUSB0) closing serial port...
>ModemManager[1888]: <debug> [1394467197.547467] [mm-serial-port.c:1073] mm_serial_port_close(): (ttyUSB0) serial port closed
>ModemManager[1888]: <debug> [1394467197.550594] [mm-serial-port.c:752] data_available(): (ttyUSB1) unexpected port hangup!
>ModemManager[1888]: <debug> [1394467197.550677] [mm-serial-port.c:1148] mm_serial_port_close_force(): (ttyUSB1) forced to close port
>ModemManager[1888]: <debug> [1394467197.550720] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
>ModemManager[1888]: <debug> [1394467197.550766] [mm-serial-port.c:1040] mm_serial_port_close(): (ttyUSB1) closing serial port...
>ModemManager[1888]: <debug> [1394467197.550845] [mm-serial-port.c:1073] mm_serial_port_close(): (ttyUSB1) serial port closed
>ModemManager[1888]: <info>  [1394467197.586511] [mm-manager.c:355] device_removed(): (tty/ttyUSB1): released by modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8
>ModemManager[1888]: <info>  [1394467197.597641] [mm-manager.c:355] device_removed(): (usbmisc/cdc-wdm0): released by modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8
>ModemManager[1888]: <info>  [1394467197.600604] [mm-manager.c:355] device_removed(): (tty/ttyUSB0): released by modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8
>ModemManager[1888]: <info>  [1394467197.623582] [mm-manager.c:355] device_removed(): (net/wwp0s29f7u8i2): released by modem /sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8
>ModemManager[1888]: <debug> [1394467197.623706] [mm-manager.c:360] device_removed(): Removing empty device '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8'
>ModemManager[1888]: <debug> [1394467197.624103] [mm-bearer.c:896] mm_bearer_dbus_unexport(): Removing from DBus bearer at '/org/freedesktop/ModemManager1/Bearer/0'
>ModemManager[1888]: <debug> [1394467197.624150] [mm-device.c:364] unexport_modem(): Unexported modem '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8' from path '/org/freedesktop/ModemManager1/Modem/0'
>ModemManager[1888]: <debug> [1394467197.626629] [mm-qmi-port.c:318] mm_qmi_port_close(): Releasing client for service 'wds'...
>ModemManager[1888]: [/dev/cdc-wdm0] Unregistered 'wds' client with ID '1'
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 17
><<<<<<   data   = 01:10:00:00:00:00:00:07:23:00:05:00:01:02:00:01:01
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 16
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 7
><<<<<<   tlv_length  = 5
><<<<<<   message     = "Release CID" (0x0023)
><<<<<< TLV:
><<<<<<   type       = "Release Info" (0x01)
><<<<<<   length     = 2
><<<<<<   value      = 01:01
><<<<<<   translated = [ service = 'wds' cid = '1' ]
>ModemManager[1888]: <debug> [1394467197.627546] [mm-qmi-port.c:318] mm_qmi_port_close(): Releasing client for service 'nas'...
>ModemManager[1888]: [/dev/cdc-wdm0] Unregistered 'nas' client with ID '1'
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 17
><<<<<<   data   = 01:10:00:00:00:00:00:08:23:00:05:00:01:02:00:03:01
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 16
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 8
><<<<<<   tlv_length  = 5
><<<<<<   message     = "Release CID" (0x0023)
><<<<<< TLV:
><<<<<<   type       = "Release Info" (0x01)
><<<<<<   length     = 2
><<<<<<   value      = 03:01
><<<<<<   translated = [ service = 'nas' cid = '1' ]
>ModemManager[1888]: <debug> [1394467197.628844] [mm-qmi-port.c:318] mm_qmi_port_close(): Releasing client for service 'dms'...
>ModemManager[1888]: [/dev/cdc-wdm0] Unregistered 'dms' client with ID '1'
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message...
><<<<<< RAW:
><<<<<<   length = 17
><<<<<<   data   = 01:10:00:00:00:00:00:09:23:00:05:00:01:02:00:02:01
>ModemManager[1888]: [/dev/cdc-wdm0] Sent message (translated)...
><<<<<< QMUX:
><<<<<<   length  = 16
><<<<<<   flags   = 0x00
><<<<<<   service = "ctl"
><<<<<<   client  = 0
><<<<<< QMI:
><<<<<<   flags       = "none"
><<<<<<   transaction = 9
><<<<<<   tlv_length  = 5
><<<<<<   message     = "Release CID" (0x0023)
><<<<<< TLV:
><<<<<<   type       = "Release Info" (0x01)
><<<<<<   length     = 2
><<<<<<   value      = 02:01
><<<<<<   translated = [ service = 'dms' cid = '1' ]
>ModemManager[1888]: <debug> [1394467197.638970] [mm-base-modem.c:1557] finalize(): Modem (ZTE) '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8' completely disposed
>ModemManager[1888]: <debug> [1394467199.615563] [mm-plugin-manager.c:675] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8] Checking device support...
>ModemManager[1888]: <debug> [1394467199.615724] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.615750] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.615773] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB0] filtered by drivers
>ModemManager[1888]: <debug> [1394467199.615793] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers
>ModemManager[1888]: <debug> [1394467199.615812] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.615833] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.615852] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.615872] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags
>ModemManager[1888]: <debug> [1394467199.615891] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.615912] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.615933] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.619023] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.619060] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.619081] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.619099] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.620561] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB0] filtered by drivers
>ModemManager[1888]: <debug> [1394467199.620677] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.622760] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.623292] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.634986] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by vendor/product IDs
>ModemManager[1888]: <debug> [1394467199.636007] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '2' plugins to try...
>ModemManager[1888]: <debug> [1394467199.636978] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'ZTE'
>ModemManager[1888]: <debug> [1394467199.641425] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Generic'
>ModemManager[1888]: <debug> [1394467199.643007] [mm-plugin.c:776] mm_plugin_supports_port(): (ZTE) [ttyUSB0] probe required: 'at, at-icera, qcdm'
>ModemManager[1888]: <debug> [1394467199.643572] [mm-port-probe.c:1337] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, at-icera, qcdm'
>ModemManager[1888]: <debug> [1394467199.644288] [mm-serial-port.c:901] mm_serial_port_open(): (ttyUSB0) opening serial port...
>ModemManager[1888]: <warn>  [1394467199.655731] [mm-serial-port.c:416] real_config_fd(): (ttyUSB0): port attributes not fully set
>ModemManager[1888]: <debug> [1394467199.656651] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1888]: <debug> [1394467199.758090] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
>ModemManager[1888]: <debug> [1394467201.521106] [mm-plugin-manager.c:646] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8] Minimum probing time consumed
>ModemManager[1888]: <debug> [1394467203.522341] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1888]: <debug> [1394467203.522563] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
>ModemManager[1888]: <debug> [1394467207.523516] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1888]: <debug> [1394467207.523747] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
>ModemManager[1888]: <debug> [1394467211.523632] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
>ModemManager[1888]: <debug> [1394467211.523751] [mm-port-probe.c:161] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
>ModemManager[1888]: <debug> [1394467211.523834] [mm-port-probe.c:659] serial_probe_qcdm(): (tty/ttyUSB0) probing QCDM...
>ModemManager[1888]: <debug> [1394467211.523884] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1888]: <debug> [1394467211.523930] [mm-serial-port.c:1040] mm_serial_port_close(): (ttyUSB0) closing serial port...
>ModemManager[1888]: <debug> [1394467211.529023] [mm-serial-port.c:1073] mm_serial_port_close(): (ttyUSB0) serial port closed
>ModemManager[1888]: <debug> [1394467211.529102] [mm-serial-port.c:1148] mm_serial_port_close_force(): (ttyUSB0) forced to close port
>ModemManager[1888]: <debug> [1394467211.529260] [mm-serial-port.c:901] mm_serial_port_open(): (ttyUSB0) opening serial port...
>ModemManager[1888]: <debug> [1394467211.530660] [mm-serial-port.c:978] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
>ModemManager[1888]: <debug> [1394467211.530792] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
>ModemManager[1888]: <debug> [1394467211.531414] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB0): <-- 7e 03 00 06 9e 4c 7e
>ModemManager[1888]: <warn>  [1394467211.531658] [mm-port-probe.c:602] serial_probe_qcdm_parse_response(): (tty/ttyUSB0) failed to parse QCDM version info command result: -4
>ModemManager[1888]: <debug> [1394467211.531925] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
>ModemManager[1888]: <debug> [1394467211.532658] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB0): <-- 7e 03 00 06 9e 4c 7e
>ModemManager[1888]: <warn>  [1394467211.532891] [mm-port-probe.c:602] serial_probe_qcdm_parse_response(): (tty/ttyUSB0) failed to parse QCDM version info command result: -4
>ModemManager[1888]: <debug> [1394467211.533656] [mm-port-probe.c:257] mm_port_probe_set_result_qcdm(): (tty/ttyUSB0) port is not QCDM-capable
>ModemManager[1888]: <debug> [1394467211.535849] [mm-serial-port.c:1024] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
>ModemManager[1888]: <debug> [1394467211.542882] [mm-serial-port.c:1040] mm_serial_port_close(): (ttyUSB0) closing serial port...
>ModemManager[1888]: <debug> [1394467211.545623] [mm-serial-port.c:1073] mm_serial_port_close(): (ttyUSB0) serial port closed
>ModemManager[1888]: <debug> [1394467211.545919] [mm-serial-port.c:1148] mm_serial_port_close_force(): (ttyUSB0) forced to close port
>ModemManager[1888]: <debug> [1394467211.547897] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (ZTE) [ttyUSB0] found best plugin for port
>ModemManager[1888]: <debug> [1394467211.548479] [mm-plugin-manager.c:201] port_probe_context_finished(): (Plugin Manager) (ZTE) [ttyUSB0]: found best plugin for device (/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8)
>ModemManager[1888]: <debug> [1394467211.549045] [mm-plugin-manager.c:285] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, last one in device
>ModemManager[1888]: <debug> [1394467211.550040] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8] device support check finished in '11.934378' seconds
>ModemManager[1888]: <info>  [1394467211.552071] [mm-device.c:486] mm_device_create_modem(): Creating modem with plugin 'ZTE' and '1' ports
>ModemManager[1888]: <warn>  [1394467211.560228] [mm-plugin.c:904] mm_plugin_create_modem(): Could not grab port (tty/ttyUSB0): 'Cannot add port 'tty/ttyUSB0', unhandled serial type'
>ModemManager[1888]: <debug> [1394467211.561184] [mm-base-modem.c:1557] finalize(): Modem (ZTE) '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8' completely disposed
>ModemManager[1888]: <warn>  [1394467211.562972] [mm-manager.c:145] find_device_support_ready(): Couldn't create modem for device at '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8': Failed to find primary AT port
>ModemManager[1888]: Operation to abort 'Start Network' failed: Transaction timed out
><debug> [1394467106.842942] [config/nm-config.c:304] read_config(): Reading config file '/etc/NetworkManager/NetworkManager.conf'
>ModemManager[1888]: <info>  [1394467249.300661] [main.c:46] quit_cb(): Caught signal, shutting down...
>ModemManager[1888]: <info>  [1394467249.310255] [main.c:179] main(): ModemManager is shut down

Actions: View

Attachments on bug 817504: 581166 | 581167 | 594252 | 850015 | 867919 | 872797