Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 872797 Details for
Bug 817504
ZTE MF60 3G Modem Not Working Without Boot Parameters
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
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:
zte-mm-nm-debug.txt
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
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 817504
:
581166
|
581167
|
594252
|
850015
|
867919
| 872797