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