Hi Salvador, 2017-03-10 13:38 GMT+01:00 Penalva, Salvador <salvador.pena...@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@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@ccimx6sbc:~# mmcli > root@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@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@ccimx6sbc:~# > root@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@ccimx6sbc:~# mmcli -i 0 > SIM '/org/freedesktop/ModemManager1/SIM/0' > ------------------------- > Properties | imsi : 'unknown' > | id : '8934569821612636732' > | operator id : 'unknown' > | operator name : 'unknown' > root@ccimx6sbc:~# > > > > MM 1.4.12 (Working case) > > root@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@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:aleksan...@aleksander.es] > Sent: jueves, 09 de marzo de 2017 17:37 > To: Penalva, Salvador > Cc: modemmanager-devel@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@lists.freedesktop.org > https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel _______________________________________________ ModemManager-devel mailing list ModemManager-devel@lists.freedesktop.org https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel