ModemManager[5693]: <info>  [1389525544.470152] [main.c:128] main(): ModemManager (version 1.1.990) starting...
ModemManager[5693]: <debug> [1389525544.475711] [main.c:61] bus_acquired_cb(): Bus acquired, creating manager...
ModemManager[5693]: <debug> [1389525544.480545] [mm-plugin-manager.c:788] load_plugins(): Looking for plugins in '/usr/lib/ModemManager'
ModemManager[5693]: <debug> [1389525544.481397] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Iridium'
ModemManager[5693]: <debug> [1389525544.481787] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Option'
ModemManager[5693]: <debug> [1389525544.482166] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'AnyDATA'
ModemManager[5693]: <debug> [1389525544.482650] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Generic'
ModemManager[5693]: <debug> [1389525544.483031] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Longcheer'
ModemManager[5693]: <debug> [1389525544.483385] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Gobi'
ModemManager[5693]: <debug> [1389525544.483774] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Nokia (Icera)'
ModemManager[5693]: <debug> [1389525544.484157] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Motorola'
ModemManager[5693]: <debug> [1389525544.484679] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'X22X'
ModemManager[5693]: <debug> [1389525544.485110] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Via CBP7'
ModemManager[5693]: <debug> [1389525544.485543] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Altair LTE'
ModemManager[5693]: <debug> [1389525544.485961] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Nokia'
ModemManager[5693]: <debug> [1389525544.486400] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Samsung'
ModemManager[5693]: <debug> [1389525544.486932] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Wavecom'
ModemManager[5693]: <debug> [1389525544.487379] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'SimTech'
ModemManager[5693]: <debug> [1389525544.487814] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Novatel LTE'
ModemManager[5693]: <debug> [1389525544.488243] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Telit'
ModemManager[5693]: <debug> [1389525544.488764] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'ZTE'
ModemManager[5693]: <debug> [1389525544.489176] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Pantech'
ModemManager[5693]: <debug> [1389525544.489622] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'MTK'
ModemManager[5693]: <debug> [1389525544.490097] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Huawei'
ModemManager[5693]: <debug> [1389525544.490549] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Novatel'
ModemManager[5693]: <debug> [1389525544.491039] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Sierra'
ModemManager[5693]: <debug> [1389525544.491484] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Cinterion'
ModemManager[5693]: <debug> [1389525544.492561] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Linktop'
ModemManager[5693]: <debug> [1389525544.492973] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Option High-Speed'
ModemManager[5693]: <debug> [1389525544.493364] [mm-plugin-manager.c:813] load_plugins(): Loaded plugin 'Ericsson MBM'
ModemManager[5693]: <debug> [1389525544.493445] [mm-plugin-manager.c:838] load_plugins(): Successfully loaded 27 plugins
ModemManager[5693]: <debug> [1389525544.494764] [main.c:79] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
ModemManager[5693]: <debug> [1389525544.494838] [mm-manager.c:456] mm_manager_start(): Starting automatic device scan...
ModemManager[5693]: <debug> [1389525544.514337] [mm-manager.c:495] mm_manager_start(): Finished device scan...
ModemManager[5693]: <debug> [1389525544.516131] [mm-plugin-manager.c:675] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2] Checking device support...
ModemManager[5693]: <debug> [1389525544.516571] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.516625] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.516666] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.516717] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB0] filtered by drivers
ModemManager[5693]: <debug> [1389525544.516754] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.516792] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.516840] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.516889] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.516937] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.516977] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.517023] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.517062] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.517108] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.517161] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '2' plugins to try...
ModemManager[5693]: <debug> [1389525544.517208] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'ZTE'
ModemManager[5693]: <debug> [1389525544.517246] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB0]   Will try with plugin 'Generic'
ModemManager[5693]: <debug> [1389525544.517319] [mm-plugin.c:776] mm_plugin_supports_port(): (ZTE) [ttyUSB0] probe required: 'at, at-icera, qcdm'
ModemManager[5693]: <debug> [1389525544.517380] [mm-port-probe.c:1330] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, at-icera, qcdm'
ModemManager[5693]: <debug> [1389525544.518977] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519034] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519077] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519118] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB1] filtered by drivers
ModemManager[5693]: <debug> [1389525544.519157] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519195] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519233] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519270] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519311] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519349] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519401] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519439] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519475] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB1] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.519514] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [ttyUSB1] Found '2' plugins to try...
ModemManager[5693]: <debug> [1389525544.519551] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'ZTE'
ModemManager[5693]: <debug> [1389525544.519597] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [ttyUSB1]   Will try with plugin 'Generic'
ModemManager[5693]: <debug> [1389525544.519654] [mm-plugin.c:776] mm_plugin_supports_port(): (ZTE) [ttyUSB1] probe required: 'at, at-icera, qcdm'
ModemManager[5693]: <debug> [1389525544.519711] [mm-port-probe.c:1330] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, at-icera, qcdm'
ModemManager[5693]: <debug> [1389525544.524650] [mm-plugin-manager.c:675] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Checking device support...
ModemManager[5693]: <debug> [1389525544.524869] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.524923] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.524977] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525024] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.525073] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [eth0] filtered by drivers
ModemManager[5693]: <debug> [1389525544.525120] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525167] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.525213] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525259] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.525305] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525352] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.525390] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525436] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.525474] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.525519] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525558] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.525603] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525642] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525687] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.525734] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525795] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525842] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [eth0] filtered by drivers
ModemManager[5693]: <debug> [1389525544.525888] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [eth0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.525934] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [eth0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.525981] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [eth0] filtered by drivers
ModemManager[5693]: <debug> [1389525544.526023] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [eth0] filtered by udev tags
ModemManager[5693]: <debug> [1389525544.526068] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [eth0] Found '1' plugins to try...
ModemManager[5693]: <debug> [1389525544.526106] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [eth0]   Will try with plugin 'Generic'
ModemManager[5693]: <debug> [1389525544.526155] [mm-plugin.c:704] mm_plugin_supports_port(): (Generic) [eth0] probing deferred until result suggested
ModemManager[5693]: <debug> [1389525544.526928] [mm-plugin-manager.c:675] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0] Checking device support...
ModemManager[5693]: <debug> [1389525544.527136] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527189] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527229] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.527278] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527325] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wlan0] filtered by drivers
ModemManager[5693]: <debug> [1389525544.527372] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.527418] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527456] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.527504] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527551] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.527597] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527643] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.527684] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527729] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527766] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.527811] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527849] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.527894] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.527953] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.527999] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.528045] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.528091] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wlan0] filtered by drivers
ModemManager[5693]: <debug> [1389525544.528137] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wlan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.528183] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.528229] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wlan0] filtered by drivers
ModemManager[5693]: <debug> [1389525544.528350] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wlan0] filtered by udev tags
ModemManager[5693]: <debug> [1389525544.528401] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [wlan0] Found '1' plugins to try...
ModemManager[5693]: <debug> [1389525544.528449] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [wlan0]   Will try with plugin 'Generic'
ModemManager[5693]: <debug> [1389525544.528492] [mm-plugin.c:704] mm_plugin_supports_port(): (Generic) [wlan0] probing deferred until result suggested
ModemManager[5693]: <debug> [1389525544.529772] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wwan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.529819] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wwan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.529868] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wwan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.529906] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.529943] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wwan0] filtered by drivers
ModemManager[5693]: <debug> [1389525544.529982] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wwan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.530029] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wwan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.530075] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wwan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.530121] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.530172] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wwan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.530218] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wwan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.530256] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wwan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.530301] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.530348] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wwan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.530395] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wwan0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.530441] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wwan0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.530488] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [wwan0] Found '2' plugins to try...
ModemManager[5693]: <debug> [1389525544.530535] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [wwan0]   Will try with plugin 'ZTE'
ModemManager[5693]: <debug> [1389525544.530594] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [wwan0]   Will try with plugin 'Generic'
ModemManager[5693]: <debug> [1389525544.530646] [mm-plugin.c:704] mm_plugin_supports_port(): (ZTE) [wwan0] probing deferred until result suggested
ModemManager[5693]: <debug> [1389525544.531470] [mm-manager.c:270] device_added(): (net/tap0): could not get port's parent device
ModemManager[5693]: <debug> [1389525544.531929] [mm-manager.c:270] device_added(): (net/tap0.42): could not get port's parent device
ModemManager[5693]: <debug> [1389525544.533221] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533269] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533317] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.533357] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533395] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers
ModemManager[5693]: <debug> [1389525544.533434] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533481] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533527] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[5693]: <debug> [1389525544.533573] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533613] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533657] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533705] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533751] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533797] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533835] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533880] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem
ModemManager[5693]: <debug> [1389525544.533927] [mm-plugin-manager.c:576] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try...
ModemManager[5693]: <debug> [1389525544.533974] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'ZTE'
ModemManager[5693]: <debug> [1389525544.534019] [mm-plugin-manager.c:580] build_plugins_list(): (Plugin Manager) [cdc-wdm0]   Will try with plugin 'Generic'
ModemManager[5693]: <debug> [1389525544.534071] [mm-plugin.c:776] mm_plugin_supports_port(): (ZTE) [cdc-wdm0] probe required: 'qmi'
ModemManager[5693]: <debug> [1389525544.534124] [mm-port-probe.c:1330] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi'
ModemManager[5693]: <debug> [1389525544.534203] [mm-plugin-manager.c:505] plugin_supports_port_ready(): (Plugin Manager) [eth0] deferring support check until result suggested
ModemManager[5693]: <debug> [1389525544.534259] [mm-plugin-manager.c:505] plugin_supports_port_ready(): (Plugin Manager) [wlan0] deferring support check until result suggested
ModemManager[5693]: <debug> [1389525544.534312] [mm-plugin-manager.c:505] plugin_supports_port_ready(): (Plugin Manager) [wwan0] deferring support check until result suggested
ModemManager[5693]: <debug> [1389525544.534805] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB0) opening serial port...
ModemManager[5693]: <warn>  [1389525544.536661] [mm-serial-port.c:416] real_config_fd(): (ttyUSB0): port attributes not fully set
ModemManager[5693]: <debug> [1389525544.536777] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[5693]: <debug> [1389525544.537336] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB1) opening serial port...
ModemManager[5693]: <warn>  [1389525544.538944] [mm-serial-port.c:416] real_config_fd(): (ttyUSB1): port attributes not fully set
ModemManager[5693]: <debug> [1389525544.539041] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[5693]: <debug> [1389525544.539457] [mm-port-probe.c:453] wdm_probe_qmi(): (usbmisc/cdc-wdm0) probing QMI...
ModemManager[5693]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
ModemManager[5693]: cannot connect to proxy: Could not connect: Connection refused
ModemManager[5693]: spawning new qmi-proxy (try 1)...
ModemManager[5693]: <debug> [1389525544.638815] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
ModemManager[5693]: <debug> [1389525544.641198] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATE0+CPMS?<CR>'
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/dev/cdc-wdm0] Checking version info (10 retries)...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/dev/cdc-wdm0] QMI Device supports 4 services:
ModemManager[5693]: [/dev/cdc-wdm0]    ctl (1.3)
ModemManager[5693]: [/dev/cdc-wdm0]    wds (1.5)
ModemManager[5693]: [/dev/cdc-wdm0]    dms (1.2)
ModemManager[5693]: [/dev/cdc-wdm0]    nas (1.0)
ModemManager[5693]: <debug> [1389525544.677316] [mm-port-probe.c:270] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable
ModemManager[5693]: <debug> [1389525544.678435] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (ZTE) [cdc-wdm0] found best plugin for port
ModemManager[5693]: <debug> [1389525544.678979] [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/usb3/3-2)
ModemManager[5693]: <debug> [1389525544.679130] [mm-plugin-manager.c:334] suggest_port_probe_result(): (Plugin Manager) (ZTE) [wwan0] deferred task completed, got suggested plugin
ModemManager[5693]: <debug> [1389525544.679264] [mm-plugin-manager.c:373] suggest_port_probe_result(): (Plugin Manager) (ZTE) [ttyUSB1] suggested plugin for port
ModemManager[5693]: <debug> [1389525544.679377] [mm-plugin-manager.c:373] suggest_port_probe_result(): (Plugin Manager) (ZTE) [ttyUSB0] suggested plugin for port
ModemManager[5693]: <debug> [1389525544.679494] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 3 running probes in this device (wwan0, ttyUSB1, ttyUSB0)
ModemManager[5693]: <debug> [1389525544.679653] [mm-plugin.c:704] mm_plugin_supports_port(): (ZTE) [wwan0] probing deferred until result suggested
ModemManager[5693]: <debug> [1389525544.679797] [mm-plugin-manager.c:485] plugin_supports_port_ready(): (Plugin Manager) (ZTE) [wwan0] task completed, got suggested plugin
ModemManager[5693]: <debug> [1389525544.679914] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'wwan0' port probe finished, still 2 running probes in this device (ttyUSB1, ttyUSB0)
ModemManager[5693]: <debug> [1389525545.650281] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CPMS: "SM",0,20,"SM",0,20,"ME",0,100<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525545.650674] [mm-port-probe.c:151] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is AT-capable
ModemManager[5693]: <debug> [1389525545.650897] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT%IPSYS?<CR>'
ModemManager[5693]: <debug> [1389525546.557100] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>ERROR<CR><LF>'
ModemManager[5693]: <debug> [1389525546.557557] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
ModemManager[5693]: <debug> [1389525546.557721] [mm-port-probe.c:223] mm_port_probe_set_result_at_icera(): (tty/ttyUSB1) Modem is probably not Icera-based
ModemManager[5693]: <debug> [1389525546.557906] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
ModemManager[5693]: <debug> [1389525546.558005] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB1) closing serial port...
ModemManager[5693]: <debug> [1389525546.560502] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB1) serial port closed
ModemManager[5693]: <debug> [1389525546.560643] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB1) forced to close port
ModemManager[5693]: <debug> [1389525546.560868] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (ZTE) [ttyUSB1] found best plugin for port
ModemManager[5693]: <debug> [1389525546.560968] [mm-plugin-manager.c:274] port_probe_context_finished(): (Plugin Manager) 'ttyUSB1' port probe finished, still 1 running probes in this device (ttyUSB0)
ModemManager[5693]: <debug> [1389525547.027723] [mm-plugin-manager.c:646] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2] Minimum probing time consumed
ModemManager[5693]: <debug> [1389525547.027905] [mm-plugin-manager.c:646] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Minimum probing time consumed
ModemManager[5693]: <debug> [1389525547.027978] [mm-plugin-manager.c:341] suggest_port_probe_result(): (Plugin Manager) [eth0] deferred task cancelled, no suggested plugin
ModemManager[5693]: <debug> [1389525547.028070] [mm-plugin-manager.c:646] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0] Minimum probing time consumed
ModemManager[5693]: <debug> [1389525547.028135] [mm-plugin-manager.c:341] suggest_port_probe_result(): (Plugin Manager) [wlan0] deferred task cancelled, no suggested plugin
ModemManager[5693]: <debug> [1389525547.028236] [mm-plugin-manager.c:164] port_probe_context_finished(): (Plugin Manager) [eth0] not supported by any plugin
ModemManager[5693]: <debug> [1389525547.028404] [mm-device.c:339] mm_device_ignore_port(): Fully ignoring port 'net/eth0' from now on
ModemManager[5693]: <debug> [1389525547.028492] [mm-plugin-manager.c:285] port_probe_context_finished(): (Plugin Manager) 'eth0' port probe finished, last one in device
ModemManager[5693]: <debug> [1389525547.028570] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] device support check finished in '2.503836' seconds
ModemManager[5693]: <warn>  [1389525547.028821] [mm-manager.c:140] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:19.0': not supported by any plugin
ModemManager[5693]: <debug> [1389525547.028947] [mm-plugin-manager.c:164] port_probe_context_finished(): (Plugin Manager) [wlan0] not supported by any plugin
ModemManager[5693]: <debug> [1389525547.029035] [mm-device.c:339] mm_device_ignore_port(): Fully ignoring port 'net/wlan0' from now on
ModemManager[5693]: <debug> [1389525547.029127] [mm-plugin-manager.c:285] port_probe_context_finished(): (Plugin Manager) 'wlan0' port probe finished, last one in device
ModemManager[5693]: <debug> [1389525547.029210] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0] device support check finished in '2.502217' seconds
ModemManager[5693]: <warn>  [1389525547.029302] [mm-manager.c:140] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0': not supported by any plugin
ModemManager[5693]: <debug> [1389525549.029750] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[5693]: <debug> [1389525549.030014] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
ModemManager[5693]: <debug> [1389525553.030343] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[5693]: <debug> [1389525553.030666] [mm-at-serial-port.c:436] debug_log(): (ttyUSB0): --> 'ATE0+CPMS?<CR>'
ModemManager[5693]: <debug> [1389525557.030292] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[5693]: <debug> [1389525557.030495] [mm-port-probe.c:161] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
ModemManager[5693]: <debug> [1389525557.030636] [mm-port-probe.c:657] serial_probe_qcdm(): (tty/ttyUSB0) probing QCDM...
ModemManager[5693]: <debug> [1389525557.030741] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
ModemManager[5693]: <debug> [1389525557.030836] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB0) closing serial port...
ModemManager[5693]: <debug> [1389525557.033736] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB0) serial port closed
ModemManager[5693]: <debug> [1389525557.033871] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB0) forced to close port
ModemManager[5693]: <debug> [1389525557.034148] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB0) opening serial port...
ModemManager[5693]: <debug> [1389525557.036490] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[5693]: <debug> [1389525557.036722] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
ModemManager[5693]: <debug> [1389525557.038008] [mm-qcdm-serial-port.c:205] debug_log(): (ttyUSB0): <-- 00 53 65 70 20 32 31 20 32 30 31 31 31 38 3a 31 34 3a 31 30 53 65 70 20 32 31 20 32 30 31 31 31 38 3a 31 34 3a 31 30 41 47 45 4e 4f 4d 46 36 00 00 08 00 00 00 04 e0 66 3f 7e
ModemManager[5693]: <debug> [1389525557.038658] [mm-port-probe.c:239] mm_port_probe_set_result_qcdm(): (tty/ttyUSB0) port is QCDM-capable
ModemManager[5693]: <debug> [1389525557.039282] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
ModemManager[5693]: <debug> [1389525557.039844] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB0) closing serial port...
ModemManager[5693]: <debug> [1389525557.042738] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB0) serial port closed
ModemManager[5693]: <debug> [1389525557.042870] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB0) forced to close port
ModemManager[5693]: <debug> [1389525557.043034] [mm-plugin-manager.c:417] plugin_supports_port_ready(): (Plugin Manager) (ZTE) [ttyUSB0] found best plugin for port
ModemManager[5693]: <debug> [1389525557.043147] [mm-plugin-manager.c:285] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, last one in device
ModemManager[5693]: <debug> [1389525557.043250] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2] device support check finished in '12.527022' seconds
ModemManager[5693]: <info>  [1389525557.043371] [mm-device.c:486] mm_device_create_modem(): Creating modem with plugin 'ZTE' and '4' ports
ModemManager[5693]: <debug> [1389525557.043495] [zte/mm-plugin-zte.c:69] create_modem(): QMI-powered ZTE modem found...
ModemManager[5693]: <debug> [1389525557.046896] [mm-base-modem.c:275] mm_base_modem_grab_port(): (cdc-wdm0) type 'qmi' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2
ModemManager[5693]: <debug> [1389525557.047065] [mm-base-modem.c:275] mm_base_modem_grab_port(): (wwan0) type 'net' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2
ModemManager[5693]: <debug> [1389525557.047227] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyUSB1) type 'at' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2
ModemManager[5693]: <debug> [1389525557.047344] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyUSB0) type 'qcdm' claimed by /sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2
ModemManager[5693]: <debug> [1389525557.047409] [mm-base-modem.c:1057] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2) tty/ttyUSB1 at (primary)
ModemManager[5693]: <debug> [1389525557.047469] [mm-base-modem.c:1057] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2) net/wwan0 data (primary)
ModemManager[5693]: <debug> [1389525557.047525] [mm-base-modem.c:1057] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2) tty/ttyUSB0 qcdm
ModemManager[5693]: <debug> [1389525557.047583] [mm-base-modem.c:1057] log_port(): (/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2) usb/cdc-wdm0 qmi (primary)
ModemManager[5693]: <info>  [1389525557.048151] [mm-manager.c:149] find_device_support_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2' successfully created
ModemManager[5693]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/dev/cdc-wdm0] Checking version info (10 retries)...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/dev/cdc-wdm0] QMI Device supports 4 services:
ModemManager[5693]: [/dev/cdc-wdm0]    ctl (1.3)
ModemManager[5693]: [/dev/cdc-wdm0]    wds (1.5)
ModemManager[5693]: [/dev/cdc-wdm0]    dms (1.2)
ModemManager[5693]: [/dev/cdc-wdm0]    nas (1.0)
ModemManager[5693]: [/dev/cdc-wdm0] Setting network port data format...
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/dev/cdc-wdm0] Network port data format operation finished
ModemManager[5693]: [/dev/cdc-wdm0] Allocating new client ID...
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/dev/cdc-wdm0] Registered 'dms' (version 1.2) client with ID '1'
ModemManager[5693]: [/dev/cdc-wdm0] Allocating new client ID...
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '1'
ModemManager[5693]: <debug> [1389525557.074528] [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[5693]: <debug> [1389525557.074648] [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[5693]: <debug> [1389525557.074769] [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[5693]: <debug> [1389525557.074837] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB1) opening serial port...
ModemManager[5693]: <warn>  [1389525557.076664] [mm-serial-port.c:416] real_config_fd(): (ttyUSB1): port attributes not fully set
ModemManager[5693]: <debug> [1389525557.076767] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[5693]: <debug> [1389525557.076827] [mm-at-serial-port.c:473] mm_at_serial_port_run_init_sequence(): (ttyUSB1): running init sequence...
ModemManager[5693]: <debug> [1389525557.076914] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 2 (open)
ModemManager[5693]: <debug> [1389525557.076987] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525557.078336] [mm-broadband-modem-qmi.c:533] modem_load_current_capabilities(): loading current capabilities...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:01:00:01:00:2B:00:00:00
ModemManager[5693]: [/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[5693]: <debug> [1389525557.078679] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
ModemManager[5693]: [/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[5693]: [/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[5693]: <debug> [1389525557.088380] [mm-broadband-modem-qmi.c:428] load_current_capabilities_get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:01:00:20:00:00:00
ModemManager[5693]: [/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[5693]: <debug> [1389525557.090531] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525557.090671] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATV1<CR>'
ModemManager[5693]: [/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[5693]: [/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[5693]: <debug> [1389525557.092380] [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[5693]: <debug> [1389525557.092545] [mm-broadband-modem-qmi.c:695] modem_load_supported_capabilities(): loading supported capabilities...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:02:00:20:00:00:00
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: <debug> [1389525557.096474] [mm-iface-modem.c:4236] interface_initialization_step(): Modem allows up to 1 bearers
ModemManager[5693]: <debug> [1389525557.096531] [mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
ModemManager[5693]: <debug> [1389525557.096670] [mm-broadband-modem-qmi.c:997] modem_load_manufacturer(): loading manufacturer...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:03:00:21:00:00:00
ModemManager[5693]: [/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[5693]: <debug> [1389525557.098171] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525557.098301] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
ModemManager[5693]: [/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[5693]: [/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[5693]: <debug> [1389525557.100561] [mm-broadband-modem-qmi.c:944] modem_load_manufacturer_finish(): loaded manufacturer: QUALCOMM INCORPORATED
ModemManager[5693]: <debug> [1389525557.100653] [mm-broadband-modem-qmi.c:1073] modem_load_model(): loading model...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:04:00:22:00:00:00
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: <debug> [1389525557.104171] [mm-broadband-modem-qmi.c:1020] modem_load_model_finish(): loaded model: 8
ModemManager[5693]: <debug> [1389525557.104257] [mm-broadband-modem-qmi.c:1149] modem_load_revision(): loading revision...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:05:00:23:00:00:00
ModemManager[5693]: [/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[5693]: [/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:53:65:70:20:32:31:20:32:30:31:31:20:31:36:3A:33:35:3A:30:39:5D
ModemManager[5693]: [/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:53:65:70:20:32:31:20:32:30:31:31:20:31:36:3A:33:35:3A:30:39:5D
>>>>>>   translated = M8200C-FACPACZD-1.0.392T  1  [Sep 21 2011 16:35:09]
ModemManager[5693]: <debug> [1389525557.110516] [mm-broadband-modem-qmi.c:1096] modem_load_revision_finish(): loaded revision: M8200C-FACPACZD-1.0.392T  1  [Sep 21 2011 16:35:09]
ModemManager[5693]: <debug> [1389525557.110606] [mm-broadband-modem-qmi.c:1291] modem_load_equipment_identifier(): loading equipment identifier...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:06:00:25:00:00:00
ModemManager[5693]: [/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[5693]: [/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:31:32:30:30:36:36:37:03:A0:48:F7:01:74:1E:78:01:32
ModemManager[5693]: [/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:31:32:30:30:36:36:37:03:A0:48:F7:01:74:1E:78:01:32
>>>>>>   translated = 355572041200667
ModemManager[5693]: <debug> [1389525557.116223] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525557.116404] [mm-broadband-modem-qmi.c:1188] modem_load_equipment_identifier_finish(): loaded equipment identifier: 355572041200667
ModemManager[5693]: <debug> [1389525557.116495] [mm-broadband-modem-qmi.c:1326] modem_load_device_identifier(): loading device identifier...
ModemManager[5693]: <debug> [1389525557.116614] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '000019d200001402355572041200667M8200C-FACPACZD-1.0.392T1[Sep21201116:35:09]8QUALCOMMINCORPORATED'
ModemManager[5693]: <debug> [1389525557.116672] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '80777bd0282d11d98ca32bb119ab8cc8712290b8'
ModemManager[5693]: <debug> [1389525557.116760] [mm-broadband-modem-qmi.c:1314] modem_load_device_identifier_finish(): loaded device identifier: 80777bd0282d11d98ca32bb119ab8cc8712290b8
ModemManager[5693]: <debug> [1389525557.116961] [mm-broadband-modem-qmi.c:1729] modem_load_supported_bands(): loading band capabilities...
ModemManager[5693]: <warn>  [1389525557.117102] [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[5693]: <debug> [1389525557.117226] [mm-broadband-modem-qmi.c:2673] load_power_state(): Getting device operating mode...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:08:00:2D:00:00:00
ModemManager[5693]: [/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[5693]: <debug> [1389525557.118066] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATX4<CR>'
ModemManager[5693]: [/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[5693]: [/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[5693]: <debug> [1389525557.122124] [mm-broadband-modem-qmi.c:1535] modem_load_unlock_required(): loading unlock required...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:09:00:2B:00:00:00
ModemManager[5693]: [/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[5693]: <debug> [1389525557.124873] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525557.125010] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT&C1<CR>'
ModemManager[5693]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 32
>>>>>>   data   = 01:1F:00:80:02:01:02:09:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:03:03:0A
ModemManager[5693]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 31
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 9
>>>>>>   tlv_length  = 19
>>>>>>   message     = "UIM Get PIN Status" (0x002B)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "PIN2 Status" (0x12)
>>>>>>   length     = 3
>>>>>>   value      = 01:03:0A
>>>>>>   translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>> TLV:
>>>>>>   type       = "PIN1 Status" (0x11)
>>>>>>   length     = 3
>>>>>>   value      = 03:03:0A
>>>>>>   translated = [ current_status = 'disabled' verify_retries_left = '3' unblock_retries_left = '10' ]
ModemManager[5693]: <debug> [1389525557.126666] [mm-broadband-modem-qmi.c:1628] modem_load_unlock_retries(): loading unlock retries...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:0A:00:2B:00:00:00
ModemManager[5693]: [/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[5693]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 32
>>>>>>   data   = 01:1F:00:80:02:01:02:0A:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:03:03:0A
ModemManager[5693]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 31
>>>>>>   flags   = 0x80
>>>>>>   service = "dms"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 10
>>>>>>   tlv_length  = 19
>>>>>>   message     = "UIM Get PIN Status" (0x002B)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "PIN2 Status" (0x12)
>>>>>>   length     = 3
>>>>>>   value      = 01:03:0A
>>>>>>   translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>> TLV:
>>>>>>   type       = "PIN1 Status" (0x11)
>>>>>>   length     = 3
>>>>>>   value      = 03:03:0A
>>>>>>   translated = [ current_status = 'disabled' verify_retries_left = '3' unblock_retries_left = '10' ]
ModemManager[5693]: <debug> [1389525557.130438] [mm-iface-modem.c:3038] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
ModemManager[5693]: <debug> [1389525557.130880] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
ModemManager[5693]: <debug> [1389525557.131073] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
ModemManager[5693]: <warn>  [1389525557.131978] [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[5693]: <debug> [1389525557.132208] [mm-sim-qmi.c:224] load_imsi(): loading IMSI...
ModemManager[5693]: <warn>  [1389525557.132533] [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[5693]: <debug> [1389525557.132875] [mm-broadband-modem-qmi.c:1410] modem_load_own_numbers(): loading own numbers...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:0E:00:24:00:00:00
ModemManager[5693]: [/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[5693]: <debug> [1389525557.133883] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525557.134144] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
ModemManager[5693]: [/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[5693]: [/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[5693]: <warn>  [1389525557.136469] [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[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:01:00:02:00:2B:00:00:00
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: <debug> [1389525557.140114] [mm-broadband-modem-qmi.c:2844] get_technology_preference_ready(): Couldn't get technology preference: QMI protocol error (71): 'InvalidQmiCommand'
ModemManager[5693]: <warn>  [1389525557.140203] [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[5693]: <debug> [1389525557.140330] [mm-broadband-modem-qmi.c:1877] modem_load_current_bands(): loading current bands...
ModemManager[5693]: <warn>  [1389525557.140469] [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[5693]: <debug> [1389525557.141149] [mm-broadband-modem-qmi.c:3272] modem_3gpp_load_imei_finish(): loaded IMEI: 355572041200667
ModemManager[5693]: <debug> [1389525557.141332] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'ph-sim' status, assuming disabled
ModemManager[5693]: <debug> [1389525557.141438] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-pers' status, assuming disabled
ModemManager[5693]: <debug> [1389525557.141568] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'net-sub-pers' status, assuming disabled
ModemManager[5693]: <debug> [1389525557.141666] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'provider-pers' status, assuming disabled
ModemManager[5693]: <debug> [1389525557.141797] [mm-broadband-modem-qmi.c:3351] dms_uim_get_ck_status_ready(): Couldn't query facility 'corp-pers' status, assuming disabled
ModemManager[5693]: <debug> [1389525557.142376] [mm-broadband-modem.c:8952] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
ModemManager[5693]: <debug> [1389525557.143065] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 4 (open)
ModemManager[5693]: <debug> [1389525557.143324] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525557.143502] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 3 (close)
ModemManager[5693]: <debug> [1389525557.143674] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
ModemManager[5693]: <debug> [1389525557.159121] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525557.159260] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525557.159359] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CNMI=?<CR>'
ModemManager[5693]: <debug> [1389525557.172394] [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[5693]: <debug> [1389525557.172585] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525557.172671] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525557.172760] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS=?<CR>'
ModemManager[5693]: <debug> [1389525557.184733] [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[5693]: <debug> [1389525557.185038] [mm-iface-modem-messaging.c:1146] load_supported_storages_ready(): Supported storages loaded:
ModemManager[5693]: <debug> [1389525557.185106] [mm-iface-modem-messaging.c:1147] load_supported_storages_ready():   mem1 (list/read/delete) storages: 'me, mt, sm, sr'
ModemManager[5693]: <debug> [1389525557.185156] [mm-iface-modem-messaging.c:1148] load_supported_storages_ready():   mem2 (write/send) storages:       'me, mt, sm, sr'
ModemManager[5693]: <debug> [1389525557.185202] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready():   mem3 (reception) storages:        'me, mt, sm, sr'
ModemManager[5693]: <debug> [1389525557.185360] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 1 (close)
ModemManager[5693]: <debug> [1389525557.185611] [mm-broadband-modem.c:8956] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
ModemManager[5693]: <debug> [1389525557.185810] [mm-broadband-modem-qmi.c:9415] signal_check_support(): Extended signal capabilities supported
ModemManager[5693]: <debug> [1389525557.186154] [mm-broadband-modem-qmi.c:8129] oma_check_support(): OMA capabilities not supported
ModemManager[5693]: <debug> [1389525557.186297] [mm-broadband-modem.c:8958] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
ModemManager[5693]: <debug> [1389525557.186481] [mm-broadband-modem-qmi.c:9081] firmware_check_support(): loading firmware images...
ModemManager[5693]: <debug> [1389525557.186647] [mm-broadband-modem.c:8959] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
ModemManager[5693]: <info>  [1389525557.186879] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
ModemManager[5693]: <debug> [1389525557.187046] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
ModemManager[5693]: <debug> [1389525557.187112] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB1) closing serial port...
ModemManager[5693]: <debug> [1389525557.189070] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB1) serial port closed
ModemManager[5693]: <debug> [1389525557.189267] [mm-base-modem.c:1026] initialize_ready(): modem properly initialized
ModemManager[5693]: <debug> [1389525557.190599] [mm-device.c:416] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2' at path '/org/freedesktop/ModemManager1/Modem/0'
ModemManager[5693]: <debug> [1389525557.190836] [mm-device.c:424] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'ZTE' modem, VID 0x19D2 PID 0x1402 (usb)
ModemManager[5693]: <info>  [1389525588.178431] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
ModemManager[5693]: <debug> [1389525588.178870] [mm-broadband-modem.c:7957] enabling_started(): Skipping modem initialization: not required
ModemManager[5693]: <debug> [1389525588.178939] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB1) opening serial port...
ModemManager[5693]: <warn>  [1389525588.180869] [mm-serial-port.c:416] real_config_fd(): (ttyUSB1): port attributes not fully set
ModemManager[5693]: <debug> [1389525588.180988] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[5693]: <debug> [1389525588.181053] [mm-at-serial-port.c:473] mm_at_serial_port_run_init_sequence(): (ttyUSB1): running init sequence...
ModemManager[5693]: <debug> [1389525588.181125] [mm-serial-port.c:892] mm_serial_port_open(): (ttyUSB0) opening serial port...
ModemManager[5693]: <debug> [1389525588.183142] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[5693]: <debug> [1389525588.183216] [mm-broadband-modem.c:7970] enabling_started(): Flashing primary AT port before enabling...
ModemManager[5693]: <debug> [1389525588.183939] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATE0<CR>'
ModemManager[5693]: <debug> [1389525588.191305] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.191543] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATV1<CR>'
ModemManager[5693]: <debug> [1389525588.198496] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.198708] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
ModemManager[5693]: <debug> [1389525588.211877] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.212147] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'ATX4<CR>'
ModemManager[5693]: <debug> [1389525588.220589] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.220851] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT&C1<CR>'
ModemManager[5693]: <debug> [1389525588.228728] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.285089] [mm-broadband-modem-qmi.c:2673] load_power_state(): Getting device operating mode...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:01:00:15:00:2D:00:00:00
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: <debug> [1389525588.290761] [mm-iface-modem.c:3199] set_power_state(): No need to change power state: already in 'on' power state
ModemManager[5693]: <debug> [1389525588.290981] [mm-broadband-modem.c:8546] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
ModemManager[5693]: [/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[5693]: [/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[5693]: [/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[5693]: [/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[5693]: <debug> [1389525588.298706] [mm-broadband-modem-qmi.c:4760] modem_3gpp_enable_unsolicited_registration_events(): Assuming serving system indications are always enabled
ModemManager[5693]: <debug> [1389525588.298817] [mm-iface-modem-3gpp.c:749] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:01:00:05:00:24:00:00:00
ModemManager[5693]: [/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[5693]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 35
>>>>>>   data   = 01:22:00:80:03:01:02:05:00:24:00:16:00:02:04:00:00:00:00:00:01:06:00:02:02:02:02:01:05:11:03:00:02:03:04
ModemManager[5693]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 34
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 5
>>>>>>   tlv_length  = 22
>>>>>>   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      = 02:02:02:02:01:05
>>>>>>   translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>>>>>> TLV:
>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>   length     = 3
>>>>>>   value      = 02:03:04
>>>>>>   translated = { [0] = 'hsdpa ' [1] = 'hsupa '}
ModemManager[5693]: <debug> [1389525588.303344] [mm-broadband-modem-qmi.c:3898] common_process_serving_system_3gpp(): Processing 3GPP info...
ModemManager[5693]: <info>  [1389525588.303429] [mm-iface-modem-3gpp.c:1167] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> idle)
ModemManager[5693]: <debug> [1389525588.303599] [mm-iface-modem.c:1540] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
ModemManager[5693]: <debug> [1389525588.303998] [mm-broadband-modem.c:8587] enabling_step(): Modem has location capabilities, enabling the Location interface...
ModemManager[5693]: <debug> [1389525588.304077] [mm-iface-modem-location.c:739] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
ModemManager[5693]: <debug> [1389525588.304225] [mm-iface-modem-3gpp.c:749] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:01:00:06:00:24:00:00:00
ModemManager[5693]: [/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[5693]: <debug> [1389525588.305060] [mm-broadband-modem.c:8600] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
ModemManager[5693]: <debug> [1389525588.305216] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 2 (open)
ModemManager[5693]: <debug> [1389525588.306031] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGF=?<CR>'
ModemManager[5693]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 35
>>>>>>   data   = 01:22:00:80:03:01:02:06:00:24:00:16:00:02:04:00:00:00:00:00:01:06:00:02:02:02:02:01:05:11:03:00:02:03:04
ModemManager[5693]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 34
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 6
>>>>>>   tlv_length  = 22
>>>>>>   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      = 02:02:02:02:01:05
>>>>>>   translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'umts '}' ]
>>>>>> TLV:
>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>   length     = 3
>>>>>>   value      = 02:03:04
>>>>>>   translated = { [0] = 'hsdpa ' [1] = 'hsupa '}
ModemManager[5693]: <debug> [1389525588.308626] [mm-broadband-modem-qmi.c:3898] common_process_serving_system_3gpp(): Processing 3GPP info...
ModemManager[5693]: <debug> [1389525588.321157] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.321459] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525588.321564] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525588.321675] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGF=0<CR>'
ModemManager[5693]: <debug> [1389525588.334886] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.335092] [mm-broadband-modem.c:5457] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU'
ModemManager[5693]: <debug> [1389525588.335297] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525588.335395] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525588.335709] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS="","MT","MT"<CR>'
ModemManager[5693]: <debug> [1389525588.372592] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CPMS: 0,20,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.372877] [mm-broadband-modem.c:6155] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me'
ModemManager[5693]: <debug> [1389525588.373038] [mm-broadband-modem.c:5351] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)...
ModemManager[5693]: <debug> [1389525588.374433] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525588.374599] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525588.375984] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS="ME"<CR>'
ModemManager[5693]: <debug> [1389525588.394838] [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[5693]: <debug> [1389525588.395318] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525588.396643] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525588.396915] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGL=4<CR>'
ModemManager[5693]: <debug> [1389525588.414287] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.414689] [mm-broadband-modem.c:6155] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
ModemManager[5693]: <debug> [1389525588.414819] [mm-broadband-modem.c:5351] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)...
ModemManager[5693]: <debug> [1389525588.414945] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525588.415080] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525588.415245] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS="SM"<CR>'
ModemManager[5693]: <debug> [1389525588.435547] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CPMS: 0,20,0,100,0,100<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.435878] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525588.436635] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525588.436797] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGL=4<CR>'
ModemManager[5693]: <debug> [1389525588.451406] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.452402] [mm-broadband-modem.c:6155] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sr'
ModemManager[5693]: <debug> [1389525588.452534] [mm-broadband-modem.c:5351] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SR), mem2 (none)...
ModemManager[5693]: <debug> [1389525588.452660] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525588.453260] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525588.453473] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CPMS="SR"<CR>'
ModemManager[5693]: <debug> [1389525588.472839] [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[5693]: <debug> [1389525588.473144] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
ModemManager[5693]: <debug> [1389525588.473779] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
ModemManager[5693]: <debug> [1389525588.473938] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CMGL=4<CR>'
ModemManager[5693]: <debug> [1389525588.487591] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CMS ERROR: 500<CR><LF>'
ModemManager[5693]: <debug> [1389525588.488586] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 500: Unknown
ModemManager[5693]: <debug> [1389525588.488800] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'sr': 'Unknown'
ModemManager[5693]: <debug> [1389525588.489326] [mm-broadband-modem.c:5763] set_messaging_unsolicited_events_handlers(): (ttyUSB1) Setting messaging unsolicited events handlers
ModemManager[5693]: <debug> [1389525588.489467] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 1 (close)
ModemManager[5693]: <debug> [1389525588.489662] [mm-serial-port.c:969] mm_serial_port_open(): (ttyUSB1) device open count is 2 (open)
ModemManager[5693]: <debug> [1389525588.489845] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CNMI=2,1,2,1,0<CR>'
ModemManager[5693]: <debug> [1389525588.515694] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
ModemManager[5693]: <debug> [1389525588.515891] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
ModemManager[5693]: <debug> [1389525588.516033] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): --> 'AT+CNMI=2,1,2,2,0<CR>'
ModemManager[5693]: <debug> [1389525588.541178] [mm-at-serial-port.c:436] debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
ModemManager[5693]: <debug> [1389525588.541478] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 1 (close)
ModemManager[5693]: <debug> [1389525588.541638] [mm-broadband-modem.c:8626] enabling_step(): Modem has extended signal reporting capabilities, enabling the Signal interface...
ModemManager[5693]: <debug> [1389525588.541784] [mm-iface-modem-signal.c:206] setup_refresh_context(): Extended signal information reporting disabled (rate: 0 seconds)
ModemManager[5693]: <info>  [1389525588.542075] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
ModemManager[5693]: <debug> [1389525783.216132] [mm-broadband-modem-qmi.c:3619] modem_3gpp_scan_networks(): Scanning networks...
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:01:00:07:00:21:00:00:00
ModemManager[5693]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Network Scan" (0x0021)
ModemManager[5693]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 18
>>>>>>   data   = 01:11:00:80:03:01:04:00:00:02:00:05:00:10:02:00:87:05
ModemManager[5693]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 17
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "indication"
>>>>>>   transaction = 0
>>>>>>   tlv_length  = 5
>>>>>>   message     = "Event Report" (0x0002)
>>>>>> TLV:
>>>>>>   type       = "Signal Strength" (0x10)
>>>>>>   length     = 2
>>>>>>   value      = 87:05
>>>>>>   translated = [ strength = '-121' radio_interface = 'umts' ]
ModemManager[5693]: <debug> [1389525813.788894] [mm-broadband-modem-qmi.c:6318] event_report_indication_cb(): Signal strength indication (umts): -121 dBm --> 0%
ModemManager[5693]: <info>  [1389525813.789066] [mm-iface-modem.c:1144] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (0)
ModemManager[5693]: <info>  [1389525813.789179] [mm-iface-modem.c:894] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> umts)
ModemManager[5693]: <debug> [1389525874.080131] [mm-iface-modem.c:1080] expire_signal_quality(): Signal quality value not updated in 60s, marking as not being recent
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 18
<<<<<<   data   = 01:11:00:00:03:01:00:08:00:01:00:05:00:01:02:00:07:00
ModemManager[5693]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 17
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 8
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Abort" (0x0001)
<<<<<< TLV:
<<<<<<   type       = "Transaction ID" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 07:00
<<<<<<   translated = 7
ModemManager[5693]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:03:01:02:08:00:01:00:07:00:02:04:00:01:00:16:00
ModemManager[5693]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 8
>>>>>>   tlv_length  = 7
>>>>>>   message     = "Abort" (0x0001)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:16:00
>>>>>>   translated = FAILURE: InvalidTransactionId
ModemManager[5693]: <info>  [1389525893.684552] [main.c:46] quit_cb(): Caught signal, shutting down...
ModemManager[5693]: Cannot read from istream: connection broken
ModemManager[5693]: <info>  [1389525893.685420] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> disabling)
ModemManager[5693]: <debug> [1389525893.686177] [mm-broadband-modem.c:8200] disabling_step(): Modem has extended signal reporting capabilities, disabling the Signal interface...
ModemManager[5693]: <debug> [1389525893.686632] [mm-iface-modem-signal.c:166] teardown_refresh_context(): Extended signal information reporting disabled
ModemManager[5693]: <debug> [1389525893.687786] [mm-broadband-modem.c:8236] disabling_step(): Modem has messaging capabilities, disabling the Messaging interface...
ModemManager[5693]: <debug> [1389525893.688368] [mm-broadband-modem.c:5763] set_messaging_unsolicited_events_handlers(): (ttyUSB1) Removing messaging unsolicited events handlers
ModemManager[5693]: <debug> [1389525893.689174] [mm-broadband-modem.c:8248] disabling_step(): Modem has location capabilities, disabling the Location interface...
ModemManager[5693]: <debug> [1389525893.689395] [mm-iface-modem-location.c:745] setup_gathering(): Need to disable the following location sources: '3gpp-lac-ci'
ModemManager[5693]: <debug> [1389525893.690718] [mm-broadband-modem.c:8288] disabling_step(): Modem has 3GPP capabilities, disabling the Modem 3GPP interface...
ModemManager[5693]: <debug> [1389525893.690832] [mm-iface-modem-3gpp.c:1307] periodic_registration_check_disable(): Periodic 3GPP registration checks disabled
ModemManager[5693]: <debug> [1389525893.691141] [mm-iface-modem-3gpp.c:1406] disable_unsolicited_registration_events_ready(): Couldn't disable unsolicited registration events: 'Device doesn't allow disabling registration events'
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 18
<<<<<<   data   = 01:11:00:00:03:01:00:09:00:02:00:05:00:10:02:00:00:00
ModemManager[5693]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 17
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 9
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Set Event Report" (0x0002)
<<<<<< TLV:
<<<<<<   type       = "Signal Strength Indicator" (0x10)
<<<<<<   length     = 2
<<<<<<   value      = 00:00
<<<<<<   translated = [ report = 'no' thresholds = '{}' ]
ModemManager[5693]: <debug> [1389525893.692458] [mm-broadband-modem-qmi.c:6016] ser_signal_strength_ready(): QMI operation failed: 'Cannot write message: Error sending data: Broken pipe'
ModemManager[5693]: <info>  [1389525893.692515] [mm-iface-modem-3gpp.c:1167] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> unknown)
ModemManager[5693]: <info>  [1389525893.692680] [mm-iface-modem.c:894] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (umts -> unknown)
ModemManager[5693]: <debug> [1389525893.693458] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
ModemManager[5693]: <debug> [1389525893.693518] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB1) closing serial port...
ModemManager[5693]: <debug> [1389525893.695568] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB1) serial port closed
ModemManager[5693]: <debug> [1389525893.695624] [mm-serial-port.c:1015] mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
ModemManager[5693]: <debug> [1389525893.695667] [mm-serial-port.c:1031] mm_serial_port_close(): (ttyUSB0) closing serial port...
ModemManager[5693]: <debug> [1389525893.697415] [mm-serial-port.c:1064] mm_serial_port_close(): (ttyUSB0) serial port closed
ModemManager[5693]: <info>  [1389525893.697478] [mm-iface-modem.c:1371] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabling -> disabled)
ModemManager[5693]: <debug> [1389525893.698321] [mm-device.c:364] unexport_modem(): Unexported modem '/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2' from path '/org/freedesktop/ModemManager1/Modem/0'
ModemManager[5693]: <debug> [1389525893.698730] [mm-qmi-port.c:318] mm_qmi_port_close(): Releasing client for service 'nas'...
ModemManager[5693]: [/dev/cdc-wdm0] Releasing 'nas' client with flags 'release-cid'...
ModemManager[5693]: [/dev/cdc-wdm0] Unregistered 'nas' client with ID '1'
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:06:23:00:05:00:01:02:00:03:01
ModemManager[5693]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 03:01
<<<<<<   translated = [ service = 'nas' cid = '1' ]
ModemManager[5693]: <debug> [1389525893.699075] [mm-qmi-port.c:318] mm_qmi_port_close(): Releasing client for service 'dms'...
ModemManager[5693]: [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
ModemManager[5693]: [/dev/cdc-wdm0] Unregistered 'dms' client with ID '1'
ModemManager[5693]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:07:23:00:05:00:01:02:00:02:01
ModemManager[5693]: [/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      = 02:01
<<<<<<   translated = [ service = 'dms' cid = '1' ]
ModemManager[5693]: <debug> [1389525893.699456] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB0) forced to close port
ModemManager[5693]: <debug> [1389525893.699515] [mm-serial-port.c:1139] mm_serial_port_close_force(): (ttyUSB1) forced to close port
ModemManager[5693]: <debug> [1389525893.699620] [mm-base-modem.c:1557] finalize(): Modem (ZTE) '/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2' completely disposed
ModemManager[5693]: <info>  [1389525893.703229] [main.c:179] main(): ModemManager is shut down
