Huawei me906s-158
Andreas Fett
andreas.fett at secunet.com
Wed Apr 6 10:13:22 UTC 2016
Hi Bjørn,
On 04/04/16 16:38, Bjørn Mork wrote:
> This makes sense even if the USB captures don't show any other
> differences. The firmware might assume that the configuration always
> switches from 0 to either 1, 2 or 3 and then stays there. That's what
> would happen on a Windows system. There are most likely a number of
> internal firmware settings associated with such configuration switches.
> Failure to reset them could very well mess up things beyond rescue.
Attached there is a usb capture of the following:
System (cold) boots with /sys/bus/usb/devices/1-2/bConfigurationValue of
"2".
echo 0 > /sys/bus/usb/devices/1-2/bConfigurationValue
echo 3 > /sys/bus/usb/devices/1-2/bConfigurationValue
cdc_mbim is loaded via udev and attaches.
echo Y > /sys/class/net/wwan0/cdc_mcn/ndp_to_end
start ModemManager
Unlock pin via mmcli
Simple connect via mmcli
I'm still experimenting with doing the configuration value switch via
udev but there also seem to be some timing issues involved...
Andreas
-------------- next part --------------
<info> [1459934828.373261] [main.c:136] main(): ModemManager (version 1.4.14) starting in system bus...
<warn> [1459934828.402725] [main.c:101] name_lost_cb(): Could not acquire the 'org.freedesktop.ModemManager1' service name
<info> [1459934828.402746] [main.c:187] main(): ModemManager is shut down
<info> [1459934859.426764] [main.c:136] main(): ModemManager (version 1.4.14) starting in system bus...
<warn> [1459934859.427587] [main.c:101] name_lost_cb(): Could not acquire the 'org.freedesktop.ModemManager1' service name
<info> [1459934859.427654] [main.c:187] main(): ModemManager is shut down
<info> [1459934888.092387] [main.c:136] main(): ModemManager (version 1.4.14) starting in system bus...
<debug> [1459934888.094077] [main.c:61] bus_acquired_cb(): Bus acquired, creating manager...
<debug> [1459934888.098465] [mm-plugin-manager.c:852] load_plugins(): Looking for plugins in '/usr/lib/ModemManager'
<debug> [1459934888.112066] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Altair LTE'
<debug> [1459934888.112187] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'AnyDATA'
<debug> [1459934888.114779] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Cinterion'
<debug> [1459934888.114879] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Generic'
<debug> [1459934888.114966] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Gobi'
<debug> [1459934888.117253] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option High-Speed'
<debug> [1459934888.121234] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Huawei'
<debug> [1459934888.123195] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Iridium'
<debug> [1459934888.123310] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Linktop'
<debug> [1459934888.123408] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Longcheer'
<debug> [1459934888.126571] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Ericsson MBM'
<debug> [1459934888.126672] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Motorola'
<debug> [1459934888.126767] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'MTK'
<debug> [1459934888.129744] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia (Icera)'
<debug> [1459934888.129847] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia'
<debug> [1459934888.132903] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel LTE'
<debug> [1459934888.132994] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel'
<debug> [1459934888.133084] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option'
<debug> [1459934888.135554] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Pantech'
<debug> [1459934888.135652] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Samsung'
<debug> [1459934888.138731] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Sierra'
<debug> [1459934888.140873] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'SimTech'
<debug> [1459934888.140957] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Telit'
<debug> [1459934888.141033] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Via CBP7'
<debug> [1459934888.142536] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Wavecom'
<debug> [1459934888.142638] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'X22X'
<debug> [1459934888.145487] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'ZTE'
<debug> [1459934888.145494] [mm-plugin-manager.c:902] load_plugins(): Successfully loaded 27 plugins
<debug> [1459934888.145785] [main.c:83] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
<debug> [1459934888.145789] [mm-base-manager.c:485] mm_base_manager_start(): Starting automatic device scan...
<debug> [1459934888.146828] [mm-base-manager.c:524] mm_base_manager_start(): Finished device scan...
<debug> [1459934888.150778] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2] Checking device support...
<debug> [1459934888.150806] [mm-device.c:327] mm_device_grab_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2) could not get vendor/product ID
<debug> [1459934888.150828] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150830] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150833] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB0] filtered by drivers
<debug> [1459934888.150834] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers
<debug> [1459934888.150835] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150837] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150838] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150839] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags
<debug> [1459934888.150841] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150842] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB0] filtered by udev tags
<debug> [1459934888.150843] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150845] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150846] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150847] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150848] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150850] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150851] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB0] filtered by drivers
<debug> [1459934888.150852] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150853] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150855] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150856] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150857] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB0] filtered by vendor/product IDs
<debug> [1459934888.150858] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '5' plugins to try...
<debug> [1459934888.150860] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Cinterion'
<debug> [1459934888.150861] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Iridium'
<debug> [1459934888.150862] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Nokia'
<debug> [1459934888.150863] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Via CBP7'
<debug> [1459934888.150865] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Generic'
<debug> [1459934888.150873] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB0] probe required: 'at, at-vendor'
<debug> [1459934888.150879] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, at-vendor'
<debug> [1459934888.150927] [mm-base-manager.c:320] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
<debug> [1459934888.150962] [mm-base-manager.c:320] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
<debug> [1459934888.150995] [mm-base-manager.c:320] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
<debug> [1459934888.151029] [mm-base-manager.c:320] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
<debug> [1459934888.151092] [mm-base-manager.c:296] device_added(): (tty/ptyp0): could not get port's parent device
<debug> [1459934888.151112] [mm-base-manager.c:296] device_added(): (tty/ptyp1): could not get port's parent device
<debug> [1459934888.151132] [mm-base-manager.c:296] device_added(): (tty/ptyp2): could not get port's parent device
<debug> [1459934888.151152] [mm-base-manager.c:296] device_added(): (tty/ptyp3): could not get port's parent device
<debug> [1459934888.151171] [mm-base-manager.c:296] device_added(): (tty/ptyp4): could not get port's parent device
<debug> [1459934888.151191] [mm-base-manager.c:296] device_added(): (tty/ptyp5): could not get port's parent device
<debug> [1459934888.151234] [mm-base-manager.c:296] device_added(): (tty/ptyp6): could not get port's parent device
<debug> [1459934888.151253] [mm-base-manager.c:296] device_added(): (tty/ptyp7): could not get port's parent device
<debug> [1459934888.151437] [mm-base-manager.c:296] device_added(): (tty/ttyp0): could not get port's parent device
<debug> [1459934888.151457] [mm-base-manager.c:296] device_added(): (tty/ttyp1): could not get port's parent device
<debug> [1459934888.151477] [mm-base-manager.c:296] device_added(): (tty/ttyp2): could not get port's parent device
<debug> [1459934888.151507] [mm-base-manager.c:296] device_added(): (tty/ttyp3): could not get port's parent device
<debug> [1459934888.151527] [mm-base-manager.c:296] device_added(): (tty/ttyp4): could not get port's parent device
<debug> [1459934888.151547] [mm-base-manager.c:296] device_added(): (tty/ttyp5): could not get port's parent device
<debug> [1459934888.151566] [mm-base-manager.c:296] device_added(): (tty/ttyp6): could not get port's parent device
<debug> [1459934888.151585] [mm-base-manager.c:296] device_added(): (tty/ttyp7): could not get port's parent device
<debug> [1459934888.151663] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151666] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151667] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151669] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wwan0] filtered by drivers
<debug> [1459934888.151670] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wwan0] filtered by drivers
<debug> [1459934888.151671] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wwan0] filtered by subsystem
<debug> [1459934888.151673] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wwan0] filtered by subsystem
<debug> [1459934888.151674] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwan0] filtered by subsystem
<debug> [1459934888.151675] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wwan0] filtered by udev tags
<debug> [1459934888.151677] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wwan0] filtered by subsystem
<debug> [1459934888.151678] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wwan0] filtered by subsystem
<debug> [1459934888.151679] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151680] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wwan0] filtered by subsystem
<debug> [1459934888.151682] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151683] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151684] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wwan0] filtered by subsystem
<debug> [1459934888.151686] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151688] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151689] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wwan0] filtered by drivers
<debug> [1459934888.151690] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wwan0] filtered by subsystem
<debug> [1459934888.151691] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wwan0] filtered by subsystem
<debug> [1459934888.151692] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwan0] filtered by subsystem
<debug> [1459934888.151694] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwan0] filtered by subsystem
<debug> [1459934888.151695] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151696] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wwan0] filtered by vendor/product IDs
<debug> [1459934888.151697] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wwan0] Found '2' plugins to try...
<debug> [1459934888.151699] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0] Will try with plugin 'Huawei'
<debug> [1459934888.151700] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0] Will try with plugin 'Generic'
<debug> [1459934888.151702] [mm-plugin.c:689] mm_plugin_supports_port(): (Huawei) [wwan0] probing deferred until result suggested
<debug> [1459934888.151751] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0] Checking device support...
<debug> [1459934888.151767] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151768] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151770] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151771] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wlan0] filtered by drivers
<debug> [1459934888.151772] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wlan0] filtered by drivers
<debug> [1459934888.151773] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151775] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlan0] filtered by subsystem
<debug> [1459934888.151776] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlan0] filtered by subsystem
<debug> [1459934888.151777] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlan0] filtered by subsystem
<debug> [1459934888.151778] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wlan0] filtered by udev tags
<debug> [1459934888.151779] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlan0] filtered by subsystem
<debug> [1459934888.151781] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlan0] filtered by subsystem
<debug> [1459934888.151782] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151783] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlan0] filtered by subsystem
<debug> [1459934888.151784] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151785] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151786] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlan0] filtered by subsystem
<debug> [1459934888.151788] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151789] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151791] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wlan0] filtered by drivers
<debug> [1459934888.151792] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wlan0] filtered by subsystem
<debug> [1459934888.151793] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlan0] filtered by subsystem
<debug> [1459934888.151795] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlan0] filtered by subsystem
<debug> [1459934888.151796] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlan0] filtered by subsystem
<debug> [1459934888.151797] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151798] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wlan0] filtered by vendor/product IDs
<debug> [1459934888.151799] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wlan0] Found '1' plugins to try...
<debug> [1459934888.151801] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wlan0] Will try with plugin 'Generic'
<debug> [1459934888.151803] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [wlan0] probing deferred until result suggested
<debug> [1459934888.151844] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1f.6] Checking device support...
<debug> [1459934888.151857] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151859] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151860] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151862] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [eth0] filtered by drivers
<debug> [1459934888.151863] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [eth0] filtered by drivers
<debug> [1459934888.151864] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151865] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [eth0] filtered by subsystem
<debug> [1459934888.151867] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [eth0] filtered by subsystem
<debug> [1459934888.151868] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [eth0] filtered by subsystem
<debug> [1459934888.151869] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [eth0] filtered by udev tags
<debug> [1459934888.151870] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [eth0] filtered by subsystem
<debug> [1459934888.151871] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [eth0] filtered by subsystem
<debug> [1459934888.151873] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151874] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [eth0] filtered by subsystem
<debug> [1459934888.151875] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151876] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151877] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [eth0] filtered by subsystem
<debug> [1459934888.151878] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151880] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151881] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [eth0] filtered by drivers
<debug> [1459934888.151882] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [eth0] filtered by subsystem
<debug> [1459934888.151883] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [eth0] filtered by subsystem
<debug> [1459934888.151884] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [eth0] filtered by subsystem
<debug> [1459934888.151886] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [eth0] filtered by subsystem
<debug> [1459934888.151888] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151889] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [eth0] filtered by vendor/product IDs
<debug> [1459934888.151890] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [eth0] Found '1' plugins to try...
<debug> [1459934888.151891] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [eth0] Will try with plugin 'Generic'
<debug> [1459934888.151893] [mm-plugin.c:689] mm_plugin_supports_port(): (Generic) [eth0] probing deferred until result suggested
<debug> [1459934888.151983] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.151985] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs
<debug> [1459934888.151987] [mm-plugin.c:326] apply_pre_probing_filters(): (Cinterion) [cdc-wdm0] filtered by vendor/product IDs
<debug> [1459934888.151988] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers
<debug> [1459934888.151989] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.151991] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.151992] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.151993] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.151994] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags
<debug> [1459934888.151996] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.151997] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.151998] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.151999] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.152003] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.152004] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by vendor/product IDs
<debug> [1459934888.152006] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.152007] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by vendor/product IDs
<debug> [1459934888.152008] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.152009] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [cdc-wdm0] filtered by drivers
<debug> [1459934888.152011] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.152012] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.152013] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.152014] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem
<debug> [1459934888.152015] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by vendor/product IDs
<debug> [1459934888.152016] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [cdc-wdm0] filtered by vendor/product IDs
<debug> [1459934888.152018] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try...
<debug> [1459934888.152019] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Huawei'
<debug> [1459934888.152037] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Generic'
<debug> [1459934888.152042] [mm-plugin.c:767] mm_plugin_supports_port(): (Huawei) [cdc-wdm0] probe required: 'mbim'
<debug> [1459934888.152044] [mm-port-probe.c:1368] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'mbim'
<debug> [1459934888.152068] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wwan0] deferring support check until result suggested
<debug> [1459934888.152071] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wlan0] deferring support check until result suggested
<debug> [1459934888.152073] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [eth0] deferring support check until result suggested
<debug> [1459934888.152193] [mm-port-serial.c:1133] mm_port_serial_open(): (ttyUSB0) opening serial port...
<warn> [1459934888.152331] [mm-port-serial.c:534] real_config_fd(): (ttyUSB0): port attributes not fully set
<debug> [1459934888.152344] [mm-port-serial.c:1271] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
<debug> [1459934888.152353] [mm-port-probe.c:525] wdm_probe_mbim(): (usbmisc/cdc-wdm0) probing MBIM...
opening device...cannot connect to proxy: Could not connect: Connection refusedspawning new mbim-proxy (try 1)...<debug> [1459934888.252925] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT^SQPORT?<CR>'
[/dev/cdc-wdm0] Read max control message size from descriptors file: 1024[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 88
<<<<<< data = 03:00:00:00:58:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:01:00:00:00:28:00:00:00:0C:00:00:00:1A:00:00:00:1E:00:00:00:2F:00:64:00:65:00:76:00:2F:00:63:00:64:00:63:00:2D:00:77:00:64:00:6D:00:30:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 88
<<<<<< type = command (0x00000003)
<<<<<< transaction = 1
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
<<<<<< cid = 'configuration' (0x00000001)
<<<<<< type = 'set' (0x00000001)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 1
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
>>>>>> cid = 'configuration' (0x00000001)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:04:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 16
<<<<<< type = open (0x00000001)
<<<<<< transaction = 2
<<<<<< Contents:
<<<<<< max_control_transfer = 1024
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 01:00:00:80:10:00:00:00:02:00:00:00:00:00:00:00
<debug> [1459934888.345901] [mm-port-probe.c:303] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm0) port is MBIM-capable
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 12
<<<<<< data = 02:00:00:00:0C:00:00:00:03:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 12
<<<<<< type = close (0x00000002)
<<<<<< transaction = 3
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 02:00:00:80:10:00:00:00:03:00:00:00:00:00:00:00
<debug> [1459934888.346219] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [cdc-wdm0] found best plugin for port
<debug> [1459934888.346226] [mm-plugin-manager.c:232] port_probe_context_finished(): (Plugin Manager) (Huawei) [cdc-wdm0]: found best plugin for device (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2)
<debug> [1459934888.346243] [mm-plugin-manager.c:361] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [wwan0] deferred task completed, got suggested plugin
<debug> [1459934888.346254] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Huawei) [ttyUSB0] suggested plugin for port
<debug> [1459934888.346260] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 2 running probes in this device (wwan0, ttyUSB0)
<debug> [1459934888.346276] [mm-plugin.c:689] mm_plugin_supports_port(): (Huawei) [wwan0] probing deferred until result suggested
<debug> [1459934888.346283] [mm-plugin-manager.c:549] plugin_supports_port_ready(): (Plugin Manager) (Huawei) [wwan0] task completed, got suggested plugin
<debug> [1459934888.346287] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'wwan0' port probe finished, still 1 running probes in this device (ttyUSB0)
<debug> [1459934889.260532] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>ERROR<CR><LF>'
<debug> [1459934889.260868] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [1459934889.261106] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
<debug> [1459934889.463068] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1459934889.463211] [mm-port-probe.c:154] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is AT-capable
<debug> [1459934889.463309] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT+CGMI<CR>'
<debug> [1459934890.036910] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2] Minimum probing time consumed
<debug> [1459934890.036961] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0] Minimum probing time consumed
<debug> [1459934890.036974] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [wlan0] deferred task cancelled, no suggested plugin
<debug> [1459934890.036996] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1f.6] Minimum probing time consumed
<debug> [1459934890.037026] [mm-plugin-manager.c:368] suggest_single_port_probe_result(): (Plugin Manager) [eth0] deferred task cancelled, no suggested plugin
<debug> [1459934890.037054] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [wlan0] not supported by any plugin
<debug> [1459934890.037073] [mm-device.c:368] mm_device_ignore_port(): Fully ignoring port 'net/wlan0' from now on
<debug> [1459934890.037087] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'wlan0' port probe finished, last one in device
<debug> [1459934890.037106] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0] device support check finished in '1.885349' seconds
<info> [1459934890.037204] [mm-base-manager.c:153] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0': not supported by any plugin
<debug> [1459934890.037290] [mm-plugin-manager.c:195] port_probe_context_finished(): (Plugin Manager) [eth0] not supported by any plugin
<debug> [1459934890.037303] [mm-device.c:368] mm_device_ignore_port(): Fully ignoring port 'net/eth0' from now on
<debug> [1459934890.037313] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'eth0' port probe finished, last one in device
<debug> [1459934890.037322] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1f.6] device support check finished in '1.885473' seconds
<info> [1459934890.037338] [mm-base-manager.c:153] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1f.6': not supported by any plugin
<debug> [1459934890.170122] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): <-- '<CR><LF>Huawei Technologies Co., Ltd.<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1459934890.170281] [mm-port-probe.c:181] mm_port_probe_set_result_at_vendor(): (tty/ttyUSB0) vendor probing finished
<debug> [1459934890.170393] [mm-port-serial.c:1328] _close_internal(): (ttyUSB0) device open count is 0 (close)
<debug> [1459934890.170414] [mm-port-serial.c:1344] _close_internal(): (ttyUSB0) closing serial port...
<debug> [1459934890.171118] [mm-port-serial.c:1393] _close_internal(): (ttyUSB0) serial port closed
<debug> [1459934890.171160] [mm-port-serial.c:1455] port_serial_close_force(): (ttyUSB0) forced to close port
<debug> [1459934890.171245] [mm-plugin.c:429] apply_post_probing_filters(): (Cinterion) [ttyUSB0] filtered by vendor strings
<debug> [1459934890.171301] [mm-plugin-manager.c:189] port_probe_context_finished(): (Plugin Manager) [ttyUSB0] assuming port can be handled by the 'Huawei' plugin
<debug> [1459934890.171313] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, last one in device
<debug> [1459934890.171323] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2] device support check finished in '2.020530' seconds
<info> [1459934890.171348] [mm-device.c:520] mm_device_create_modem(): Creating modem with plugin 'Huawei' and '3' ports
<debug> [1459934890.171423] [huawei/mm-plugin-huawei.c:506] create_modem(): MBIM-powered Huawei modem found...
<debug> [1459934890.174030] [huawei/mm-plugin-huawei.c:558] grab_port(): (usbmisc/cdc-wdm0) Port will have AT flags 'none'
<debug> [1459934890.174087] [mm-base-modem.c:304] mm_base_modem_grab_port(): (cdc-wdm0) type 'mbim' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2
<debug> [1459934890.174114] [huawei/mm-plugin-huawei.c:558] grab_port(): (net/wwan0) Port will have AT flags 'none'
<debug> [1459934890.174139] [mm-base-modem.c:304] mm_base_modem_grab_port(): (wwan0) type 'net' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2
<debug> [1459934890.174163] [huawei/mm-plugin-huawei.c:558] grab_port(): (tty/ttyUSB0) Port will have AT flags 'ppp'
<debug> [1459934890.174323] [mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyUSB0) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2
<debug> [1459934890.174355] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2) net/wwan0 data (primary)
<debug> [1459934890.174366] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2) tty/ttyUSB0 data (secondary)
<debug> [1459934890.174376] [mm-base-modem.c:970] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2) usb/cdc-wdm0 mbim (primary)
<info> [1459934890.174877] [mm-base-manager.c:162] find_device_support_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2' successfully created
opening device...[/dev/cdc-wdm0] Read max control message size from descriptors file: 1024[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 88
<<<<<< data = 03:00:00:00:58:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:01:00:00:00:28:00:00:00:0C:00:00:00:1A:00:00:00:1E:00:00:00:2F:00:64:00:65:00:76:00:2F:00:63:00:64:00:63:00:2D:00:77:00:64:00:6D:00:30:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 88
<<<<<< type = command (0x00000003)
<<<<<< transaction = 1
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
<<<<<< cid = 'configuration' (0x00000001)
<<<<<< type = 'set' (0x00000001)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 1
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
>>>>>> cid = 'configuration' (0x00000001)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:04:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 16
<<<<<< type = open (0x00000001)
<<<<<< transaction = 2
<<<<<< Contents:
<<<<<< max_control_transfer = 1024
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 01:00:00:80:10:00:00:00:02:00:00:00:00:00:00:00
<debug> [1459934890.176601] [mm-broadband-modem-mbim.c:1293] parent_initialization_started_ready(): Couldn't start parent initialization: Couldn't open ports during modem initialization: Couldn't get primary port
<debug> [1459934890.177071] [mm-broadband-modem-mbim.c:214] modem_load_current_capabilities(): loading current capabilities...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 3
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'device-caps' (0x00000001)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 208
>>>>>> data = 03:00:00:80:D0:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:A0:00:00:00:01:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:3F:00:00:80:03:00:00:00:03:00:00:00:03:00:00:00:40:00:00:00:0A:00:00:00:4C:00:00:00:1E:00:00:00:6C:00:00:00:1E:00:00:00:8C:00:00:00:14:00:00:00:48:00:53:00:50:00:41:00:2B:00:00:00:38:00:36:00:37:00:31:00:36:00:30:00:30:00:32:00:30:00:31:00:36:00:34:00:32:00:33:00:36:00:00:00:31:00:31:00:2E:00:36:00:31:00:37:00:2E:00:30:00:30:00:2E:00:30:00:30:00:2E:00:30:00:30:00:00:00:4D:00:4C:00:31:00:4D:00:45:00:39:00:30:00:36:00:53:00:4D:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 208
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 3
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'device-caps' (0x00000001)
<debug> [1459934890.297402] [mm-broadband-modem-mbim.c:140] modem_load_current_capabilities_finish(): loaded modem capabilities: gsm-umts, lte
<debug> [1459934890.297579] [mm-iface-modem.c:4345] interface_initialization_step(): Modem allows up to 2 bearers
<debug> [1459934890.297593] [mm-bearer-list.c:270] mm_bearer_list_new(): Creating bearer list (max: 2, max active: 2)
<debug> [1459934890.297935] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '000012d1000015c186716002016423611.617.00.00.00MBIM[12D1:15C1]Huawei'
<debug> [1459934890.297949] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID 'ec26c516234cd91805a6aecbdd2ab35e8a64dea3'
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 4
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'radio-state' (0x00000003)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 56
>>>>>> data = 03:00:00:80:38:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 56
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 4
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'radio-state' (0x00000003)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 5
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 116
>>>>>> data = 03:00:00:80:74:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:44:00:00:00:06:00:00:00:00:00:00:00:00:00:00:00:1C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:38:00:39:00:34:00:39:00:30:00:32:00:30:00:30:00:30:00:30:00:30:00:38:00:31:00:35:00:31:00:35:00:31:00:33:00:33:00:32:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 116
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 5
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 6
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin' (0x00000004)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 60
>>>>>> data = 03:00:00:80:3C:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:02:00:00:00:01:00:00:00:03:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 60
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 6
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin' (0x00000004)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:07:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 7
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin' (0x00000004)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 60
>>>>>> data = 03:00:00:80:3C:00:00:00:07:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:02:00:00:00:01:00:00:00:03:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 60
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 7
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin' (0x00000004)
<info> [1459934890.736157] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem: state changed (unknown -> locked)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 8
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 116
>>>>>> data = 03:00:00:80:74:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:44:00:00:00:06:00:00:00:00:00:00:00:00:00:00:00:1C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:38:00:39:00:34:00:39:00:30:00:32:00:30:00:30:00:30:00:30:00:30:00:38:00:31:00:35:00:31:00:35:00:31:00:33:00:33:00:32:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 116
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 8
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:09:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 9
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 116
>>>>>> data = 03:00:00:80:74:00:00:00:09:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:44:00:00:00:06:00:00:00:00:00:00:00:00:00:00:00:1C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:38:00:39:00:34:00:39:00:30:00:32:00:30:00:30:00:30:00:30:00:30:00:38:00:31:00:35:00:31:00:35:00:31:00:33:00:33:00:32:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 116
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 9
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:0A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 10
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'home-provider' (0x00000006)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:0A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:05:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 10
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'PinRequired' (0x00000005)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'home-provider' (0x00000006)
<warn> [1459934891.135237] [mm-base-sim.c:1480] load_operator_identifier_ready(): couldn't load Operator identifier: 'PinRequired'
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:0B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 11
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'home-provider' (0x00000006)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:0B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:05:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 11
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'PinRequired' (0x00000005)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'home-provider' (0x00000006)
<warn> [1459934891.215120] [mm-base-sim.c:1481] load_operator_name_ready(): couldn't load Operator name: 'PinRequired'
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:0C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 12
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 116
>>>>>> data = 03:00:00:80:74:00:00:00:0C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:44:00:00:00:06:00:00:00:00:00:00:00:00:00:00:00:1C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:38:00:39:00:34:00:39:00:30:00:32:00:30:00:30:00:30:00:30:00:30:00:38:00:31:00:35:00:31:00:35:00:31:00:33:00:33:00:32:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 116
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 12
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
<debug> [1459934891.375576] [mm-broadband-modem.c:9231] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
<debug> [1459934891.375729] [mm-base-modem.c:950] initialize_ready(): Couldn't finish initialization in the current state: 'Modem is currently locked, cannot fully initialize'
<debug> [1459934891.376424] [mm-device.c:447] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2' at path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [1459934891.376438] [mm-device.c:457] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Huawei' modem, VID 0x12D1 PID 0x15C1 (usb)
<debug> [1459935790.160884] [mm-sim-mbim.c:431] send_pin(): Sending PIN...
<debug> [1459935790.160924] [mm-sim-mbim.c:41] inhibit_mbim_traces(): PIN logging enabled
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 80
<<<<<< data = 03:00:00:00:50:00:00:00:0D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:01:00:00:00:20:00:00:00:02:00:00:00:00:00:00:00:18:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:35:00:38:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 80
<<<<<< type = command (0x00000003)
<<<<<< transaction = 13
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin' (0x00000004)
<<<<<< type = 'set' (0x00000001)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 60
>>>>>> data = 03:00:00:80:3C:00:00:00:0D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 60
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 13
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin' (0x00000004)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:0E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 14
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 144
>>>>>> data = 07:00:00:80:90:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:31:00:36:00:34:00:34:00:31:00:30:00:33:00:30:00:35:00:35:00:36:00:00:00:38:00:39:00:34:00:39:00:30:00:32:00:30:00:30:00:30:00:30:00:30:00:38:00:31:00:35:00:31:00:35:00:31:00:33:00:33:00:32:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 144
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 148
>>>>>> data = 03:00:00:80:94:00:00:00:0E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:31:00:36:00:34:00:34:00:31:00:30:00:33:00:30:00:35:00:35:00:36:00:00:00:38:00:39:00:34:00:39:00:30:00:32:00:30:00:30:00:30:00:30:00:30:00:38:00:31:00:35:00:31:00:35:00:31:00:33:00:33:00:32:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 148
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 14
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:0F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 15
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin' (0x00000004)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 92
>>>>>> data = 07:00:00:80:5C:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:30:00:00:00:00:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 92
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'register-state' (0x00000009)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 72
>>>>>> data = 07:00:00:80:48:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:1C:00:00:00:00:00:00:00:04:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 72
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'packet-service' (0x0000000a)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 60
>>>>>> data = 03:00:00:80:3C:00:00:00:0F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:00:00:00:00:00:00:00:00:03:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 60
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 15
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin' (0x00000004)
<debug> [1459935792.228388] [mm-iface-modem.c:3110] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
<info> [1459935792.228908] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (locked -> initializing)
<debug> [1459935792.230234] [mm-broadband-modem-mbim.c:1293] parent_initialization_started_ready(): Couldn't start parent initialization: Couldn't open ports during modem initialization: Couldn't get primary port
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:10:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 16
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 148
>>>>>> data = 03:00:00:80:94:00:00:00:10:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:31:00:36:00:34:00:34:00:31:00:30:00:33:00:30:00:35:00:35:00:36:00:00:00:38:00:39:00:34:00:39:00:30:00:32:00:30:00:30:00:30:00:30:00:30:00:38:00:31:00:35:00:31:00:35:00:31:00:33:00:33:00:32:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 148
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 16
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:11:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 17
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'home-provider' (0x00000006)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 124
>>>>>> data = 07:00:00:80:7C:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:50:00:00:00:00:00:00:00:03:00:00:00:01:00:00:00:20:00:00:00:01:00:00:00:30:00:00:00:0A:00:00:00:3C:00:00:00:14:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00:32:00:36:00:32:00:30:00:31:00:00:00:54:00:65:00:6C:00:65:00:6B:00:6F:00:6D:00:2E:00:64:00:65:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 124
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'register-state' (0x00000009)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 112
>>>>>> data = 03:00:00:80:70:00:00:00:11:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:40:00:00:00:20:00:00:00:0A:00:00:00:01:00:00:00:2C:00:00:00:14:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:31:00:00:00:54:00:65:00:6C:00:65:00:6B:00:6F:00:6D:00:2E:00:64:00:65:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 112
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 17
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'home-provider' (0x00000006)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:12:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 18
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'home-provider' (0x00000006)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 112
>>>>>> data = 03:00:00:80:70:00:00:00:12:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:40:00:00:00:20:00:00:00:0A:00:00:00:01:00:00:00:2C:00:00:00:14:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:31:00:00:00:54:00:65:00:6C:00:65:00:6B:00:6F:00:6D:00:2E:00:64:00:65:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 112
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 18
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'home-provider' (0x00000006)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:13:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 19
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 148
>>>>>> data = 03:00:00:80:94:00:00:00:13:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:31:00:36:00:34:00:34:00:31:00:30:00:33:00:30:00:35:00:35:00:36:00:00:00:38:00:39:00:34:00:39:00:30:00:32:00:30:00:30:00:30:00:30:00:30:00:38:00:31:00:35:00:31:00:35:00:31:00:33:00:33:00:32:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 148
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 19
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:14:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 20
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin-list' (0x00000005)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 208
>>>>>> data = 03:00:00:80:D0:00:00:00:14:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:00:00:00:00:A0:00:00:00:01:00:00:00:01:00:00:00:04:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00:01:00:00:00:08:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 208
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 20
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin-list' (0x00000005)
<debug> [1459935793.719397] [mm-iface-modem-3gpp-ussd.c:786] check_support_ready(): USSD support check failed: 'No AT port available to run command'
<debug> [1459935793.719442] [mm-broadband-modem.c:9224] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
<debug> [1459935793.720408] [mm-broadband-modem-mbim.c:2596] messaging_check_support(): Messaging capabilities supported
<debug> [1459935793.720502] [mm-iface-modem-messaging.c:1148] load_supported_storages_ready(): Supported storages loaded:
<debug> [1459935793.720512] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): mem1 (list/read/delete) storages: 'mt'
<debug> [1459935793.720522] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready(): mem2 (write/send) storages: 'mt'
<debug> [1459935793.720529] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready(): mem3 (reception) storages: 'mt'
<debug> [1459935793.721073] [mm-iface-modem-time.c:831] check_support_ready(): Time support check failed: 'No AT port available to run command'
<debug> [1459935793.721111] [mm-broadband-modem.c:9228] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
<debug> [1459935793.721310] [mm-broadband-modem.c:9229] iface_modem_signal_initialize_ready(): Couldn't initialize interface: 'Extended Signal information not supported'
<debug> [1459935793.721645] [mm-broadband-modem.c:9230] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
<debug> [1459935793.721720] [mm-broadband-modem.c:9231] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
<info> [1459935793.721769] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (initializing -> disabled)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 64
>>>>>> data = 07:00:00:80:40:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0B:00:00:00:14:00:00:00:16:00:00:00:63:00:00:00:05:00:00:00:05:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 64
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'signal-state' (0x0000000b)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 64
>>>>>> data = 07:00:00:80:40:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0B:00:00:00:14:00:00:00:15:00:00:00:63:00:00:00:05:00:00:00:05:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 64
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'signal-state' (0x0000000b)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 64
>>>>>> data = 07:00:00:80:40:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0B:00:00:00:14:00:00:00:17:00:00:00:63:00:00:00:05:00:00:00:05:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 64
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'signal-state' (0x0000000b)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 64
>>>>>> data = 07:00:00:80:40:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0B:00:00:00:14:00:00:00:16:00:00:00:63:00:00:00:05:00:00:00:05:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 64
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'signal-state' (0x0000000b)
<info> [1459936313.691532] [mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...
<debug> [1459936313.691541] [mm-iface-modem-simple.c:651] connect_auth_ready(): PIN: unspecified
<debug> [1459936313.691544] [mm-iface-modem-simple.c:653] connect_auth_ready(): Operator ID: unspecified
<debug> [1459936313.691546] [mm-iface-modem-simple.c:655] connect_auth_ready(): Allowed roaming: yes
<debug> [1459936313.691550] [mm-iface-modem-simple.c:657] connect_auth_ready(): APN: internet.t-mobile
<debug> [1459936313.691552] [mm-iface-modem-simple.c:665] connect_auth_ready(): IP family: unspecified
<debug> [1459936313.691555] [mm-iface-modem-simple.c:673] connect_auth_ready(): Allowed authentication: unspecified
<debug> [1459936313.691558] [mm-iface-modem-simple.c:675] connect_auth_ready(): User: t-mobile
<debug> [1459936313.691561] [mm-iface-modem-simple.c:677] connect_auth_ready(): Password: tm
<debug> [1459936313.691563] [mm-iface-modem-simple.c:679] connect_auth_ready(): Number: unspecified
<info> [1459936313.691565] [mm-iface-modem-simple.c:461] connection_step(): Simple connect state (3/8): Enable
<info> [1459936313.691579] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
<debug> [1459936313.691669] [mm-broadband-modem.c:8166] enabling_started(): Skipping modem initialization: not required
<debug> [1459936313.691679] [mm-broadband-modem-mbim.c:1220] parent_enabling_started_ready(): Couldn't start parent enabling: Couldn't open ports during modem enabling: Couldn't get primary port
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 21
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'radio-state' (0x00000003)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 56
>>>>>> data = 03:00:00:80:38:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 56
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 21
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'radio-state' (0x00000003)
<debug> [1459936313.774100] [mm-iface-modem.c:3293] set_power_state(): No need to change power state: already in 'on' power state
<debug> [1459936313.774147] [mm-broadband-modem.c:8808] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
<debug> [1459936313.774186] [mm-broadband-modem-mbim.c:1980] common_setup_cleanup_unsolicited_events(): Supported notifications: signal (yes), registration (no), sms (no), connect (yes), subscriber (yes), packet (yes)
<debug> [1459936313.774225] [mm-broadband-modem-mbim.c:2119] common_enable_disable_unsolicited_events(): Enabled notifications: signal (yes), registration (no), sms (no), connect (yes), subscriber (yes), packet (yes)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 96
<<<<<< data = 03:00:00:00:60:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:01:00:00:00:30:00:00:00:01:00:00:00:0C:00:00:00:24:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:0B:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 96
<<<<<< type = command (0x00000003)
<<<<<< transaction = 22
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'device-service-subscribe-list' (0x00000013)
<<<<<< type = 'set' (0x00000001)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 96
>>>>>> data = 03:00:00:80:60:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:00:00:00:00:30:00:00:00:01:00:00:00:0C:00:00:00:24:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:0B:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 96
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 22
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'device-service-subscribe-list' (0x00000013)
<debug> [1459936313.774638] [mm-broadband-modem-mbim.c:1980] common_setup_cleanup_unsolicited_events(): Supported notifications: signal (yes), registration (yes), sms (no), connect (yes), subscriber (yes), packet (yes)
<debug> [1459936313.774675] [mm-broadband-modem-mbim.c:2119] common_enable_disable_unsolicited_events(): Enabled notifications: signal (yes), registration (yes), sms (no), connect (yes), subscriber (yes), packet (yes)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 100
<<<<<< data = 03:00:00:00:64:00:00:00:17:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:01:00:00:00:34:00:00:00:01:00:00:00:0C:00:00:00:28:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 100
<<<<<< type = command (0x00000003)
<<<<<< transaction = 23
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'device-service-subscribe-list' (0x00000013)
<<<<<< type = 'set' (0x00000001)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 100
>>>>>> data = 03:00:00:80:64:00:00:00:17:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:00:00:00:00:34:00:00:00:01:00:00:00:0C:00:00:00:28:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 100
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 23
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'device-service-subscribe-list' (0x00000013)
<debug> [1459936313.774977] [mm-broadband-modem.c:8849] enabling_step(): Modem has location capabilities, enabling the Location interface...
<debug> [1459936313.775028] [mm-iface-modem-location.c:759] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
<debug> [1459936313.775112] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:18:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 24
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'register-state' (0x00000009)
<<<<<< type = 'query' (0x00000000)
<debug> [1459936313.775448] [mm-broadband-modem.c:8862] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
<debug> [1459936313.775564] [mm-broadband-modem-mbim.c:2751] load_initial_sms_parts(): loading SMS parts...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 60
<<<<<< data = 03:00:00:00:3C:00:00:00:19:00:00:00:01:00:00:00:00:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:00:00:00:00:0C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 60
<<<<<< type = command (0x00000003)
<<<<<< transaction = 25
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f)
<<<<<< cid = 'read' (0x00000002)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 128
>>>>>> data = 03:00:00:80:80:00:00:00:18:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:00:00:00:00:50:00:00:00:00:00:00:00:03:00:00:00:01:00:00:00:20:00:00:00:01:00:00:00:30:00:00:00:0A:00:00:00:3C:00:00:00:14:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00:32:00:36:00:32:00:30:00:31:00:00:00:54:00:65:00:6C:00:65:00:6B:00:6F:00:6D:00:2E:00:64:00:65:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 128
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 24
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'register-state' (0x00000009)
<info> [1459936314.074536] [mm-iface-modem-3gpp.c:1171] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
<debug> [1459936314.074677] [mm-iface-modem.c:933] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> lte)
<debug> [1459936314.074798] [mm-iface-modem-location.c:295] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '262', MNC: '1', Location area code: '0', Cell ID: '0')
<info> [1459936314.075541] [mm-iface-modem-3gpp.c:1096] update_registration_reload_current_registration_info_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
<debug> [1459936314.075658] [mm-iface-modem.c:1600] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:19:00:00:00:01:00:00:00:00:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:0E:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 25
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'NotInitialized' (0x0000000e)
>>>>>> service = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f)
>>>>>> cid = 'read' (0x00000002)
<debug> [1459936314.154175] [mm-iface-modem-messaging.c:775] load_initial_sms_parts_ready(): Couldn't load SMS parts from storage 'mt': 'NotInitialized'
<debug> [1459936314.154203] [mm-broadband-modem-mbim.c:1980] common_setup_cleanup_unsolicited_events(): Supported notifications: signal (yes), registration (yes), sms (yes), connect (yes), subscriber (yes), packet (yes)
<debug> [1459936314.154274] [mm-broadband-modem-mbim.c:2119] common_enable_disable_unsolicited_events(): Enabled notifications: signal (yes), registration (yes), sms (yes), connect (yes), subscriber (yes), packet (yes)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 136
<<<<<< data = 03:00:00:00:88:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:01:00:00:00:58:00:00:00:02:00:00:00:14:00:00:00:28:00:00:00:3C:00:00:00:1C:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:02:00:00:00:05:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 136
<<<<<< type = command (0x00000003)
<<<<<< transaction = 26
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'device-service-subscribe-list' (0x00000013)
<<<<<< type = 'set' (0x00000001)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 136
>>>>>> data = 03:00:00:80:88:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:00:00:00:00:58:00:00:00:02:00:00:00:14:00:00:00:28:00:00:00:3C:00:00:00:1C:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:02:00:00:00:05:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 136
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 26
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'device-service-subscribe-list' (0x00000013)
<info> [1459936314.154750] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
<debug> [1459936314.155173] [mm-iface-modem.c:1075] periodic_access_technologies_check_enable(): Periodic access technology checks enabled
<info> [1459936314.155235] [mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled
<info> [1459936314.155286] [mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register
<debug> [1459936314.155332] [mm-iface-modem-3gpp.c:400] mm_iface_modem_3gpp_register_in_network(): Already registered in network '26201', automatic registration not launched...
<info> [1459936314.155378] [mm-iface-modem-simple.c:501] connection_step(): Simple connect state (6/8): Bearer
<debug> [1459936314.155401] [mm-iface-modem-simple.c:521] connection_step(): Creating new bearer...
<debug> [1459936314.155424] [mm-broadband-modem-mbim.c:1163] modem_create_bearer(): Creating MBIM bearer in MBIM modem
<debug> [1459936314.156275] [mm-broadband-modem-mbim.c:1077] modem_create_bearer_finish(): New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1459936314.156582] [mm-iface-modem-simple.c:583] connection_step(): Simple connect state (7/8): Connect
<debug> [1459936314.156603] [mm-base-bearer.c:590] mm_base_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1459936314.156630] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
<debug> [1459936314.157101] [mm-bearer-mbim.c:897] _connect(): Launching connection with data port (net/wwan0)
<debug> [1459936314.157133] [mm-bearer-mbim.c:655] connect_context_step(): Activating packet service...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 52
<<<<<< data = 03:00:00:00:34:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:01:00:00:00:04:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 52
<<<<<< type = command (0x00000003)
<<<<<< transaction = 27
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'packet-service' (0x0000000a)
<<<<<< type = 'set' (0x00000001)
<debug> [1459936314.157337] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 28
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'register-state' (0x00000009)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 72
>>>>>> data = 07:00:00:80:48:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:1C:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 72
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'packet-service' (0x0000000a)
<debug> [1459936314.234294] [mm-broadband-modem-mbim.c:1934] device_notification_cb(): Received notification (service 'basic-connect', command 'packet-service')
<debug> [1459936314.234325] [mm-broadband-modem-mbim.c:1749] basic_connect_notification_packet_service(): Packet service state: 'attaching', data class: '(null)'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 76
>>>>>> data = 03:00:00:80:4C:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:00:00:00:00:1C:00:00:00:00:00:00:00:02:00:00:00:20:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 76
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 27
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'packet-service' (0x0000000a)
<debug> [1459936314.314252] [mm-bearer-mbim.c:594] packet_service_set_ready(): Packet service update:
<debug> [1459936314.314269] [mm-bearer-mbim.c:595] packet_service_set_ready(): state: 'attached'
<debug> [1459936314.314279] [mm-bearer-mbim.c:596] packet_service_set_ready(): data class: 'lte'
<debug> [1459936314.314299] [mm-bearer-mbim.c:597] packet_service_set_ready(): uplink: '0' bps
<debug> [1459936314.314308] [mm-bearer-mbim.c:598] packet_service_set_ready(): downlink: '0' bps
<debug> [1459936314.314318] [mm-bearer-mbim.c:676] connect_context_step(): Listing provisioned contexts...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 29
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'provisioned-contexts' (0x0000000d)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 128
>>>>>> data = 03:00:00:80:80:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:00:00:00:00:50:00:00:00:00:00:00:00:03:00:00:00:01:00:00:00:20:00:00:00:01:00:00:00:30:00:00:00:0A:00:00:00:3C:00:00:00:14:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00:32:00:36:00:32:00:30:00:31:00:00:00:54:00:65:00:6C:00:65:00:6B:00:6F:00:6D:00:2E:00:64:00:65:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 128
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 28
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'register-state' (0x00000009)
<debug> [1459936314.614459] [mm-broadband-modem.c:8201] modem_3gpp_run_registration_checks_ready(): Initial 3GPP registration checks finished
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 52
>>>>>> data = 03:00:00:80:34:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:04:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 52
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 29
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'provisioned-contexts' (0x0000000d)
<debug> [1459936314.874104] [mm-bearer-mbim.c:529] provisioned_contexts_query_ready(): Provisioned contexts found (0):
<debug> [1459936314.874132] [mm-bearer-mbim.c:708] connect_context_step(): Using default (PAP) authentication method
<debug> [1459936314.874149] [mm-bearer-mbim.c:741] connect_context_step(): No specific IP family requested, defaulting to ipv4
<debug> [1459936314.874156] [mm-bearer-mbim.c:772] connect_context_step(): Launching connection with APN 'internet.t-mobile'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 164
<<<<<< data = 03:00:00:00:A4:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:01:00:00:00:74:00:00:00:00:00:00:00:01:00:00:00:3C:00:00:00:22:00:00:00:60:00:00:00:10:00:00:00:70:00:00:00:04:00:00:00:00:00:00:00:01:00:00:00:01:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:69:00:6E:00:74:00:65:00:72:00:6E:00:65:00:74:00:2E:00:74:00:2D:00:6D:00:6F:00:62:00:69:00:6C:00:65:00:00:00:74:00:2D:00:6D:00:6F:00:62:00:69:00:6C:00:65:00:74:00:6D:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 164
<<<<<< type = command (0x00000003)
<<<<<< transaction = 30
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'connect' (0x0000000c)
<<<<<< type = 'set' (0x00000001)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 84
>>>>>> data = 03:00:00:80:54:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:00:00:00:00:24:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:03:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 84
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 30
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'connect' (0x0000000c)
<debug> [1459936315.604194] [mm-bearer-mbim.c:484] connect_set_ready(): Session ID '0': activated (IP type: ipv4v6)
<debug> [1459936315.604213] [mm-bearer-mbim.c:803] connect_context_step(): Querying IP configuration...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 108
<<<<<< data = 03:00:00:00:6C:00:00:00:1F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0F:00:00:00:00:00:00:00:3C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 108
<<<<<< type = command (0x00000003)
<<<<<< transaction = 31
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'ip-configuration' (0x0000000f)
<<<<<< type = 'query' (0x00000000)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 72
>>>>>> data = 07:00:00:80:48:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:1C:00:00:00:00:00:00:00:02:00:00:00:20:00:00:00:80:D1:F0:08:00:00:00:00:80:D1:F0:08:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 72
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'packet-service' (0x0000000a)
<debug> [1459936315.694224] [mm-broadband-modem-mbim.c:1934] device_notification_cb(): Received notification (service 'basic-connect', command 'packet-service')
<debug> [1459936315.694245] [mm-broadband-modem-mbim.c:1749] basic_connect_notification_packet_service(): Packet service state: 'attached', data class: 'lte'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 196
>>>>>> data = 03:00:00:80:C4:00:00:00:1F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0F:00:00:00:00:00:00:00:94:00:00:00:00:00:00:00:0F:00:00:00:00:00:00:00:01:00:00:00:80:00:00:00:00:00:00:00:00:00:00:00:88:00:00:00:00:00:00:00:02:00:00:00:8C:00:00:00:00:00:00:00:00:00:00:00:DC:05:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:1E:00:00:00:0A:33:82:6A:0A:33:82:69:0A:4A:D2:D2:0A:4A:D2:D3
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 196
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 31
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'ip-configuration' (0x0000000f)
<debug> [1459936315.734343] [mm-bearer-mbim.c:212] ip_configuration_query_ready(): IPv4 configuration available: 'address, gateway, dns, mtu'
<debug> [1459936315.734373] [mm-bearer-mbim.c:218] ip_configuration_query_ready(): IP addresses (1)
<debug> [1459936315.734597] [mm-bearer-mbim.c:222] ip_configuration_query_ready(): IP [0]: '10.51.130.106/30'
<debug> [1459936315.734626] [mm-bearer-mbim.c:231] ip_configuration_query_ready(): Gateway: '10.51.130.105'
<debug> [1459936315.734637] [mm-bearer-mbim.c:239] ip_configuration_query_ready(): DNS addresses (2)
<debug> [1459936315.734657] [mm-bearer-mbim.c:244] ip_configuration_query_ready(): DNS [0]: '10.74.210.210'
<debug> [1459936315.734675] [mm-bearer-mbim.c:244] ip_configuration_query_ready(): DNS [1]: '10.74.210.211'
<debug> [1459936315.734686] [mm-bearer-mbim.c:252] ip_configuration_query_ready(): MTU: '1500'
<debug> [1459936315.734695] [mm-bearer-mbim.c:258] ip_configuration_query_ready(): IPv6 configuration available: 'none'
<debug> [1459936315.734814] [mm-port.c:95] mm_port_set_connected(): (wwan0): port now connected
<debug> [1459936315.734860] [mm-base-bearer.c:488] connect_ready(): Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1459936315.735157] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
<info> [1459936315.735597] [mm-iface-modem-simple.c:602] connection_step(): Simple connect state (8/8): All done
<info> [1459936354.798433] [main.c:42] quit_cb(): Caught signal, shutting down...
<debug> [1459936354.798505] [mm-base-manager.c:814] set_property(): Stopping connection in object manager server
<info> [1459936354.798815] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disabling)
<debug> [1459936354.799124] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (0)
<debug> [1459936354.799145] [mm-iface-modem.c:1321] periodic_signal_quality_check_disable(): Periodic signal quality checks disabled
<debug> [1459936354.799193] [mm-iface-modem.c:933] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (lte -> unknown)
<debug> [1459936354.799210] [mm-iface-modem.c:1047] periodic_access_technologies_check_disable(): Periodic access technology checks disabled
<debug> [1459936354.799235] [mm-base-bearer.c:760] mm_base_bearer_disconnect(): Disconnecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
<debug> [1459936354.799271] [mm-bearer-mbim.c:1109] disconnect(): Launching disconnection on data port (net/wwan0)
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 108
<<<<<< data = 03:00:00:00:6C:00:00:00:20:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:01:00:00:00:3C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 108
<<<<<< type = command (0x00000003)
<<<<<< transaction = 32
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'connect' (0x0000000c)
<<<<<< type = 'set' (0x00000001)
[/dev/cdc-wdm0] unexpected port hangup!
-------------- next part --------------
A non-text attachment was scrubbed...
Name: usbmon.pcap
Type: application/vnd.tcpdump.pcap
Size: 31638 bytes
Desc: not available
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20160406/30811b26/attachment-0001.pcap>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 198 bytes
Desc: OpenPGP digital signature
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20160406/30811b26/attachment-0001.sig>
More information about the ModemManager-devel
mailing list