Telit HE910 not connecting with Modem Manager 1.6.4
Penalva, Salvador
Salvador.Penalva at digi.com
Mon Mar 13 09:55:33 UTC 2017
Hi Daniele Hi Aleksander,
I have tried also with the FW versión 12.00.003 and the problem remains. And also both firmwares work in an older version of MM, but none of them in version 1.6.4, so the problem doesn't seem to be in the FW but I will contact Telit in case there is any newer FW.
Anyway, I also think that the modem gets stuck and is not answering to AT commands and it could be due to the "lock and unlock part" that I mentioned in the last Mail.
> Looking over the documentation of HE910 (http://www.telit.com/fileadmin/user_upload/products/Downloads/3G/Telit_3G_Modules_AT_Commands_Reference_Guide_r10.pdf , page 115) which explains the CSIM command, it says that you have to do first AT+CSIM=1 to lock the interface and after executing the rest of commands for CSIM you have to do AT+CSIM=0 to unlock the device. The problem could be that with this Modem if you don't do the unlock after doing "'AT+CSIM=10,0020000100", the SIM could remain locked and thus no able to connect later.
So even if it works with a new FW of the Modem (probably not), I think that it would be worth it to try to understand this problem and solve it because not everyone is gonna have the latest update of the FM.
Thank you,
Salvador
-----Original Message-----
From: Daniele Palmas [mailto:dnlplm at gmail.com]
Sent: viernes, 10 de marzo de 2017 14:03
To: Penalva, Salvador
Cc: Aleksander Morgado; modemmanager-devel at lists.freedesktop.org
Subject: Re: Telit HE910 not connecting with Modem Manager 1.6.4
Hi Salvador,
2017-03-10 13:38 GMT+01:00 Penalva, Salvador <Salvador.Penalva at digi.com>:
> Hi Aleksander,
>
> Sorry for the delay but I was trying to debug a little more the logs to isolate the problem.
>
> Answering to your question: We use SimpleConnect command inside a script but we don't use network Manager.
>
> Finally, we got to the conclusion, that the problem could start when trying to unlock the SIM. We tested just unlocking the SIM card with the pin in both versions of ModemManager (1.4.12 and 1.6.4) and we think that the error could be in this step that seem to have changed between both versions of ModemManager. This part is just after sending the PIN to the SIM with CPIN in MM 1.6.4:
>
> ModemManager[814]: <debug> [1489145392.858937] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
> ModemManager[814]: <debug> [1489145392.859398] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CSIM=10,0020000100<CR>'
> ModemManager[814]: <warn> [1489145396.334171] [../../ModemManager-1.6.4/plugins/telit/mm-broadband-modem-telit.c:342] csim_query_ready(): No respose for step 1: Serial command timed out
> ModemManager[814]: <debug> [1489145396.334617] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145396.334962] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145396.335307] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CSIM=10,002C000100<CR>'
> ModemManager[814]: <warn> [1489145399.334315] [../../ModemManager-1.6.4/plugins/telit/mm-broadband-modem-telit.c:342] csim_query_ready(): No respose for step 2: Serial command timed out
> ModemManager[814]: <debug> [1489145399.334760] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145399.335107] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145399.335456] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CSIM=10,0020008100<CR>'
> ModemManager[814]: <warn> [1489145402.811803] [../../ModemManager-1.6.4/plugins/telit/mm-broadband-modem-telit.c:342] csim_query_ready(): No respose for step 3: Sending command failed: 'Resource tempora'
> ModemManager[814]: <debug> [1489145402.812322] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145402.812684] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145402.813025] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CSIM=10,002C008100<CR>'
> ModemManager[814]: <warn> [1489145406.273564] [../../ModemManager-1.6.4/plugins/telit/mm-broadband-modem-telit.c:342] csim_query_ready(): No respose for step 4: Sending command failed: 'Resource tempora'
> ModemManager[814]: <warn> [1489145406.273995] [../../ModemManager-1.6.4/src/mm-iface-modem.c:2960] load_unlock_retries_ready(): Couldn't load unlock retries: 'Could not get any of the SIM unlock retries'
> ModemManager[814]: <debug> [1489145406.274272] [../../ModemManager-1.6.4/src/mm-iface-modem.c:3110] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
> ModemManager[814]: <debug> [1489145406.274795] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 0 (close)
> ModemManager[814]: <debug> [1489145406.275051] [../../ModemManager-1.6.4/src/mm-port-serial.c:1361] _close_internal(): (ttyACM0) closing serial port...
> ModemManager[814]: <debug> [1489145411.274803] [../../ModemManager-1.6.4/src/mm-port-serial.c:1409] _close_internal(): (ttyACM0) serial port closed
> ModemManager[814]: <info> [1489145411.277327] [../../ModemManager-1.6.4/src/mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (loc)
> ModemManager[814]: <debug> [1489145411.282002] [../../ModemManager-1.6.4/src/mm-port-serial.c:1158] mm_port_serial_open(): (ttyACM0) opening serial port...
>
It seems to me that the modem got stuck and is not answering to AT
command. I see you are using firmware version 12.00.026: maybe it is
worth to contact Telit technical support for receiving latest firmware
to see if that makes a difference.
Regards,
Daniele
> Looking over the documentation of HE910 (http://www.telit.com/fileadmin/user_upload/products/Downloads/3G/Telit_3G_Modules_AT_Commands_Reference_Guide_r10.pdf , page 115) which explains the CSIM command, it says that you have to do first AT+CSIM=1 to lock the interface and after executing the rest of commands for CSIM you have to do AT+CSIM=0 to unlock the device. The problem could be that with this Modem if you don't do the unlock after doing "'AT+CSIM=10,0020000100", the SIM could remain locked and thus no able to connect later.
>
> Next you have the logs of both tests (with ModemManager 1.4.12 and 1.6.4) with the same device and doing the same operation (mmcli -i 0 --pin="....").
>
> MM 1.6.4 (Not working case)
>
> root at ccimx6sbc:~# mmcli -m 0
>
> /org/freedesktop/ModemManager1/Modem/0 (device id '6cb907e9b25fd81d9b144d3346b48cc89f489910')
> -------------------------
> Hardware | manufacturer: 'Telit'
> | model: 'HE910-D'
> | revision: '12.00.026'
> | supported: 'gsm-umts'
> | current: 'gsm-umts'
> | equipment id: '356136076325358'
> -------------------------
> System | device: '/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3'
> | drivers: 'cdc_acm'
> | plugin: 'Telit'
> | primary port: 'ttyACM0'
> | ports: 'ttyACM3 (at), ttyACM4 (unknown), ttyACM5 (unknown), ttyACM0 (at), ttyACM1 (unknown), ttyACM2 (unknown)'
> -------------------------
> Numbers | own : 'unknown'
> -------------------------
> Status | lock: 'sim-pin'
> | unlock retries: 'sim-pin (3), sim-pin2 (3), sim-puk (10), sim-puk2 (10)'
> | state: 'locked'
> | power state: 'on'
> | access tech: 'unknown'
> | signal quality: '0' (cached)
> -------------------------
> Modes | supported: 'allowed: 2g; preferred: none
> | allowed: 3g; preferred: none
> | allowed: 2g, 3g; preferred: none'
> | current: 'allowed: 2g, 3g; preferred: none'
> -------------------------
> Bands | supported: 'egsm, dcs, pcs, g850, u2100, u1900, u850, u900, u17iv'
> | current: 'egsm, dcs, u900'
> -------------------------
> IP | supported: 'none'
> -------------------------
> SIM | path: '/org/freedesktop/ModemManager1/SIM/0'
>
> -------------------------
> Bearers | paths: 'none'
> root at ccimx6sbc:~# mmcli
> root at ccimx6sbc:~# mmcli -i 0 --pin=8770
> ModemManager[814]: <debug> [1489145392.733144] [../../ModemManager-1.6.4/src/mm-port-serial.c:1158] mm_port_serial_open(): (ttyACM0) opening serial port...
> ModemManager[814]: <debug> [1489145392.735326] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
> ModemManager[814]: <debug> [1489145392.735479] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CPIN="8770"<CR>'
> ModemManager[814]: <debug> [1489145392.835696] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> ModemManager[814]: <debug> [1489145392.835963] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:1302] modem_load_unlock_required(): checking if unlock required...
> ModemManager[814]: <debug> [1489145392.836081] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145392.836210] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145392.836329] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
> ModemManager[814]: <debug> [1489145392.850056] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): <-- '<CR><LF>'
> ModemManager[814]: <debug> [1489145392.851446] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): <-- '+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
> ModemManager[814]: <debug> [1489145392.851633] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 0 (close)
> ModemManager[814]: <debug> [1489145392.851721] [../../ModemManager-1.6.4/src/mm-port-serial.c:1361] _close_internal(): (ttyACM0) closing serial port...
> ModemManager[814]: <debug> [1489145392.856477] [../../ModemManager-1.6.4/src/mm-port-serial.c:1409] _close_internal(): (ttyACM0) serial port closed
> ModemManager[814]: <debug> [1489145392.856914] [../../ModemManager-1.6.4/src/mm-port-serial.c:1158] mm_port_serial_open(): (ttyACM0) opening serial port...
> ModemManager[814]: <debug> [1489145392.858937] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
> ModemManager[814]: <debug> [1489145392.859398] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CSIM=10,0020000100<CR>'
> ModemManager[814]: <warn> [1489145396.334171] [../../ModemManager-1.6.4/plugins/telit/mm-broadband-modem-telit.c:342] csim_query_ready(): No respose for step 1: Serial command timed out
> ModemManager[814]: <debug> [1489145396.334617] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145396.334962] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145396.335307] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CSIM=10,002C000100<CR>'
> ModemManager[814]: <warn> [1489145399.334315] [../../ModemManager-1.6.4/plugins/telit/mm-broadband-modem-telit.c:342] csim_query_ready(): No respose for step 2: Serial command timed out
> ModemManager[814]: <debug> [1489145399.334760] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145399.335107] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145399.335456] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CSIM=10,0020008100<CR>'
> ModemManager[814]: <warn> [1489145402.811803] [../../ModemManager-1.6.4/plugins/telit/mm-broadband-modem-telit.c:342] csim_query_ready(): No respose for step 3: Sending command failed: 'Resource tempora'
> ModemManager[814]: <debug> [1489145402.812322] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145402.812684] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145402.813025] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CSIM=10,002C008100<CR>'
> ModemManager[814]: <warn> [1489145406.273564] [../../ModemManager-1.6.4/plugins/telit/mm-broadband-modem-telit.c:342] csim_query_ready(): No respose for step 4: Sending command failed: 'Resource tempora'
> ModemManager[814]: <warn> [1489145406.273995] [../../ModemManager-1.6.4/src/mm-iface-modem.c:2960] load_unlock_retries_ready(): Couldn't load unlock retries: 'Could not get any of the SIM unlock retries'
> ModemManager[814]: <debug> [1489145406.274272] [../../ModemManager-1.6.4/src/mm-iface-modem.c:3110] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
> ModemManager[814]: <debug> [1489145406.274795] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 0 (close)
> ModemManager[814]: <debug> [1489145406.275051] [../../ModemManager-1.6.4/src/mm-port-serial.c:1361] _close_internal(): (ttyACM0) closing serial port...
> ModemManager[814]: <debug> [1489145411.274803] [../../ModemManager-1.6.4/src/mm-port-serial.c:1409] _close_internal(): (ttyACM0) serial port closed
> ModemManager[814]: <info> [1489145411.277327] [../../ModemManager-1.6.4/src/mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (loc)
> ModemManager[814]: <debug> [1489145411.282002] [../../ModemManager-1.6.4/src/mm-port-serial.c:1158] mm_port_serial_open(): (ttyACM0) opening serial port...
> successfully sent PIN code to the SIM
> root at ccimx6sbc:~# ModemManager[814]: <debug> [1489145416.283132] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
> ModemManager[814]: <debug> [1489145416.283545] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyACM0): running init sequence...
> ModemManager[814]: <debug> [1489145416.283987] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145416.284344] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> ModemManager[814]: <debug> [1489145416.284997] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:1687] modem_load_supported_ip_families(): loading supported IP families...
> ModemManager[814]: <debug> [1489145416.285321] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
> ModemManager[814]: <debug> [1489145416.285705] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'ATE0<CR>'
> ModemManager[814]: <debug> [1489145419.334006] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'ATV1<CR>'
> ModemManager[814]: <debug> [1489145422.334379] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
> ModemManager[814]: <debug> [1489145425.802696] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'ATX4<CR>'
> ModemManager[814]: <debug> [1489145429.265623] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT&C1<CR>'
> ModemManager[814]: <debug> [1489145432.727342] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'ATE0<CR>'
> ModemManager[814]: <debug> [1489145436.188806] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 3 (close)
> ModemManager[814]: <debug> [1489145436.189294] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
> ModemManager[814]: <debug> [1489145439.650389] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 2 (close)
> ModemManager[814]: <debug> [1489145439.650852] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CGDCONT=?<CR>'
> ModemManager[814]: <warn> [1489145443.116875] [../../ModemManager-1.6.4/src/mm-iface-modem.c:4023] load_supported_ip_families_ready(): couldn't load Supported IP families: 'Sending command failed: 'Reso'
> ModemManager[814]: <debug> [1489145443.117307] [../../ModemManager-1.6.4/src/mm-base-sim.c:1074] load_imsi(): loading IMSI...
> ModemManager[814]: <debug> [1489145443.117613] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> ModemManager[814]: <debug> [1489145443.117960] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 2 (close)
> ModemManager[814]: <debug> [1489145443.118287] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CIMI<CR>'
> ModemManager[814]: <warn> [1489145446.579260] [../../ModemManager-1.6.4/src/mm-base-sim.c:1423] load_imsi_ready(): couldn't load IMSI: 'Sending command failed: 'Resource temporarily unavailable''
> ModemManager[814]: <debug> [1489145446.579619] [../../ModemManager-1.6.4/src/mm-base-sim.c:1194] load_operator_identifier(): loading Operator ID...
> ModemManager[814]: <debug> [1489145446.579911] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> ModemManager[814]: <debug> [1489145446.580255] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 2 (close)
> ModemManager[814]: <debug> [1489145446.580667] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CRSM=176,28589,0,0,4<CR>'
> ModemManager[814]: <warn> [1489145450.042355] [../../ModemManager-1.6.4/src/mm-base-sim.c:1424] load_operator_identifier_ready(): couldn't load Operator identifier: 'Sending command failed: 'Resource te'
> ModemManager[814]: <debug> [1489145450.042764] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145450.043128] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:1180] modem_load_own_numbers(): loading own numbers...
> ModemManager[814]: <debug> [1489145450.043432] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145450.043820] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CNUM<CR>'
> ModemManager[814]: <warn> [1489145453.506480] [../../ModemManager-1.6.4/src/mm-iface-modem.c:4130] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Sending command failed: 'Resource tempora'
> ModemManager[814]: <debug> [1489145453.507260] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145453.509643] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:3247] modem_3gpp_load_imei(): loading IMEI...
> ModemManager[814]: <debug> [1489145453.509971] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145453.510515] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
> ModemManager[814]: <warn> [1489145456.977018] [../../ModemManager-1.6.4/src/mm-iface-modem-3gpp.c:1923] load_imei_ready(): couldn't load IMEI: 'Sending command failed: 'Resource temporarily unavailable''
> ModemManager[814]: <debug> [1489145456.977405] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:3406] modem_3gpp_load_enabled_facility_locks(): loading enabled facility locks...
> ModemManager[814]: <debug> [1489145456.977695] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> ModemManager[814]: <debug> [1489145456.978019] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 2 (close)
> ModemManager[814]: <debug> [1489145456.978344] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CLCK=?<CR>'
> ModemManager[814]: <warn> [1489145460.446987] [../../ModemManager-1.6.4/src/mm-iface-modem-3gpp.c:1886] load_enabled_facility_locks_ready(): couldn't load facility locks: 'Sending command failed: 'Resou'
> ModemManager[814]: <debug> [1489145460.449326] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145460.451516] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145460.452027] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CUSD=?<CR>'
> ModemManager[814]: <debug> [1489145463.922990] [../../ModemManager-1.6.4/src/mm-iface-modem-3gpp-ussd.c:786] check_support_ready(): USSD support check failed: 'Sending command failed: 'Resource temporari'
> ModemManager[814]: <debug> [1489145463.923487] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145463.923830] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:9627] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
> ModemManager[814]: <debug> [1489145463.928613] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145463.929151] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CNMI=?<CR>'
> ModemManager[814]: <debug> [1489145467.397851] [../../ModemManager-1.6.4/src/mm-iface-modem-messaging.c:1201] check_support_ready(): Messaging support check failed: 'Sending command failed: 'Resource tem'
> ModemManager[814]: <debug> [1489145467.398295] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145467.398632] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:9630] iface_modem_messaging_initialize_ready(): Couldn't initialize interface: 'Messaging not supported'
> ModemManager[814]: <debug> [1489145467.399602] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145467.400013] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'ATH<CR>'
> ModemManager[814]: <debug> [1489145470.875211] [../../ModemManager-1.6.4/src/mm-iface-modem-voice.c:916] check_support_ready(): Voice support check failed: 'Sending command failed: 'Resource temporarily '
> ModemManager[814]: <debug> [1489145470.875688] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145470.876025] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:9631] iface_modem_voice_initialize_ready(): Couldn't initialize interface: 'Voice not supported'
> ModemManager[814]: <debug> [1489145470.876923] [../../ModemManager-1.6.4/src/mm-port-serial.c:1288] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> ModemManager[814]: <debug> [1489145470.877452] [../../ModemManager-1.6.4/src/mm-port-serial-at.c:459] debug_log(): (ttyACM0): --> 'AT+CTZU=1<CR>'
> ModemManager[814]: <debug> [1489145474.345465] [../../ModemManager-1.6.4/src/mm-iface-modem-time.c:831] check_support_ready(): Time support check failed: 'Sending command failed: 'Resource temporarily un'
> ModemManager[814]: <debug> [1489145474.345886] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 1 (close)
> ModemManager[814]: <debug> [1489145474.346226] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:9632] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
> ModemManager[814]: <debug> [1489145474.347273] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:9633] iface_modem_signal_initialize_ready(): Couldn't initialize interface: 'Extended Signal information '
> ModemManager[814]: <debug> [1489145474.348773] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:9634] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
> ModemManager[814]: <debug> [1489145474.349378] [../../ModemManager-1.6.4/src/mm-broadband-modem.c:9635] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware interface not ava'
> ModemManager[814]: <info> [1489145474.349791] [../../ModemManager-1.6.4/src/mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (ini)
> ModemManager[814]: <debug> [1489145474.351674] [../../ModemManager-1.6.4/src/mm-port-serial.c:1345] _close_internal(): (ttyACM0) device open count is 0 (close)
> ModemManager[814]: <debug> [1489145474.351982] [../../ModemManager-1.6.4/src/mm-port-serial.c:1361] _close_internal(): (ttyACM0) closing serial port...
> ModemManager[814]: <debug> [1489145479.351957] [../../ModemManager-1.6.4/src/mm-port-serial.c:1409] _close_internal(): (ttyACM0) serial port closed
>
> root at ccimx6sbc:~#
> root at ccimx6sbc:~# mmcli -m 0
>
> /org/freedesktop/ModemManager1/Modem/0 (device id '6cb907e9b25fd81d9b144d3346b48cc89f489910')
> -------------------------
> Hardware | manufacturer: 'Telit'
> | model: 'HE910-D'
> | revision: '12.00.026'
> | supported: 'gsm-umts'
> | current: 'gsm-umts'
> | equipment id: '356136076325358'
> -------------------------
> System | device: '/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3'
> | drivers: 'cdc_acm'
> | plugin: 'Telit'
> | primary port: 'ttyACM0'
> | ports: 'ttyACM3 (at), ttyACM4 (unknown), ttyACM5 (unknown), ttyACM0 (at), ttyACM1 (unknown), ttyACM2 (unknown)'
> -------------------------
> Numbers | own : 'unknown'
> -------------------------
> Status | lock: 'none'
> | unlock retries: 'sim-pin (3), sim-pin2 (3), sim-puk (10), sim-puk2 (10)'
> | state: 'disabled'
> | power state: 'on'
> | access tech: 'unknown'
> | signal quality: '0' (cached)
> -------------------------
> Modes | supported: 'allowed: 2g; preferred: none
> | allowed: 3g; preferred: none
> | allowed: 2g, 3g; preferred: none'
> | current: 'allowed: 2g, 3g; preferred: none'
> -------------------------
> Bands | supported: 'egsm, dcs, pcs, g850, u2100, u1900, u850, u900, u17iv'
> | current: 'egsm, dcs, u900'
> -------------------------
> IP | supported: 'none'
> -------------------------
> 3GPP | imei: 'unknown'
> | enabled locks: 'none'
> | operator id: 'unknown'
> | operator name: 'unknown'
> | subscription: 'unknown'
> | registration: 'unknown'
> -------------------------
> SIM | path: '/org/freedesktop/ModemManager1/SIM/0'
>
> -------------------------
> Bearers | paths: 'none'
>
> root at ccimx6sbc:~# mmcli -i 0
> SIM '/org/freedesktop/ModemManager1/SIM/0'
> -------------------------
> Properties | imsi : 'unknown'
> | id : '8934569821612636732'
> | operator id : 'unknown'
> | operator name : 'unknown'
> root at ccimx6sbc:~#
>
>
>
> MM 1.4.12 (Working case)
>
> root at ccimx6sbc:~# mmcli -m 0
>
> /org/freedesktop/ModemManager1/Modem/0 (device id '6cb907e9b25fd81d9b144d3346b48cc89f489910')
> -------------------------
> Hardware | manufacturer: 'Telit'
> | model: 'HE910-D'
> | revision: '12.00.026'
> | supported: 'gsm-umts'
> | current: 'gsm-umts'
> | equipment id: '356136076325358'
> -------------------------
> System | device: '/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3'
> | drivers: 'cdc_acm'
> | plugin: 'Telit'
> | primary port: 'ttyACM0'
> | ports: 'ttyACM3 (at), ttyACM0 (at)'
> -------------------------
> Numbers | own : 'unknown'
> -------------------------
> Status | lock: 'sim-pin'
> | unlock retries: 'unknown'
> | state: 'locked'
> | power state: 'on'
> | access tech: 'unknown'
> | signal quality: '0' (cached)
> -------------------------
> Modes | supported: 'allowed: 2g, 3g; preferred: none'
> | current: 'allowed: 2g, 3g; preferred: none'
> -------------------------
> Bands | supported: 'unknown'
> | current: 'unknown'
> -------------------------
> IP | supported: 'none'
> -------------------------
> SIM | path: '/org/freedesktop/ModemManager1/SIM/0'
>
> -------------------------
> Bearers | paths: 'none'
>
>
> info> [1479478779.251232] [/main.c:136] main(): ModemManager (version 1.4.12) starting in system bus...
> <debug> [1479478779.296455] [/main.c:61] bus_acquired_cb(): Bus acquired, creating manager...
> <debug> [1479478779.304099] [/mm-plugin-manager.c:852] load_plugins(): Looking for plugins in '/usr/lib/ModemManager'
> <debug> [1479478779.315851] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Iridium'
> <debug> [1479478779.321612] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Telit'
> <debug> [1479478779.327148] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Longcheer'
> <debug> [1479478779.332490] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Motorola'
> <debug> [1479478779.338645] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'AnyDATA'
> <debug> [1479478779.345795] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'ZTE'
> <debug> [1479478779.352066] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel LTE'
> <debug> [1479478779.360439] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Huawei'
> <debug> [1479478779.365938] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Pantech'
> <debug> [1479478779.371435] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia'
> <debug> [1479478779.377450] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option'
> <debug> [1479478779.384175] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Altair LTE'
> <debug> [1479478779.390402] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'MTK'
> <debug> [1479478779.396784] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Novatel'
> <debug> [1479478779.403516] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Via CBP7'
> <debug> [1479478779.410490] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Cinterion'
> <debug> [1479478779.416179] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'SimTech'
> <debug> [1479478779.421656] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Generic'
> <debug> [1479478779.428851] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Nokia (Icera)'
> <debug> [1479478779.434499] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Linktop'
> <debug> [1479478779.441330] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Option High-Speed'
> <debug> [1479478779.447822] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Wavecom'
> <debug> [1479478779.454949] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Samsung'
> <debug> [1479478779.461943] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Ericsson MBM'
> <debug> [1479478779.467719] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'X22X'
> <debug> [1479478779.475747] [/mm-plugin-manager.c:877] load_plugins(): Loaded plugin 'Sierra'
> <debug> [1479478779.479017] [/mm-plugin-manager.c:902] load_plugins(): Successfully loaded 26 plugins
> <debug> [1479478779.486096] [/main.c:83] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
> <debug> [1479478779.489377] [/mm-base-manager.c:485] mm_base_manager_start(): Starting automatic device scan...
> <debug> [1479478779.522656] [/mm-base-manager.c:524] mm_base_manager_start(): Finished device scan...
> <debug> [1479478779.528078] [/mm-base-manager.c:320] device_added(): (tty/ttymxc0): port's parent platform driver is not whitelisted
> <debug> [1479478779.536582] [/mm-plugin-manager.c:739] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3] Checking device support...
> <debug> [1479478779.541947] [/mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.545129] [/mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.548456] [/mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.551613] [/mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.554785] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.557927] [/mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.561199] [/mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.564350] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option) [ttyACM0] filtered by drivers
> <debug> [1479478779.568206] [/mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.571584] [/mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyACM0] filtered by udev tags
> <debug> [1479478779.574783] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.577951] [/mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.588398] [/mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.591711] [/mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.594946] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyACM0] filtered by drivers
> <debug> [1479478779.598178] [/mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.601358] [/mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.604601] [/mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyACM0] filtered by udev tags
> <debug> [1479478779.608605] [/mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyACM0] filtered by vendor/product IDs
> <debug> [1479478779.611795] [/mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyACM0] filtered by drivers
> <debug> [1479478779.615073] [/mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyACM0] Found '6' plugins to try...
> <debug> [1479478779.618319] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM0] Will try with plugin 'Telit'
> <debug> [1479478779.621508] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM0] Will try with plugin 'Iridium'
> <debug> [1479478779.624773] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM0] Will try with plugin 'Nokia'
> <debug> [1479478779.627925] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM0] Will try with plugin 'Via CBP7'
> <debug> [1479478779.631154] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM0] Will try with plugin 'Cinterion'
> <debug> [1479478779.640266] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM0] Will try with plugin 'Generic'
> <debug> [1479478779.646858] [/mm-plugin.c:767] mm_plugin_supports_port(): (Telit) [ttyACM0] probe required: 'at'
> <debug> [1479478779.650990] [/mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyACM0) launching port probing: 'at'
> <debug> [1479478779.656769] [/mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.660112] [/mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.663367] [/mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.666514] [/mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.669630] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.672847] [/mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.762919] [/mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.766374] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option) [ttyACM5] filtered by drivers
> <debug> [1479478779.769566] [/mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.772761] [/mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyACM5] filtered by udev tags
> <debug> [1479478779.775925] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.787138] [/mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.790497] [/mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.793714] [/mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.796960] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyACM5] filtered by drivers
> <debug> [1479478779.800207] [/mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.803460] [/mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.806630] [/mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyACM5] filtered by udev tags
> <debug> [1479478779.809936] [/mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyACM5] filtered by vendor/product IDs
> <debug> [1479478779.813209] [/mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyACM5] filtered by drivers
> <debug> [1479478779.816444] [/mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyACM5] Found '6' plugins to try...
> <debug> [1479478779.819681] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM5] Will try with plugin 'Telit'
> <debug> [1479478779.822915] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM5] Will try with plugin 'Iridium'
> <debug> [1479478779.826120] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM5] Will try with plugin 'Nokia'
> <debug> [1479478779.829391] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM5] Will try with plugin 'Via CBP7'
> <debug> [1479478779.833822] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM5] Will try with plugin 'Cinterion'
> <debug> [1479478779.837105] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM5] Will try with plugin 'Generic'
> <debug> [1479478779.840430] [/mm-plugin.c:767] mm_plugin_supports_port(): (Telit) [ttyACM5] probe required: 'at'
> <debug> [1479478779.843707] [/mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyACM5) launching port probing: 'at'
> <debug> [1479478779.849477] [/mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.852720] [/mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.855875] [/mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.859101] [/mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.862348] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.865546] [/mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.869512] [/mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.872776] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option) [ttyACM1] filtered by drivers
> <debug> [1479478779.875976] [/mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.879170] [/mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyACM1] filtered by udev tags
> <debug> [1479478779.882388] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.886339] [/mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.889659] [/mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.892874] [/mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.896078] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyACM1] filtered by drivers
> <debug> [1479478779.899309] [/mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.903272] [/mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.906449] [/mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyACM1] filtered by udev tags
> <debug> [1479478779.909847] [/mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyACM1] filtered by vendor/product IDs
> <debug> [1479478779.913038] [/mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyACM1] filtered by drivers
> <debug> [1479478779.916222] [/mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyACM1] Found '6' plugins to try...
> <debug> [1479478779.919542] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM1] Will try with plugin 'Telit'
> <debug> [1479478779.922768] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM1] Will try with plugin 'Iridium'
> <debug> [1479478779.925984] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM1] Will try with plugin 'Nokia'
> <debug> [1479478779.929234] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM1] Will try with plugin 'Via CBP7'
> <debug> [1479478779.944856] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM1] Will try with plugin 'Cinterion'
> <debug> [1479478779.948965] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM1] Will try with plugin 'Generic'
> <debug> [1479478779.952269] [/mm-plugin.c:767] mm_plugin_supports_port(): (Telit) [ttyACM1] probe required: 'at'
> <debug> [1479478779.955497] [/mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyACM1) launching port probing: 'at'
> <debug> [1479478779.961110] [/mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478779.964450] [/mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478779.967692] [/mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478779.970920] [/mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478779.974202] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478779.977406] [/mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478779.980691] [/mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478779.983969] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option) [ttyACM2] filtered by drivers
> <debug> [1479478779.987174] [/mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478779.990534] [/mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyACM2] filtered by udev tags
> <debug> [1479478779.999621] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478780.003589] [/mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478780.006947] [/mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478780.010248] [/mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478780.013427] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyACM2] filtered by drivers
> <debug> [1479478780.016640] [/mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478780.020025] [/mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478780.023285] [/mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyACM2] filtered by udev tags
> <debug> [1479478780.026533] [/mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyACM2] filtered by vendor/product IDs
> <debug> [1479478780.029790] [/mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyACM2] filtered by drivers
> <debug> [1479478780.033110] [/mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyACM2] Found '6' plugins to try...
> <debug> [1479478780.036343] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM2] Will try with plugin 'Telit'
> <debug> [1479478780.039639] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM2] Will try with plugin 'Iridium'
> <debug> [1479478780.043841] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM2] Will try with plugin 'Nokia'
> <debug> [1479478780.047185] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM2] Will try with plugin 'Via CBP7'
> <debug> [1479478780.059354] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM2] Will try with plugin 'Cinterion'
> <debug> [1479478780.062718] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM2] Will try with plugin 'Generic'
> <debug> [1479478780.066068] [/mm-plugin.c:767] mm_plugin_supports_port(): (Telit) [ttyACM2] probe required: 'at'
> <debug> [1479478780.069425] [/mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyACM2) launching port probing: 'at'
> <debug> [1479478780.075077] [/mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.084093] [/mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.130334] [/mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.133530] [/mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.137442] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.140622] [/mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.143813] [/mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.146987] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option) [ttyACM3] filtered by drivers
> <debug> [1479478780.150401] [/mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.153675] [/mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyACM3] filtered by udev tags
> <debug> [1479478780.164764] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.168033] [/mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.171304] [/mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.174546] [/mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.178606] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyACM3] filtered by drivers
> <debug> [1479478780.181950] [/mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.185183] [/mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.188508] [/mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyACM3] filtered by udev tags
> <debug> [1479478780.191752] [/mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyACM3] filtered by vendor/product IDs
> <debug> [1479478780.195014] [/mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyACM3] filtered by drivers
> <debug> [1479478780.201138] [/mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyACM3] Found '6' plugins to try...
> <debug> [1479478780.204440] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM3] Will try with plugin 'Telit'
> <debug> [1479478780.207728] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM3] Will try with plugin 'Iridium'
> <debug> [1479478780.211016] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM3] Will try with plugin 'Nokia'
> <debug> [1479478780.215770] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM3] Will try with plugin 'Via CBP7'
> <debug> [1479478780.219067] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM3] Will try with plugin 'Cinterion'
> <debug> [1479478780.222405] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM3] Will try with plugin 'Generic'
> <debug> [1479478780.225769] [/mm-plugin.c:767] mm_plugin_supports_port(): (Telit) [ttyACM3] probe required: 'at'
> <debug> [1479478780.229157] [/mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyACM3) launching port probing: 'at'
> <debug> [1479478780.235010] [/mm-plugin.c:326] apply_pre_probing_filters(): (Longcheer) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.238452] [/mm-plugin.c:326] apply_pre_probing_filters(): (Motorola) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.241777] [/mm-plugin.c:326] apply_pre_probing_filters(): (AnyDATA) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.245070] [/mm-plugin.c:326] apply_pre_probing_filters(): (ZTE) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.248432] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel LTE) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.251733] [/mm-plugin.c:326] apply_pre_probing_filters(): (Huawei) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.255024] [/mm-plugin.c:326] apply_pre_probing_filters(): (Pantech) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.258412] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option) [ttyACM4] filtered by drivers
> <debug> [1479478780.261712] [/mm-plugin.c:326] apply_pre_probing_filters(): (Altair LTE) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.264988] [/mm-plugin.c:380] apply_pre_probing_filters(): (MTK) [ttyACM4] filtered by udev tags
> <debug> [1479478780.281688] [/mm-plugin.c:326] apply_pre_probing_filters(): (Novatel) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.285073] [/mm-plugin.c:326] apply_pre_probing_filters(): (SimTech) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.288435] [/mm-plugin.c:326] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.291720] [/mm-plugin.c:326] apply_pre_probing_filters(): (Linktop) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.295010] [/mm-plugin.c:253] apply_pre_probing_filters(): (Option High-Speed) [ttyACM4] filtered by drivers
> <debug> [1479478780.298282] [/mm-plugin.c:326] apply_pre_probing_filters(): (Wavecom) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.301669] [/mm-plugin.c:326] apply_pre_probing_filters(): (Samsung) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.304998] [/mm-plugin.c:380] apply_pre_probing_filters(): (Ericsson MBM) [ttyACM4] filtered by udev tags
> <debug> [1479478780.308351] [/mm-plugin.c:326] apply_pre_probing_filters(): (X22X) [ttyACM4] filtered by vendor/product IDs
> <debug> [1479478780.311653] [/mm-plugin.c:253] apply_pre_probing_filters(): (Sierra) [ttyACM4] filtered by drivers
> <debug> [1479478780.314926] [/mm-plugin-manager.c:640] build_plugins_list(): (Plugin Manager) [ttyACM4] Found '6' plugins to try...
> <debug> [1479478780.319143] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM4] Will try with plugin 'Telit'
> <debug> [1479478780.322432] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM4] Will try with plugin 'Iridium'
> <debug> [1479478780.325710] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM4] Will try with plugin 'Nokia'
> <debug> [1479478780.335806] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM4] Will try with plugin 'Via CBP7'
> <debug> [1479478780.339231] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM4] Will try with plugin 'Cinterion'
> <debug> [1479478780.342575] [/mm-plugin-manager.c:644] build_plugins_list(): (Plugin Manager) [ttyACM4] Will try with plugin 'Generic'
> <debug> [1479478780.345952] [/mm-plugin.c:767] mm_plugin_supports_port(): (Telit) [ttyACM4] probe required: 'at'
> <debug> [1479478780.349367] [/mm-port-probe.c:1368] mm_port_probe_run(): (tty/ttyACM4) launching port probing: 'at'
> <debug> [1479478780.353951] [/mm-base-manager.c:320] device_added(): (tty/ttymxc1): port's parent platform driver is not whitelisted
> <debug> [1479478780.358641] [/mm-base-manager.c:320] device_added(): (tty/ttymxc2): port's parent platform driver is not whitelisted
> <debug> [1479478780.364255] [/mm-base-manager.c:320] device_added(): (tty/ttymxc3): port's parent platform driver is not whitelisted
> <debug> [1479478780.368841] [/mm-base-manager.c:320] device_added(): (tty/ttymxc4): port's parent platform driver is not whitelisted
> <debug> [1479478780.380596] [/mm-base-manager.c:320] device_added(): (net/can0): port's parent platform driver is not whitelisted
> <debug> [1479478780.385181] [/mm-base-manager.c:320] device_added(): (net/can1): port's parent platform driver is not whitelisted
> <debug> [1479478780.389667] [/mm-base-manager.c:320] device_added(): (net/eth0): port's parent platform driver is not whitelisted
> <debug> [1479478780.394839] [/mm-base-manager.c:320] device_added(): (net/wlan0): port's parent platform driver is not whitelisted
> <debug> [1479478780.399663] [/mm-base-manager.c:296] device_added(): (net/sit0): could not get port's parent device
> <debug> [1479478780.411159] [/mm-port-serial.c:1099] mm_port_serial_open(): (ttyACM0) opening serial port...
> <debug> [1479478780.418804] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
> <debug> [1479478780.424580] [/mm-port-serial.c:1099] mm_port_serial_open(): (ttyACM5) opening serial port...
> <debug> [1479478780.433689] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM5) device open count is 1 (open)
> <debug> [1479478780.438521] [/mm-port-serial.c:1099] mm_port_serial_open(): (ttyACM1) opening serial port...
> <debug> [1479478780.444293] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM1) device open count is 1 (open)
> <debug> [1479478780.448962] [/mm-port-serial.c:1099] mm_port_serial_open(): (ttyACM2) opening serial port...
> <debug> [1479478780.455276] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM2) device open count is 1 (open)
> <debug> [1479478780.461046] [/mm-port-serial.c:1099] mm_port_serial_open(): (ttyACM3) opening serial port...
> <debug> [1479478780.469091] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM3) device open count is 1 (open)
> <debug> [1479478780.474494] [/mm-port-serial.c:1099] mm_port_serial_open(): (ttyACM4) opening serial port...
> <debug> [1479478780.480545] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM4) device open count is 1 (open)
> <debug> [1479478780.526302] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT#PORTCFG?<CR>'
> <debug> [1479478780.539764] [/mm-port-serial-at.c:440] debug_log(): (ttyACM5): --> 'AT<CR>'
> <debug> [1479478781.630687] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- 'AT#PORTCFG?'
> <debug> [1479478781.650462] [/mm-plugin-manager.c:710] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3] Minimum probing time consumed
> <debug> [1479478781.654015] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><CR><LF>#PORTCFG: 1,1<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478781.657970] [/home/builder/jenkins/workspace/dey-fb-release/projects/ccimx6sbc/tmp/work/cortexa9hf-vfp-neon-dey-linux-gnueabi/modemmanager/1.4.12-r0/ModemManager-1.4.12/plugins/telit/mm-plugin-telit.c:244] getportcfg_ready(): telit: retrieving port mode layout
> <debug> [1479478781.715598] [/mm-port-probe.c:154] mm_port_probe_set_result_at(): (tty/ttyACM0) port is AT-capable
> <debug> [1479478781.719645] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 0 (close)
> <debug> [1479478781.723092] [/mm-port-serial.c:1310] _close_internal(): (ttyACM0) closing serial port...
> <debug> [1479478781.732616] [/mm-port-serial.c:1359] _close_internal(): (ttyACM0) serial port closed
> <debug> [1479478781.736105] [/mm-port-serial.c:1421] port_serial_close_force(): (ttyACM0) forced to close port
> <debug> [1479478781.739919] [/mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Telit) [ttyACM0] found best plugin for port
> <debug> [1479478781.743371] [/mm-plugin-manager.c:232] port_probe_context_finished(): (Plugin Manager) (Telit) [ttyACM0]: found best plugin for device (/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3)
> <debug> [1479478781.746806] [/mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Telit) [ttyACM4] suggested plugin for port
> <debug> [1479478781.750469] [/mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Telit) [ttyACM3] suggested plugin for port
> <debug> [1479478781.753837] [/mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Telit) [ttyACM2] suggested plugin for port
> <debug> [1479478781.757223] [/mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Telit) [ttyACM1] suggested plugin for port
> <debug> [1479478781.768129] [/mm-plugin-manager.c:407] suggest_single_port_probe_result(): (Plugin Manager) (Telit) [ttyACM5] suggested plugin for port
> <debug> [1479478781.771709] [/mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyACM0' port probe finished, still 5 running probes in this device (ttyACM4, ttyACM3, ttyACM2, ttyACM1, ttyACM5)
> <debug> [1479478781.775517] [/mm-port-serial-at.c:440] debug_log(): (ttyACM1): --> 'AT<CR>'
> <debug> [1479478781.779099] [/mm-port-serial-at.c:440] debug_log(): (ttyACM2): --> 'AT<CR>'
> <debug> [1479478781.782607] [/mm-port-serial-at.c:440] debug_log(): (ttyACM3): --> 'AT<CR>'
> <debug> [1479478781.786185] [/mm-port-serial-at.c:440] debug_log(): (ttyACM4): --> 'AT<CR>'
> <debug> [1479478781.986262] [/mm-port-serial-at.c:440] debug_log(): (ttyACM3): <-- 'AT'
> <debug> [1479478781.998873] [/mm-port-serial-at.c:440] debug_log(): (ttyACM3): <-- '<CR>'
> <debug> [1479478782.002529] [/mm-port-serial-at.c:440] debug_log(): (ttyACM3): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478782.006050] [/mm-port-probe.c:154] mm_port_probe_set_result_at(): (tty/ttyACM3) port is AT-capable
> <debug> [1479478782.009669] [/mm-port-serial.c:1294] _close_internal(): (ttyACM3) device open count is 0 (close)
> <debug> [1479478782.013056] [/mm-port-serial.c:1310] _close_internal(): (ttyACM3) closing serial port...
> <debug> [1479478782.021798] [/mm-port-serial.c:1359] _close_internal(): (ttyACM3) serial port closed
> <debug> [1479478782.025233] [/mm-port-serial.c:1421] port_serial_close_force(): (ttyACM3) forced to close port
> <debug> [1479478782.028836] [/mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Telit) [ttyACM3] found best plugin for port
> <debug> [1479478782.033838] [/mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyACM3' port probe finished, still 4 running probes in this device (ttyACM4, ttyACM2, ttyACM1, ttyACM5)
> <debug> [1479478783.641287] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478783.652849] [/mm-port-serial-at.c:440] debug_log(): (ttyACM5): --> 'AT<CR>'
> <debug> [1479478785.641150] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478785.652464] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478785.655981] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478785.659721] [/mm-port-serial-at.c:440] debug_log(): (ttyACM1): --> 'AT<CR>'
> <debug> [1479478785.663163] [/mm-port-serial-at.c:440] debug_log(): (ttyACM2): --> 'AT<CR>'
> <debug> [1479478785.666665] [/mm-port-serial-at.c:440] debug_log(): (ttyACM4): --> 'AT<CR>'
> <debug> [1479478786.639594] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478786.651977] [/mm-port-serial-at.c:440] debug_log(): (ttyACM5): --> 'AT<CR>'
> <debug> [1479478788.641134] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478788.652443] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478788.655970] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478788.672992] [/mm-port-serial-at.c:440] debug_log(): (ttyACM1): --> 'AT<CR>'
> <debug> [1479478788.676577] [/mm-port-serial-at.c:440] debug_log(): (ttyACM2): --> 'AT<CR>'
> <debug> [1479478788.680086] [/mm-port-serial-at.c:440] debug_log(): (ttyACM4): --> 'AT<CR>'
> <debug> [1479478789.640098] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478789.651308] [/mm-port-probe.c:164] mm_port_probe_set_result_at(): (tty/ttyACM5) port is not AT-capable
> <debug> [1479478789.654890] [/mm-port-serial.c:1294] _close_internal(): (ttyACM5) device open count is 0 (close)
> <debug> [1479478789.659408] [/mm-port-serial.c:1310] _close_internal(): (ttyACM5) closing serial port...
> <debug> [1479478789.668028] [/mm-port-serial.c:1359] _close_internal(): (ttyACM5) serial port closed
> <debug> [1479478789.671509] [/mm-port-serial.c:1421] port_serial_close_force(): (ttyACM5) forced to close port
> <debug> [1479478789.675075] [/mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Telit) [ttyACM5] found best plugin for port
> <debug> [1479478789.678561] [/mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyACM5' port probe finished, still 3 running probes in this device (ttyACM4, ttyACM2, ttyACM1)
> <debug> [1479478791.641203] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478791.652437] [/mm-port-probe.c:164] mm_port_probe_set_result_at(): (tty/ttyACM1) port is not AT-capable
> <debug> [1479478791.656230] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478791.659848] [/mm-port-probe.c:164] mm_port_probe_set_result_at(): (tty/ttyACM2) port is not AT-capable
> <debug> [1479478791.672509] [/mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
> <debug> [1479478791.677092] [/mm-port-probe.c:164] mm_port_probe_set_result_at(): (tty/ttyACM4) port is not AT-capable
> <debug> [1479478791.680841] [/mm-port-serial.c:1294] _close_internal(): (ttyACM1) device open count is 0 (close)
> <debug> [1479478791.684406] [/mm-port-serial.c:1310] _close_internal(): (ttyACM1) closing serial port...
> <debug> [1479478791.693164] [/mm-port-serial.c:1359] _close_internal(): (ttyACM1) serial port closed
> <debug> [1479478791.696839] [/mm-port-serial.c:1421] port_serial_close_force(): (ttyACM1) forced to close port
> <debug> [1479478791.700557] [/mm-port-serial.c:1294] _close_internal(): (ttyACM2) device open count is 0 (close)
> <debug> [1479478791.704091] [/mm-port-serial.c:1310] _close_internal(): (ttyACM2) closing serial port...
> <debug> [1479478791.712639] [/mm-port-serial.c:1359] _close_internal(): (ttyACM2) serial port closed
> <debug> [1479478791.716257] [/mm-port-serial.c:1421] port_serial_close_force(): (ttyACM2) forced to close port
> <debug> [1479478791.719938] [/mm-port-serial.c:1294] _close_internal(): (ttyACM4) device open count is 0 (close)
> <debug> [1479478791.723376] [/mm-port-serial.c:1310] _close_internal(): (ttyACM4) closing serial port...
> <debug> [1479478791.731560] [/mm-port-serial.c:1359] _close_internal(): (ttyACM4) serial port closed
> <debug> [1479478791.735114] [/mm-port-serial.c:1421] port_serial_close_force(): (ttyACM4) forced to close port
> <debug> [1479478791.738814] [/mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Telit) [ttyACM1] found best plugin for port
> <debug> [1479478791.751813] [/mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyACM1' port probe finished, still 2 running probes in this device (ttyACM4, ttyACM2)
> <debug> [1479478791.755477] [/mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Telit) [ttyACM2] found best plugin for port
> <debug> [1479478791.759059] [/mm-plugin-manager.c:305] port_probe_context_finished(): (Plugin Manager) 'ttyACM2' port probe finished, still 1 running probes in this device (ttyACM4)
> <debug> [1479478791.762754] [/mm-plugin-manager.c:462] plugin_supports_port_ready(): (Plugin Manager) (Telit) [ttyACM4] found best plugin for port
> <debug> [1479478791.766402] [/mm-plugin-manager.c:316] port_probe_context_finished(): (Plugin Manager) 'ttyACM4' port probe finished, last one in device
> <debug> [1479478791.769997] [/mm-plugin-manager.c:138] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3] device support check finished in '12.230112' seconds
> <info> [1479478791.773690] [/mm-device.c:520] mm_device_create_modem(): Creating modem with plugin 'Telit' and '6' ports
> <warn> [1479478791.790484] [/mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyACM4): 'Cannot add port 'tty/ttyACM4', unhandled serial type'
> <debug> [1479478791.794251] [/home/builder/jenkins/workspace/dey-fb-release/projects/ccimx6sbc/tmp/work/cortexa9hf-vfp-neon-dey-linux-gnueabi/modemmanager/1.4.12-r0/ModemManager-1.4.12/plugins/telit/mm-plugin-telit.c:105] grab_port(): telit: AT port 'tty/ttyACM3' flagged as secondary
> <debug> [1479478791.804231] [/mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyACM3) type 'at' claimed by /sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3
> <warn> [1479478791.914128] [/mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyACM2): 'Cannot add port 'tty/ttyACM2', unhandled serial type'
> <warn> [1479478791.917758] [/mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyACM1): 'Cannot add port 'tty/ttyACM1', unhandled serial type'
> <warn> [1479478791.922497] [/mm-plugin.c:903] mm_plugin_create_modem(): Could not grab port (tty/ttyACM5): 'Cannot add port 'tty/ttyACM5', unhandled serial type'
> <debug> [1479478791.931997] [/home/builder/jenkins/workspace/dey-fb-release/projects/ccimx6sbc/tmp/work/cortexa9hf-vfp-neon-dey-linux-gnueabi/modemmanager/1.4.12-r0/ModemManager-1.4.12/plugins/telit/mm-plugin-telit.c:100] grab_port(): telit: AT port 'tty/ttyACM0' flagged as primary
> <debug> [1479478791.937254] [/mm-base-modem.c:304] mm_base_modem_grab_port(): (ttyACM0) type 'at' claimed by /sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3
> <debug> [1479478791.940950] [/mm-base-modem.c:970] log_port(): (/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3) tty/ttyACM0 at (primary)
> <debug> [1479478791.944550] [/mm-base-modem.c:970] log_port(): (/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3) tty/ttyACM3 at (secondary)
> <debug> [1479478791.948161] [/mm-base-modem.c:970] log_port(): (/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3) tty/ttyACM0 data (primary)
> <debug> [1479478791.953510] [/mm-port-serial.c:1099] mm_port_serial_open(): (ttyACM0) opening serial port...
> <debug> [1479478791.960063] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
> <debug> [1479478791.963688] [/mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyACM0): running init sequence...
> <debug> [1479478791.967507] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478791.972444] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <info> [1479478791.976203] [/mm-base-manager.c:162] find_device_support_ready(): Modem for device at '/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3' successfully created
> <debug> [1479478791.986395] [/mm-broadband-modem.c:699] modem_load_current_capabilities(): loading current capabilities...
> <debug> [1479478791.990244] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
> <debug> [1479478792.007829] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'ATE0<CR>'
> <debug> [1479478792.024276] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- 'ATE0<CR>'
> <debug> [1479478792.028336] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478792.032249] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'ATV1<CR>'
> <debug> [1479478792.048645] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478792.052789] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
> <debug> [1479478792.080895] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478792.084971] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'ATX4<CR>'
> <debug> [1479478792.100892] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478792.104892] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT&C1<CR>'
> <debug> [1479478792.121144] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478792.125201] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'ATE0<CR>'
> <debug> [1479478792.142021] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478792.145936] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 3 (close)
> <debug> [1479478792.149738] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
> <debug> [1479478792.176898] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478792.182870] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.186602] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+GCAP<CR>'
> <debug> [1479478792.203278] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.207238] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+GCAP: +CGSM,+DS,+FCLASS,+MS,+ES<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.211220] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.214949] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.218612] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+WS46=?<CR>'
> <debug> [1479478792.245655] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.249671] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+WS46: (12,22,25)<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.253560] [/mm-broadband-modem.c:353] modem_load_current_capabilities_finish(): loaded current capabilities: gsm-umts
> <debug> [1479478792.257667] [/mm-iface-modem.c:4306] interface_initialization_step(): Modem allows up to 1 bearers
> <debug> [1479478792.261275] [/mm-bearer-list.c:276] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
> <debug> [1479478792.265244] [/mm-broadband-modem.c:759] modem_load_manufacturer(): loading manufacturer...
> <debug> [1479478792.268904] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.272630] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.286075] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CGMI<CR>'
> <debug> [1479478792.302294] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.306267] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- 'Telit<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.310219] [/mm-broadband-modem.c:743] modem_load_manufacturer_finish(): loaded manufacturer: Telit
> <debug> [1479478792.313894] [/mm-broadband-modem.c:799] modem_load_model(): loading model...
> <debug> [1479478792.317491] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.321324] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.325039] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CGMM<CR>'
> <debug> [1479478792.340914] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.344863] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- 'HE910-D<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.348692] [/mm-broadband-modem.c:783] modem_load_model_finish(): loaded model: HE910-D
> <debug> [1479478792.353591] [/mm-broadband-modem.c:839] modem_load_revision(): loading revision...
> <debug> [1479478792.357295] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.360973] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.364635] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CGMR<CR>'
> <debug> [1479478792.388699] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.392700] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '12.00.026<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.396572] [/mm-broadband-modem.c:823] modem_load_revision_finish(): loaded revision: 12.00.026
> <debug> [1479478792.400389] [/mm-broadband-modem.c:900] modem_load_equipment_identifier(): loading equipment identifier...
> <debug> [1479478792.404166] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.407965] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.413100] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
> <debug> [1479478792.446684] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.450830] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '356136076325358<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.454816] [/mm-broadband-modem.c:882] modem_load_equipment_identifier_finish(): loaded equipment identifier: 356136076325358
> <debug> [1479478792.458673] [/mm-broadband-modem.c:988] modem_load_device_identifier(): loading device identifier...
> <debug> [1479478792.462506] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.466407] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.470321] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'ATI<CR>'
> <debug> [1479478792.486303] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.490339] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '332<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.496102] [/mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '00001bc70000002133235613607632535812.00.026HE910-DTelit'
> <debug> [1479478792.499802] [/mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '6cb907e9b25fd81d9b144d3346b48cc89f489910'
> <debug> [1479478792.503501] [/mm-broadband-modem.c:951] modem_load_device_identifier_finish(): loaded device identifier: 6cb907e9b25fd81d9b144d3346b48cc89f489910
> <debug> [1479478792.563216] [/mm-broadband-modem.c:1601] modem_load_supported_modes(): loading supported modes...
> <debug> [1479478792.567177] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.570952] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.574697] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT*CNTI=2<CR>'
> <debug> [1479478792.601931] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>ERROR<CR><LF>'
> <debug> [1479478792.606460] [/mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
> <debug> [1479478792.610180] [/mm-broadband-modem.c:1535] supported_modes_cnti_ready(): Generic query of supported 3GPP networks with *CNTI failed: 'Unknown error'
> <debug> [1479478792.613909] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.617638] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <warn> [1479478792.621410] [/mm-port-serial.c:811] port_serial_queue_process(): (ttyACM0) response array is not empty when using cached reply, cleaning up 5 bytes
> <debug> [1479478792.625107] [/mm-broadband-modem.c:1438] supported_modes_ws46_test_ready(): Device allows (3GPP) 2G-only network mode
> <debug> [1479478792.638544] [/mm-broadband-modem.c:1443] supported_modes_ws46_test_ready(): Device allows (3GPP) 3G-only network mode
> <debug> [1479478792.642283] [/mm-broadband-modem.c:1472] supported_modes_ws46_test_ready(): Device allows every supported 3GPP network mode (2G/3G)
> <debug> [1479478792.646450] [/mm-broadband-modem.c:1675] modem_load_supported_ip_families(): loading supported IP families...
> <debug> [1479478792.650188] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.653909] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.657604] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CGDCONT=?<CR>'
> <debug> [1479478792.684435] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
> <debug> [1479478792.688481] [/mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 11: SIM PIN required
> <warn> [1479478792.692179] [/mm-iface-modem.c:3984] load_supported_ip_families_ready(): couldn't load Supported IP families: 'SIM PIN required'
> <debug> [1479478792.695864] [/mm-broadband-modem.c:3132] load_power_state(): loading power state...
> <debug> [1479478792.699592] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.703316] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.708354] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CFUN?<CR>'
> <debug> [1479478792.724281] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.728382] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.741730] [/mm-broadband-modem.c:1290] modem_load_unlock_required(): checking if unlock required...
> <debug> [1479478792.745455] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.749191] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.752969] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
> <debug> [1479478792.769292] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.773362] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CPIN: SIM PIN<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.777293] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <info> [1479478792.782544] [/mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem: state changed (unknown -> locked)
> <debug> [1479478792.788371] [/mm-base-sim.c:1013] load_sim_identifier(): loading SIM identifier...
> <debug> [1479478792.792124] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478792.795895] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
> <debug> [1479478792.862705] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478792.875075] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CRSM: 145,85,984365891216626337F2<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478792.879221] [/mm-base-sim.c:1002] load_sim_identifier_finish(): loaded SIM identifier: 8934569821612636732
> <debug> [1479478792.890149] [/mm-base-sim.c:1083] load_imsi(): loading IMSI...
> <debug> [1479478792.893940] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.899058] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.902895] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CIMI<CR>'
> <debug> [1479478792.918809] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
> <debug> [1479478792.922790] [/mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 11: SIM PIN required
> <warn> [1479478792.926602] [/mm-base-sim.c:1464] load_imsi_ready(): couldn't load IMSI: 'SIM PIN required'
> <debug> [1479478792.930361] [/mm-base-sim.c:1219] load_operator_identifier(): loading Operator ID...
> <debug> [1479478792.935130] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478792.942540] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478792.946183] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CRSM=176,28589,0,0,4<CR>'
> <debug> [1479478793.018182] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478793.029801] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CRSM: 145,85,00000002<CR><LF><CR><LF>OK<CR><LF>'
> <warn> [1479478793.033639] [/mm-base-sim.c:1465] load_operator_identifier_ready(): couldn't load Operator identifier: 'Cannot load Operator ID without IMSI'
> <debug> [1479478793.037219] [/mm-base-sim.c:1330] load_operator_name(): loading Operator Name...
> <debug> [1479478793.040863] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478793.056676] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478793.060342] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CRSM=176,28486,0,0,17<CR>'
> <debug> [1479478793.130814] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478793.142499] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CRSM: 145,85,01FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478793.146519] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <debug> [1479478793.150674] [/mm-broadband-modem.c:1168] modem_load_own_numbers(): loading own numbers...
> <debug> [1479478793.154337] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478793.158152] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CNUM<CR>'
> <debug> [1479478793.174912] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
> <debug> [1479478793.178924] [/mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 11: SIM PIN required
> <warn> [1479478793.182527] [/mm-iface-modem.c:4091] load_own_numbers_ready(): couldn't load list of Own Numbers: 'SIM PIN required'
> <debug> [1479478793.187119] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <debug> [1479478793.193655] [/mm-broadband-modem.c:9168] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
> <debug> [1479478793.198374] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 0 (close)
> <debug> [1479478793.209296] [/mm-port-serial.c:1310] _close_internal(): (ttyACM0) closing serial port...
> <debug> [1479478793.217690] [/mm-port-serial.c:1359] _close_internal(): (ttyACM0) serial port closed
> <debug> [1479478793.221521] [/mm-base-modem.c:950] initialize_ready(): Couldn't finish initialization in the current state: 'Modem is currently locked, cannot fully initialize'
> <debug> [1479478793.229572] [/mm-device.c:447] export_modem(): Exported modem '/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3' at path '/org/freedesktop/ModemManager1/Modem/0'
> <debug> [1479478793.233337] [/mm-device.c:457] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Telit' modem, VID 0x1BC7 PID 0x0021 (usb)
> <debug> [1479478884.441520] [/mm-port-serial.c:1099] mm_port_serial_open(): (ttyACM0) opening serial port...
> <debug> [1479478884.455824] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
> <debug> [1479478884.459887] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CPIN="8770"<CR>'
> <debug> [1479478884.564092] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478884.575778] [/mm-broadband-modem.c:1290] modem_load_unlock_required(): checking if unlock required...
> <debug> [1479478884.633149] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478884.637045] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <debug> [1479478884.640791] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
> <debug> [1479478884.657037] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478884.660934] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478884.674362] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 0 (close)
> <debug> [1479478884.677923] [/mm-port-serial.c:1310] _close_internal(): (ttyACM0) closing serial port...
> <debug> [1479478884.699520] [/mm-port-serial.c:1359] _close_internal(): (ttyACM0) serial port closed
> <debug> [1479478884.703335] [/mm-iface-modem.c:3071] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
> <info> [1479478884.708604] [/mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (locked -> initializing)
> <debug> [1479478884.722832] [/mm-port-serial.c:1099] mm_port_serial_open(): (ttyACM0) opening serial port...
> <debug> [1479478884.729789] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
> <debug> [1479478884.733428] [/mm-port-serial-at.c:476] mm_port_serial_at_run_init_sequence(): (ttyACM0): running init sequence...
> <debug> [1479478884.737125] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478884.740941] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478884.745170] [/mm-broadband-modem.c:1675] modem_load_supported_ip_families(): loading supported IP families...
> <debug> [1479478884.748797] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
> <debug> [1479478884.752481] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'ATE0<CR>'
> <debug> [1479478884.768779] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478884.772796] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'ATV1<CR>'
> <debug> [1479478884.798013] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478884.802018] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
> <debug> [1479478884.829276] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478884.833269] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'ATX4<CR>'
> <debug> [1479478884.850037] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478884.854005] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT&C1<CR>'
> <debug> [1479478884.870397] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478884.874367] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'ATE0<CR>'
> <debug> [1479478884.890524] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478884.894454] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 3 (close)
> <debug> [1479478884.898167] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
> <debug> [1479478884.926146] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478884.930093] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478884.934872] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CGDCONT=?<CR>'
> <debug> [1479478884.962522] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478884.976155] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CGDCONT: (1-5),"IP",,,(0,1),(0,1)<CR><LF>+CGDCONT: (1-5),"IPV6",,,(0,1),(0,1)<CR><LF>+CGDCONT: (1-5),"IPV4V6",,,(0,1),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478884.980859] [/mm-base-sim.c:1083] load_imsi(): loading IMSI...
> <debug> [1479478884.984536] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478884.988188] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478884.992632] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CIMI<CR>'
> <debug> [1479478885.009906] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.013861] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '214019822988433<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.017673] [/mm-base-sim.c:1072] load_imsi_finish(): loaded IMSI: 214019822988433
> <debug> [1479478885.022450] [/mm-base-sim.c:1219] load_operator_identifier(): loading Operator ID...
> <debug> [1479478885.026058] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.029796] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.034206] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CRSM=176,28589,0,0,4<CR>'
> <debug> [1479478885.299674] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.311359] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CRSM: 144,0,00000002<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.315459] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <debug> [1479478885.329257] [/mm-broadband-modem.c:1168] modem_load_own_numbers(): loading own numbers...
> <debug> [1479478885.333042] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478885.336822] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CNUM<CR>'
> <debug> [1479478885.355151] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 22<CR><LF>'
> <debug> [1479478885.359098] [/mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 22: Not found
> <warn> [1479478885.362825] [/mm-iface-modem.c:4091] load_own_numbers_ready(): couldn't load list of Own Numbers: 'Not found'
> <debug> [1479478885.366921] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <debug> [1479478885.372741] [/mm-broadband-modem.c:3235] modem_3gpp_load_imei(): loading IMEI...
> <debug> [1479478885.376461] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478885.380282] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
> <debug> [1479478885.415900] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.420175] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '356136076325358<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.424104] [/mm-broadband-modem.c:3226] modem_3gpp_load_imei_finish(): loaded IMEI: 356136076325358
> <debug> [1479478885.427858] [/mm-broadband-modem.c:3394] modem_3gpp_load_enabled_facility_locks(): loading enabled facility locks...
> <debug> [1479478885.431689] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.444071] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.447853] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CLCK=?<CR>'
> <debug> [1479478885.465047] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.469204] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CLCK: ("SC","FD","AO","OI","OX","AI","IR","AB","AG","AC","PN","PU","PP","PC","PS","PF")<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.473436] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.477327] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.481126] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CLCK="SC",2<CR>'
> <debug> [1479478885.508798] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.512898] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CLCK: 1<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.517049] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.521032] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.524847] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CLCK="FD",2<CR>'
> <debug> [1479478885.553895] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.558153] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.563541] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.576820] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.580651] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CLCK="PS",2<CR>'
> <debug> [1479478885.607767] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.726627] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.730886] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.734664] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.738447] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CLCK="PF",2<CR>'
> <debug> [1479478885.766137] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.770226] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.775440] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.779217] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.782988] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CLCK="PN",2<CR>'
> <debug> [1479478885.810417] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.814451] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.818470] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.822244] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.835859] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CLCK="PU",2<CR>'
> <debug> [1479478885.863645] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.867721] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.872910] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.876692] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.880447] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CLCK="PP",2<CR>'
> <debug> [1479478885.907523] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.911543] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.915538] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478885.919292] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478885.923034] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CLCK="PC",2<CR>'
> <debug> [1479478885.952652] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478885.956724] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478885.963366] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <debug> [1479478885.969052] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478885.981525] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CUSD=?<CR>'
> <debug> [1479478886.009156] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478886.013235] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CUSD: (0-2)<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478886.019270] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <debug> [1479478886.027424] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478886.031368] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CNMI=?<CR>'
> <debug> [1479478886.058909] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478886.062990] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CNMI: (0-3),(0-3),(0,2),(0-2),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478886.066975] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
> <debug> [1479478886.070891] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 2 (close)
> <debug> [1479478886.074638] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CPMS=?<CR>'
> <debug> [1479478886.102021] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478886.106218] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CPMS: ("SM","ME"),("SM","ME"),("SM","ME")<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478886.110501] [/mm-iface-modem-messaging.c:1148] load_supported_storages_ready(): Supported storages loaded:
> <debug> [1479478886.115349] [/mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): mem1 (list/read/delete) storages: 'sm, me'
> <debug> [1479478886.128685] [/mm-iface-modem-messaging.c:1150] load_supported_storages_ready(): mem2 (write/send) storages: 'sm, me'
> <debug> [1479478886.132397] [/mm-iface-modem-messaging.c:1151] load_supported_storages_ready(): mem3 (reception) storages: 'sm, me'
> <debug> [1479478886.138397] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <debug> [1479478886.142848] [/mm-port-serial.c:1237] mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
> <debug> [1479478886.146937] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CTZU=1<CR>'
> <debug> [1479478886.174529] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> [1479478886.178691] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): --> 'AT+CCLK?<CR>'
> <debug> [1479478886.196017] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '<CR><LF>'
> <debug> [1479478886.200138] [/mm-port-serial-at.c:440] debug_log(): (ttyACM0): <-- '+CCLK: "00/01/01,00:02:53+04"<CR><LF><CR><LF>OK<CR><LF>'
> <debug> [1479478886.207154] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 1 (close)
> <debug> [1479478886.212031] [/mm-broadband-modem.c:9166] iface_modem_signal_initialize_ready(): Couldn't initialize interface: 'Extended Signal information not supported'
> <debug> [1479478886.217162] [/mm-broadband-modem.c:9167] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
> <debug> [1479478886.221403] [/mm-broadband-modem.c:9168] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
> <info> [1479478886.225356] [/mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (initializing -> disabled)
> <debug> [1479478886.233069] [/mm-port-serial.c:1294] _close_internal(): (ttyACM0) device open count is 0 (close)
> <debug> [1479478886.236855] [/mm-port-serial.c:1310] _close_internal(): (ttyACM0) closing serial port...
> <debug> [1479478886.245474] [/mm-port-serial.c:1359] _close_internal(): (ttyACM0) serial port closed
> <info> [1479478929.959636] [/main.c:42] quit_cb(): Caught signal, shutting down...
> <debug> [1479478929.972705] [/mm-base-manager.c:814] set_property(): Stopping connection in object manager server
> <debug> [1479478929.977695] [/mm-device.c:393] unexport_modem(): Unexported modem '/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3' from path '/org/freedesktop/ModemManager1/Modem/0'
> <debug> [1479478929.983969] [/mm-port-serial.c:1421] port_serial_close_force(): (ttyACM3) forced to close port
> <debug> [1479478929.987939] [/mm-port-serial.c:1421] port_serial_close_force(): (ttyACM0) forced to close port
> <debug> [1479478929.991972] [/mm-base-modem.c:1485] finalize(): Modem (Telit) '/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3' completely disposed
> <info> [1479478930.006833] [/main.c:187] main(): ModemManager is shut down
>
> root at ccimx6sbc:~# mmcli -m 0
>
> /org/freedesktop/ModemManager1/Modem/0 (device id '6cb907e9b25fd81d9b144d3346b48cc89f489910')
> -------------------------
> Hardware | manufacturer: 'Telit'
> | model: 'HE910-D'
> | revision: '12.00.026'
> | supported: 'gsm-umts'
> | current: 'gsm-umts'
> | equipment id: '356136076325358'
> -------------------------
> System | device: '/sys/devices/soc0/soc.0/2100000.aips-bus/2184200.usb/ci_hdrc.1/usb1/1-1/1-1.3'
> | drivers: 'cdc_acm'
> | plugin: 'Telit'
> | primary port: 'ttyACM0'
> | ports: 'ttyACM3 (at), ttyACM0 (at)'
> -------------------------
> Numbers | own : 'unknown'
> -------------------------
> Status | lock: 'none'
> | unlock retries: 'unknown'
> | state: 'disabled'
> | power state: 'on'
> | access tech: 'unknown'
> | signal quality: '0' (cached)
> -------------------------
> Modes | supported: 'allowed: 2g, 3g; preferred: none'
> | current: 'allowed: 2g, 3g; preferred: none'
> -------------------------
> Bands | supported: 'unknown'
> | current: 'unknown'
> -------------------------
> IP | supported: 'ipv4, ipv6, ipv4v6'
> -------------------------
> 3GPP | imei: '356136076325358'
> | enabled locks: 'sim'
> | operator id: 'unknown'
> | operator name: 'unknown'
> | subscription: 'unknown'
> | registration: 'unknown'
> -------------------------
> SIM | path: '/org/freedesktop/ModemManager1/SIM/0'
>
> -------------------------
> Bearers | paths: 'none'
>
>
> Thank you,
>
> Salvador
> -----Original Message-----
> From: Aleksander Morgado [mailto:aleksander at aleksander.es]
> Sent: jueves, 09 de marzo de 2017 17:37
> To: Penalva, Salvador
> Cc: modemmanager-devel at lists.freedesktop.org
> Subject: Re: Telit HE910 not connecting with Modem Manager 1.6.4
>
> Hey Salvador,
>
> From a quick look, I don't see any major difference in both runs, truth be told.
>
> In the first run MM detects that the modem is SIM-PIN locked, and once it gets exported to DBus you're calling Simple.Connect() including the PIN number in the connection details, so the modem gets unlocked and connected.
>
> In the second run MM also detects that the modem is SIM-PIN locked, and once it gets exported to DBus I see no logs saying that any
> Simple.Connect() was performed.
>
> Is this with NetworkManager? If so, how are you requesting the connection to be started?
>
>
> --
> Aleksander
> https://aleksander.es
> _______________________________________________
> ModemManager-devel mailing list
> ModemManager-devel at lists.freedesktop.org
> https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
More information about the ModemManager-devel
mailing list