Hi,
I'm testing Ublox TOBY-L210 with MM-master branch and a SIM with PIN Lock. I
can connect it but only after issuing the simple-connect command twice. The
first time I receive an message saying that the SIM is locked.
With an unlocked SIM the connection is establish at the first attempt.
These is the simple connect procedure:
root@ccimx6ulsbc:~# mmcli -m 0
--simple-connect="apn=ac.vodafone.es,pin=8770,number=*99#"
ModemManager[917]: <info> Simple connect started...
ModemManager[917]: <info> Simple connect state (1/8): Unlock check
error: couldn't connect the modem:
'GDBus.Error:org.freedesktop.ModemManager1.Error.MobileEquipment.SimPin: Device
is locked: 'sim-pin''
root@ccimx6ulsbc:~# mmcli -m 0
--simple-connect="apn=ac.vodafone.es,pin=8770,number=*99#"
ModemManager[917]: <info> Simple connect started...
ModemManager[917]: <info> Simple connect state (1/8): Unlock check
ModemManager[917]: <info> Simple connect state (2/8): Wait to get fully
initialized
ModemManager[917]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state
changed (locked -> initializing)
ModemManager[917]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state
changed (initializing -> disabled)
ModemManager[917]: <info> Simple connect state (3/8): Enable
ModemManager[917]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state
changed (disabled -> enabling)
ModemManager[917]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP
Registration state changed (unknown -> registering)
ModemManager[917]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP
Registration state changed (registering -> home)
ModemManager[917]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state
changed (enabling -> registered)
ModemManager[917]: <info> Simple connect state (4/8): Wait to get fully enabled
ModemManager[917]: <info> Simple connect state (5/8): Register
ModemManager[917]: <info> Simple connect state (6/8): Bearer
ModemManager[917]: <info> Simple connect state (7/8): Connect
ModemManager[917]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state
changed (registered -> connecting)
ModemManager[917]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state
changed (connecting -> connected)
ModemManager[917]: <info> Simple connect state (8/8): All done
successfully connected the modem
Thank you,
Salvador
--------------------------------------------
Here you go the debug log:
<info> [1493975621.330048] [../../git/src/mm-iface-modem-simple.c:650]
connect_auth_ready(): Simple connect started...
<debug> [1493975621.330202] [../../git/src/mm-iface-modem-simple.c:660]
connect_auth_ready(): PIN: 8770
<debug> [1493975621.330269] [../../git/src/mm-iface-modem-simple.c:662]
connect_auth_ready(): Operator ID: unspecified
<debug> [1493975621.330327] [../../git/src/mm-iface-modem-simple.c:664]
connect_auth_ready(): Allowed roaming: yes
<debug> [1493975621.330388] [../../git/src/mm-iface-modem-simple.c:666]
connect_auth_ready(): APN: ac.vodafone.es
<debug> [1493975621.330444] [../../git/src/mm-iface-modem-simple.c:674]
connect_auth_ready(): IP family: unspecified
<debug> [1493975621.330501] [../../git/src/mm-iface-modem-simple.c:682]
connect_auth_ready(): Allowed authentication: unspecified
<debug> [1493975621.330556] [../../git/src/mm-iface-modem-simple.c:684]
connect_auth_ready(): User: unspecified
<debug> [1493975621.330607] [../../git/src/mm-iface-modem-simple.c:686]
connect_auth_ready(): Password: unspecified
<debug> [1493975621.330659] [../../git/src/mm-iface-modem-simple.c:688]
connect_auth_ready(): Number: *99#
<info> [1493975621.330701] [../../git/src/mm-iface-modem-simple.c:452]
connection_step(): Simple connect state (1/8): Unlock check
<debug> [1493975621.330838] [../../git/src/mm-broadband-modem.c:1339]
modem_load_unlock_required(): checking if unlock required...
<debug> [1493975621.330995] [../../git/src/mm-port-serial.c:1120]
mm_port_serial_open(): (ttyACM0) opening serial port...
<debug> [1493975621.332233] [../../git/src/mm-port-serial.c:421]
real_config_fd(): (ttyACM0): setting up baudrate: 57600
<debug> [1493975621.332607] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
<debug> [1493975621.333028] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
<debug> [1493975621.358806] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CPIN: SIM PIN<CR><LF>'
<debug> [1493975621.359550] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975621.360058] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 0 (close)
<debug> [1493975621.360170] [../../git/src/mm-port-serial.c:1323]
_close_internal(): (ttyACM0) closing serial port...
<debug> [1493975621.363881] [../../git/src/mm-port-serial.c:1371]
_close_internal(): (ttyACM0) serial port closed
<debug> [1493975621.364348] [../../git/src/mm-port-serial.c:1120]
mm_port_serial_open(): (ttyACM0) opening serial port...
<debug> [1493975621.365320] [../../git/src/mm-port-serial.c:421]
real_config_fd(): (ttyACM0): setting up baudrate: 57600
<debug> [1493975621.365672] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
<debug> [1493975621.366445] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+UPINCNT<CR>'
<debug> [1493975621.389356] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+UPINCNT: 3,3,10,10<CR><LF>'
<debug> [1493975621.390398] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975621.391521] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 0 (close)
<debug> [1493975621.391678] [../../git/src/mm-port-serial.c:1323]
_close_internal(): (ttyACM0) closing serial port...
<debug> [1493975621.395455] [../../git/src/mm-port-serial.c:1371]
_close_internal(): (ttyACM0) serial port closed
<debug> [1493975621.396137] [../../git/src/mm-port-serial.c:1120]
mm_port_serial_open(): (ttyACM0) opening serial port...
<debug> [1493975621.397202] [../../git/src/mm-port-serial.c:421]
real_config_fd(): (ttyACM0): setting up baudrate: 57600
<debug> [1493975621.397505] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
<debug> [1493975621.397922] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CPIN="8770"<CR>'
<debug> [1493975621.484268] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975621.485273] [../../git/src/mm-broadband-modem.c:1339]
modem_load_unlock_required(): checking if unlock required...
<debug> [1493975621.485618] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975621.486500] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975621.486979] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
<debug> [1493975621.513303] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CPIN: SIM PIN<CR><LF>'
<debug> [1493975621.515441] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975621.517437] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 0 (close)
<debug> [1493975621.517703] [../../git/src/mm-port-serial.c:1323]
_close_internal(): (ttyACM0) closing serial port...
<debug> [1493975621.523061] [../../git/src/mm-port-serial.c:1371]
_close_internal(): (ttyACM0) serial port closed
<debug> [1493975621.523903] [../../git/src/mm-port-serial.c:1120]
mm_port_serial_open(): (ttyACM0) opening serial port...
<debug> [1493975621.525526] [../../git/src/mm-port-serial.c:421]
real_config_fd(): (ttyACM0): setting up baudrate: 57600
<debug> [1493975621.526340] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
<debug> [1493975621.527094] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+UPINCNT<CR>'
<debug> [1493975621.552485] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+UPINCNT: 3,3,10,10<CR><LF>'
<debug> [1493975621.553961] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975621.556503] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 0 (close)
<debug> [1493975621.556691] [../../git/src/mm-port-serial.c:1323]
_close_internal(): (ttyACM0) closing serial port...
<debug> [1493975621.560616] [../../git/src/mm-port-serial.c:1371]
_close_internal(): (ttyACM0) serial port closed
<info> [1493975623.135156] [../../git/src/mm-iface-modem-simple.c:650]
connect_auth_ready(): Simple connect started...
<debug> [1493975623.135294] [../../git/src/mm-iface-modem-simple.c:660]
connect_auth_ready(): PIN: 8770
<debug> [1493975623.135351] [../../git/src/mm-iface-modem-simple.c:662]
connect_auth_ready(): Operator ID: unspecified
<debug> [1493975623.135395] [../../git/src/mm-iface-modem-simple.c:664]
connect_auth_ready(): Allowed roaming: yes
<debug> [1493975623.135483] [../../git/src/mm-iface-modem-simple.c:666]
connect_auth_ready(): APN: ac.vodafone.es
<debug> [1493975623.135537] [../../git/src/mm-iface-modem-simple.c:674]
connect_auth_ready(): IP family: unspecified
<debug> [1493975623.135578] [../../git/src/mm-iface-modem-simple.c:682]
connect_auth_ready(): Allowed authentication: unspecified
<debug> [1493975623.135617] [../../git/src/mm-iface-modem-simple.c:684]
connect_auth_ready(): User: unspecified
<debug> [1493975623.135658] [../../git/src/mm-iface-modem-simple.c:686]
connect_auth_ready(): Password: unspecified
<debug> [1493975623.135697] [../../git/src/mm-iface-modem-simple.c:688]
connect_auth_ready(): Number: *99#
<info> [1493975623.136386] [../../git/src/mm-iface-modem-simple.c:452]
connection_step(): Simple connect state (1/8): Unlock check
<debug> [1493975623.136533] [../../git/src/mm-broadband-modem.c:1339]
modem_load_unlock_required(): checking if unlock required...
<debug> [1493975623.136681] [../../git/src/mm-port-serial.c:1120]
mm_port_serial_open(): (ttyACM0) opening serial port...
<debug> [1493975623.137805] [../../git/src/mm-port-serial.c:421]
real_config_fd(): (ttyACM0): setting up baudrate: 57600
<debug> [1493975623.138123] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
<debug> [1493975623.138553] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
<debug> [1493975623.162986] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CPIN: READY<CR><LF>'
<debug> [1493975623.165026] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975623.165542] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 0 (close)
<debug> [1493975623.165644] [../../git/src/mm-port-serial.c:1323]
_close_internal(): (ttyACM0) closing serial port...
<debug> [1493975623.169439] [../../git/src/mm-port-serial.c:1371]
_close_internal(): (ttyACM0) serial port closed
<debug> [1493975623.169821] [../../git/src/mm-iface-modem.c:3101]
update_lock_info_context_step(): SIM is ready, and no need for the after SIM
unlock step...
<debug> [1493975623.170007] [../../git/src/mm-port-serial.c:1120]
mm_port_serial_open(): (ttyACM0) opening serial port...
<debug> [1493975623.170947] [../../git/src/mm-port-serial.c:421]
real_config_fd(): (ttyACM0): setting up baudrate: 57600
<debug> [1493975623.171241] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
<debug> [1493975623.171634] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+UPINCNT<CR>'
<debug> [1493975623.193222] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+UPINCNT: 3,3,10,10<CR><LF>'
<debug> [1493975623.194006] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975623.195254] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 0 (close)
<debug> [1493975623.195408] [../../git/src/mm-port-serial.c:1323]
_close_internal(): (ttyACM0) closing serial port...
<debug> [1493975623.199681] [../../git/src/mm-port-serial.c:1371]
_close_internal(): (ttyACM0) serial port closed
<info> [1493975623.205486] [../../git/src/mm-iface-modem-simple.c:461]
connection_step(): Simple connect state (2/8): Wait to get fully initialized
<info> [1493975623.206262] [../../git/src/mm-iface-modem.c:1433]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (locked -> initializing)
<debug> [1493975623.219431] [../../git/src/mm-port-serial.c:1120]
mm_port_serial_open(): (ttyACM0) opening serial port...
<debug> [1493975623.220622] [../../git/src/mm-port-serial.c:421]
real_config_fd(): (ttyACM0): setting up baudrate: 57600
<debug> [1493975623.220964] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
<debug> [1493975623.221120] [../../git/src/mm-port-serial-at.c:495]
mm_port_serial_at_run_init_sequence(): (ttyACM0): running init sequence...
<debug> [1493975623.221474] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975623.221713] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975623.222829] [../../git/src/mm-base-sim.c:1074] load_imsi():
loading IMSI...
<debug> [1493975623.223055] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975623.223409] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATE0<CR>'
<debug> [1493975623.234865] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975623.235503] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATV1<CR>'
<debug> [1493975623.243362] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975623.243978] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1493975623.260964] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975623.261636] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATX4<CR>'
<debug> [1493975623.274865] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1493975623.275330] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [1493975623.275869] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT&C1<CR>'
<debug> [1493975623.290634] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975623.291253] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATE0<CR>'
<debug> [1493975623.306269] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975623.306744] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975623.307057] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1493975623.323611] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975623.324100] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975623.324409] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CIMI<CR>'
<debug> [1493975623.341137] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>214019822988433<CR><LF>'
<debug> [1493975623.341946] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975623.342432] [../../git/src/mm-base-sim.c:1063]
load_imsi_finish(): loaded IMSI: 214019822988433
<debug> [1493975623.342781] [../../git/src/mm-base-sim.c:1194]
load_operator_identifier(): loading Operator ID...
<debug> [1493975623.342985] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975623.343240] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975623.350291] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CRSM=176,28589,0,0,4<CR>'
<debug> [1493975623.946352] [../../git/src/mm-port-probe-at.c:43]
mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed
out'
<debug> [1493975623.946927] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttymxc1): --> 'AT<CR>'
<debug> [1493975624.585504] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"00000002"<CR><LF>'
<debug> [1493975624.587157] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975624.589102] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975624.597615] [../../git/src/mm-broadband-modem.c:1217]
modem_load_own_numbers(): loading own numbers...
<debug> [1493975624.598037] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975624.598666] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CNUM<CR>'
<debug> [1493975624.631688] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975624.634975] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975624.641663] [../../git/src/mm-broadband-modem.c:3362]
modem_3gpp_load_imei(): loading IMEI...
<debug> [1493975624.642044] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975624.642705] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
<debug> [1493975624.661680] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>352255060049271<CR><LF>'
<debug> [1493975624.662932] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975624.663886] [../../git/src/mm-broadband-modem.c:3353]
modem_3gpp_load_imei_finish(): loaded IMEI: 352255060049271
<debug> [1493975624.664370] [../../git/src/mm-broadband-modem.c:3521]
modem_3gpp_load_enabled_facility_locks(): loading enabled facility locks...
<debug> [1493975624.664661] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975624.665032] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975624.665496] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CLCK=?<CR>'
<debug> [1493975624.687536] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK:
("CS","PS","PF","SC","AO","OI","OX","AI","IR","NT","NM","NS","NA","AB","AG","AC","FD","PN","PU","PP","PC")<CR><LF>'
<debug> [1493975624.688479] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975624.691063] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975624.691382] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975624.691693] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CLCK="SC",2<CR>'
<debug> [1493975624.717024] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 1<CR><LF>'
<debug> [1493975624.718064] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975624.718946] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975624.719240] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975624.719526] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CLCK="FD",2<CR>'
<debug> [1493975624.747519] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF>'
<debug> [1493975624.748308] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975624.749110] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975624.749396] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975624.749688] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CLCK="PS",2<CR>'
<debug> [1493975624.779142] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
<debug> [1493975624.779599] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 4: Operation not supported
<debug> [1493975624.779996] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975624.780260] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975624.780529] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CLCK="PF",2<CR>'
<debug> [1493975624.810315] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 3<CR><LF>'
<debug> [1493975624.811083] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 3: Operation not allowed
<debug> [1493975624.811610] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975624.812035] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975624.812471] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CLCK="PN",2<CR>'
<debug> [1493975624.841573] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
<debug> [1493975624.842443] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 4: Operation not supported
<debug> [1493975624.842956] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975624.843373] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975624.843820] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CLCK="PU",2<CR>'
<debug> [1493975624.872676] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
<debug> [1493975624.873433] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 4: Operation not supported
<debug> [1493975624.873945] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975624.874358] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975624.874783] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CLCK="PP",2<CR>'
<debug> [1493975624.904059] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
<debug> [1493975624.904814] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 4: Operation not supported
<debug> [1493975624.905336] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975624.906445] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975624.906780] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CLCK="PC",2<CR>'
<debug> [1493975624.935016] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
<debug> [1493975624.935488] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 4: Operation not supported
<debug> [1493975624.944810] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975624.948962] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975624.949477] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CUSD=?<CR>'
<debug> [1493975624.970024] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CUSD: (0-2)<CR><LF>'
<debug> [1493975624.971180] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975624.983206] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975625.005634] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975625.011676] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CNMI=?<CR>'
<debug> [1493975625.048260] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CNMI:
(0-2),(0-3),(0,2,3),(0-1),(0-1)<CR><LF>'
<debug> [1493975625.049279] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975625.049982] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975625.050251] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975625.050482] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CPMS=?<CR>'
<debug> [1493975625.076873] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: ("SM"),("SM"),("SM")<CR><LF>'
<debug> [1493975625.079657] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975625.091822] [../../git/src/mm-iface-modem-messaging.c:1149]
load_supported_storages_ready(): Supported storages loaded:
<debug> [1493975625.091937] [../../git/src/mm-iface-modem-messaging.c:1150]
load_supported_storages_ready(): mem1 (list/read/delete) storages: 'sm'
<debug> [1493975625.091981] [../../git/src/mm-iface-modem-messaging.c:1151]
load_supported_storages_ready(): mem2 (write/send) storages: 'sm'
<debug> [1493975625.092019] [../../git/src/mm-iface-modem-messaging.c:1152]
load_supported_storages_ready(): mem3 (reception) storages: 'sm'
<debug> [1493975625.092403] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975625.092665] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975625.092911] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CPMS?<CR>'
<debug> [1493975625.122491] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS:
"SM",0,20,"SM",0,20,"SM",0,20<CR><LF>'
<debug> [1493975625.123238] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975625.124025] [../../git/src/mm-broadband-modem.c:5412]
cpms_query_ready(): Current storages initialized:
<debug> [1493975625.124155] [../../git/src/mm-broadband-modem.c:5414]
cpms_query_ready(): mem1 (list/read/delete) storages: 'sm'
<debug> [1493975625.124215] [../../git/src/mm-broadband-modem.c:5416]
cpms_query_ready(): mem2 (write/send) storages: 'sm'
<debug> [1493975625.124281] [../../git/src/mm-iface-modem-messaging.c:1232]
init_current_storages_ready(): Current storages initialized
<debug> [1493975625.134287] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975625.135664] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975625.138558] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATH<CR>'
<debug> [1493975625.151007] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1493975625.151488] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [1493975625.151700] [../../git/src/mm-iface-modem-voice.c:916]
check_support_ready(): Voice support check failed: 'Unknown error'
<debug> [1493975625.151897] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975625.152148] [../../git/src/mm-broadband-modem.c:10008]
iface_modem_voice_initialize_ready(): Couldn't initialize interface: 'Voice not
supported'
<debug> [1493975625.153406] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975625.153883] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CTZU=1<CR>'
<debug> [1493975625.191030] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975625.191856] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CCLK?<CR>'
<debug> [1493975625.214391] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CCLK: "70/01/01,02:09:25+08"<CR><LF>'
<debug> [1493975625.215265] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975625.223523] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975625.225112] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975625.225613] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CESQ=?<CR>'
<debug> [1493975625.242523] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CESQ:
(0-63,99),(0-7,99),(0-96,255),(0-49,255),(0-34,255),(0-97,255)<CR><LF>'
<debug> [1493975625.243417] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975625.258271] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975625.258665] [../../git/src/mm-broadband-modem.c:10011]
iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not
supported'
<debug> [1493975625.259334] [../../git/src/mm-broadband-modem.c:10012]
iface_modem_firmware_initialize_ready(): Couldn't initialize interface:
'Firmware interface not available'
<info> [1493975625.259706] [../../git/src/mm-iface-modem.c:1433]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (initializing -> disabled)
<info> [1493975625.260225] [../../git/src/mm-iface-modem-simple.c:470]
connection_step(): Simple connect state (3/8): Enable
<debug> [1493975625.295548] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 0 (close)
<debug> [1493975625.296139] [../../git/src/mm-port-serial.c:1323]
_close_internal(): (ttyACM0) closing serial port...
<debug> [1493975625.304773] [../../git/src/mm-port-serial.c:1371]
_close_internal(): (ttyACM0) serial port closed
<info> [1493975625.310399] [../../git/src/mm-iface-modem.c:1433]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
<debug> [1493975625.320479] [../../git/src/mm-port-serial.c:1120]
mm_port_serial_open(): (ttyACM0) opening serial port...
<debug> [1493975625.322575] [../../git/src/mm-port-serial.c:421]
real_config_fd(): (ttyACM0): setting up baudrate: 57600
<debug> [1493975625.322925] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 1 (open)
<debug> [1493975625.323032] [../../git/src/mm-broadband-modem.c:8928]
enabling_started(): Flashing primary AT port before enabling...
<debug> [1493975625.323548] [../../git/src/mm-port-serial.c:399]
internal_tcsetattr(): (ttyACM0): port attributes not fully set
<debug> [1493975625.425276] [../../git/src/mm-broadband-modem.c:8817]
enabling_flash_done(): Running modem initialization sequence...
<debug> [1493975625.427453] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975625.428163] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATZ<CR>'
<debug> [1493975625.681943] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975625.682766] [../../git/src/mm-broadband-modem.c:8798]
enabling_modem_init_ready(): Giving some time to settle the modem...
<debug> [1493975625.682998] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975626.184468] [../../git/src/mm-port-serial-at.c:495]
mm_port_serial_at_run_init_sequence(): (ttyACM0): running init sequence...
<debug> [1493975626.185520] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975626.186410] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATE0<CR>'
<debug> [1493975626.197552] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.198559] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATV1<CR>'
<debug> [1493975626.213281] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.214285] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1493975626.237402] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.238379] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATX4<CR>'
<debug> [1493975626.251568] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
<debug> [1493975626.252397] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [1493975626.252946] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT&C1<CR>'
<debug> [1493975626.267274] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.268351] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CFUN?<CR>'
<debug> [1493975626.289054] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CFUN: 1,0<CR><LF>'
<debug> [1493975626.290278] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.291452] [../../git/src/mm-iface-modem.c:3298]
set_power_state(): No need to change power state: already in 'on' power state
<debug> [1493975626.291724] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975626.292413] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975626.293181] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+IFC=?<CR>'
<debug> [1493975626.314211] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+IFC: (0-2),(0-2)<CR><LF>'
<debug> [1493975626.315550] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.316940] [../../git/src/mm-modem-helpers.c:541]
flow_control_array_to_mask(): TE supports no flow control
<debug> [1493975626.317153] [../../git/src/mm-modem-helpers.c:545]
flow_control_array_to_mask(): TE supports XON/XOFF flow control
<debug> [1493975626.317269] [../../git/src/mm-modem-helpers.c:549]
flow_control_array_to_mask(): TE supports RTS/CTS flow control
<debug> [1493975626.317412] [../../git/src/mm-modem-helpers.c:541]
flow_control_array_to_mask(): TA supports no flow control
<debug> [1493975626.317521] [../../git/src/mm-modem-helpers.c:545]
flow_control_array_to_mask(): TA supports XON/XOFF flow control
<debug> [1493975626.317618] [../../git/src/mm-modem-helpers.c:549]
flow_control_array_to_mask(): TA supports RTS/CTS flow control
<debug> [1493975626.317948] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975626.318541] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975626.318942] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975626.319406] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+IFC=2,2<CR>'
<debug> [1493975626.345439] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.346449] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975626.346998] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CSCS=?<CR>'
<debug> [1493975626.376943] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS:
("IRA","GSM","UCS2","HEX","8859-1")<CR><LF>'
<debug> [1493975626.377943] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.379054] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975626.379404] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975626.379698] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CSCS="UCS2"<CR>'
<debug> [1493975626.417015] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.417610] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975626.417895] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975626.418153] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CSCS?<CR>'
<debug> [1493975626.440035] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF>'
<debug> [1493975626.440887] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.441482] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975626.441769] [../../git/src/mm-broadband-modem.c:9573]
enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP
interface...
<debug> [1493975626.442932] [../../git/src/mm-broadband-modem.c:2703]
modem_3gpp_setup_unsolicited_events(): Checking indicator support...
<debug> [1493975626.443192] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975626.443602] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CIND=?<CR>'
<debug> [1493975626.470595] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CIND:
("battchg",(0-5)),("signal",(0-5)),("service",(0-1)),("sounder",(0-1)),("message",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("gprs",(0-2)),("callsetup",(0-3)),("callheld",(0-1)),("simind",(0-2))<CR><LF>'
<debug> [1493975626.471579] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.472843] [../../git/src/mm-broadband-modem.c:2656]
cind_format_check_ready(): Modem supports signal quality indications via CIND
at index '2'(min: 0, max: 5)
<debug> [1493975626.472975] [../../git/src/mm-broadband-modem.c:2665]
cind_format_check_ready(): Modem supports roaming indications via CIND at index
'7'
<debug> [1493975626.473035] [../../git/src/mm-broadband-modem.c:2674]
cind_format_check_ready(): Modem supports service indications via CIND at index
'3'
<debug> [1493975626.473245] [../../git/src/mm-broadband-modem.c:2607]
set_unsolicited_events_handlers(): (ttyACM0) Setting 3GPP unsolicited events
handlers
<debug> [1493975626.473533] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975626.473800] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975626.474089] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CMER=3,0,0,1<CR>'
<debug> [1493975626.509661] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
<debug> [1493975626.510142] [../../git/src/mm-serial-parsers.c:364]
mm_serial_parser_v1_parse(): Got failure code 4: Operation not supported
<debug> [1493975626.510344] [../../git/src/mm-broadband-modem.c:2793]
unsolicited_events_setup_ready(): Couldn't enable event reporting: 'Operation
not supported'
<debug> [1493975626.512717] [../../git/src/mm-broadband-modem.c:3735]
modem_3gpp_setup_unsolicited_registration_events(): (ttyACM0) setting up 3GPP
unsolicited registration messages handlers
<debug> [1493975626.513068] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975626.513616] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975626.514022] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CREG=2<CR>'
<debug> [1493975626.539991] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.549448] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975626.549790] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975626.552267] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"1072","01A50BF7",6<CR><LF>'
<info> [1493975626.552771] [../../git/src/mm-iface-modem-3gpp.c:1197]
update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP
Registration state changed (unknown -> registering)
<debug> [1493975626.552964] [../../git/src/mm-broadband-modem.c:3564]
modem_3gpp_load_operator_code(): loading Operator Code...
<debug> [1493975626.553129] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975626.553723] [../../git/src/mm-iface-modem.c:935]
mm_iface_modem_update_access_technologies(): Modem
/org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown ->
hspa)
<debug> [1493975626.561362] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CGREG=2<CR>'
<debug> [1493975626.601889] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.602561] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975626.602840] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975626.603151] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
<debug> [1493975626.625607] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 1,"1072","00000BF7",6,"00"<CR><LF>'
<debug> [1493975626.673092] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+COPS:
0,2,"21401",6<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1493975626.707828] [../../git/src/mm-broadband-modem.c:3555]
modem_3gpp_load_operator_code_finish(): loaded Operator Code: 21401
<debug> [1493975626.708430] [../../git/src/mm-broadband-modem.c:3607]
modem_3gpp_load_operator_name(): loading Operator Name...
<debug> [1493975626.708643] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975626.708879] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975626.714425] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CEREG=2<CR>'
<debug> [1493975626.743076] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.743723] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975626.744353] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CEREG: 4<CR><LF>'
<debug> [1493975626.745053] [../../git/src/mm-broadband-modem.c:9586]
enabling_step(): Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD
interface...
<debug> [1493975626.745384] [../../git/src/mm-broadband-modem.c:5049]
set_unsolicited_result_code_handlers(): (ttyACM0) Setting unsolicited result
code handlers
<debug> [1493975626.746079] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975626.746480] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
<debug> [1493975626.811784] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"vodafone ES",6<CR><LF>'
<debug> [1493975626.812735] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.813654] [../../git/src/mm-broadband-modem.c:3598]
modem_3gpp_load_operator_name_finish(): loaded Operator Name: vodafone ES
<debug> [1493975626.814172] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<info> [1493975626.823103] [../../git/src/mm-iface-modem-3gpp.c:1114]
update_registration_reload_current_registration_info_ready(): Modem
/org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed
(registering -> home)
<debug> [1493975626.823988] [../../git/src/mm-iface-modem.c:1602]
get_updated_consolidated_state(): Will start keeping track of state for
subsystem '3gpp'
<debug> [1493975626.833092] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CUSD=1<CR>'
<debug> [1493975626.854942] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.856486] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975626.865571] [../../git/src/mm-broadband-modem.c:9614]
enabling_step(): Modem has location capabilities, enabling the Location
interface...
<debug> [1493975626.866246] [../../git/src/mm-iface-modem-location.c:762]
setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
<debug> [1493975626.869658] [../../git/src/mm-iface-modem-3gpp.c:798]
mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS:
'yes', PS: 'yes', EPS: 'yes')
<debug> [1493975626.870952] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975626.871540] [../../git/src/mm-broadband-modem.c:3564]
modem_3gpp_load_operator_code(): loading Operator Code...
<debug> [1493975626.871810] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975626.881540] [../../git/src/mm-broadband-modem.c:9627]
enabling_step(): Modem has messaging capabilities, enabling the Messaging
interface...
<debug> [1493975626.882961] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975626.883580] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
<debug> [1493975626.905139] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"1072","01A50BF7",6<CR><LF>'
<debug> [1493975626.906231] [../../git/src/mm-iface-modem-location.c:297]
notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0:
3GPP location updated (MCC: '0', MNC: '0', Location area code: '1072', Cell ID:
'1A50BF7')
<debug> [1493975626.906810] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.907323] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 5 (open)
<debug> [1493975626.907570] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 4 (close)
<debug> [1493975626.907864] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
<debug> [1493975626.946594] [../../git/src/mm-port-probe-at.c:43]
mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed
out'
<debug> [1493975626.947124] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttymxc1): --> 'AT<CR>'
<debug> [1493975626.974410] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"21401",6<CR><LF>'
<debug> [1493975626.975941] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975626.977695] [../../git/src/mm-broadband-modem.c:3555]
modem_3gpp_load_operator_code_finish(): loaded Operator Code: 21401
<debug> [1493975626.978459] [../../git/src/mm-iface-modem-location.c:297]
notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0:
3GPP location updated (MCC: '214', MNC: '1', Location area code: '1072', Cell
ID: '1A50BF7')
<debug> [1493975626.978787] [../../git/src/mm-broadband-modem.c:3607]
modem_3gpp_load_operator_name(): loading Operator Name...
<debug> [1493975626.979069] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 5 (open)
<debug> [1493975626.979435] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 4 (close)
<debug> [1493975626.979973] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CMGF=?<CR>'
<debug> [1493975627.000637] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CMGF: (0,1)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1493975627.002418] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 5 (open)
<debug> [1493975627.002843] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 4 (close)
<debug> [1493975627.003360] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
<debug> [1493975627.029970] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG:
2,1,"1072","00000BF7",6,"00"<CR><LF>'
<debug> [1493975627.031660] [../../git/src/mm-iface-modem-location.c:297]
notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0:
3GPP location updated (MCC: '214', MNC: '1', Location area code: '1072', Cell
ID: 'BF7')
<debug> [1493975627.032516] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.033352] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 5 (open)
<debug> [1493975627.033761] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 4 (close)
<debug> [1493975627.034268] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
<debug> [1493975627.101338] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"vodafone ES",6<CR><LF>'
<debug> [1493975627.102250] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.103171] [../../git/src/mm-broadband-modem.c:3598]
modem_3gpp_load_operator_name_finish(): loaded Operator Name: vodafone ES
<debug> [1493975627.103664] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975627.104443] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CMGF=0<CR>'
<debug> [1493975627.120749] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.121261] [../../git/src/mm-broadband-modem.c:5729]
cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU'
<debug> [1493975627.121920] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975627.122194] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975627.129222] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CEREG?<CR>'
<debug> [1493975627.155463] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CEREG: 2,4<CR><LF>'
<debug> [1493975627.157652] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.158448] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975627.159086] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CPMS="SM","SM","SM"<CR>'
<debug> [1493975627.207422] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,20,0,20,0,20<CR><LF>'
<debug> [1493975627.208321] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.208850] [../../git/src/mm-broadband-modem.c:6501]
modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
<debug> [1493975627.209039] [../../git/src/mm-broadband-modem.c:5611]
mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM),
mem2 (none)...
<debug> [1493975627.209245] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975627.209516] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975627.209796] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CPMS="SM"<CR>'
<debug> [1493975627.238654] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,20,0,20,0,20<CR><LF>'
<debug> [1493975627.239519] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.240053] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975627.240452] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975627.240833] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
<debug> [1493975627.306113] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.307778] [../../git/src/mm-broadband-modem.c:6032]
set_messaging_unsolicited_events_handlers(): (ttyACM0) Setting messaging
unsolicited events handlers
<debug> [1493975627.308157] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975627.308644] [../../git/src/mm-broadband-modem.c:6205]
modem_messaging_enable_unsolicited_events(): (ttyACM0) Enabling messaging
unsolicited events on primary port
<debug> [1493975627.308961] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975627.309543] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CNMI=2,1,2,1,0<CR>'
<debug> [1493975627.350192] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.351871] [../../git/src/mm-broadband-modem.c:6166]
modem_messaging_enable_unsolicited_events_primary_ready(): (ttyACM0) Messaging
unsolicited events enabled on primary
<debug> [1493975627.352278] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 1 (close)
<debug> [1493975627.352712] [../../git/src/mm-broadband-modem.c:9653]
enabling_step(): Modem has time capabilities, enabling the Time interface...
<debug> [1493975627.354508] [../../git/src/mm-broadband-modem.c:9666]
enabling_step(): Modem has extended signal reporting capabilities, enabling the
Signal interface...
<debug> [1493975627.354909] [../../git/src/mm-iface-modem-signal.c:206]
setup_refresh_context(): Extended signal information reporting disabled (rate:
0 seconds)
<info> [1493975627.356715] [../../git/src/mm-iface-modem.c:1433]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
<debug> [1493975627.374002] [../../git/src/mm-iface-modem.c:1357]
periodic_signal_quality_check_enable(): Periodic signal quality checks enabled
(interval = 3s)
<debug> [1493975627.374323] [../../git/src/mm-broadband-modem.c:2074]
modem_load_signal_quality(): loading signal quality...
<debug> [1493975627.374670] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 2 (open)
<debug> [1493975627.375065] [../../git/src/mm-iface-modem.c:1077]
periodic_access_technologies_check_enable(): Periodic access technology checks
enabled
<info> [1493975627.375696] [../../git/src/mm-iface-modem-simple.c:478]
connection_step(): Simple connect state (4/8): Wait to get fully enabled
<info> [1493975627.376518] [../../git/src/mm-iface-modem-simple.c:487]
connection_step(): Simple connect state (5/8): Register
<debug> [1493975627.376960] [../../git/src/mm-iface-modem-3gpp.c:437]
mm_iface_modem_3gpp_register_in_network(): Already registered in network
'21401', automatic registration not launched...
<info> [1493975627.378385] [../../git/src/mm-iface-modem-simple.c:510]
connection_step(): Simple connect state (6/8): Bearer
<debug> [1493975627.378731] [../../git/src/mm-iface-modem-simple.c:530]
connection_step(): Creating new bearer...
<debug> [1493975627.379093]
[../../git/plugins/ublox/mm-broadband-modem-ublox.c:813] create_bearer_step():
u-blox: checking current USB profile...
<debug> [1493975627.379392] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975627.380312] [../../git/src/mm-iface-modem-3gpp.c:798]
mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS:
'yes', PS: 'yes', EPS: 'yes')
<debug> [1493975627.380684] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975627.381096] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
<debug> [1493975627.401981] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,0,1,0,0,0,0,0,1,0,0,1<CR><LF>'
<debug> [1493975627.403408] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.404955] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 5 (open)
<debug> [1493975627.405390] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 4 (close)
<debug> [1493975627.406281] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+UUSBCONF?<CR>'
<debug> [1493975627.432807] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+UUSBCONF: 3,"RNDIS",,"0x1146"<CR><LF>'
<debug> [1493975627.434250] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.435670]
[../../git/plugins/ublox/mm-broadband-modem-ublox.c:790] profile_check_ready():
u-blox: usb profile loaded: rndis
<debug> [1493975627.436436]
[../../git/plugins/ublox/mm-broadband-modem-ublox.c:828] create_bearer_step():
u-blox: checking current networking mode...
<debug> [1493975627.436641] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 5 (open)
<debug> [1493975627.436880] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 4 (close)
<debug> [1493975627.437219] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
<debug> [1493975627.459461] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"1072","01A50BF7",6<CR><LF>'
<debug> [1493975627.460404] [../../git/src/mm-iface-modem-location.c:297]
notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0:
3GPP location updated (MCC: '214', MNC: '1', Location area code: '1072', Cell
ID: '1A50BF7')
<debug> [1493975627.460967] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.462328] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 5 (open)
<debug> [1493975627.462669] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 4 (close)
<debug> [1493975627.463014] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
<debug> [1493975627.482653] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 7,2<CR><LF>'
<debug> [1493975627.483816] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.485256] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975627.486371] [../../git/src/mm-iface-modem.c:1206]
update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal
quality updated (22)
<debug> [1493975627.486565] [../../git/src/mm-iface-modem.c:1276]
signal_quality_check_ready(): Periodic signal quality checks rescheduled
(interval = 30s)
<debug> [1493975627.492271] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+UBMCONF?<CR>'
<debug> [1493975627.529269] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+UBMCONF: 1<CR><LF>'
<debug> [1493975627.530142] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.530904]
[../../git/plugins/ublox/mm-broadband-modem-ublox.c:760] mode_check_ready():
u-blox: networking mode loaded: router
<debug> [1493975627.531063]
[../../git/plugins/ublox/mm-broadband-modem-ublox.c:864] create_bearer_step():
u-blox: creating generic broadband bearer...
<debug> [1493975627.541177] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975627.541441] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975627.541548] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975627.542300]
[../../git/plugins/ublox/mm-broadband-modem-ublox.c:712]
broadband_bearer_new_ready(): u-blox: new generic broadband bearer created at
DBus path '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1493975627.548783] [../../git/src/mm-iface-modem-simple.c:592]
connection_step(): Simple connect state (7/8): Connect
<debug> [1493975627.549059] [../../git/src/mm-base-bearer.c:817]
mm_base_bearer_connect(): Connecting bearer
'/org/freedesktop/ModemManager1/Bearer/0'
<info> [1493975627.549298] [../../git/src/mm-iface-modem.c:1433]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
<debug> [1493975627.561550] [../../git/src/mm-broadband-bearer.c:1393]
connect(): Launching 3GPP connection attempt with APN 'ac.vodafone.es'
<debug> [1493975627.562647] [../../git/src/mm-broadband-bearer.c:96]
select_bearer_ip_family(): No specific IP family requested, defaulting to ipv4
<debug> [1493975627.562814] [../../git/src/mm-broadband-bearer.c:96]
select_bearer_ip_family(): No specific IP family requested, defaulting to ipv4
<debug> [1493975627.562931] [../../git/src/mm-broadband-bearer.c:1001]
cid_selection_3gpp(): Looking for best CID...
<debug> [1493975627.563102] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975627.563487] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
<debug> [1493975627.584396] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG:
2,1,"1072","00000BF7",6,"00"<CR><LF>'
<debug> [1493975627.585488] [../../git/src/mm-iface-modem-location.c:297]
notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0:
3GPP location updated (MCC: '214', MNC: '1', Location area code: '1072', Cell
ID: 'BF7')
<debug> [1493975627.586167] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.587335] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975627.587636] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975627.587998] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
<debug> [1493975627.612288] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.612814] [../../git/src/mm-broadband-bearer.c:909]
parse_pdp_list(): No PDP contexts found
<debug> [1493975627.613193] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CEREG?<CR>'
<debug> [1493975627.639081] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>+CEREG: 2,4<CR><LF>'
<debug> [1493975627.640081] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.640587] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975627.640990] [../../git/src/mm-broadband-modem.c:8950]
modem_3gpp_run_registration_checks_ready(): Initial 3GPP registration checks
finished
<debug> [1493975627.641807] [../../git/src/mm-broadband-bearer.c:843]
parse_cid_range(): Using empty CID 1 with PDP type 'ipv4'
<debug> [1493975627.642094] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975627.642362] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975627.642632] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'AT+CGDCONT=1,"IP","ac.vodafone.es"<CR>'
<debug> [1493975627.724973] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1493975627.739185] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 3 (open)
<debug> [1493975627.739437] [../../git/src/mm-broadband-bearer.c:234]
common_get_at_data_port(): Connection through a plain serial AT port (ttyACM0)
<debug> [1493975627.739762] [../../git/src/mm-port-serial.c:1250]
mm_port_serial_open(): (ttyACM0) device open count is 4 (open)
<debug> [1493975627.740264] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 3 (close)
<debug> [1493975627.740756] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): --> 'ATD*99***1#<CR>'
<debug> [1493975627.785326] [../../git/src/mm-port-serial-at.c:459]
debug_log(): (ttyACM0): <-- '<CR><LF>CONNECT<CR><LF>'
<debug> [1493975627.788181] [../../git/src/mm-broadband-bearer.c:612]
atd_ready(): [ttyACM0] Setting flow control: rts-cts
<debug> [1493975627.788337] [../../git/src/mm-port-serial.c:1811]
mm_port_serial_set_flow_control(): (ttyACM0): enabling RTS/CTS flow control
<debug> [1493975627.788618] [../../git/src/mm-port.c:94]
mm_port_set_connected(): (ttyACM0): port now connected
<debug> [1493975627.789023] [../../git/src/mm-port-serial.c:1307]
_close_internal(): (ttyACM0) device open count is 2 (close)
<debug> [1493975627.789434] [../../git/src/mm-base-bearer.c:715]
connect_ready(): Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1493975627.791078] [../../git/src/mm-iface-modem.c:1433]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
<info> [1493975627.801787] [../../git/src/mm-iface-modem-simple.c:611]
connection_step(): Simple connect state (8/8): All done
_______________________________________________
ModemManager-devel mailing list
ModemManager-devel@lists.freedesktop.org
https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
