Fwd: ptxdist-2013.12.0 + ModemManager + Cinterion PLS8 rel.1
Raul Suarez Moreno
raul.suarez at blue-tc.com
Tue Sep 1 04:46:37 PDT 2015
On Tue, Sep 1, 2015 at 11:58 AM, Aleksander Morgado <
aleksander at aleksander.es> wrote:
> On Tue, Sep 1, 2015 at 12:19 PM, Raul Suarez Moreno
> <raul.suarez at blue-tc.com> wrote:
> > Aleksander, I've enabled debug mode to udev and here is the log content:
>
> I think there was a misunderstanding; what we need is full
> ModemManager debug logs, not udev debug logs :)
>
> --
> Aleksander
> https://aleksander.es
>
Sorry, that was my fault. I attach here the full ModemManager debug log
just from the system booting until the modem enabling process finished. I
got it after applying Daniels patch, so don't know if anything may be
different. Should I do any checking about Dans patch?
--
Saludos / Best regards
Raúl Suárez
Blue TC
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freedesktop.org/archives/modemmanager-devel/attachments/20150901/846fe36b/attachment-0001.html>
-------------- next part --------------
<info> [997876977.521677] [main.c:136] main(): ModemManager (version 1.4.8) starting in system bus...
<warn> [997876977.699602] [main.c:101] name_lost_cb(): Could not acquire the 'org.freedesktop.ModemManager1' service name
<info> [997876977.719053] [main.c:187] main(): ModemManager is shut down
<info> [997876986.983326] [main.c:136] main(): ModemManager (version 1.4.8) starting in system bus...
<debug> [997876987.181529] [main.c:61] bus_acquired_cb(): Bus acquired, creating manager...
<debug> [997876987.217929] [mm-plugin-manager.c:852] load_plugins(): Looking for plugins in '/usr/lib/ModemManager'
<debug> [997876987.278074] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Samsung'
<debug> [997876987.306882] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Linktop'
<debug> [997876987.336239] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Via CBP7'
<debug> [997876987.368893] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'ZTE'
<debug> [997876987.427263] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Huawei'
<debug> [997876987.466718] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Longcheer'
<debug> [997876987.507391] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option'
<debug> [997876987.558647] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Sierra'
<debug> [997876987.621993] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel LTE'
<debug> [997876987.654053] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option High-Speed'
<debug> [997876987.688261] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Telit'
<debug> [997876987.728518] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Wavecom'
<debug> [997876987.758367] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Ericsson MBM'
<debug> [997876987.797754] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'AnyDATA'
<debug> [997876987.836458] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Pantech'
<debug> [997876987.876217] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Generic'
<debug> [997876987.934236] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'X22X'
<debug> [997876987.972298] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Gobi'
<debug> [997876988.011659] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Altair LTE'
<debug> [997876988.046809] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'MTK'
<debug> [997876988.101692] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia (Icera)'
<debug> [997876988.144172] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel'
<debug> [997876988.169232] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Cinterion'
<debug> [997876988.319054] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Motorola'
<debug> [997876988.362007] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia'
<debug> [997876988.399928] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'SimTech'
<debug> [997876988.419863] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Iridium'
<debug> [997876988.467141] [mm-plugin-manager.c:902] load_plugins(): Successfully loaded 27 plugins
<debug> [997876988.522315] [main.c:83] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
<debug> [997876988.530257] [mm-base-manager.c:485] mm_base_manager_start(): Starting automatic device scan...
<debug> [997876989.109948] [mm-base-manager.c:524] mm_base_manager_start(): Finished device scan...
<debug> [997876989.237677] [mm-base-manager.c:320] device_added(): (tty/ttyAPP0): port's parent platform driver is not whitelisted
<debug> [997876989.278717] [mm-base-manager.c:320] device_added(): (tty/ttyAPP1): port's parent platform driver is not whitelisted
<debug> [997876989.329575] [mm-base-manager.c:320] device_added(): (tty/ttyAPP3): port's parent platform driver is not whitelisted
<debug> [997876989.368012] [mm-base-manager.c:320] device_added(): (tty/ttyAMA0): port's parent platform driver is not whitelisted
<debug> [997876989.432432] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1] Checking device support...
<debug> [997876989.448568] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.468058] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.476878] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.486564] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.495747] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.503874] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.511902] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB0] filtered by drivers
<debug> [997876989.521333] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.529283] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers
<debug> [997876989.538372] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.547757] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.556085] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags
<debug> [997876989.564119] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.573499] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.581802] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.591590] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB0] filtered by drivers
<debug> [997876989.600811] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.609118] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB0] filtered by udev tags
<debug> [997876989.617385] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.627420] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.635382] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.644594] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by vendor/product IDs
<debug> [997876989.653917] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '5' plugins to try...
<debug> [997876989.662099] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Cinterion'
<debug> [997876989.669967] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Via CBP7'
<debug> [997876989.679602] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Nokia'
<debug> [997876989.687932] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Iridium'
<debug> [997876989.697252] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Generic'
<debug> [997876989.714694] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB0] probe required: 'at'
<debug> [997876989.723364] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at'
<debug> [997876989.759785] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.778090] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.789307] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.798430] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.807873] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.816212] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.824239] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB1] filtered by drivers
<debug> [997876989.833698] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.842062] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB1] filtered by drivers
<debug> [997876989.851639] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.860813] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.869227] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB1] filtered by udev tags
<debug> [997876989.877274] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.889043] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.897037] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.907621] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB1] filtered by drivers
<debug> [997876989.917193] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.927308] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB1] filtered by udev tags
<debug> [997876989.935526] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.949204] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.959461] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.968761] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB1] filtered by vendor/product IDs
<debug> [997876989.980642] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB1] Found '5' plugins to try...
<debug> [997876989.989031] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Cinterion'
<debug> [997876990.001903] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Via CBP7'
<debug> [997876990.011723] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Nokia'
<debug> [997876990.019833] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Iridium'
<debug> [997876990.036829] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Generic'
<debug> [997876990.046153] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB1] probe required: 'at'
<debug> [997876990.054293] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at'
<debug> [997876990.094449] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.103514] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.110925] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.119864] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.128772] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.139578] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.147090] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB2] filtered by drivers
<debug> [997876990.155828] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.163424] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB2] filtered by drivers
<debug> [997876990.172236] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.180861] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.188778] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB2] filtered by udev tags
<debug> [997876990.196254] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.215568] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.223252] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.231902] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB2] filtered by drivers
<debug> [997876990.240472] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.248174] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB2] filtered by udev tags
<debug> [997876990.255808] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.264464] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.271962] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.280624] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB2] filtered by vendor/product IDs
<debug> [997876990.289449] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB2] Found '5' plugins to try...
<debug> [997876990.297145] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Cinterion'
<debug> [997876990.304594] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Via CBP7'
<debug> [997876990.313318] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Nokia'
<debug> [997876990.320764] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Iridium'
<debug> [997876990.329498] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Generic'
<debug> [997876990.338212] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB2] probe required: 'at'
<debug> [997876990.346085] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at'
<debug> [997876990.372103] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876990.381303] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876990.388915] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876990.397671] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876990.406372] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876990.413933] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876990.421533] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB3] filtered by drivers
<debug> [997876991.017289] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.078010] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB3] filtered by drivers
<debug> [997876991.088117] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.096852] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.104334] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB3] filtered by udev tags
<debug> [997876991.111911] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.120494] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.129616] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.138426] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB3] filtered by drivers
<debug> [997876991.146083] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.154756] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB3] filtered by udev tags
<debug> [997876991.162186] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.169630] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.178347] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.187058] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB3] filtered by vendor/product IDs
<debug> [997876991.194505] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB3] Found '5' plugins to try...
<debug> [997876991.203213] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Cinterion'
<debug> [997876991.210530] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Via CBP7'
<debug> [997876991.218209] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Nokia'
<debug> [997876991.226911] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Iridium'
<debug> [997876991.235796] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Generic'
<debug> [997876991.243517] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB3] probe required: 'at'
<debug> [997876991.252342] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB3) launching port probing: 'at'
<debug> [997876991.325756] [mm-base-manager.c:320] device_added(): (net/can0): port's parent platform driver is not whitelisted
<debug> [997876991.348104] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wwan0] filtered by vendor/product IDs
<debug> [997876991.357287] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wwan0] filtered by subsystem
<debug> [997876991.366234] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwan0] filtered by subsystem
<debug> [997876991.373751] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wwan0] filtered by vendor/product IDs
<debug> [997876991.382515] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wwan0] filtered by vendor/product IDs
<debug> [997876991.390038] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwan0] filtered by subsystem
<debug> [997876991.397823] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wwan0] filtered by subsystem
<debug> [997876991.406725] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wwan0] filtered by drivers
<debug> [997876991.415585] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wwan0] filtered by vendor/product IDs
<debug> [997876991.423334] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wwan0] filtered by drivers
<debug> [997876991.435489] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wwan0] filtered by subsystem
<debug> [997876991.443148] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwan0] filtered by subsystem
<debug> [997876991.450686] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wwan0] filtered by udev tags
<debug> [997876991.459537] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wwan0] filtered by vendor/product IDs
<debug> [997876991.468196] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wwan0] filtered by vendor/product IDs
<debug> [997876991.475967] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wwan0] filtered by vendor/product IDs
<debug> [997876991.484649] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wwan0] filtered by drivers
<debug> [997876991.492193] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wwan0] filtered by vendor/product IDs
<debug> [997876991.499757] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wwan0] filtered by subsystem
<debug> [997876991.508501] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wwan0] filtered by vendor/product IDs
<debug> [997876991.517171] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wwan0] filtered by vendor/product IDs
<debug> [997876991.524687] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wwan0] filtered by subsystem
<debug> [997876991.533453] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wwan0] filtered by subsystem
<debug> [997876991.541168] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wwan0] filtered by subsystem
<debug> [997876991.548660] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wwan0] filtered by subsystem
<debug> [997876991.557446] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wwan0] Found '2' plugins to try...
<debug> [997876991.566216] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0] Will try with plugin 'Cinterion'
<debug> [997876991.573685] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0] Will try with plugin 'Generic'
<debug> [997876991.582388] [mm-plugin.c:689] mm_plugin_supports_port(): (Cinterion) [wwan0] probing deferred until result suggested
<debug> [997876991.597270] [mm-base-manager.c:320] device_added(): (net/eth0): port's parent platform driver is not whitelisted
<debug> [997876991.613756] [mm-base-manager.c:296] device_added(): (net/sit0): could not get port's parent device
<debug> [997876991.626936] [mm-base-manager.c:296] device_added(): (net/tunl0): could not get port's parent device
<debug> [997876991.650360] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem
<debug> [997876991.658439] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem
<debug> [997876991.667325] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
<debug> [997876991.674912] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997876991.682541] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997876991.691287] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
<debug> [997876991.699993] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem
<debug> [997876991.707695] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [cdc-wdm0] filtered by drivers
<debug> [997876991.716338] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem
<debug> [997876991.723885] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem
<debug> [997876991.731459] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem
<debug> [997876991.740310] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem
<debug> [997876991.752542] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags
<debug> [997876991.759856] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997876991.768809] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997876991.776370] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997876991.783979] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers
<debug> [997876991.792707] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
<debug> [997876991.801463] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [cdc-wdm0] filtered by subsystem
<debug> [997876991.808958] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem
<debug> [997876991.817613] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997876991.825228] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem
<debug> [997876991.833016] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem
<debug> [997876991.841675] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by subsystem
<debug> [997876991.850278] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem
<debug> [997876991.858085] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try...
<debug> [997876991.866947] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Cinterion'
<debug> [997876991.874423] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Generic'
<debug> [997876991.882357] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [cdc-wdm0] probe required: 'qmi'
<debug> [997876991.891420] [mm-port-probe.c:1368] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi'
<debug> [997876991.900862] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wwan0] deferring support check until result suggested
<debug> [997876991.917410] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB0) opening serial port...
<warn> [997876991.937242] [mm-port-serial.c:515] real_config_fd(): (ttyUSB0): port attributes not fully set
<debug> [997876991.945694] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
<debug> [997876991.958155] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
<warn> [997876991.970689] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set
<debug> [997876991.980029] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
<debug> [997876991.991188] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
<warn> [997876992.003570] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
<debug> [997876992.011920] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
<debug> [997876992.023250] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB3) opening serial port...
<warn> [997876992.036074] [mm-port-serial.c:515] real_config_fd(): (ttyUSB3): port attributes not fully set
<debug> [997876992.045399] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB3) device open count is 1 (open)
<debug> [997876992.054734] [mm-port-probe.c:458] wdm_probe_qmi(): (usbmisc/cdc-wdm0) probing QMI...
[/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...cannot connect to proxy: Could not connect: Connection refusedspawning new qmi-proxy (try 1)...<debug> [997876992.178182] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1] Minimum probing time consumed
<debug> [997876992.200900] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT^SQPORT?<CR>'
<debug> [997876992.228569] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT^SQPORT?<CR>'
<debug> [997876992.237549] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT^SQPORT?<CR>'
<debug> [997876992.247701] [mm-port-serial-at.c:440] debug_log(): (ttyUSB3): --> 'AT^SQPORT?<CR>'
cannot connect to proxy: Could not connect: Connection refusedspawning new qmi-proxy (try 2)...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 28
<<<<<< data = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 27
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 16
<<<<<< message = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<< type = "Device Path" (0x01)
<<<<<< length = 13
<<<<<< value = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<< translated = /dev/cdc-wdm0
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 19
>>>>>> data = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 18
>>>>>> flags = 0x00
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 7
>>>>>> message = "Internal Proxy Open" (0xFF00)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
[/dev/cdc-wdm0] Checking version info (10 retries)...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 12
<<<<<< data = 01:0B:00:00:00:00:00:02:21:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 11
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 0
<<<<<< message = "Get Version Info" (0x0021)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 133
>>>>>> data = 01:84:00:80:00:00:01:02:21:00:79:00:02:04:00:00:00:00:00:01:6F:00:16:00:01:00:05:00:01:01:00:10:00:02:01:00:0D:00:03:01:00:19:00:04:01:00:03:00:05:01:00:08:00:06:01:00:0D:00:07:01:00:02:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:0B:00:0B:01:00:0C:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:12:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:00:00:1D:01:00:00:00:E0:02:00:0B:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 132
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 121
>>>>>> message = "Get Version Info" (0x0021)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Service list" (0x01)
>>>>>> length = 111
>>>>>> value = 16:00:01:00:05:00:01:01:00:10:00:02:01:00:0D:00:03:01:00:19:00:04:01:00:03:00:05:01:00:08:00:06:01:00:0D:00:07:01:00:02:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:0B:00:0B:01:00:0C:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:12:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:00:00:1D:01:00:00:00:E0:02:00:0B:00
>>>>>> translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '5' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '16' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '13' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '25' ] ' [4] = '[ service = 'qos' major_version = '1' minor_version = '3' ] ' [5] = '[ service = 'wms' major_version = '1' minor_version = '8' ] ' [6] = '[ service = 'pds' major_version = '1' minor_version = '13' ] ' [7] = '[ service = 'auth' major_version = '1' minor_version = '2' ] ' [8] = '[ service = 'at' major_version = '1' minor_version = '2' ] ' [9] = '[ service = 'voice' major_version = '2' minor_version = '1' ] ' [10] = '[ service = 'cat2' major_version = '2' minor_version = '11' ] ' [11] = '[ service = 'uim' major_version = '1' minor_version = '12' ] ' [12] = '[ service = 'pbm' major_version = '1' minor_version = '4' ] ' [13] = '[ service = 'test' major_version = '1' minor_version = '0' ] ' [14] = '[ service = 'loc' major_version = '2' minor_version = '0' ] ' [15] = '[ service = 'sar' major_version = '1' minor_version = '0' ] ' [16] = '[ service = 'ims' major_version = '1' minor_version = '0' ] ' [17] = '[ service = 'ts' major_version = '1' minor_version = '0' ] ' [18] = '[ service = 'tmd' major_version = '1' minor_version = '0' ] ' [19] = '[ service = 'wda' major_version = '1' minor_version = '0' ] ' [20] = '[ service = 'csvt' major_version = '1' minor_version = '0' ] ' [21] = '[ service = 'cat' major_version = '2' minor_version = '11' ] '}
[/dev/cdc-wdm0] QMI Device supports 22 services:[/dev/cdc-wdm0] ctl (1.5)[/dev/cdc-wdm0] wds (1.16)[/dev/cdc-wdm0] dms (1.13)[/dev/cdc-wdm0] nas (1.25)[/dev/cdc-wdm0] qos (1.3)[/dev/cdc-wdm0] wms (1.8)[/dev/cdc-wdm0] pds (1.13)[/dev/cdc-wdm0] auth (1.2)[/dev/cdc-wdm0] at (1.2)[/dev/cdc-wdm0] voice (2.1)[/dev/cdc-wdm0] cat2 (2.11)[/dev/cdc-wdm0] uim (1.12)[/dev/cdc-wdm0] pbm (1.4)[/dev/cdc-wdm0] test (1.0)[/dev/cdc-wdm0] loc (2.0)[/dev/cdc-wdm0] sar (1.0)[/dev/cdc-wdm0] ims (1.0)[/dev/cdc-wdm0] ts (1.0)[/dev/cdc-wdm0] tmd (1.0)[/dev/cdc-wdm0] wda (1.0)[/dev/cdc-wdm0] csvt (1.0)[/dev/cdc-wdm0] cat (2.11)<debug> [997876992.663791] [mm-port-probe.c:273] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable
<debug> [997876993.310284] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'AT^SQPORT?'
<debug> [997876993.320653] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><CR><LF>ERROR<CR><LF>'
<debug> [997876993.332506] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [997876993.344440] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [cdc-wdm0] found best plugin for port
<debug> [997876993.353347] [mm-plugin-manager.c:232] port_probe_context_finished(): (Plugin Manager) (Cinterion) [cdc-wdm0]: found best plugin for device (/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1)
<debug> [997876993.362380] [mm-plugin-manager.c:361] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [wwan0] deferred task completed, got suggested plugin
<debug> [997876993.370453] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [ttyUSB3] suggested plugin for port
<debug> [997876993.378397] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [ttyUSB2] suggested plugin for port
<debug> [997876993.387310] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [ttyUSB1] suggested plugin for port
<debug> [997876993.394941] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [ttyUSB0] suggested plugin for port
<debug> [997876993.404283] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 5 running probes in this device (wwan0, ttyUSB3, ttyUSB2, ttyUSB1, ttyUSB0)
<debug> [997876993.413745] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT<CR>'
<debug> [997876993.422342] [mm-plugin.c:689] mm_plugin_supports_port(): (Cinterion) [wwan0] probing deferred until result suggested
<debug> [997876993.430880] [mm-plugin-manager.c:549] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [wwan0] task completed, got suggested plugin
<debug> [997876993.440080] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'wwan0' port probe finished, still 4 running probes in this device (ttyUSB3, ttyUSB2, ttyUSB1, ttyUSB0)
<debug> [997876993.624475] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'AT'
<debug> [997876993.634663] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [997876993.644544] [mm-port-probe.c:154] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable
<debug> [997876993.653444] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 0 (close)
<debug> [997876993.661446] [mm-port-serial.c:1310] _close_internal(): (ttyUSB2) closing serial port...
<debug> [997876993.679180] [mm-port-serial.c:1359] _close_internal(): (ttyUSB2) serial port closed
<debug> [997876993.687228] [mm-port-serial.c:1421] port_serial_close_force(): (ttyUSB2) forced to close port
<debug> [997876993.697527] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [ttyUSB2] found best plugin for port
<debug> [997876993.706702] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB2' port probe finished, still 3 running probes in this device (ttyUSB3, ttyUSB1, ttyUSB0)
<debug> [997876995.672209] [mm-port-serial-at.c:440] debug_log(): (ttyUSB3): --> 'AT<CR>'
<debug> [997876996.174476] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
<debug> [997876996.185420] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
<debug> [997876998.615805] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Sending command failed: 'Resource temporarily unavailable''
<debug> [997876998.627964] [mm-port-serial.c:1294] _close_internal(): (ttyUSB3) device open count is 0 (close)
<debug> [997876998.640338] [mm-port-serial.c:1310] _close_internal(): (ttyUSB3) closing serial port...
<debug> [997876998.662754] [mm-port-serial.c:1359] _close_internal(): (ttyUSB3) serial port closed
<debug> [997876998.672585] [mm-port-serial.c:1421] port_serial_close_force(): (ttyUSB3) forced to close port
<debug> [997876998.682994] [mm-plugin-manager.c:480] plugin_supports_port_ready(): (Plugin Manager) [ttyUSB3] ignoring port unsupported by physical modem's plugin
<debug> [997876998.691353] [mm-plugin-manager.c:189] port_probe_context_finished(): (Plugin Manager) [ttyUSB3] assuming port can be handled by the 'Cinterion' plugin
<debug> [997876998.700472] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB3' port probe finished, still 2 running probes in this device (ttyUSB1, ttyUSB0)
<debug> [997876999.314311] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997876999.324902] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997876999.336060] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
<debug> [997876999.345928] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
<debug> [997877003.175831] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877003.199187] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877003.209260] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
<debug> [997877003.736822] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
<debug> [997877004.515352] [mm-base-manager.c:320] device_added(): (net/wlan0): port's parent platform driver is not whitelisted
<debug> [997877007.174233] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877007.245390] [mm-port-probe.c:164] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
<debug> [997877007.256267] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877007.265252] [mm-port-probe.c:164] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is not AT-capable
<debug> [997877007.276681] [mm-port-serial.c:1294] _close_internal(): (ttyUSB0) device open count is 0 (close)
<debug> [997877007.284299] [mm-port-serial.c:1310] _close_internal(): (ttyUSB0) closing serial port...
<debug> [997877007.297252] [mm-port-serial.c:1359] _close_internal(): (ttyUSB0) serial port closed
<debug> [997877007.304997] [mm-port-serial.c:1421] port_serial_close_force(): (ttyUSB0) forced to close port
<debug> [997877007.314948] [mm-port-serial.c:1294] _close_internal(): (ttyUSB1) device open count is 0 (close)
<debug> [997877007.329153] [mm-port-serial.c:1310] _close_internal(): (ttyUSB1) closing serial port...
<debug> [997877007.341663] [mm-port-serial.c:1359] _close_internal(): (ttyUSB1) serial port closed
<debug> [997877007.349564] [mm-port-serial.c:1421] port_serial_close_force(): (ttyUSB1) forced to close port
<debug> [997877007.359452] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [ttyUSB0] found best plugin for port
<debug> [997877007.367486] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, still 1 running probes in this device (ttyUSB1)
<debug> [997877007.384264] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [ttyUSB1] found best plugin for port
<debug> [997877007.410082] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyUSB1' port probe finished, last one in device
<debug> [997877007.418274] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1] device support check finished in '17.973733' seconds
<info> [997877007.427385] [mm-device.c:520] mm_device_create_modem(): Creating modem with plugin 'Cinterion' and '6' ports
<debug> [997877007.436662] [cinterion/mm-plugin-cinterion.c:142] create_modem(): QMI-powered Cinterion modem found...
<debug> [997877007.494415] [mm-base-modem.c:304] mm_base_modem_grab_port(): (cdc-wdm0) type 'qmi' claimed by /sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1
<debug> [997877007.504365] [mm-base-modem.c:304] mm_base_modem_grab_port(): (wwan0) type 'net' claimed by /sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1
<warn> [997877007.513819] [mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyUSB3): 'Cannot add port 'tty/ttyUSB3', unhandled serial type'
<debug> [997877007.523625] [mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyUSB2) type 'at' claimed by /sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1
<warn> [997877007.531925] [mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyUSB1): 'Cannot add port 'tty/ttyUSB1', unhandled serial type'
<warn> [997877007.540928] [mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyUSB0): 'Cannot add port 'tty/ttyUSB0', unhandled serial type'
<debug> [997877007.548728] [mm-base-modem.c:970] log_port(): (/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1) tty/ttyUSB2 at (primary)
<debug> [997877007.557379] [mm-base-modem.c:970] log_port(): (/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1) net/wwan0 data (primary)
<debug> [997877007.566050] [mm-base-modem.c:970] log_port(): (/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1) usb/cdc-wdm0 qmi (primary)
<info> [997877007.582150] [mm-base-manager.c:162] find_device_support_ready(): Modem for device at '/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1' successfully created
[/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 28
<<<<<< data = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 27
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 16
<<<<<< message = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<< type = "Device Path" (0x01)
<<<<<< length = 13
<<<<<< value = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<< translated = /dev/cdc-wdm0
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 19
>>>>>> data = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 18
>>>>>> flags = 0x00
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 7
>>>>>> message = "Internal Proxy Open" (0xFF00)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
[/dev/cdc-wdm0] Checking version info (10 retries)...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 12
<<<<<< data = 01:0B:00:00:00:00:00:02:21:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 11
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 0
<<<<<< message = "Get Version Info" (0x0021)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 133
>>>>>> data = 01:84:00:80:00:00:01:02:21:00:79:00:02:04:00:00:00:00:00:01:6F:00:16:00:01:00:05:00:01:01:00:10:00:02:01:00:0D:00:03:01:00:19:00:04:01:00:03:00:05:01:00:08:00:06:01:00:0D:00:07:01:00:02:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:0B:00:0B:01:00:0C:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:12:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:00:00:1D:01:00:00:00:E0:02:00:0B:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 132
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 121
>>>>>> message = "Get Version Info" (0x0021)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Service list" (0x01)
>>>>>> length = 111
>>>>>> value = 16:00:01:00:05:00:01:01:00:10:00:02:01:00:0D:00:03:01:00:19:00:04:01:00:03:00:05:01:00:08:00:06:01:00:0D:00:07:01:00:02:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:0B:00:0B:01:00:0C:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:12:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:00:00:1D:01:00:00:00:E0:02:00:0B:00
>>>>>> translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '5' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '16' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '13' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '25' ] ' [4] = '[ service = 'qos' major_version = '1' minor_version = '3' ] ' [5] = '[ service = 'wms' major_version = '1' minor_version = '8' ] ' [6] = '[ service = 'pds' major_version = '1' minor_version = '13' ] ' [7] = '[ service = 'auth' major_version = '1' minor_version = '2' ] ' [8] = '[ service = 'at' major_version = '1' minor_version = '2' ] ' [9] = '[ service = 'voice' major_version = '2' minor_version = '1' ] ' [10] = '[ service = 'cat2' major_version = '2' minor_version = '11' ] ' [11] = '[ service = 'uim' major_version = '1' minor_version = '12' ] ' [12] = '[ service = 'pbm' major_version = '1' minor_version = '4' ] ' [13] = '[ service = 'test' major_version = '1' minor_version = '0' ] ' [14] = '[ service = 'loc' major_version = '2' minor_version = '0' ] ' [15] = '[ service = 'sar' major_version = '1' minor_version = '0' ] ' [16] = '[ service = 'ims' major_version = '1' minor_version = '0' ] ' [17] = '[ service = 'ts' major_version = '1' minor_version = '0' ] ' [18] = '[ service = 'tmd' major_version = '1' minor_version = '0' ] ' [19] = '[ service = 'wda' major_version = '1' minor_version = '0' ] ' [20] = '[ service = 'csvt' major_version = '1' minor_version = '0' ] ' [21] = '[ service = 'cat' major_version = '2' minor_version = '11' ] '}
[/dev/cdc-wdm0] QMI Device supports 22 services:[/dev/cdc-wdm0] ctl (1.5)[/dev/cdc-wdm0] wds (1.16)[/dev/cdc-wdm0] dms (1.13)[/dev/cdc-wdm0] nas (1.25)[/dev/cdc-wdm0] qos (1.3)[/dev/cdc-wdm0] wms (1.8)[/dev/cdc-wdm0] pds (1.13)[/dev/cdc-wdm0] auth (1.2)[/dev/cdc-wdm0] at (1.2)[/dev/cdc-wdm0] voice (2.1)[/dev/cdc-wdm0] cat2 (2.11)[/dev/cdc-wdm0] uim (1.12)[/dev/cdc-wdm0] pbm (1.4)[/dev/cdc-wdm0] test (1.0)[/dev/cdc-wdm0] loc (2.0)[/dev/cdc-wdm0] sar (1.0)[/dev/cdc-wdm0] ims (1.0)[/dev/cdc-wdm0] ts (1.0)[/dev/cdc-wdm0] tmd (1.0)[/dev/cdc-wdm0] wda (1.0)[/dev/cdc-wdm0] csvt (1.0)[/dev/cdc-wdm0] cat (2.11)[/dev/cdc-wdm0] Setting network port data format...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 21
<<<<<< data = 01:14:00:00:00:00:00:03:26:00:09:00:10:02:00:01:00:01:01:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 20
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 3
<<<<<< tlv_length = 9
<<<<<< message = "Set Data Format" (0x0026)
<<<<<< TLV:
<<<<<< type = "Protocol" (0x10)
<<<<<< length = 2
<<<<<< value = 01:00
<<<<<< translated = 802-3
<<<<<< TLV:
<<<<<< type = "Format" (0x01)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = absent
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 28
>>>>>> data = 01:1B:00:80:00:00:01:03:26:00:10:00:02:04:00:00:00:00:00:12:01:00:00:10:02:00:01:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 27
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 3
>>>>>> tlv_length = 16
>>>>>> message = "Set Data Format" (0x0026)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = 0x12
>>>>>> length = 1
>>>>>> value = 00
>>>>>> TLV:
>>>>>> type = "Protocol" (0x10)
>>>>>> length = 2
>>>>>> value = 01:00
>>>>>> translated = 802-3
[/dev/cdc-wdm0] Network port data format operation finished[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:04:22:00:04:00:01:01:00:02
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 4
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 02
<<<<<< translated = dms
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:04:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 4
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 02:01
>>>>>> translated = [ service = 'dms' cid = '1' ]
[/dev/cdc-wdm0] Registered 'dms' (version 1.13) client with ID '1'[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:05:22:00:04:00:01:01:00:03
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 5
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 03
<<<<<< translated = nas
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:05:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:03:01
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 5
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 03:01
>>>>>> translated = [ service = 'nas' cid = '1' ]
[/dev/cdc-wdm0] Registered 'nas' (version 1.25) client with ID '1'[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:06:22:00:04:00:01:01:00:05
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 6
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 05
<<<<<< translated = wms
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:06:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:05:01
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 6
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 05:01
>>>>>> translated = [ service = 'wms' cid = '1' ]
[/dev/cdc-wdm0] Registered 'wms' (version 1.8) client with ID '1'[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:07:22:00:04:00:01:01:00:06
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 7
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 06
<<<<<< translated = pds
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:07:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:06:01
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 7
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 06:01
>>>>>> translated = [ service = 'pds' cid = '1' ]
[/dev/cdc-wdm0] Registered 'pds' (version 1.13) client with ID '1'<debug> [997877007.866327] [mm-broadband-modem-qmi.c:10206] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'oma': Couldn't create client for service 'oma': Service 'oma' not supported by the device
<debug> [997877007.947456] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
<warn> [997877007.959579] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
<debug> [997877007.967680] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
<debug> [997877007.976467] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
<debug> [997877007.985355] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
<debug> [997877007.993832] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 18
>>>>>> data = 01:11:00:80:06:FF:04:00:00:20:00:05:00:01:02:00:01:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 17
>>>>>> flags = 0x80
>>>>>> service = "pds"
>>>>>> client = 255
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 5
>>>>>> message = (0x0020)
>>>>>> TLV:
>>>>>> type = 0x01
>>>>>> length = 2
>>>>>> value = 01:00
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 13
>>>>>> data = 01:0C:00:80:06:FF:04:00:00:60:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 12
>>>>>> flags = 0x80
>>>>>> service = "pds"
>>>>>> client = 255
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 0
>>>>>> message = (0x0060)
<debug> [997877008.039137] [mm-broadband-modem-qmi.c:531] modem_load_current_capabilities(): loading current capabilities...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:03:01:00:01:00:34:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 0
<<<<<< message = "Get System Selection Preference" (0x0034)
<debug> [997877008.052942] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
<debug> [997877008.070741] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'ATE0<CR><CR><LF>OK<CR><LF>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 83
>>>>>> data = 01:52:00:80:03:01:02:01:00:34:00:46:00:02:04:00:00:00:00:00:10:01:00:00:11:02:00:1C:00:12:08:00:80:03:40:01:00:00:02:00:14:02:00:FF:00:15:08:00:C4:00:08:00:00:00:00:00:16:01:00:00:18:04:00:02:00:00:00:19:04:00:00:00:00:00:1C:06:00:05:08:05:04:01:02
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 82
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 70
>>>>>> message = "Get System Selection Preference" (0x0034)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Emergency mode" (0x10)
>>>>>> length = 1
>>>>>> value = 00
>>>>>> translated = no
>>>>>> TLV:
>>>>>> type = "Mode Preference" (0x11)
>>>>>> length = 2
>>>>>> value = 1C:00
>>>>>> translated = gsm, umts, lte
>>>>>> TLV:
>>>>>> type = "Band Preference" (0x12)
>>>>>> length = 8
>>>>>> value = 80:03:40:01:00:00:02:00
>>>>>> translated = gsm-dcs-1800, gsm-900-extended, gsm-900-primary, wcdma-2100, wcdma-dcs-1800, bc-15
>>>>>> TLV:
>>>>>> type = "Roaming Preference" (0x14)
>>>>>> length = 2
>>>>>> value = FF:00
>>>>>> translated = any
>>>>>> TLV:
>>>>>> type = "LTE Band Preference" (0x15)
>>>>>> length = 8
>>>>>> value = C4:00:08:00:00:00:00:00
>>>>>> translated = 3, 7, 8, 20
>>>>>> TLV:
>>>>>> type = "Network Selection Preference" (0x16)
>>>>>> length = 1
>>>>>> value = 00
>>>>>> translated = automatic
>>>>>> TLV:
>>>>>> type = "Service Domain Preference" (0x18)
>>>>>> length = 4
>>>>>> value = 02:00:00:00
>>>>>> translated = cs-ps
>>>>>> TLV:
>>>>>> type = "GSM WCDMA Acquisition Order Preference" (0x19)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = automatic
>>>>>> TLV:
>>>>>> type = 0x1c
>>>>>> length = 6
>>>>>> value = 05:08:05:04:01:02
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:03:01:00:02:00:2B:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 0
<<<<<< message = "Get Technology Preference" (0x002B)
<debug> [997877008.101878] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 26
>>>>>> data = 01:19:00:80:03:01:02:02:00:2B:00:0D:00:01:03:00:00:00:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 25
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 13
>>>>>> message = "Get Technology Preference" (0x002B)
>>>>>> TLV:
>>>>>> type = "Active" (0x01)
>>>>>> length = 3
>>>>>> value = 00:00:00
>>>>>> translated = [ technology_preference = 'auto' technology_preference_duration = 'permanent' ]
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:01:00:20:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 0
<<<<<< message = "Get Capabilities" (0x0020)
<debug> [997877008.155889] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997877008.165142] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 59
>>>>>> data = 01:3A:00:80:02:01:02:01:00:20:00:2E:00:02:04:00:00:00:00:00:01:0E:00:80:F0:FA:02:00:E1:F5:05:04:02:03:04:05:08:11:08:00:03:00:00:00:00:00:00:00:12:08:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 58
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 46
>>>>>> message = "Get Capabilities" (0x0020)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Info" (0x01)
>>>>>> length = 14
>>>>>> value = 80:F0:FA:02:00:E1:F5:05:04:02:03:04:05:08
>>>>>> translated = [ max_tx_channel_rate = '50000000' max_rx_channel_rate = '100000000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'gsm ' [1] = 'umts ' [2] = 'lte '}' ]
>>>>>> TLV:
>>>>>> type = 0x11
>>>>>> length = 8
>>>>>> value = 03:00:00:00:00:00:00:00
>>>>>> TLV:
>>>>>> type = 0x12
>>>>>> length = 8
>>>>>> value = 00:00:00:00:00:00:00:00
<debug> [997877008.194966] [mm-modem-helpers-qmi.c:1268] mm_modem_capability_from_qmi_capabilities_context(): Current capabilities built: 'gsm-umts, lte'
SSP mode preference: 'gsm, umts, lte'
TP: 'auto'
DMS Capabilities: 'gsm-umts, lte'
<debug> [997877008.207280] [mm-broadband-modem-qmi.c:693] modem_load_supported_capabilities(): loading supported capabilities...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:02:00:20:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 0
<<<<<< message = "Get Capabilities" (0x0020)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 59
>>>>>> data = 01:3A:00:80:02:01:02:02:00:20:00:2E:00:02:04:00:00:00:00:00:01:0E:00:80:F0:FA:02:00:E1:F5:05:04:02:03:04:05:08:11:08:00:03:00:00:00:00:00:00:00:12:08:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 58
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 46
>>>>>> message = "Get Capabilities" (0x0020)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Info" (0x01)
>>>>>> length = 14
>>>>>> value = 80:F0:FA:02:00:E1:F5:05:04:02:03:04:05:08
>>>>>> translated = [ max_tx_channel_rate = '50000000' max_rx_channel_rate = '100000000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'gsm ' [1] = 'umts ' [2] = 'lte '}' ]
>>>>>> TLV:
>>>>>> type = 0x11
>>>>>> length = 8
>>>>>> value = 03:00:00:00:00:00:00:00
>>>>>> TLV:
>>>>>> type = 0x12
>>>>>> length = 8
>>>>>> value = 00:00:00:00:00:00:00:00
<debug> [997877008.248489] [mm-iface-modem.c:4311] interface_initialization_step(): Modem allows up to 1 bearers
<debug> [997877008.256336] [mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
<debug> [997877008.266052] [mm-broadband-modem-qmi.c:995] modem_load_manufacturer(): loading manufacturer...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:03:00:21:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 3
<<<<<< tlv_length = 0
<<<<<< message = "Get Manufacturer" (0x0021)
<debug> [997877008.283382] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997877008.292611] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 44
>>>>>> data = 01:2B:00:80:02:01:02:03:00:21:00:1F:00:02:04:00:00:00:00:00:01:15:00:51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 43
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 3
>>>>>> tlv_length = 31
>>>>>> message = "Get Manufacturer" (0x0021)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Manufacturer" (0x01)
>>>>>> length = 21
>>>>>> value = 51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44
>>>>>> translated = QUALCOMM INCORPORATED
<debug> [997877008.311723] [mm-broadband-modem-qmi.c:942] modem_load_manufacturer_finish(): loaded manufacturer: QUALCOMM INCORPORATED
<debug> [997877008.321238] [mm-broadband-modem-qmi.c:1071] modem_load_model(): loading model...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:04:00:22:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 4
<<<<<< tlv_length = 0
<<<<<< message = "Get Model" (0x0022)
<debug> [997877008.338515] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
<debug> [997877008.372446] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 11: SIM PIN required
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:02:01:02:04:00:22:00:0B:00:02:04:00:00:00:00:00:01:01:00:30
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 4
>>>>>> tlv_length = 11
>>>>>> message = "Get Model" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Model" (0x01)
>>>>>> length = 1
>>>>>> value = 30
>>>>>> translated = 0
<debug> [997877008.383959] [mm-broadband-modem-qmi.c:1018] modem_load_model_finish(): loaded model: 0
<debug> [997877008.392212] [mm-broadband-modem-qmi.c:1147] modem_load_revision(): loading revision...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:05:00:23:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 5
<<<<<< tlv_length = 0
<<<<<< message = "Get Revision" (0x0023)
<debug> [997877008.406524] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
<debug> [997877008.429242] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
<debug> [997877008.438210] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 11: SIM PIN required
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 75
>>>>>> data = 01:4A:00:80:02:01:02:05:00:23:00:3E:00:02:04:00:00:00:00:00:01:34:00:4D:39:36:31:35:41:2D:43:45:54:57:4D:41:5A:4D:2D:32:2E:30:2E:31:39:30:33:30:20:20:31:20:20:5B:4A:75:6C:20:30:38:20:32:30:31:33:20:30:32:3A:30:30:3A:30:30:5D
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 74
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 5
>>>>>> tlv_length = 62
>>>>>> message = "Get Revision" (0x0023)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Revision" (0x01)
>>>>>> length = 52
>>>>>> value = 4D:39:36:31:35:41:2D:43:45:54:57:4D:41:5A:4D:2D:32:2E:30:2E:31:39:30:33:30:20:20:31:20:20:5B:4A:75:6C:20:30:38:20:32:30:31:33:20:30:32:3A:30:30:3A:30:30:5D
>>>>>> translated = M9615A-CETWMAZM-2.0.19030 1 [Jul 08 2013 02:00:00]
<debug> [997877008.452014] [mm-broadband-modem-qmi.c:1094] modem_load_revision_finish(): loaded revision: M9615A-CETWMAZM-2.0.19030 1 [Jul 08 2013 02:00:00]
<debug> [997877008.460213] [mm-broadband-modem-qmi.c:1289] modem_load_equipment_identifier(): loading equipment identifier...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:06:00:25:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 6
<<<<<< tlv_length = 0
<<<<<< message = "Get IDs" (0x0025)
<debug> [997877008.986173] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
<debug> [997877009.008144] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997877009.019411] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 2 (close)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 46
>>>>>> data = 01:2D:00:80:02:01:02:06:00:25:00:21:00:02:04:00:00:00:00:00:13:01:00:31:10:01:00:30:11:0F:00:33:35:38:37:30:39:30:35:30:30:31:31:36:33:38
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 45
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 6
>>>>>> tlv_length = 33
>>>>>> message = "Get IDs" (0x0025)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = 0x13
>>>>>> length = 1
>>>>>> value = 31
>>>>>> TLV:
>>>>>> type = "Esn" (0x10)
>>>>>> length = 1
>>>>>> value = 30
>>>>>> translated = 0
>>>>>> TLV:
>>>>>> type = "Imei" (0x11)
>>>>>> length = 15
>>>>>> value = 33:35:38:37:30:39:30:35:30:30:31:31:36:33:38
>>>>>> translated = 358709050011638
<debug> [997877009.031856] [mm-broadband-modem-qmi.c:1186] modem_load_equipment_identifier_finish(): loaded equipment identifier: 358709050011638
<debug> [997877009.071961] [mm-broadband-modem-qmi.c:1324] modem_load_device_identifier(): loading device identifier...
<debug> [997877009.082547] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '00001e2d00000060358709050011638M9615A-CETWMAZM-2.0.190301[Jul08201302:00:00]0QUALCOMMINCORPORATED'
<debug> [997877009.090300] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '6c38cc8aee85cabc91529a6661ae83b27933885c'
<debug> [997877009.100044] [mm-broadband-modem-qmi.c:1312] modem_load_device_identifier_finish(): loaded device identifier: 6c38cc8aee85cabc91529a6661ae83b27933885c
<debug> [997877009.110650] [mm-broadband-modem-qmi.c:1727] modem_load_supported_bands(): loading band capabilities...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:07:00:45:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 7
<<<<<< tlv_length = 0
<<<<<< message = "Get Band Capabilities" (0x0045)
<debug> [997877009.125429] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 42
>>>>>> data = 01:29:00:80:02:01:02:07:00:45:00:1D:00:02:04:00:00:00:00:00:10:08:00:C5:00:08:00:00:00:00:00:01:08:00:80:03:40:01:00:00:02:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 41
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 7
>>>>>> tlv_length = 29
>>>>>> message = "Get Band Capabilities" (0x0045)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "LTE Band Capability" (0x10)
>>>>>> length = 8
>>>>>> value = C5:00:08:00:00:00:00:00
>>>>>> translated = 1, 3, 7, 8, 20
>>>>>> TLV:
>>>>>> type = "Band Capability" (0x01)
>>>>>> length = 8
>>>>>> value = 80:03:40:01:00:00:02:00
>>>>>> translated = gsm-dcs-1800, gsm-900-extended, gsm-900-primary, wcdma-2100, wcdma-dcs-1800, bc-15
<debug> [997877009.657265] [mm-broadband-modem-qmi.c:2781] load_power_state(): Getting device operating mode...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:08:00:2D:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 8
<<<<<< tlv_length = 0
<<<<<< message = "Get Operating Mode" (0x002D)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:02:01:02:08:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 8
>>>>>> tlv_length = 11
>>>>>> message = "Get Operating Mode" (0x002D)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Mode" (0x01)
>>>>>> length = 1
>>>>>> value = 00
>>>>>> translated = online
<debug> [997877009.689893] [mm-broadband-modem-qmi.c:1533] modem_load_unlock_required(): loading unlock required...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:09:00:2B:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 9
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get PIN Status" (0x002B)
<debug> [997877009.737278] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997877009.747955] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 1 (close)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:02:01:02:09:00:2B:00:07:00:02:04:00:01:00:25:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 9
>>>>>> tlv_length = 7
>>>>>> message = "UIM Get PIN Status" (0x002B)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:25:00
>>>>>> translated = FAILURE: UimUninitialized
<debug> [997877009.763385] [mm-iface-modem.c:266] load_unlock_required_ready(): Couldn't check if unlock required: 'SIM failure: QMI protocol error (37): 'UimUninitialized''
<warn> [997877009.780167] [mm-broadband-modem.c:8912] iface_modem_initialize_ready(): Modem couldn't be initialized: Couldn't check unlock status: SIM failure: QMI protocol error (37): 'UimUninitialized'
<info> [997877009.788490] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem: state changed (unknown -> failed)
<debug> [997877009.802060] [mm-broadband-modem-qmi.c:9301] firmware_check_support(): loading firmware images...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:0A:00:49:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 10
<<<<<< tlv_length = 0
<<<<<< message = "List Stored Images" (0x0049)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:02:01:02:0A:00:49:00:07:00:02:04:00:01:00:47:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 10
>>>>>> tlv_length = 7
>>>>>> message = "List Stored Images" (0x0049)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:47:00
>>>>>> translated = FAILURE: InvalidQmiCommand
<debug> [997877009.846668] [mm-broadband-modem.c:9005] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
<debug> [997877009.860844] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 0 (close)
<debug> [997877009.868903] [mm-port-serial.c:1310] _close_internal(): (ttyUSB2) closing serial port...
<debug> [997877009.881904] [mm-port-serial.c:1359] _close_internal(): (ttyUSB2) serial port closed
<debug> [997877009.889941] [mm-base-modem.c:950] initialize_ready(): Couldn't finish initialization in the current state: 'Modem is unusable, cannot fully initialize'
<debug> [997877009.949865] [mm-device.c:447] export_modem(): Exported modem '/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1' at path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [997877010.018278] [mm-device.c:457] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Cinterion' modem, VID 0x1E2D PID 0x0060 (usb)
<warn> [997877686.935992] [main.c:101] name_lost_cb(): Could not acquire the 'org.freedesktop.ModemManager1' service name
<debug> [997877687.039455] [mm-base-manager.c:816] set_property(): Stopping connection in object manager server
<info> [997877687.273494] [main.c:42] quit_cb(): Caught signal, shutting down...
<info> [997877855.160540] [main.c:136] main(): ModemManager (version 1.4.8) starting in system bus...
<warn> [997877855.871327] [main.c:101] name_lost_cb(): Could not acquire the 'org.freedesktop.ModemManager1' service name
<info> [997877855.889686] [main.c:187] main(): ModemManager is shut down
<info> [997877865.983985] [main.c:136] main(): ModemManager (version 1.4.8) starting in system bus...
<debug> [997877866.184381] [main.c:61] bus_acquired_cb(): Bus acquired, creating manager...
<debug> [997877866.219019] [mm-plugin-manager.c:852] load_plugins(): Looking for plugins in '/usr/lib/ModemManager'
<debug> [997877866.292159] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Samsung'
<debug> [997877866.322570] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Linktop'
<debug> [997877866.341254] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Via CBP7'
<debug> [997877866.375774] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'ZTE'
<debug> [997877866.417293] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Huawei'
<debug> [997877866.460910] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Longcheer'
<debug> [997877866.523433] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option'
<debug> [997877866.549652] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Sierra'
<debug> [997877866.598145] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel LTE'
<debug> [997877866.638999] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option High-Speed'
<debug> [997877866.679131] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Telit'
<debug> [997877866.718412] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Wavecom'
<debug> [997877866.765614] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Ericsson MBM'
<debug> [997877866.788498] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'AnyDATA'
<debug> [997877866.831148] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Pantech'
<debug> [997877866.868805] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Generic'
<debug> [997877866.924507] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'X22X'
<debug> [997877866.959213] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Gobi'
<debug> [997877866.980933] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Altair LTE'
<debug> [997877867.038507] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'MTK'
<debug> [997877867.087146] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia (Icera)'
<debug> [997877867.107245] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel'
<debug> [997877867.168396] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Cinterion'
<debug> [997877867.303509] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Motorola'
<debug> [997877867.336240] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia'
<debug> [997877867.369350] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'SimTech'
<debug> [997877867.400129] [mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Iridium'
<debug> [997877867.423855] [mm-plugin-manager.c:902] load_plugins(): Successfully loaded 27 plugins
<debug> [997877867.472522] [main.c:83] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
<debug> [997877867.562472] [mm-base-manager.c:485] mm_base_manager_start(): Starting automatic device scan...
<debug> [997877868.137390] [mm-base-manager.c:524] mm_base_manager_start(): Finished device scan...
<debug> [997877868.200737] [mm-base-manager.c:320] device_added(): (tty/ttyAPP0): port's parent platform driver is not whitelisted
<debug> [997877868.274663] [mm-base-manager.c:320] device_added(): (tty/ttyAPP1): port's parent platform driver is not whitelisted
<debug> [997877868.310690] [mm-base-manager.c:320] device_added(): (tty/ttyAPP3): port's parent platform driver is not whitelisted
<debug> [997877868.347842] [mm-base-manager.c:320] device_added(): (tty/ttyAMA0): port's parent platform driver is not whitelisted
<debug> [997877868.424165] [mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1] Checking device support...
<debug> [997877868.438632] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.454038] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.462461] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.470480] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.479848] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.487854] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.497320] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB0] filtered by drivers
<debug> [997877868.506457] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.514353] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers
<debug> [997877868.522417] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.531843] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.539862] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags
<debug> [997877868.548884] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.559644] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.567667] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.576195] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB0] filtered by drivers
<debug> [997877868.585327] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.593700] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB0] filtered by udev tags
<debug> [997877868.602848] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.612265] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.620431] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.628552] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by vendor/product IDs
<debug> [997877868.638131] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB0] Found '5' plugins to try...
<debug> [997877868.646605] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Cinterion'
<debug> [997877868.656091] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Via CBP7'
<debug> [997877868.732538] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Nokia'
<debug> [997877868.740551] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Iridium'
<debug> [997877868.748962] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB0] Will try with plugin 'Generic'
<debug> [997877868.768721] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB0] probe required: 'at'
<debug> [997877868.777742] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at'
<debug> [997877868.840461] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.858978] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.867354] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.875549] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.892082] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.900069] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.909303] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB1] filtered by drivers
<debug> [997877868.918769] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.929242] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB1] filtered by drivers
<debug> [997877868.937558] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.947660] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.955639] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB1] filtered by udev tags
<debug> [997877868.964345] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.973320] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.980774] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877868.988538] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB1] filtered by drivers
<debug> [997877868.997350] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877869.004945] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB1] filtered by udev tags
<debug> [997877869.013689] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877869.022526] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877869.030018] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877869.037580] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB1] filtered by vendor/product IDs
<debug> [997877869.046231] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB1] Found '5' plugins to try...
<debug> [997877869.053687] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Cinterion'
<debug> [997877869.062344] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Via CBP7'
<debug> [997877869.070853] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Nokia'
<debug> [997877869.078616] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Iridium'
<debug> [997877869.086317] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB1] Will try with plugin 'Generic'
<debug> [997877869.171062] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB1] probe required: 'at'
<debug> [997877869.179013] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at'
<debug> [997877869.205723] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.214533] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.222364] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.229842] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.238478] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.246201] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.254850] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB2] filtered by drivers
<debug> [997877869.263694] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.271142] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB2] filtered by drivers
<debug> [997877869.278989] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.287828] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.295535] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB2] filtered by udev tags
<debug> [997877869.304216] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.312910] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.320305] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.327829] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB2] filtered by drivers
<debug> [997877869.336370] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.343909] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB2] filtered by udev tags
<debug> [997877869.352447] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.361066] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.368790] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.376619] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB2] filtered by vendor/product IDs
<debug> [997877869.385367] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB2] Found '5' plugins to try...
<debug> [997877869.392826] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Cinterion'
<debug> [997877869.401570] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Via CBP7'
<debug> [997877869.410239] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Nokia'
<debug> [997877869.417759] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Iridium'
<debug> [997877869.425195] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB2] Will try with plugin 'Generic'
<debug> [997877869.433999] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB2] probe required: 'at'
<debug> [997877869.441914] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at'
<debug> [997877869.468690] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877869.550938] [mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877869.559264] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877869.566869] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877869.575428] [mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877869.582887] [mm-plugin.c:326] apply_pre_probing_filters(): (Option) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.112765] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyUSB3] filtered by drivers
<debug> [997877870.174158] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.183215] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB3] filtered by drivers
<debug> [997877870.192040] [mm-plugin.c:326] apply_pre_probing_filters(): (Telit) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.199584] [mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.207147] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB3] filtered by udev tags
<debug> [997877870.216888] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.224382] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.231927] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.240627] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [ttyUSB3] filtered by drivers
<debug> [997877870.248367] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.256167] [mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyUSB3] filtered by udev tags
<debug> [997877870.266061] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.273845] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.349810] [mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.358798] [mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyUSB3] filtered by vendor/product IDs
<debug> [997877870.367707] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyUSB3] Found '5' plugins to try...
<debug> [997877870.375113] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Cinterion'
<debug> [997877870.383744] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Via CBP7'
<debug> [997877870.392365] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Nokia'
<debug> [997877870.399867] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Iridium'
<debug> [997877870.407295] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyUSB3] Will try with plugin 'Generic'
<debug> [997877870.416156] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [ttyUSB3] probe required: 'at'
<debug> [997877870.423912] [mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyUSB3) launching port probing: 'at'
<debug> [997877870.498376] [mm-base-manager.c:320] device_added(): (net/can0): port's parent platform driver is not whitelisted
<debug> [997877870.521893] [mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [wwan0] filtered by vendor/product IDs
<debug> [997877870.529480] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wwan0] filtered by subsystem
<debug> [997877870.537275] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwan0] filtered by subsystem
<debug> [997877870.554453] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [wwan0] filtered by vendor/product IDs
<debug> [997877870.562064] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [wwan0] filtered by vendor/product IDs
<debug> [997877870.570772] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwan0] filtered by subsystem
<debug> [997877870.579507] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wwan0] filtered by subsystem
<debug> [997877870.587078] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [wwan0] filtered by drivers
<debug> [997877870.594710] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [wwan0] filtered by vendor/product IDs
<debug> [997877870.603450] [mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [wwan0] filtered by drivers
<debug> [997877870.610971] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wwan0] filtered by subsystem
<debug> [997877870.619893] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwan0] filtered by subsystem
<debug> [997877870.628543] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [wwan0] filtered by udev tags
<debug> [997877870.636194] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [wwan0] filtered by vendor/product IDs
<debug> [997877870.643704] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [wwan0] filtered by vendor/product IDs
<debug> [997877870.652408] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [wwan0] filtered by vendor/product IDs
<debug> [997877870.659813] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [wwan0] filtered by drivers
<debug> [997877870.668382] [mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [wwan0] filtered by vendor/product IDs
<debug> [997877870.677244] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wwan0] filtered by subsystem
<debug> [997877870.684792] [mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [wwan0] filtered by vendor/product IDs
<debug> [997877870.692283] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [wwan0] filtered by vendor/product IDs
<debug> [997877870.700928] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wwan0] filtered by subsystem
<debug> [997877870.708709] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wwan0] filtered by subsystem
<debug> [997877870.717407] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [wwan0] filtered by subsystem
<debug> [997877870.726084] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wwan0] filtered by subsystem
<debug> [997877870.733798] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [wwan0] Found '2' plugins to try...
<debug> [997877870.741228] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0] Will try with plugin 'Cinterion'
<debug> [997877870.749967] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [wwan0] Will try with plugin 'Generic'
<debug> [997877870.757642] [mm-plugin.c:689] mm_plugin_supports_port(): (Cinterion) [wwan0] probing deferred until result suggested
<debug> [997877870.773858] [mm-base-manager.c:320] device_added(): (net/eth0): port's parent platform driver is not whitelisted
<debug> [997877870.790787] [mm-base-manager.c:296] device_added(): (net/sit0): could not get port's parent device
<debug> [997877870.803346] [mm-base-manager.c:296] device_added(): (net/tunl0): could not get port's parent device
<debug> [997877870.825211] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem
<debug> [997877870.834195] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem
<debug> [997877870.841966] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
<debug> [997877870.850505] [mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997877870.859094] [mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997877870.943433] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
<debug> [997877870.950920] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem
<debug> [997877870.958723] [mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [cdc-wdm0] filtered by drivers
<debug> [997877870.967619] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem
<debug> [997877870.976425] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem
<debug> [997877870.983960] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem
<debug> [997877870.993537] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem
<debug> [997877871.001018] [mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags
<debug> [997877871.009820] [mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997877871.018547] [mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997877871.027058] [mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997877871.034474] [mm-plugin.c:253] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers
<debug> [997877871.043370] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
<debug> [997877871.050877] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [cdc-wdm0] filtered by subsystem
<debug> [997877871.058616] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem
<debug> [997877871.067487] [mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by vendor/product IDs
<debug> [997877871.076122] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem
<debug> [997877871.084083] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem
<debug> [997877871.092687] [mm-plugin.c:212] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by subsystem
<debug> [997877871.100393] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem
<debug> [997877871.108000] [mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try...
<debug> [997877871.116689] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Cinterion'
<debug> [997877871.125331] [mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Generic'
<debug> [997877871.133080] [mm-plugin.c:767] mm_plugin_supports_port(): (Cinterion) [cdc-wdm0] probe required: 'qmi'
<debug> [997877871.142012] [mm-port-probe.c:1368] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'qmi'
<debug> [997877871.149880] [mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1] Minimum probing time consumed
<debug> [997877871.157699] [mm-plugin-manager.c:569] plugin_supports_port_ready(): (Plugin Manager) [wwan0] deferring support check until result suggested
<debug> [997877871.175366] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB0) opening serial port...
<warn> [997877871.192828] [mm-port-serial.c:515] real_config_fd(): (ttyUSB0): port attributes not fully set
<debug> [997877871.201313] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
<debug> [997877871.214014] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB1) opening serial port...
<warn> [997877871.225343] [mm-port-serial.c:515] real_config_fd(): (ttyUSB1): port attributes not fully set
<debug> [997877871.233423] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
<debug> [997877871.321025] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
<warn> [997877871.333478] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
<debug> [997877871.341847] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
<debug> [997877871.353993] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB3) opening serial port...
<warn> [997877871.365106] [mm-port-serial.c:515] real_config_fd(): (ttyUSB3): port attributes not fully set
<debug> [997877871.373258] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB3) device open count is 1 (open)
<debug> [997877871.383259] [mm-port-probe.c:458] wdm_probe_qmi(): (usbmisc/cdc-wdm0) probing QMI...
[/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...cannot connect to proxy: Could not connect: Connection refusedspawning new qmi-proxy (try 1)...<debug> [997877871.514984] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT^SQPORT?<CR>'
<debug> [997877871.531017] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT^SQPORT?<CR>'
<debug> [997877871.542729] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT^SQPORT?<CR>'
<debug> [997877871.553425] [mm-port-serial-at.c:440] debug_log(): (ttyUSB3): --> 'AT^SQPORT?<CR>'
cannot connect to proxy: Could not connect: Connection refusedspawning new qmi-proxy (try 2)...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 28
<<<<<< data = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 27
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 16
<<<<<< message = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<< type = "Device Path" (0x01)
<<<<<< length = 13
<<<<<< value = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<< translated = /dev/cdc-wdm0
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 19
>>>>>> data = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 18
>>>>>> flags = 0x00
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 7
>>>>>> message = "Internal Proxy Open" (0xFF00)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
[/dev/cdc-wdm0] Checking version info (10 retries)...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 12
<<<<<< data = 01:0B:00:00:00:00:00:02:21:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 11
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 0
<<<<<< message = "Get Version Info" (0x0021)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 133
>>>>>> data = 01:84:00:80:00:00:01:02:21:00:79:00:02:04:00:00:00:00:00:01:6F:00:16:00:01:00:05:00:01:01:00:10:00:02:01:00:0D:00:03:01:00:19:00:04:01:00:03:00:05:01:00:08:00:06:01:00:0D:00:07:01:00:02:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:0B:00:0B:01:00:0C:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:12:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:00:00:1D:01:00:00:00:E0:02:00:0B:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 132
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 121
>>>>>> message = "Get Version Info" (0x0021)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Service list" (0x01)
>>>>>> length = 111
>>>>>> value = 16:00:01:00:05:00:01:01:00:10:00:02:01:00:0D:00:03:01:00:19:00:04:01:00:03:00:05:01:00:08:00:06:01:00:0D:00:07:01:00:02:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:0B:00:0B:01:00:0C:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:12:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:00:00:1D:01:00:00:00:E0:02:00:0B:00
>>>>>> translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '5' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '16' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '13' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '25' ] ' [4] = '[ service = 'qos' major_version = '1' minor_version = '3' ] ' [5] = '[ service = 'wms' major_version = '1' minor_version = '8' ] ' [6] = '[ service = 'pds' major_version = '1' minor_version = '13' ] ' [7] = '[ service = 'auth' major_version = '1' minor_version = '2' ] ' [8] = '[ service = 'at' major_version = '1' minor_version = '2' ] ' [9] = '[ service = 'voice' major_version = '2' minor_version = '1' ] ' [10] = '[ service = 'cat2' major_version = '2' minor_version = '11' ] ' [11] = '[ service = 'uim' major_version = '1' minor_version = '12' ] ' [12] = '[ service = 'pbm' major_version = '1' minor_version = '4' ] ' [13] = '[ service = 'test' major_version = '1' minor_version = '0' ] ' [14] = '[ service = 'loc' major_version = '2' minor_version = '0' ] ' [15] = '[ service = 'sar' major_version = '1' minor_version = '0' ] ' [16] = '[ service = 'ims' major_version = '1' minor_version = '0' ] ' [17] = '[ service = 'ts' major_version = '1' minor_version = '0' ] ' [18] = '[ service = 'tmd' major_version = '1' minor_version = '0' ] ' [19] = '[ service = 'wda' major_version = '1' minor_version = '0' ] ' [20] = '[ service = 'csvt' major_version = '1' minor_version = '0' ] ' [21] = '[ service = 'cat' major_version = '2' minor_version = '11' ] '}
[/dev/cdc-wdm0] QMI Device supports 22 services:[/dev/cdc-wdm0] ctl (1.5)[/dev/cdc-wdm0] wds (1.16)[/dev/cdc-wdm0] dms (1.13)[/dev/cdc-wdm0] nas (1.25)[/dev/cdc-wdm0] qos (1.3)[/dev/cdc-wdm0] wms (1.8)[/dev/cdc-wdm0] pds (1.13)[/dev/cdc-wdm0] auth (1.2)[/dev/cdc-wdm0] at (1.2)[/dev/cdc-wdm0] voice (2.1)[/dev/cdc-wdm0] cat2 (2.11)[/dev/cdc-wdm0] uim (1.12)[/dev/cdc-wdm0] pbm (1.4)[/dev/cdc-wdm0] test (1.0)[/dev/cdc-wdm0] loc (2.0)[/dev/cdc-wdm0] sar (1.0)[/dev/cdc-wdm0] ims (1.0)[/dev/cdc-wdm0] ts (1.0)[/dev/cdc-wdm0] tmd (1.0)[/dev/cdc-wdm0] wda (1.0)[/dev/cdc-wdm0] csvt (1.0)[/dev/cdc-wdm0] cat (2.11)<debug> [997877872.106702] [mm-port-probe.c:273] mm_port_probe_set_result_qmi(): (usbmisc/cdc-wdm0) port is QMI-capable
<debug> [997877872.680366] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'AT^SQPORT?<CR><CR><LF>ERROR<CR><LF>'
<debug> [997877872.693102] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [997877872.705073] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [cdc-wdm0] found best plugin for port
<debug> [997877872.714099] [mm-plugin-manager.c:232] port_probe_context_finished(): (Plugin Manager) (Cinterion) [cdc-wdm0]: found best plugin for device (/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1)
<debug> [997877872.722105] [mm-plugin-manager.c:361] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [wwan0] deferred task completed, got suggested plugin
<debug> [997877872.730225] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [ttyUSB3] suggested plugin for port
<debug> [997877872.739092] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [ttyUSB2] suggested plugin for port
<debug> [997877872.747848] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [ttyUSB1] suggested plugin for port
<debug> [997877872.755531] [mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Cinterion) [ttyUSB0] suggested plugin for port
<debug> [997877872.788234] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 5 running probes in this device (wwan0, ttyUSB3, ttyUSB2, ttyUSB1, ttyUSB0)
<debug> [997877872.796508] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT<CR>'
<debug> [997877872.805515] [mm-plugin.c:689] mm_plugin_supports_port(): (Cinterion) [wwan0] probing deferred until result suggested
<debug> [997877872.815075] [mm-plugin-manager.c:549] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [wwan0] task completed, got suggested plugin
<debug> [997877872.824066] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'wwan0' port probe finished, still 4 running probes in this device (ttyUSB3, ttyUSB2, ttyUSB1, ttyUSB0)
<debug> [997877873.006902] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'AT<CR><CR><LF>OK<CR><LF>'
<debug> [997877873.017105] [mm-port-probe.c:154] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable
<debug> [997877873.026017] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 0 (close)
<debug> [997877873.033922] [mm-port-serial.c:1310] _close_internal(): (ttyUSB2) closing serial port...
<debug> [997877873.052088] [mm-port-serial.c:1359] _close_internal(): (ttyUSB2) serial port closed
<debug> [997877873.061008] [mm-port-serial.c:1421] port_serial_close_force(): (ttyUSB2) forced to close port
<debug> [997877873.070012] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [ttyUSB2] found best plugin for port
<debug> [997877873.078871] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB2' port probe finished, still 3 running probes in this device (ttyUSB3, ttyUSB1, ttyUSB0)
<debug> [997877875.045793] [mm-port-serial-at.c:440] debug_log(): (ttyUSB3): --> 'AT<CR>'
<debug> [997877875.994226] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
<debug> [997877876.003765] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
<debug> [997877877.980291] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Sending command failed: 'Resource temporarily unavailable''
<debug> [997877878.036116] [mm-port-serial.c:1294] _close_internal(): (ttyUSB3) device open count is 0 (close)
<debug> [997877878.045047] [mm-port-serial.c:1310] _close_internal(): (ttyUSB3) closing serial port...
<debug> [997877878.058798] [mm-port-serial.c:1359] _close_internal(): (ttyUSB3) serial port closed
<debug> [997877878.080280] [mm-port-serial.c:1421] port_serial_close_force(): (ttyUSB3) forced to close port
<debug> [997877878.090237] [mm-plugin-manager.c:480] plugin_supports_port_ready(): (Plugin Manager) [ttyUSB3] ignoring port unsupported by physical modem's plugin
<debug> [997877878.100377] [mm-plugin-manager.c:189] port_probe_context_finished(): (Plugin Manager) [ttyUSB3] assuming port can be handled by the 'Cinterion' plugin
<debug> [997877878.109791] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB3' port probe finished, still 2 running probes in this device (ttyUSB1, ttyUSB0)
<debug> [997877878.993268] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877879.003691] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877879.014324] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
<debug> [997877879.022862] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
<debug> [997877882.025763] [mm-base-manager.c:320] device_added(): (net/wlan0): port's parent platform driver is not whitelisted
<debug> [997877882.061118] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877882.071739] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877882.641974] [mm-port-serial-at.c:440] debug_log(): (ttyUSB0): --> 'AT<CR>'
<debug> [997877882.704906] [mm-port-serial-at.c:440] debug_log(): (ttyUSB1): --> 'AT<CR>'
<debug> [997877885.995932] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877886.004195] [mm-port-probe.c:164] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
<debug> [997877886.013431] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
<debug> [997877886.020950] [mm-port-probe.c:164] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is not AT-capable
<debug> [997877886.030434] [mm-port-serial.c:1294] _close_internal(): (ttyUSB0) device open count is 0 (close)
<debug> [997877886.039494] [mm-port-serial.c:1310] _close_internal(): (ttyUSB0) closing serial port...
<debug> [997877886.564317] [mm-port-serial.c:1359] _close_internal(): (ttyUSB0) serial port closed
<debug> [997877886.572007] [mm-port-serial.c:1421] port_serial_close_force(): (ttyUSB0) forced to close port
<debug> [997877886.650790] [mm-port-serial.c:1294] _close_internal(): (ttyUSB1) device open count is 0 (close)
<debug> [997877886.662138] [mm-port-serial.c:1310] _close_internal(): (ttyUSB1) closing serial port...
<debug> [997877886.674216] [mm-port-serial.c:1359] _close_internal(): (ttyUSB1) serial port closed
<debug> [997877886.683018] [mm-port-serial.c:1421] port_serial_close_force(): (ttyUSB1) forced to close port
<debug> [997877886.691190] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [ttyUSB0] found best plugin for port
<debug> [997877886.699092] [mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyUSB0' port probe finished, still 1 running probes in this device (ttyUSB1)
<debug> [997877886.708196] [mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Cinterion) [ttyUSB1] found best plugin for port
<debug> [997877886.715798] [mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyUSB1' port probe finished, last one in device
<debug> [997877886.724670] [mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1] device support check finished in '18.290154' seconds
<info> [997877886.734041] [mm-device.c:520] mm_device_create_modem(): Creating modem with plugin 'Cinterion' and '6' ports
<debug> [997877886.742304] [cinterion/mm-plugin-cinterion.c:142] create_modem(): QMI-powered Cinterion modem found...
<debug> [997877886.799789] [mm-base-modem.c:304] mm_base_modem_grab_port(): (cdc-wdm0) type 'qmi' claimed by /sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1
<debug> [997877886.809513] [mm-base-modem.c:304] mm_base_modem_grab_port(): (wwan0) type 'net' claimed by /sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1
<warn> [997877886.817662] [mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyUSB3): 'Cannot add port 'tty/ttyUSB3', unhandled serial type'
<debug> [997877886.828677] [mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyUSB2) type 'at' claimed by /sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1
<warn> [997877886.838107] [mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyUSB1): 'Cannot add port 'tty/ttyUSB1', unhandled serial type'
<warn> [997877886.854186] [mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyUSB0): 'Cannot add port 'tty/ttyUSB0', unhandled serial type'
<debug> [997877886.871840] [mm-base-modem.c:970] log_port(): (/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1) tty/ttyUSB2 at (primary)
<debug> [997877886.882182] [mm-base-modem.c:970] log_port(): (/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1) net/wwan0 data (primary)
<debug> [997877886.889590] [mm-base-modem.c:970] log_port(): (/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1) usb/cdc-wdm0 qmi (primary)
<info> [997877886.906809] [mm-base-manager.c:162] find_device_support_ready(): Modem for device at '/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1' successfully created
[/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 28
<<<<<< data = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 27
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 16
<<<<<< message = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<< type = "Device Path" (0x01)
<<<<<< length = 13
<<<<<< value = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
<<<<<< translated = /dev/cdc-wdm0
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 19
>>>>>> data = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 18
>>>>>> flags = 0x00
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 7
>>>>>> message = "Internal Proxy Open" (0xFF00)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
[/dev/cdc-wdm0] Checking version info (10 retries)...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 12
<<<<<< data = 01:0B:00:00:00:00:00:02:21:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 11
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 0
<<<<<< message = "Get Version Info" (0x0021)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 133
>>>>>> data = 01:84:00:80:00:00:01:02:21:00:79:00:02:04:00:00:00:00:00:01:6F:00:16:00:01:00:05:00:01:01:00:10:00:02:01:00:0D:00:03:01:00:19:00:04:01:00:03:00:05:01:00:08:00:06:01:00:0D:00:07:01:00:02:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:0B:00:0B:01:00:0C:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:12:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:00:00:1D:01:00:00:00:E0:02:00:0B:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 132
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 121
>>>>>> message = "Get Version Info" (0x0021)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Service list" (0x01)
>>>>>> length = 111
>>>>>> value = 16:00:01:00:05:00:01:01:00:10:00:02:01:00:0D:00:03:01:00:19:00:04:01:00:03:00:05:01:00:08:00:06:01:00:0D:00:07:01:00:02:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:0B:00:0B:01:00:0C:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:12:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:00:00:1D:01:00:00:00:E0:02:00:0B:00
>>>>>> translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '5' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '16' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '13' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '25' ] ' [4] = '[ service = 'qos' major_version = '1' minor_version = '3' ] ' [5] = '[ service = 'wms' major_version = '1' minor_version = '8' ] ' [6] = '[ service = 'pds' major_version = '1' minor_version = '13' ] ' [7] = '[ service = 'auth' major_version = '1' minor_version = '2' ] ' [8] = '[ service = 'at' major_version = '1' minor_version = '2' ] ' [9] = '[ service = 'voice' major_version = '2' minor_version = '1' ] ' [10] = '[ service = 'cat2' major_version = '2' minor_version = '11' ] ' [11] = '[ service = 'uim' major_version = '1' minor_version = '12' ] ' [12] = '[ service = 'pbm' major_version = '1' minor_version = '4' ] ' [13] = '[ service = 'test' major_version = '1' minor_version = '0' ] ' [14] = '[ service = 'loc' major_version = '2' minor_version = '0' ] ' [15] = '[ service = 'sar' major_version = '1' minor_version = '0' ] ' [16] = '[ service = 'ims' major_version = '1' minor_version = '0' ] ' [17] = '[ service = 'ts' major_version = '1' minor_version = '0' ] ' [18] = '[ service = 'tmd' major_version = '1' minor_version = '0' ] ' [19] = '[ service = 'wda' major_version = '1' minor_version = '0' ] ' [20] = '[ service = 'csvt' major_version = '1' minor_version = '0' ] ' [21] = '[ service = 'cat' major_version = '2' minor_version = '11' ] '}
[/dev/cdc-wdm0] QMI Device supports 22 services:[/dev/cdc-wdm0] ctl (1.5)[/dev/cdc-wdm0] wds (1.16)[/dev/cdc-wdm0] dms (1.13)[/dev/cdc-wdm0] nas (1.25)[/dev/cdc-wdm0] qos (1.3)[/dev/cdc-wdm0] wms (1.8)[/dev/cdc-wdm0] pds (1.13)[/dev/cdc-wdm0] auth (1.2)[/dev/cdc-wdm0] at (1.2)[/dev/cdc-wdm0] voice (2.1)[/dev/cdc-wdm0] cat2 (2.11)[/dev/cdc-wdm0] uim (1.12)[/dev/cdc-wdm0] pbm (1.4)[/dev/cdc-wdm0] test (1.0)[/dev/cdc-wdm0] loc (2.0)[/dev/cdc-wdm0] sar (1.0)[/dev/cdc-wdm0] ims (1.0)[/dev/cdc-wdm0] ts (1.0)[/dev/cdc-wdm0] tmd (1.0)[/dev/cdc-wdm0] wda (1.0)[/dev/cdc-wdm0] csvt (1.0)[/dev/cdc-wdm0] cat (2.11)[/dev/cdc-wdm0] Setting network port data format...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 21
<<<<<< data = 01:14:00:00:00:00:00:03:26:00:09:00:10:02:00:01:00:01:01:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 20
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 3
<<<<<< tlv_length = 9
<<<<<< message = "Set Data Format" (0x0026)
<<<<<< TLV:
<<<<<< type = "Protocol" (0x10)
<<<<<< length = 2
<<<<<< value = 01:00
<<<<<< translated = 802-3
<<<<<< TLV:
<<<<<< type = "Format" (0x01)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = absent
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 28
>>>>>> data = 01:1B:00:80:00:00:01:03:26:00:10:00:02:04:00:00:00:00:00:12:01:00:00:10:02:00:01:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 27
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 3
>>>>>> tlv_length = 16
>>>>>> message = "Set Data Format" (0x0026)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = 0x12
>>>>>> length = 1
>>>>>> value = 00
>>>>>> TLV:
>>>>>> type = "Protocol" (0x10)
>>>>>> length = 2
>>>>>> value = 01:00
>>>>>> translated = 802-3
[/dev/cdc-wdm0] Network port data format operation finished[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:04:22:00:04:00:01:01:00:02
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 4
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 02
<<<<<< translated = dms
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:04:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:01
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 4
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 02:01
>>>>>> translated = [ service = 'dms' cid = '1' ]
[/dev/cdc-wdm0] Registered 'dms' (version 1.13) client with ID '1'[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:05:22:00:04:00:01:01:00:03
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 5
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 03
<<<<<< translated = nas
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:05:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:03:01
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 5
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 03:01
>>>>>> translated = [ service = 'nas' cid = '1' ]
[/dev/cdc-wdm0] Registered 'nas' (version 1.25) client with ID '1'[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:06:22:00:04:00:01:01:00:05
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 6
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 05
<<<<<< translated = wms
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:06:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:05:01
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 6
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 05:01
>>>>>> translated = [ service = 'wms' cid = '1' ]
[/dev/cdc-wdm0] Registered 'wms' (version 1.8) client with ID '1'[/dev/cdc-wdm0] Allocating new client ID...[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:07:22:00:04:00:01:01:00:06
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 7
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 06
<<<<<< translated = pds
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:07:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:06:01
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 7
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 06:01
>>>>>> translated = [ service = 'pds' cid = '1' ]
[/dev/cdc-wdm0] Registered 'pds' (version 1.13) client with ID '1'<debug> [997877887.230905] [mm-broadband-modem-qmi.c:10206] qmi_port_allocate_client_ready(): Couldn't allocate client for service 'oma': Couldn't create client for service 'oma': Service 'oma' not supported by the device
<debug> [997877887.246531] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
<warn> [997877887.258816] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
<debug> [997877887.267190] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
<debug> [997877887.276049] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
<debug> [997877887.287863] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
<debug> [997877887.296524] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 18
>>>>>> data = 01:11:00:80:06:FF:04:00:00:20:00:05:00:01:02:00:01:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 17
>>>>>> flags = 0x80
>>>>>> service = "pds"
>>>>>> client = 255
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 5
>>>>>> message = (0x0020)
>>>>>> TLV:
>>>>>> type = 0x01
>>>>>> length = 2
>>>>>> value = 01:00
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 13
>>>>>> data = 01:0C:00:80:06:FF:04:00:00:60:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 12
>>>>>> flags = 0x80
>>>>>> service = "pds"
>>>>>> client = 255
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 0
>>>>>> message = (0x0060)
<debug> [997877887.346012] [mm-broadband-modem-qmi.c:531] modem_load_current_capabilities(): loading current capabilities...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:03:01:00:01:00:34:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 0
<<<<<< message = "Get System Selection Preference" (0x0034)
<debug> [997877887.358172] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
<debug> [997877887.376472] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- 'ATE0<CR><CR><LF>OK<CR><LF>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 83
>>>>>> data = 01:52:00:80:03:01:02:01:00:34:00:46:00:02:04:00:00:00:00:00:10:01:00:00:11:02:00:1C:00:12:08:00:80:03:40:01:00:00:02:00:14:02:00:FF:00:15:08:00:C4:00:08:00:00:00:00:00:16:01:00:00:18:04:00:02:00:00:00:19:04:00:00:00:00:00:1C:06:00:05:08:05:04:01:02
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 82
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 70
>>>>>> message = "Get System Selection Preference" (0x0034)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Emergency mode" (0x10)
>>>>>> length = 1
>>>>>> value = 00
>>>>>> translated = no
>>>>>> TLV:
>>>>>> type = "Mode Preference" (0x11)
>>>>>> length = 2
>>>>>> value = 1C:00
>>>>>> translated = gsm, umts, lte
>>>>>> TLV:
>>>>>> type = "Band Preference" (0x12)
>>>>>> length = 8
>>>>>> value = 80:03:40:01:00:00:02:00
>>>>>> translated = gsm-dcs-1800, gsm-900-extended, gsm-900-primary, wcdma-2100, wcdma-dcs-1800, bc-15
>>>>>> TLV:
>>>>>> type = "Roaming Preference" (0x14)
>>>>>> length = 2
>>>>>> value = FF:00
>>>>>> translated = any
>>>>>> TLV:
>>>>>> type = "LTE Band Preference" (0x15)
>>>>>> length = 8
>>>>>> value = C4:00:08:00:00:00:00:00
>>>>>> translated = 3, 7, 8, 20
>>>>>> TLV:
>>>>>> type = "Network Selection Preference" (0x16)
>>>>>> length = 1
>>>>>> value = 00
>>>>>> translated = automatic
>>>>>> TLV:
>>>>>> type = "Service Domain Preference" (0x18)
>>>>>> length = 4
>>>>>> value = 02:00:00:00
>>>>>> translated = cs-ps
>>>>>> TLV:
>>>>>> type = "GSM WCDMA Acquisition Order Preference" (0x19)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = automatic
>>>>>> TLV:
>>>>>> type = 0x1c
>>>>>> length = 6
>>>>>> value = 05:08:05:04:01:02
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:03:01:00:02:00:2B:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 0
<<<<<< message = "Get Technology Preference" (0x002B)
<debug> [997877887.408483] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 26
>>>>>> data = 01:19:00:80:03:01:02:02:00:2B:00:0D:00:01:03:00:00:00:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 25
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 13
>>>>>> message = "Get Technology Preference" (0x002B)
>>>>>> TLV:
>>>>>> type = "Active" (0x01)
>>>>>> length = 3
>>>>>> value = 00:00:00
>>>>>> translated = [ technology_preference = 'auto' technology_preference_duration = 'permanent' ]
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:01:00:20:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 0
<<<<<< message = "Get Capabilities" (0x0020)
<debug> [997877887.462968] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997877887.472174] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 59
>>>>>> data = 01:3A:00:80:02:01:02:01:00:20:00:2E:00:02:04:00:00:00:00:00:01:0E:00:80:F0:FA:02:00:E1:F5:05:04:02:03:04:05:08:11:08:00:03:00:00:00:00:00:00:00:12:08:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 58
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 46
>>>>>> message = "Get Capabilities" (0x0020)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Info" (0x01)
>>>>>> length = 14
>>>>>> value = 80:F0:FA:02:00:E1:F5:05:04:02:03:04:05:08
>>>>>> translated = [ max_tx_channel_rate = '50000000' max_rx_channel_rate = '100000000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'gsm ' [1] = 'umts ' [2] = 'lte '}' ]
>>>>>> TLV:
>>>>>> type = 0x11
>>>>>> length = 8
>>>>>> value = 03:00:00:00:00:00:00:00
>>>>>> TLV:
>>>>>> type = 0x12
>>>>>> length = 8
>>>>>> value = 00:00:00:00:00:00:00:00
<debug> [997877887.493085] [mm-modem-helpers-qmi.c:1268] mm_modem_capability_from_qmi_capabilities_context(): Current capabilities built: 'gsm-umts, lte'
SSP mode preference: 'gsm, umts, lte'
TP: 'auto'
DMS Capabilities: 'gsm-umts, lte'
<debug> [997877887.516414] [mm-broadband-modem-qmi.c:693] modem_load_supported_capabilities(): loading supported capabilities...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:02:00:20:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 0
<<<<<< message = "Get Capabilities" (0x0020)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 59
>>>>>> data = 01:3A:00:80:02:01:02:02:00:20:00:2E:00:02:04:00:00:00:00:00:01:0E:00:80:F0:FA:02:00:E1:F5:05:04:02:03:04:05:08:11:08:00:03:00:00:00:00:00:00:00:12:08:00:00:00:00:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 58
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 46
>>>>>> message = "Get Capabilities" (0x0020)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Info" (0x01)
>>>>>> length = 14
>>>>>> value = 80:F0:FA:02:00:E1:F5:05:04:02:03:04:05:08
>>>>>> translated = [ max_tx_channel_rate = '50000000' max_rx_channel_rate = '100000000' data_service_capability = 'non-simultaneous-cs-ps' sim_capability = 'supported' radio_interface_list = '{ [0] = 'gsm ' [1] = 'umts ' [2] = 'lte '}' ]
>>>>>> TLV:
>>>>>> type = 0x11
>>>>>> length = 8
>>>>>> value = 03:00:00:00:00:00:00:00
>>>>>> TLV:
>>>>>> type = 0x12
>>>>>> length = 8
>>>>>> value = 00:00:00:00:00:00:00:00
<debug> [997877887.549391] [mm-iface-modem.c:4311] interface_initialization_step(): Modem allows up to 1 bearers
<debug> [997877887.557128] [mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
<debug> [997877887.569320] [mm-broadband-modem-qmi.c:995] modem_load_manufacturer(): loading manufacturer...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:03:00:21:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 3
<<<<<< tlv_length = 0
<<<<<< message = "Get Manufacturer" (0x0021)
<debug> [997877887.581776] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997877887.590816] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 44
>>>>>> data = 01:2B:00:80:02:01:02:03:00:21:00:1F:00:02:04:00:00:00:00:00:01:15:00:51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 43
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 3
>>>>>> tlv_length = 31
>>>>>> message = "Get Manufacturer" (0x0021)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Manufacturer" (0x01)
>>>>>> length = 21
>>>>>> value = 51:55:41:4C:43:4F:4D:4D:20:49:4E:43:4F:52:50:4F:52:41:54:45:44
>>>>>> translated = QUALCOMM INCORPORATED
<debug> [997877887.619905] [mm-broadband-modem-qmi.c:942] modem_load_manufacturer_finish(): loaded manufacturer: QUALCOMM INCORPORATED
<debug> [997877887.648353] [mm-broadband-modem-qmi.c:1071] modem_load_model(): loading model...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:04:00:22:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 4
<<<<<< tlv_length = 0
<<<<<< message = "Get Model" (0x0022)
<debug> [997877887.664427] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
<debug> [997877887.675351] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 11: SIM PIN required
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:02:01:02:04:00:22:00:0B:00:02:04:00:00:00:00:00:01:01:00:30
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 4
>>>>>> tlv_length = 11
>>>>>> message = "Get Model" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Model" (0x01)
>>>>>> length = 1
>>>>>> value = 30
>>>>>> translated = 0
<debug> [997877887.687766] [mm-broadband-modem-qmi.c:1018] modem_load_model_finish(): loaded model: 0
<debug> [997877887.696049] [mm-broadband-modem-qmi.c:1147] modem_load_revision(): loading revision...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:05:00:23:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 5
<<<<<< tlv_length = 0
<<<<<< message = "Get Revision" (0x0023)
<debug> [997877887.708086] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
<debug> [997877887.729845] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
<debug> [997877887.738663] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 11: SIM PIN required
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 75
>>>>>> data = 01:4A:00:80:02:01:02:05:00:23:00:3E:00:02:04:00:00:00:00:00:01:34:00:4D:39:36:31:35:41:2D:43:45:54:57:4D:41:5A:4D:2D:32:2E:30:2E:31:39:30:33:30:20:20:31:20:20:5B:4A:75:6C:20:30:38:20:32:30:31:33:20:30:32:3A:30:30:3A:30:30:5D
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 74
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 5
>>>>>> tlv_length = 62
>>>>>> message = "Get Revision" (0x0023)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Revision" (0x01)
>>>>>> length = 52
>>>>>> value = 4D:39:36:31:35:41:2D:43:45:54:57:4D:41:5A:4D:2D:32:2E:30:2E:31:39:30:33:30:20:20:31:20:20:5B:4A:75:6C:20:30:38:20:32:30:31:33:20:30:32:3A:30:30:3A:30:30:5D
>>>>>> translated = M9615A-CETWMAZM-2.0.19030 1 [Jul 08 2013 02:00:00]
<debug> [997877887.752387] [mm-broadband-modem-qmi.c:1094] modem_load_revision_finish(): loaded revision: M9615A-CETWMAZM-2.0.19030 1 [Jul 08 2013 02:00:00]
<debug> [997877887.761963] [mm-broadband-modem-qmi.c:1289] modem_load_equipment_identifier(): loading equipment identifier...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:06:00:25:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 6
<<<<<< tlv_length = 0
<<<<<< message = "Get IDs" (0x0025)
<debug> [997877887.774669] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 46
>>>>>> data = 01:2D:00:80:02:01:02:06:00:25:00:21:00:02:04:00:00:00:00:00:13:01:00:31:10:01:00:30:11:0F:00:33:35:38:37:30:39:30:35:30:30:31:31:36:33:38
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 45
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 6
>>>>>> tlv_length = 33
>>>>>> message = "Get IDs" (0x0025)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = 0x13
>>>>>> length = 1
>>>>>> value = 31
>>>>>> TLV:
>>>>>> type = "Esn" (0x10)
>>>>>> length = 1
>>>>>> value = 30
>>>>>> translated = 0
>>>>>> TLV:
>>>>>> type = "Imei" (0x11)
>>>>>> length = 15
>>>>>> value = 33:35:38:37:30:39:30:35:30:30:31:31:36:33:38
>>>>>> translated = 358709050011638
<debug> [997877887.816274] [mm-broadband-modem-qmi.c:1186] modem_load_equipment_identifier_finish(): loaded equipment identifier: 358709050011638
<debug> [997877887.824638] [mm-broadband-modem-qmi.c:1324] modem_load_device_identifier(): loading device identifier...
<debug> [997877887.833982] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '00001e2d00000060358709050011638M9615A-CETWMAZM-2.0.190301[Jul08201302:00:00]0QUALCOMMINCORPORATED'
<debug> [997877887.843915] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '6c38cc8aee85cabc91529a6661ae83b27933885c'
<debug> [997877887.853171] [mm-broadband-modem-qmi.c:1312] modem_load_device_identifier_finish(): loaded device identifier: 6c38cc8aee85cabc91529a6661ae83b27933885c
<debug> [997877887.864487] [mm-broadband-modem-qmi.c:1727] modem_load_supported_bands(): loading band capabilities...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:07:00:45:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 7
<<<<<< tlv_length = 0
<<<<<< message = "Get Band Capabilities" (0x0045)
<debug> [997877887.879361] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997877887.887891] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 2 (close)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 42
>>>>>> data = 01:29:00:80:02:01:02:07:00:45:00:1D:00:02:04:00:00:00:00:00:10:08:00:C5:00:08:00:00:00:00:00:01:08:00:80:03:40:01:00:00:02:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 41
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 7
>>>>>> tlv_length = 29
>>>>>> message = "Get Band Capabilities" (0x0045)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "LTE Band Capability" (0x10)
>>>>>> length = 8
>>>>>> value = C5:00:08:00:00:00:00:00
>>>>>> translated = 1, 3, 7, 8, 20
>>>>>> TLV:
>>>>>> type = "Band Capability" (0x01)
>>>>>> length = 8
>>>>>> value = 80:03:40:01:00:00:02:00
>>>>>> translated = gsm-dcs-1800, gsm-900-extended, gsm-900-primary, wcdma-2100, wcdma-dcs-1800, bc-15
<debug> [997877887.905542] [mm-broadband-modem-qmi.c:2781] load_power_state(): Getting device operating mode...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:08:00:2D:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 8
<<<<<< tlv_length = 0
<<<<<< message = "Get Operating Mode" (0x002D)
<debug> [997877887.945512] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:02:01:02:08:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 8
>>>>>> tlv_length = 11
>>>>>> message = "Get Operating Mode" (0x002D)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Mode" (0x01)
>>>>>> length = 1
>>>>>> value = 00
>>>>>> translated = online
<debug> [997877887.964799] [mm-broadband-modem-qmi.c:1533] modem_load_unlock_required(): loading unlock required...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:09:00:2B:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 9
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get PIN Status" (0x002B)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 32
>>>>>> data = 01:1F:00:80:02:01:02:09:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:01:03:0A
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 31
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 9
>>>>>> tlv_length = 19
>>>>>> message = "UIM Get PIN Status" (0x002B)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "PIN2 Status" (0x12)
>>>>>> length = 3
>>>>>> value = 01:03:0A
>>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>> TLV:
>>>>>> type = "PIN1 Status" (0x11)
>>>>>> length = 3
>>>>>> value = 01:03:0A
>>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
<debug> [997877887.997944] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997877888.007356] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 1 (close)
<debug> [997877888.016513] [mm-broadband-modem-qmi.c:1626] modem_load_unlock_retries(): loading unlock retries...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:0A:00:2B:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 10
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get PIN Status" (0x002B)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 32
>>>>>> data = 01:1F:00:80:02:01:02:0A:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:01:03:0A
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 31
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 10
>>>>>> tlv_length = 19
>>>>>> message = "UIM Get PIN Status" (0x002B)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "PIN2 Status" (0x12)
>>>>>> length = 3
>>>>>> value = 01:03:0A
>>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>> TLV:
>>>>>> type = "PIN1 Status" (0x11)
>>>>>> length = 3
>>>>>> value = 01:03:0A
>>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
<info> [997877888.063005] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem: state changed (unknown -> locked)
<debug> [997877888.102469] [mm-sim-qmi.c:148] load_sim_identifier(): loading SIM identifier...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:0B:00:3C:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 11
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get ICCID" (0x003C)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 42
>>>>>> data = 01:29:00:80:02:01:02:0B:00:3C:00:1D:00:02:04:00:00:00:00:00:01:13:00:38:39:33:34:35:36:35:35:32:31:35:30:30:37:34:36:36:37:36
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 41
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 11
>>>>>> tlv_length = 29
>>>>>> message = "UIM Get ICCID" (0x003C)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "ICCID" (0x01)
>>>>>> length = 19
>>>>>> value = 38:39:33:34:35:36:35:35:32:31:35:30:30:37:34:36:36:37:36
>>>>>> translated = 8934565521500746676
<debug> [997877888.123272] [mm-sim-qmi.c:95] load_sim_identifier_finish(): loaded SIM identifier: 8934565521500746676
<debug> [997877888.131898] [mm-sim-qmi.c:224] load_imsi(): loading IMSI...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:0C:00:43:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 12
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get IMSI" (0x0043)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:02:01:02:0C:00:43:00:07:00:02:04:00:01:00:25:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 12
>>>>>> tlv_length = 7
>>>>>> message = "UIM Get IMSI" (0x0043)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:25:00
>>>>>> translated = FAILURE: UimUninitialized
<warn> [997877888.158270] [mm-base-sim.c:1464] load_imsi_ready(): couldn't load IMSI: 'Couldn't get UIM IMSI: QMI protocol error (37): 'UimUninitialized''
<debug> [997877888.169312] [mm-broadband-modem-qmi.c:1408] modem_load_own_numbers(): loading own numbers...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:0D:00:24:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 13
<<<<<< tlv_length = 0
<<<<<< message = "Get MSISDN" (0x0024)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:02:01:02:0D:00:24:00:07:00:02:04:00:01:00:25:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 13
>>>>>> tlv_length = 7
>>>>>> message = "Get MSISDN" (0x0024)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:25:00
>>>>>> translated = FAILURE: UimUninitialized
<warn> [997877888.186937] [mm-iface-modem.c:4096] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (37): 'UimUninitialized''
<debug> [997877888.214259] [mm-broadband-modem-qmi.c:1875] modem_load_current_bands(): loading current bands...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:03:01:00:03:00:31:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 3
<<<<<< tlv_length = 0
<<<<<< message = "Get RF Band Information" (0x0031)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 29
>>>>>> data = 01:1C:00:80:03:01:02:03:00:31:00:10:00:02:04:00:00:00:00:00:01:06:00:01:05:57:00:F6:0B
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 28
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 3
>>>>>> tlv_length = 16
>>>>>> message = "Get RF Band Information" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = 0x01
>>>>>> length = 6
>>>>>> value = 01:05:57:00:F6:0B
<warn> [997877888.250776] [mm-iface-modem.c:4146] load_current_bands_ready(): couldn't load current Bands: 'Couldn't parse the list of current bands'
<debug> [997877888.268591] [mm-broadband-modem-qmi.c:9301] firmware_check_support(): loading firmware images...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:0E:00:49:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 14
<<<<<< tlv_length = 0
<<<<<< message = "List Stored Images" (0x0049)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:02:01:02:0E:00:49:00:07:00:02:04:00:01:00:47:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 14
>>>>>> tlv_length = 7
>>>>>> message = "List Stored Images" (0x0049)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:47:00
>>>>>> translated = FAILURE: InvalidQmiCommand
<debug> [997877888.315658] [mm-broadband-modem.c:9005] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
<debug> [997877888.327937] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 0 (close)
<debug> [997877888.335749] [mm-port-serial.c:1310] _close_internal(): (ttyUSB2) closing serial port...
<debug> [997877888.348631] [mm-port-serial.c:1359] _close_internal(): (ttyUSB2) serial port closed
<debug> [997877888.356788] [mm-base-modem.c:950] initialize_ready(): Couldn't finish initialization in the current state: 'Modem is currently locked, cannot fully initialize'
<debug> [997877888.387203] [mm-device.c:447] export_modem(): Exported modem '/sys/devices/soc0/80080000.ahb/80090000.usb/ci_hdrc.1/usb2/2-1' at path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [997877888.396014] [mm-device.c:457] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Cinterion' modem, VID 0x1E2D PID 0x0060 (usb)
<debug> [997878344.419421] [mm-sim-qmi.c:318] send_pin(): Sending PIN...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 22
<<<<<< data = 01:15:00:00:02:01:00:0F:00:28:00:09:00:01:06:00:01:04:32:37:33:35
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 21
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 15
<<<<<< tlv_length = 9
<<<<<< message = "UIM Verify PIN" (0x0028)
<<<<<< TLV:
<<<<<< type = "Info" (0x01)
<<<<<< length = 6
<<<<<< value = 01:04:32:37:33:35
<<<<<< translated = [ pin_id = 'pin' pin = '2735' ]
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:02:01:02:0F:00:28:00:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 15
>>>>>> tlv_length = 7
>>>>>> message = "UIM Verify PIN" (0x0028)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
<debug> [997878344.538397] [mm-broadband-modem-qmi.c:1533] modem_load_unlock_required(): loading unlock required...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:10:00:2B:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 16
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get PIN Status" (0x002B)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 32
>>>>>> data = 01:1F:00:80:02:01:02:10:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:02:03:0A
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 31
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 16
>>>>>> tlv_length = 19
>>>>>> message = "UIM Get PIN Status" (0x002B)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "PIN2 Status" (0x12)
>>>>>> length = 3
>>>>>> value = 01:03:0A
>>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>> TLV:
>>>>>> type = "PIN1 Status" (0x11)
>>>>>> length = 3
>>>>>> value = 02:03:0A
>>>>>> translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
<debug> [997878344.763542] [mm-broadband-modem-qmi.c:1626] modem_load_unlock_retries(): loading unlock retries...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:11:00:2B:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 17
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get PIN Status" (0x002B)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 32
>>>>>> data = 01:1F:00:80:02:01:02:11:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:02:03:0A
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 31
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 17
>>>>>> tlv_length = 19
>>>>>> message = "UIM Get PIN Status" (0x002B)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "PIN2 Status" (0x12)
>>>>>> length = 3
>>>>>> value = 01:03:0A
>>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>> TLV:
>>>>>> type = "PIN1 Status" (0x11)
>>>>>> length = 3
>>>>>> value = 02:03:0A
>>>>>> translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
<debug> [997878345.724486] [mm-iface-modem.c:3076] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
<info> [997878345.762879] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (locked -> initializing)
<debug> [997878346.296899] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
<warn> [997878346.363225] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
<debug> [997878346.372515] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
<debug> [997878346.380482] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
<debug> [997878346.389485] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
<debug> [997878346.399135] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 34
>>>>>> data = 01:21:00:80:03:01:04:00:00:24:00:15:00:22:05:00:04:03:04:01:00:11:01:00:00:01:06:00:00:02:02:00:01:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 33
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 21
>>>>>> message = "Serving System" (0x0024)
>>>>>> TLV:
>>>>>> type = "Detailed Service Status" (0x22)
>>>>>> length = 5
>>>>>> value = 04:03:04:01:00
>>>>>> translated = [ status = 'power-save' capability = 'cs-ps' hdr_status = 'power-save' hdr_hybrid = 'yes' forbidden = 'no' ]
>>>>>> TLV:
>>>>>> type = "Data Service Capability" (0x11)
>>>>>> length = 1
>>>>>> value = 00
>>>>>> translated = {}
>>>>>> TLV:
>>>>>> type = "Serving System" (0x01)
>>>>>> length = 6
>>>>>> value = 00:02:02:00:01:00
>>>>>> translated = [ registration_state = 'not-registered' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = 'unknown' radio_interfaces = '{ [0] = 'none '}' ]
<debug> [997878346.418467] [mm-broadband-modem-qmi.c:1533] modem_load_unlock_required(): loading unlock required...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:12:00:2B:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 18
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get PIN Status" (0x002B)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 60
>>>>>> data = 01:3B:00:80:03:01:04:00:00:24:00:2F:00:29:05:00:D6:00:01:00:00:22:05:00:01:02:04:01:00:15:03:00:01:08:01:12:05:00:D6:00:01:00:00:11:01:00:00:10:01:00:01:01:06:00:02:02:02:02:01:08
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 59
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 47
>>>>>> message = "Serving System" (0x0024)
>>>>>> TLV:
>>>>>> type = "MNC PCS Digit Include Status" (0x29)
>>>>>> length = 5
>>>>>> value = D6:00:01:00:00
>>>>>> translated = [ mcc = '214' mnc = '1' includes_pcs_digit = 'no' ]
>>>>>> TLV:
>>>>>> type = "Detailed Service Status" (0x22)
>>>>>> length = 5
>>>>>> value = 01:02:04:01:00
>>>>>> translated = [ status = 'limited' capability = 'ps' hdr_status = 'power-save' hdr_hybrid = 'yes' forbidden = 'no' ]
>>>>>> TLV:
>>>>>> type = "Roaming Indicator List" (0x15)
>>>>>> length = 3
>>>>>> value = 01:08:01
>>>>>> translated = { [0] = '[ radio_interface = 'lte' roaming_indicator = 'off' ] '}
>>>>>> TLV:
>>>>>> type = "Current PLMN" (0x12)
>>>>>> length = 5
>>>>>> value = D6:00:01:00:00
>>>>>> translated = [ mcc = '214' mnc = '1' description = '' ]
>>>>>> TLV:
>>>>>> type = "Data Service Capability" (0x11)
>>>>>> length = 1
>>>>>> value = 00
>>>>>> translated = {}
>>>>>> TLV:
>>>>>> type = "Roaming Indicator" (0x10)
>>>>>> length = 1
>>>>>> value = 01
>>>>>> translated = off
>>>>>> TLV:
>>>>>> type = "Serving System" (0x01)
>>>>>> length = 6
>>>>>> value = 02:02:02:02:01:08
>>>>>> translated = [ registration_state = 'not-registered-searching' cs_attach_state = 'detached' ps_attach_state = 'detached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'lte '}' ]
<debug> [997878346.438018] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
<debug> [997878346.482001] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 32
>>>>>> data = 01:1F:00:80:02:01:02:12:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:02:03:0A
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 31
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 18
>>>>>> tlv_length = 19
>>>>>> message = "UIM Get PIN Status" (0x002B)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "PIN2 Status" (0x12)
>>>>>> length = 3
>>>>>> value = 01:03:0A
>>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>> TLV:
>>>>>> type = "PIN1 Status" (0x11)
>>>>>> length = 3
>>>>>> value = 02:03:0A
>>>>>> translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
<debug> [997878346.495599] [mm-broadband-modem-qmi.c:1626] modem_load_unlock_retries(): loading unlock retries...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:13:00:2B:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 19
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get PIN Status" (0x002B)
<debug> [997878346.508144] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
<debug> [997878346.527798] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997878346.538231] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
<debug> [997878346.567686] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997878346.577813] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
<debug> [997878346.596414] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997878346.605473] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 32
>>>>>> data = 01:1F:00:80:02:01:02:13:00:2B:00:13:00:02:04:00:00:00:00:00:12:03:00:01:03:0A:11:03:00:02:03:0A
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 31
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 19
>>>>>> tlv_length = 19
>>>>>> message = "UIM Get PIN Status" (0x002B)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "PIN2 Status" (0x12)
>>>>>> length = 3
>>>>>> value = 01:03:0A
>>>>>> translated = [ current_status = 'enabled-not-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
>>>>>> TLV:
>>>>>> type = "PIN1 Status" (0x11)
>>>>>> length = 3
>>>>>> value = 02:03:0A
>>>>>> translated = [ current_status = 'enabled-verified' verify_retries_left = '3' unblock_retries_left = '10' ]
<debug> [997878346.622735] [mm-iface-modem.c:3076] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
<debug> [997878346.663064] [mm-sim-qmi.c:224] load_imsi(): loading IMSI...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:14:00:43:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 20
<<<<<< tlv_length = 0
<<<<<< message = "UIM Get IMSI" (0x0043)
<debug> [997878346.681872] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997878346.690766] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 38
>>>>>> data = 01:25:00:80:02:01:02:14:00:43:00:19:00:02:04:00:00:00:00:00:01:0F:00:32:31:34:30:31:35:35:31:38:37:39:31:34:33:39
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 37
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 20
>>>>>> tlv_length = 25
>>>>>> message = "UIM Get IMSI" (0x0043)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "IMSI" (0x01)
>>>>>> length = 15
>>>>>> value = 32:31:34:30:31:35:35:31:38:37:39:31:34:33:39
>>>>>> translated = 214015518791439
<debug> [997878346.715570] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997878346.724181] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 2 (close)
<debug> [997878346.732932] [mm-sim-qmi.c:171] load_imsi_finish(): loaded IMSI: 214015518791439
<debug> [997878346.749199] [mm-broadband-modem-qmi.c:1408] modem_load_own_numbers(): loading own numbers...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:15:00:24:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 21
<<<<<< tlv_length = 0
<<<<<< message = "Get MSISDN" (0x0024)
<debug> [997878346.761721] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
<debug> [997878346.790554] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997878346.800621] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 1 (close)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:02:01:02:15:00:24:00:07:00:02:04:00:01:00:10:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 21
>>>>>> tlv_length = 7
>>>>>> message = "Get MSISDN" (0x0024)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:10:00
>>>>>> translated = FAILURE: NotProvisioned
<warn> [997878346.938342] [mm-iface-modem.c:4096] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
<debug> [997878346.977658] [mm-broadband-modem-qmi.c:1875] modem_load_current_bands(): loading current bands...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:03:01:00:04:00:31:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 4
<<<<<< tlv_length = 0
<<<<<< message = "Get RF Band Information" (0x0031)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 29
>>>>>> data = 01:1C:00:80:03:01:02:04:00:31:00:10:00:02:04:00:00:00:00:00:01:06:00:01:08:7A:00:C8:05
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 28
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 4
>>>>>> tlv_length = 16
>>>>>> message = "Get RF Band Information" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = 0x01
>>>>>> length = 6
>>>>>> value = 01:08:7A:00:C8:05
<warn> [997878347.034541] [mm-iface-modem.c:4146] load_current_bands_ready(): couldn't load current Bands: 'Couldn't parse the list of current bands'
<debug> [997878347.055127] [mm-broadband-modem-qmi.c:3380] modem_3gpp_load_imei_finish(): loaded IMEI: 358709050011638
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:02:01:00:16:00:40:00:04:00:01:01:00:04
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 22
<<<<<< tlv_length = 4
<<<<<< message = "UIM Get CK Status" (0x0040)
<<<<<< TLV:
<<<<<< type = "Facility" (0x01)
<<<<<< length = 1
<<<<<< value = 04
<<<<<< translated = pf
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 26
>>>>>> data = 01:19:00:80:02:01:02:16:00:40:00:0D:00:02:04:00:00:00:00:00:01:03:00:00:0A:20
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 25
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 22
>>>>>> tlv_length = 13
>>>>>> message = "UIM Get CK Status" (0x0040)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "CK Status" (0x01)
>>>>>> length = 3
>>>>>> value = 00:0A:20
>>>>>> translated = [ facility_state = 'deactivated' verify_retries_left = '10' unblock_retries_left = '32' ]
<debug> [997878347.098597] [mm-broadband-modem-qmi.c:3475] dms_uim_get_ck_status_ready(): Facility 'ph-sim' is: 'deactivated'
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:02:01:00:17:00:40:00:04:00:01:01:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 23
<<<<<< tlv_length = 4
<<<<<< message = "UIM Get CK Status" (0x0040)
<<<<<< TLV:
<<<<<< type = "Facility" (0x01)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = pn
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 26
>>>>>> data = 01:19:00:80:02:01:02:17:00:40:00:0D:00:02:04:00:00:00:00:00:01:03:00:00:0A:20
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 25
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 23
>>>>>> tlv_length = 13
>>>>>> message = "UIM Get CK Status" (0x0040)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "CK Status" (0x01)
>>>>>> length = 3
>>>>>> value = 00:0A:20
>>>>>> translated = [ facility_state = 'deactivated' verify_retries_left = '10' unblock_retries_left = '32' ]
<debug> [997878347.163758] [mm-broadband-modem-qmi.c:3475] dms_uim_get_ck_status_ready(): Facility 'net-pers' is: 'deactivated'
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:02:01:00:18:00:40:00:04:00:01:01:00:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 24
<<<<<< tlv_length = 4
<<<<<< message = "UIM Get CK Status" (0x0040)
<<<<<< TLV:
<<<<<< type = "Facility" (0x01)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = pu
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 32
>>>>>> data = 01:1F:00:80:05:01:04:00:00:46:00:13:00:01:10:00:31:34:35:0C:2B:33:34:36:30:37:30:30:33:31:31:30
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 31
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 19
>>>>>> message = (0x0046)
>>>>>> TLV:
>>>>>> type = 0x01
>>>>>> length = 16
>>>>>> value = 31:34:35:0C:2B:33:34:36:30:37:30:30:33:31:31:30
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 26
>>>>>> data = 01:19:00:80:02:01:02:18:00:40:00:0D:00:02:04:00:00:00:00:00:01:03:00:00:0A:20
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 25
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 24
>>>>>> tlv_length = 13
>>>>>> message = "UIM Get CK Status" (0x0040)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "CK Status" (0x01)
>>>>>> length = 3
>>>>>> value = 00:0A:20
>>>>>> translated = [ facility_state = 'deactivated' verify_retries_left = '10' unblock_retries_left = '32' ]
<debug> [997878347.322471] [mm-broadband-modem-qmi.c:3475] dms_uim_get_ck_status_ready(): Facility 'net-sub-pers' is: 'deactivated'
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:02:01:00:19:00:40:00:04:00:01:01:00:02
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 25
<<<<<< tlv_length = 4
<<<<<< message = "UIM Get CK Status" (0x0040)
<<<<<< TLV:
<<<<<< type = "Facility" (0x01)
<<<<<< length = 1
<<<<<< value = 02
<<<<<< translated = pp
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 79
>>>>>> data = 01:4E:00:80:03:01:04:00:00:24:00:42:00:29:05:00:D6:00:01:00:00:25:02:00:02:11:22:05:00:02:03:04:01:00:1E:04:00:02:0B:66:05:1D:02:00:FE:FF:19:01:00:01:12:0C:00:D6:00:01:00:07:56:6F:64:61:20:45:53:11:02:00:01:0B:01:06:00:01:01:01:02:01:08
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 78
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 66
>>>>>> message = "Serving System" (0x0024)
>>>>>> TLV:
>>>>>> type = "MNC PCS Digit Include Status" (0x29)
>>>>>> length = 5
>>>>>> value = D6:00:01:00:00
>>>>>> translated = [ mcc = '214' mnc = '1' includes_pcs_digit = 'no' ]
>>>>>> TLV:
>>>>>> type = "LTE TAC" (0x25)
>>>>>> length = 2
>>>>>> value = 02:11
>>>>>> translated = 4354
>>>>>> TLV:
>>>>>> type = "Detailed Service Status" (0x22)
>>>>>> length = 5
>>>>>> value = 02:03:04:01:00
>>>>>> translated = [ status = 'available' capability = 'cs-ps' hdr_status = 'power-save' hdr_hybrid = 'yes' forbidden = 'no' ]
>>>>>> TLV:
>>>>>> type = "CID 3GPP" (0x1e)
>>>>>> length = 4
>>>>>> value = 02:0B:66:05
>>>>>> translated = 90573570
>>>>>> TLV:
>>>>>> type = "LAC 3GPP" (0x1d)
>>>>>> length = 2
>>>>>> value = FE:FF
>>>>>> translated = 65534
>>>>>> TLV:
>>>>>> type = "PLMN Name Flag 3GPP" (0x19)
>>>>>> length = 1
>>>>>> value = 01
>>>>>> translated = yes
>>>>>> TLV:
>>>>>> type = "Current PLMN" (0x12)
>>>>>> length = 12
>>>>>> value = D6:00:01:00:07:56:6F:64:61:20:45:53
>>>>>> translated = [ mcc = '214' mnc = '1' description = 'Voda ES' ]
>>>>>> TLV:
>>>>>> type = "Data Service Capability" (0x11)
>>>>>> length = 2
>>>>>> value = 01:0B
>>>>>> translated = { [0] = 'lte '}
>>>>>> TLV:
>>>>>> type = "Serving System" (0x01)
>>>>>> length = 6
>>>>>> value = 01:01:01:02:01:08
>>>>>> translated = [ registration_state = 'registered' cs_attach_state = 'attached' ps_attach_state = 'attached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'lte '}' ]
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 26
>>>>>> data = 01:19:00:80:02:01:02:19:00:40:00:0D:00:02:04:00:00:00:00:00:01:03:00:00:0A:20
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 25
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 25
>>>>>> tlv_length = 13
>>>>>> message = "UIM Get CK Status" (0x0040)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "CK Status" (0x01)
>>>>>> length = 3
>>>>>> value = 00:0A:20
>>>>>> translated = [ facility_state = 'deactivated' verify_retries_left = '10' unblock_retries_left = '32' ]
<debug> [997878347.482535] [mm-broadband-modem-qmi.c:3475] dms_uim_get_ck_status_ready(): Facility 'provider-pers' is: 'deactivated'
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:02:01:00:1A:00:40:00:04:00:01:01:00:03
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 26
<<<<<< tlv_length = 4
<<<<<< message = "UIM Get CK Status" (0x0040)
<<<<<< TLV:
<<<<<< type = "Facility" (0x01)
<<<<<< length = 1
<<<<<< value = 03
<<<<<< translated = pc
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 73
>>>>>> data = 01:48:00:80:03:01:04:00:00:24:00:3C:00:29:05:00:D6:00:01:00:00:1C:08:00:DF:07:09:01:0B:19:30:04:1B:01:00:01:1A:01:00:04:12:10:00:D6:00:01:00:0B:76:6F:64:61:66:6F:6E:65:20:45:53:11:02:00:01:0B:01:06:00:01:01:01:02:01:08
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 72
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 60
>>>>>> message = "Serving System" (0x0024)
>>>>>> TLV:
>>>>>> type = "MNC PCS Digit Include Status" (0x29)
>>>>>> length = 5
>>>>>> value = D6:00:01:00:00
>>>>>> translated = [ mcc = '214' mnc = '1' includes_pcs_digit = 'no' ]
>>>>>> TLV:
>>>>>> type = "Universal Time and Local Time Zone 3GPP" (0x1c)
>>>>>> length = 8
>>>>>> value = DF:07:09:01:0B:19:30:04
>>>>>> translated = [ year = '2015' month = '9' day = '1' hour = '11' minute = '25' second = '48' time_zone = '4' ]
>>>>>> TLV:
>>>>>> type = "Daylight Saving Time Adjustment 3GPP" (0x1b)
>>>>>> length = 1
>>>>>> value = 01
>>>>>> translated = 1
>>>>>> TLV:
>>>>>> type = "Time Zone 3GPP" (0x1a)
>>>>>> length = 1
>>>>>> value = 04
>>>>>> translated = 4
>>>>>> TLV:
>>>>>> type = "Current PLMN" (0x12)
>>>>>> length = 16
>>>>>> value = D6:00:01:00:0B:76:6F:64:61:66:6F:6E:65:20:45:53
>>>>>> translated = [ mcc = '214' mnc = '1' description = 'vodafone ES' ]
>>>>>> TLV:
>>>>>> type = "Data Service Capability" (0x11)
>>>>>> length = 2
>>>>>> value = 01:0B
>>>>>> translated = { [0] = 'lte '}
>>>>>> TLV:
>>>>>> type = "Serving System" (0x01)
>>>>>> length = 6
>>>>>> value = 01:01:01:02:01:08
>>>>>> translated = [ registration_state = 'registered' cs_attach_state = 'attached' ps_attach_state = 'attached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'lte '}' ]
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 42
>>>>>> data = 01:29:00:80:03:01:04:00:00:3A:00:1D:00:14:1A:00:00:00:00:00:0A:F6:37:39:6C:7E:BB:CB:A0:E2:14:0A:F6:37:39:6C:7E:BB:CB:A0:E2:14
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 41
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "indication"
>>>>>> transaction = 0
>>>>>> tlv_length = 29
>>>>>> message = (0x003a)
>>>>>> TLV:
>>>>>> type = 0x14
>>>>>> length = 26
>>>>>> value = 00:00:00:00:0A:F6:37:39:6C:7E:BB:CB:A0:E2:14:0A:F6:37:39:6C:7E:BB:CB:A0:E2:14
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 26
>>>>>> data = 01:19:00:80:02:01:02:1A:00:40:00:0D:00:02:04:00:00:00:00:00:01:03:00:00:0A:20
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 25
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 26
>>>>>> tlv_length = 13
>>>>>> message = "UIM Get CK Status" (0x0040)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "CK Status" (0x01)
>>>>>> length = 3
>>>>>> value = 00:0A:20
>>>>>> translated = [ facility_state = 'deactivated' verify_retries_left = '10' unblock_retries_left = '32' ]
<debug> [997878347.578218] [mm-broadband-modem-qmi.c:3475] dms_uim_get_ck_status_ready(): Facility 'corp-pers' is: 'deactivated'
<debug> [997878347.612975] [mm-broadband-modem.c:8998] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
<debug> [997878347.640367] [mm-broadband-modem-qmi.c:6796] messaging_check_support(): Messaging capabilities supported
<debug> [997878347.656136] [mm-iface-modem-messaging.c:1148] load_supported_storages_ready(): Supported storages loaded:
<debug> [997878347.746482] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): mem1 (list/read/delete) storages: 'sm, me'
<debug> [997878347.757740] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready(): mem2 (write/send) storages: 'sm, me'
<debug> [997878347.767855] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready(): mem3 (reception) storages: 'sm, me'
<debug> [997878347.786889] [mm-broadband-modem.c:9002] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
<debug> [997878347.798789] [mm-broadband-modem-qmi.c:9635] signal_check_support(): Extended signal capabilities supported
<debug> [997878347.822346] [mm-broadband-modem-qmi.c:8349] oma_check_support(): OMA capabilities not supported
<debug> [997878347.831130] [mm-broadband-modem.c:9004] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
<debug> [997878347.841308] [mm-broadband-modem.c:9005] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
<info> [997878347.852683] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (initializing -> disabled)
<debug> [997878348.397534] [mm-port-serial.c:1294] _close_internal(): (ttyUSB2) device open count is 0 (close)
<debug> [997878348.408424] [mm-port-serial.c:1310] _close_internal(): (ttyUSB2) closing serial port...
<debug> [997878348.419381] [mm-port-serial.c:1359] _close_internal(): (ttyUSB2) serial port closed
<info> [997878357.740788] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
<debug> [997878357.785843] [mm-broadband-modem.c:8003] enabling_started(): Skipping modem initialization: not required
<debug> [997878357.794749] [mm-port-serial.c:1099] mm_port_serial_open(): (ttyUSB2) opening serial port...
<warn> [997878357.805810] [mm-port-serial.c:515] real_config_fd(): (ttyUSB2): port attributes not fully set
<debug> [997878357.815176] [mm-port-serial.c:1237] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
<debug> [997878357.822968] [mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
<debug> [997878357.831821] [mm-broadband-modem.c:8016] enabling_started(): Flashing primary AT port before enabling...
<debug> [997878357.842105] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
<debug> [997878357.864584] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997878357.873631] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
<debug> [997878357.894547] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997878357.903807] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
<debug> [997878357.933420] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
<debug> [997878357.945696] [mm-broadband-modem-qmi.c:2781] load_power_state(): Getting device operating mode...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:01:00:1B:00:2D:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 27
<<<<<< tlv_length = 0
<<<<<< message = "Get Operating Mode" (0x002D)
<debug> [997878357.959193] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:02:01:02:1B:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 27
>>>>>> tlv_length = 11
>>>>>> message = "Get Operating Mode" (0x002D)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Mode" (0x01)
>>>>>> length = 1
>>>>>> value = 00
>>>>>> translated = online
<debug> [997878357.979400] [mm-iface-modem.c:3259] set_power_state(): No need to change power state: already in 'on' power state
<debug> [997878357.990512] [mm-broadband-modem.c:8592] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
<debug> [997878358.000716] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 27
<<<<<< data = 01:1A:00:00:03:01:00:05:00:50:00:0E:00:10:0B:00:0A:9C:9F:A1:A4:A6:AB:B0:B5:BA:BF
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 26
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 5
<<<<<< tlv_length = 14
<<<<<< message = "Config Signal Info" (0x0050)
<<<<<< TLV:
<<<<<< type = "RSSI Threshold" (0x10)
<<<<<< length = 11
<<<<<< value = 0A:9C:9F:A1:A4:A6:AB:B0:B5:BA:BF
<<<<<< translated = { [0] = '-100 ' [1] = '-97 ' [2] = '-95 ' [3] = '-92 ' [4] = '-90 ' [5] = '-85 ' [6] = '-80 ' [7] = '-75 ' [8] = '-70 ' [9] = '-65 '}
<debug> [997878358.016323] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:03:01:02:05:00:50:00:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 5
>>>>>> tlv_length = 7
>>>>>> message = "Config Signal Info" (0x0050)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:03:01:00:06:00:03:00:04:00:19:01:00:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 6
<<<<<< tlv_length = 4
<<<<<< message = "Register Indications" (0x0003)
<<<<<< TLV:
<<<<<< type = "Signal Info" (0x19)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = yes
<debug> [997878358.086541] [mm-port-serial-at.c:440] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:03:01:02:06:00:03:00:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 6
>>>>>> tlv_length = 7
>>>>>> message = "Register Indications" (0x0003)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:03:01:00:07:00:03:00:04:00:13:01:00:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 7
<<<<<< tlv_length = 4
<<<<<< message = "Register Indications" (0x0003)
<<<<<< TLV:
<<<<<< type = "Serving System Events" (0x13)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = yes
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:03:01:02:07:00:03:00:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 7
>>>>>> tlv_length = 7
>>>>>> message = "Register Indications" (0x0003)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
<debug> [997878358.138712] [mm-iface-modem-3gpp.c:751] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:03:01:00:08:00:4D:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 8
<<<<<< tlv_length = 0
<<<<<< message = "Get System Info" (0x004D)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 97
>>>>>> data = 01:60:00:80:03:01:02:08:00:4D:00:54:00:02:04:00:00:00:00:00:12:03:00:00:00:00:13:03:00:00:00:00:14:03:00:02:02:00:19:1D:00:01:03:01:03:01:00:01:00:00:FF:FF:01:02:0B:66:05:00:00:00:01:32:31:34:30:31:FF:01:02:11:1E:02:00:FF:FF:21:01:00:01:27:04:00:01:00:00:00:29:01:00:00:2A:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 96
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 8
>>>>>> tlv_length = 84
>>>>>> message = "Get System Info" (0x004D)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "GSM Service Status" (0x12)
>>>>>> length = 3
>>>>>> value = 00:00:00
>>>>>> translated = [ service_status = 'none' true_service_status = 'none' preferred_data_path = 'no' ]
>>>>>> TLV:
>>>>>> type = "WCDMA Service Status" (0x13)
>>>>>> length = 3
>>>>>> value = 00:00:00
>>>>>> translated = [ service_status = 'none' true_service_status = 'none' preferred_data_path = 'no' ]
>>>>>> TLV:
>>>>>> type = "LTE Service Status" (0x14)
>>>>>> length = 3
>>>>>> value = 02:02:00
>>>>>> translated = [ service_status = 'available' true_service_status = 'available' preferred_data_path = 'no' ]
>>>>>> TLV:
>>>>>> type = "LTE System Info" (0x19)
>>>>>> length = 29
>>>>>> value = 01:03:01:03:01:00:01:00:00:FF:FF:01:02:0B:66:05:00:00:00:01:32:31:34:30:31:FF:01:02:11
>>>>>> translated = [ domain_valid = 'yes' domain = 'cs-ps' service_capability_valid = 'yes' service_capability = 'cs-ps' roaming_status_valid = 'yes' roaming_status = 'off' forbidden_valid = 'yes' forbidden = 'no' lac_valid = 'no' lac = '65535' cid_valid = 'yes' cid = '90573570' registration_reject_info_valid = 'no' registration_reject_domain = 'none' registration_reject_cause = '0' network_id_valid = 'yes' mcc = '214' mnc = '01�' tac_valid = 'yes' tac = '4354' ]
>>>>>> TLV:
>>>>>> type = "Additional LTE System Info" (0x1e)
>>>>>> length = 2
>>>>>> value = FF:FF
>>>>>> translated = [ geo_system_index = '65535' ]
>>>>>> TLV:
>>>>>> type = "LTE Voice Support" (0x21)
>>>>>> length = 1
>>>>>> value = 01
>>>>>> translated = yes
>>>>>> TLV:
>>>>>> type = "SIM Reject Info" (0x27)
>>>>>> length = 4
>>>>>> value = 01:00:00:00
>>>>>> translated = vailable
>>>>>> TLV:
>>>>>> type = 0x29
>>>>>> length = 1
>>>>>> value = 00
>>>>>> TLV:
>>>>>> type = 0x2a
>>>>>> length = 4
>>>>>> value = 00:00:00:00
<info> [997878358.175453] [mm-broadband-modem-qmi.c:4294] dump(): #### LTE: system info:
service status: available
domain valid: 1
domain: cs-ps
romaing valid: 1
roaming status: off
forbidden valid: 1
forbidden: 0
lac valid: 0
lac: 65535
cid valid: 1
cid: 90573570
networkid valid: 1
mcc: 214
mnc: 01�
cs reg state: unknown
ps reg state: unknown
<info> [997878358.195446] [mm-broadband-modem-qmi.c:4666] common_process_system_info_3gpp(): #### common_process_system_info_3gpp: LTE reg states: cs 'unknown', ps 'unknown'
<info> [997878358.203183] [mm-broadband-modem-qmi.c:4707] common_process_system_info_3gpp(): #### common_process_system_info_3gpp: final reg states: cs 'unknown', ps 'unknown'
<debug> [997878358.212302] [mm-broadband-modem.c:8633] enabling_step(): Modem has location capabilities, enabling the Location interface...
<debug> [997878358.221880] [mm-iface-modem-location.c:735] setup_gathering(): Location 'gps-raw' gathering is already disabled...
<debug> [997878358.230999] [mm-iface-modem-location.c:735] setup_gathering(): Location 'gps-nmea' gathering is already disabled...
<debug> [997878358.239000] [mm-iface-modem-location.c:759] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
<debug> [997878358.249321] [mm-iface-modem-3gpp.c:751] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:03:01:00:09:00:4D:00:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "nas"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 9
<<<<<< tlv_length = 0
<<<<<< message = "Get System Info" (0x004D)
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 97
>>>>>> data = 01:60:00:80:03:01:02:09:00:4D:00:54:00:02:04:00:00:00:00:00:12:03:00:00:00:00:13:03:00:00:00:00:14:03:00:02:02:00:19:1D:00:01:03:01:03:01:00:01:00:00:FF:FF:01:02:0B:66:05:00:00:00:01:32:31:34:30:31:FF:01:02:11:1E:02:00:FF:FF:21:01:00:01:27:04:00:01:00:00:00:29:01:00:00:2A:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 96
>>>>>> flags = 0x80
>>>>>> service = "nas"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 9
>>>>>> tlv_length = 84
>>>>>> message = "Get System Info" (0x004D)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "GSM Service Status" (0x12)
>>>>>> length = 3
>>>>>> value = 00:00:00
>>>>>> translated = [ service_status = 'none' true_service_status = 'none' preferred_data_path = 'no' ]
>>>>>> TLV:
>>>>>> type = "WCDMA Service Status" (0x13)
>>>>>> length = 3
>>>>>> value = 00:00:00
>>>>>> translated = [ service_status = 'none' true_service_status = 'none' preferred_data_path = 'no' ]
>>>>>> TLV:
>>>>>> type = "LTE Service Status" (0x14)
>>>>>> length = 3
>>>>>> value = 02:02:00
>>>>>> translated = [ service_status = 'available' true_service_status = 'available' preferred_data_path = 'no' ]
>>>>>> TLV:
>>>>>> type = "LTE System Info" (0x19)
>>>>>> length = 29
>>>>>> value = 01:03:01:03:01:00:01:00:00:FF:FF:01:02:0B:66:05:00:00:00:01:32:31:34:30:31:FF:01:02:11
>>>>>> translated = [ domain_valid = 'yes' domain = 'cs-ps' service_capability_valid = 'yes' service_capability = 'cs-ps' roaming_status_valid = 'yes' roaming_status = 'off' forbidden_valid = 'yes' forbidden = 'no' lac_valid = 'no' lac = '65535' cid_valid = 'yes' cid = '90573570' registration_reject_info_valid = 'no' registration_reject_domain = 'none' registration_reject_cause = '0' network_id_valid = 'yes' mcc = '214' mnc = '01�' tac_valid = 'yes' tac = '4354' ]
>>>>>> TLV:
>>>>>> type = "Additional LTE System Info" (0x1e)
>>>>>> length = 2
>>>>>> value = FF:FF
>>>>>> translated = [ geo_system_index = '65535' ]
>>>>>> TLV:
>>>>>> type = "LTE Voice Support" (0x21)
>>>>>> length = 1
>>>>>> value = 01
>>>>>> translated = yes
>>>>>> TLV:
>>>>>> type = "SIM Reject Info" (0x27)
>>>>>> length = 4
>>>>>> value = 01:00:00:00
>>>>>> translated = vailable
>>>>>> TLV:
>>>>>> type = 0x29
>>>>>> length = 1
>>>>>> value = 00
>>>>>> TLV:
>>>>>> type = 0x2a
>>>>>> length = 4
>>>>>> value = 00:00:00:00
<info> [997878358.303923] [mm-broadband-modem-qmi.c:4294] dump(): #### LTE: system info:
service status: available
domain valid: 1
domain: cs-ps
romaing valid: 1
roaming status: off
forbidden valid: 1
forbidden: 0
lac valid: 0
lac: 65535
cid valid: 1
cid: 90573570
networkid valid: 1
mcc: 214
mnc: 01�
cs reg state: unknown
ps reg state: unknown
<info> [997878358.311934] [mm-broadband-modem-qmi.c:4666] common_process_system_info_3gpp(): #### common_process_system_info_3gpp: LTE reg states: cs 'unknown', ps 'unknown'
<info> [997878358.319600] [mm-broadband-modem-qmi.c:4707] common_process_system_info_3gpp(): #### common_process_system_info_3gpp: final reg states: cs 'unknown', ps 'unknown'
<debug> [997878358.342366] [mm-broadband-modem.c:8646] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
<debug> [997878358.353867] [mm-broadband-modem-qmi.c:6984] messaging_set_default_storage(): setting default messaging routes...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 26
<<<<<< data = 01:19:00:00:05:01:00:01:00:32:00:0D:00:01:0A:00:02:00:00:00:01:01:00:01:01:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 25
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 13
<<<<<< message = "Set Routes" (0x0032)
<<<<<< TLV:
<<<<<< type = "Route List" (0x01)
<<<<<< length = 10
<<<<<< value = 02:00:00:00:01:01:00:01:01:01
<<<<<< translated = { [0] = '[ message_type = 'point' message_class = '0' storage = 'nv' receipt_action = 'store-and-notify' ] ' [1] = '[ message_type = 'point' message_class = '1' storage = 'nv' receipt_action = 'store-and-notify' ] '}
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:01:00:32:00:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 7
>>>>>> message = "Set Routes" (0x0032)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
<debug> [997878358.395280] [mm-broadband-modem-qmi.c:7277] load_initial_sms_parts_step(): loading all 3GPP messages from storage 'sm'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 21
<<<<<< data = 01:14:00:00:05:01:00:02:00:31:00:08:00:12:01:00:01:01:01:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 20
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 8
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = uim
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:02:00:31:00:07:00:02:04:00:01:00:11:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 7
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:11:00
>>>>>> translated = FAILURE: MissingArgument
Couldn't read SMS messages: QMI protocol error (17): 'MissingArgument'<debug> [997878358.426555] [mm-broadband-modem-qmi.c:7282] load_initial_sms_parts_step(): loading 3GPP MT-read messages from storage 'sm'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 25
<<<<<< data = 01:18:00:00:05:01:00:03:00:31:00:0C:00:12:01:00:01:11:01:00:00:01:01:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 24
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 3
<<<<<< tlv_length = 12
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Message Tag" (0x11)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = mt-read
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = uim
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:03:00:31:00:07:00:02:04:00:01:00:34:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 3
>>>>>> tlv_length = 7
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:34:00
>>>>>> translated = FAILURE: DeviceNotReady
Couldn't read SMS messages: QMI protocol error (52): 'DeviceNotReady'<debug> [997878358.458395] [mm-broadband-modem-qmi.c:7288] load_initial_sms_parts_step(): loading 3GPP MT-not-read messages from storage 'sm'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 25
<<<<<< data = 01:18:00:00:05:01:00:04:00:31:00:0C:00:12:01:00:01:11:01:00:01:01:01:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 24
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 4
<<<<<< tlv_length = 12
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Message Tag" (0x11)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = mt-not-read
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = uim
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 27
>>>>>> data = 01:1A:00:80:05:01:02:04:00:31:00:0E:00:02:04:00:00:00:00:00:01:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 26
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 4
>>>>>> tlv_length = 14
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Message List" (0x01)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = {}
<debug> [997878358.522612] [mm-broadband-modem-qmi.c:7294] load_initial_sms_parts_step(): loading 3GPP MO-sent messages from storage 'sm'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 25
<<<<<< data = 01:18:00:00:05:01:00:05:00:31:00:0C:00:12:01:00:01:11:01:00:02:01:01:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 24
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 5
<<<<<< tlv_length = 12
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Message Tag" (0x11)
<<<<<< length = 1
<<<<<< value = 02
<<<<<< translated = mo-sent
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = uim
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:05:00:31:00:07:00:02:04:00:01:00:30:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 5
>>>>>> tlv_length = 7
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:30:00
>>>>>> translated = FAILURE: InvalidArgument
Couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument'<debug> [997878358.555273] [mm-broadband-modem-qmi.c:7300] load_initial_sms_parts_step(): loading 3GPP MO-not-sent messages from storage 'sm'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 25
<<<<<< data = 01:18:00:00:05:01:00:06:00:31:00:0C:00:12:01:00:01:11:01:00:03:01:01:00:00
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 24
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 6
<<<<<< tlv_length = 12
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Message Tag" (0x11)
<<<<<< length = 1
<<<<<< value = 03
<<<<<< translated = mo-not-sent
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = uim
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:06:00:31:00:07:00:02:04:00:01:00:30:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 6
>>>>>> tlv_length = 7
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:30:00
>>>>>> translated = FAILURE: InvalidArgument
Couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument'<debug> [997878358.586943] [mm-broadband-modem-qmi.c:7277] load_initial_sms_parts_step(): loading all 3GPP messages from storage 'me'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 21
<<<<<< data = 01:14:00:00:05:01:00:07:00:31:00:08:00:12:01:00:01:01:01:00:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 20
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 7
<<<<<< tlv_length = 8
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = nv
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:07:00:31:00:07:00:02:04:00:01:00:11:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 7
>>>>>> tlv_length = 7
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:11:00
>>>>>> translated = FAILURE: MissingArgument
Couldn't read SMS messages: QMI protocol error (17): 'MissingArgument'<debug> [997878358.618538] [mm-broadband-modem-qmi.c:7282] load_initial_sms_parts_step(): loading 3GPP MT-read messages from storage 'me'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 25
<<<<<< data = 01:18:00:00:05:01:00:08:00:31:00:0C:00:12:01:00:01:11:01:00:00:01:01:00:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 24
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 8
<<<<<< tlv_length = 12
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Message Tag" (0x11)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = mt-read
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = nv
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:08:00:31:00:07:00:02:04:00:01:00:34:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 8
>>>>>> tlv_length = 7
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:34:00
>>>>>> translated = FAILURE: DeviceNotReady
Couldn't read SMS messages: QMI protocol error (52): 'DeviceNotReady'<debug> [997878358.650485] [mm-broadband-modem-qmi.c:7288] load_initial_sms_parts_step(): loading 3GPP MT-not-read messages from storage 'me'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 25
<<<<<< data = 01:18:00:00:05:01:00:09:00:31:00:0C:00:12:01:00:01:11:01:00:01:01:01:00:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 24
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 9
<<<<<< tlv_length = 12
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Message Tag" (0x11)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = mt-not-read
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = nv
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 27
>>>>>> data = 01:1A:00:80:05:01:02:09:00:31:00:0E:00:02:04:00:00:00:00:00:01:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 26
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 9
>>>>>> tlv_length = 14
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Message List" (0x01)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = {}
<debug> [997878358.714531] [mm-broadband-modem-qmi.c:7294] load_initial_sms_parts_step(): loading 3GPP MO-sent messages from storage 'me'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 25
<<<<<< data = 01:18:00:00:05:01:00:0A:00:31:00:0C:00:12:01:00:01:11:01:00:02:01:01:00:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 24
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 10
<<<<<< tlv_length = 12
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Message Tag" (0x11)
<<<<<< length = 1
<<<<<< value = 02
<<<<<< translated = mo-sent
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = nv
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:0A:00:31:00:07:00:02:04:00:01:00:30:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 10
>>>>>> tlv_length = 7
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:30:00
>>>>>> translated = FAILURE: InvalidArgument
Couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument'<debug> [997878358.747142] [mm-broadband-modem-qmi.c:7300] load_initial_sms_parts_step(): loading 3GPP MO-not-sent messages from storage 'me'...
[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 25
<<<<<< data = 01:18:00:00:05:01:00:0B:00:31:00:0C:00:12:01:00:01:11:01:00:03:01:01:00:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 24
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 11
<<<<<< tlv_length = 12
<<<<<< message = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<< type = "Message Mode" (0x12)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = gsm-wcdma
<<<<<< TLV:
<<<<<< type = "Message Tag" (0x11)
<<<<<< length = 1
<<<<<< value = 03
<<<<<< translated = mo-not-sent
<<<<<< TLV:
<<<<<< type = "Storage Type" (0x01)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = nv
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:0B:00:31:00:07:00:02:04:00:01:00:30:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 11
>>>>>> tlv_length = 7
>>>>>> message = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:30:00
>>>>>> translated = FAILURE: InvalidArgument
Couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument'[/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:05:01:00:0C:00:01:00:04:00:10:01:00:01
[/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "wms"
<<<<<< client = 1
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 12
<<<<<< tlv_length = 4
<<<<<< message = "Set Event Report" (0x0001)
<<<<<< TLV:
<<<<<< type = "New MT Message Indicator" (0x10)
<<<<<< length = 1
<<<<<< value = 01
<<<<<< translated = [ report = 'yes' ]
[/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:05:01:02:0C:00:01:00:07:00:02:04:00:00:00:00:00
[/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "wms"
>>>>>> client = 1
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 12
>>>>>> tlv_length = 7
>>>>>> message = "Set Event Report" (0x0001)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
<debug> [997878358.843147] [mm-broadband-modem.c:8672] enabling_step(): Modem has extended signal reporting capabilities, enabling the Signal interface...
<debug> [997878358.851219] [mm-iface-modem-signal.c:206] setup_refresh_context(): Extended signal information reporting disabled (rate: 0 seconds)
<info> [997878358.861910] [mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
More information about the ModemManager-devel
mailing list