[PATCH v2] altair-lte: add SIMREFRESH support

[Thieu Le]

Debug log showing SIMREFRESH:

2013-11-19T12:40:24.840690-08:00 localhost ModemManager[1362]: <info>
 ModemManager (version 1.1.0) starting...
2013-11-19T12:40:24.871373-08:00 localhost ModemManager[1362]: <debug> Bus
acquired, creating manager...
2013-11-19T12:40:24.871419-08:00 localhost ModemManager[1362]: <debug>
Looking for plugins in '/usr/lib/ModemManager'
2013-11-19T12:40:24.875425-08:00 localhost ModemManager[1362]: <debug>
Loaded plugin 'Longcheer'
2013-11-19T12:40:24.877343-08:00 localhost ModemManager[1362]: <debug>
Loaded plugin 'Samsung'
2013-11-19T12:40:24.879694-08:00 localhost ModemManager[1362]: <debug>
Loaded plugin 'Huawei'
2013-11-19T12:40:24.881963-08:00 localhost ModemManager[1362]: <debug>
Loaded plugin 'ZTE'
2013-11-19T12:40:24.883824-08:00 localhost ModemManager[1362]: <debug>
Loaded plugin 'Novatel LTE'
2013-11-19T12:40:24.889024-08:00 localhost ModemManager[1362]: <debug>
Loaded plugin 'Generic'
2013-11-19T12:40:24.914418-08:00 localhost ModemManager[1362]: <debug>
Loaded plugin 'Altair LTE'
2013-11-19T12:40:24.914458-08:00 localhost ModemManager[1362]: <debug>
Successfully loaded 7 plugins
2013-11-19T12:40:24.917493-08:00 localhost ModemManager[1362]: <debug>
Service name 'org.freedesktop.ModemManager1' was acquired
2013-11-19T12:40:24.917509-08:00 localhost ModemManager[1362]: <debug>
Starting automatic device scan...
2013-11-19T12:40:24.939683-08:00 localhost ModemManager[1362]: <debug>
Finished device scan...
2013-11-19T12:40:24.966035-08:00 localhost ModemManager[1362]: <debug>
(net/mlan0): port's parent platform driver is not whitelisted
2013-11-19T12:40:25.363414-08:00 localhost ModemManager[1362]: <debug>
(net/mlan0): port's parent platform driver is not whitelisted
2013-11-19T12:40:25.498929-08:00 localhost ModemManager[1362]: <debug>
(tty/ttyS0): port's parent platform driver is not whitelisted
2013-11-19T12:40:25.502221-08:00 localhost ModemManager[1362]: <debug>
(tty/ttyS1): port's parent platform driver is not whitelisted
2013-11-19T12:40:25.504513-08:00 localhost ModemManager[1362]: <debug>
(tty/ttyS2): port's parent platform driver is not whitelisted
2013-11-19T12:40:25.509879-08:00 localhost ModemManager[1362]: <debug>
(tty/ttyS3): port's parent platform driver is not whitelisted
2013-11-19T12:40:26.222361-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [/sys/devices/s5p-ehci/usb1/1-2/1-2.2] Checking device
support...
2013-11-19T12:40:26.223846-08:00 localhost ModemManager[1362]: <debug>
(Longcheer) [ttyACM0] filtered by vendor/product IDs
2013-11-19T12:40:26.223881-08:00 localhost ModemManager[1362]: <debug>
(Samsung) [ttyACM0] filtered by vendor/product IDs
2013-11-19T12:40:26.223896-08:00 localhost ModemManager[1362]: <debug>
(Huawei) [ttyACM0] filtered by vendor/product IDs
2013-11-19T12:40:26.223908-08:00 localhost ModemManager[1362]: <debug>
(ZTE) [ttyACM0] filtered by vendor/product IDs
2013-11-19T12:40:26.223919-08:00 localhost ModemManager[1362]: <debug>
(Novatel LTE) [ttyACM0] filtered by vendor/product IDs
2013-11-19T12:40:26.223931-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [ttyACM0] Found '2' plugins to try...
2013-11-19T12:40:26.223942-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [ttyACM0]   Will try with plugin 'Altair LTE'
2013-11-19T12:40:26.223953-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [ttyACM0]   Will try with plugin 'Generic'
2013-11-19T12:40:26.223964-08:00 localhost ModemManager[1362]: <debug>
(Altair LTE) [ttyACM0] probe required: 'at'
2013-11-19T12:40:26.223974-08:00 localhost ModemManager[1362]: <debug>
(tty/ttyACM0) launching port probing: 'at'
2013-11-19T12:40:26.223984-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) opening serial port...
2013-11-19T12:40:26.228110-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (open)
2013-11-19T12:40:26.248700-08:00 localhost ModemManager[1362]: <debug>
(Longcheer) [eth0] filtered by subsystem
2013-11-19T12:40:26.248740-08:00 localhost ModemManager[1362]: <debug>
(Samsung) [eth0] filtered by vendor/product IDs
2013-11-19T12:40:26.248756-08:00 localhost ModemManager[1362]: <debug>
(Huawei) [eth0] filtered by vendor/product IDs
2013-11-19T12:40:26.248768-08:00 localhost ModemManager[1362]: <debug>
(ZTE) [eth0] filtered by vendor/product IDs
2013-11-19T12:40:26.248786-08:00 localhost ModemManager[1362]: <debug>
(Novatel LTE) [eth0] filtered by vendor/product IDs
2013-11-19T12:40:26.248801-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [eth0] Found '2' plugins to try...
2013-11-19T12:40:26.248818-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [eth0]   Will try with plugin 'Altair LTE'
2013-11-19T12:40:26.248838-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [eth0]   Will try with plugin 'Generic'
2013-11-19T12:40:26.248859-08:00 localhost ModemManager[1362]: <debug>
(Altair LTE) [eth0] probing deferred until result suggested
2013-11-19T12:40:26.248879-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [eth0] deferring support check until result suggested
2013-11-19T12:40:26.329440-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT<CR><LF>'
2013-11-19T12:40:28.589608-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- 'AT<CR><LF><CR><LF>'
2013-11-19T12:40:28.600244-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- 'AT<LF><LF>'
2013-11-19T12:40:28.605868-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:28.605931-08:00 localhost ModemManager[1362]: <debug>
(tty/ttyACM0) port is AT-capable
2013-11-19T12:40:28.606020-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 0 (close)
2013-11-19T12:40:28.606034-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) closing serial port...
2013-11-19T12:40:28.609633-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) serial port closed
2013-11-19T12:40:28.609676-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) forced to close port
2013-11-19T12:40:28.610027-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) (Altair LTE) [ttyACM0] found best plugin for port
2013-11-19T12:40:28.610048-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) (Altair LTE) [ttyACM0]: found best plugin for device
(/sys/devices/s5p-ehci/usb1/1-2/1-2.2)
2013-11-19T12:40:28.610063-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) (Altair LTE) [eth0] deferred task completed, got suggested
plugin
2013-11-19T12:40:28.610078-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) 'ttyACM0' port probe finished, still 1 running probes in
this device (eth0)
2013-11-19T12:40:28.610092-08:00 localhost ModemManager[1362]: <debug>
(Altair LTE) [eth0] probing deferred until result suggested
2013-11-19T12:40:28.610107-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) (Altair LTE) [eth0] task completed, got suggested plugin
2013-11-19T12:40:28.610146-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) 'eth0' port probe finished, last one in device, but
minimum probing time not consumed yet ('2.386635' seconds elapsed)
2013-11-19T12:40:28.877399-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [/sys/devices/s5p-ehci/usb1/1-2/1-2.2] Minimum probing
time consumed and no more ports to probe
2013-11-19T12:40:28.877440-08:00 localhost ModemManager[1362]: <debug>
(Plugin Manager) [/sys/devices/s5p-ehci/usb1/1-2/1-2.2] device support
check finished in '2.655100' seconds
2013-11-19T12:40:28.877457-08:00 localhost ModemManager[1362]: <info>
 Creating modem with plugin 'Altair LTE' and '2' ports
2013-11-19T12:40:28.880219-08:00 localhost ModemManager[1362]: <debug>
(eth0) type 'net' claimed by /sys/devices/s5p-ehci/usb1/1-2/1-2.2
2013-11-19T12:40:28.881990-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) type 'at' claimed by /sys/devices/s5p-ehci/usb1/1-2/1-2.2
2013-11-19T12:40:28.882319-08:00 localhost ModemManager[1362]: <debug>
(/sys/devices/s5p-ehci/usb1/1-2/1-2.2) tty/ttyACM0 at (primary)
2013-11-19T12:40:28.882669-08:00 localhost ModemManager[1362]: <debug>
(/sys/devices/s5p-ehci/usb1/1-2/1-2.2) net/eth0 data (primary)
2013-11-19T12:40:28.883397-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) opening serial port...
2013-11-19T12:40:28.884242-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (open)
2013-11-19T12:40:28.884584-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): running init sequence...
2013-11-19T12:40:28.884923-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:28.885264-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:28.885626-08:00 localhost ModemManager[1362]: <info>
 Modem for device at '/sys/devices/s5p-ehci/usb1/1-2/1-2.2' successfully
created
2013-11-19T12:40:28.892014-08:00 localhost ModemManager[1362]: <debug>
Loading (Altair LTE) current capabilities...
2013-11-19T12:40:28.892620-08:00 localhost ModemManager[1362]: <debug>
Loaded current capabilities: lte
2013-11-19T12:40:28.893056-08:00 localhost ModemManager[1362]: <debug>
Modem allows up to 1 bearers
2013-11-19T12:40:28.893344-08:00 localhost ModemManager[1362]: <debug>
Creating bearer list (max: 1, max active: 1)
2013-11-19T12:40:28.893782-08:00 localhost ModemManager[1362]: <debug>
loading manufacturer...
2013-11-19T12:40:28.894105-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 4 (open)
2013-11-19T12:40:28.894468-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CMEE=1<CR><LF>'
2013-11-19T12:40:28.899693-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- 'AT+CMEE=1<CR><LF>'
2013-11-19T12:40:28.903137-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:28.903172-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'ATE0<CR><LF>'
2013-11-19T12:40:28.907696-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- 'ATE0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:28.907740-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (close)
2013-11-19T12:40:28.907756-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CMEE=1<CR><LF>'
2013-11-19T12:40:28.915621-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:28.915665-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:28.915681-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CGMI<CR><LF>'
2013-11-19T12:40:28.923675-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>ALTAIR-SEMICONDUCTOR<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:28.923720-08:00 localhost ModemManager[1362]: <debug>
loaded manufacturer: ALTAIR-SEMICONDUCTOR
2013-11-19T12:40:28.923738-08:00 localhost ModemManager[1362]: <debug>
loading model...
2013-11-19T12:40:28.923756-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:28.923773-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:28.923789-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CGMM<CR><LF>'
2013-11-19T12:40:28.947547-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>ALT3100<CR><LF>OK<CR><LF>'
2013-11-19T12:40:28.947590-08:00 localhost ModemManager[1362]: <debug>
loaded model: ALT3100
2013-11-19T12:40:28.947607-08:00 localhost ModemManager[1362]: <debug>
loading revision...
2013-11-19T12:40:28.947624-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:28.947640-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:28.947654-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CGMR<CR><LF>'
2013-11-19T12:40:29.108915-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>ALT3100_04_05_06_00_84_TF<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.108951-08:00 localhost ModemManager[1362]: <debug>
loaded revision: ALT3100_04_05_06_00_84_TF
2013-11-19T12:40:29.108964-08:00 localhost ModemManager[1362]: <debug>
loading equipment identifier...
2013-11-19T12:40:29.108983-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.109000-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.109015-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CGSN<CR><LF>'
2013-11-19T12:40:29.119794-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>359636040115576<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.119835-08:00 localhost ModemManager[1362]: <debug>
loaded equipment identifier: 359636040115576
2013-11-19T12:40:29.119851-08:00 localhost ModemManager[1362]: <debug>
loading device identifier...
2013-11-19T12:40:29.119871-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.119889-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.119900-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'ATI<CR><LF>'
2013-11-19T12:40:29.202873-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>Manufacturer: ALTAIR-SEMICONDUCTOR<CR><LF>Model:
ALT3100<CR><LF>Revision: ALT3100_04_05_06_00_84_TF<CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.202917-08:00 localhost ModemManager[1362]: <debug>
Device ID source
'0000216f00000047Manufacturer:ALTAIR-SEMICONDUCTORModel:ALT3100Revision:ALT3100_04_05_06_00_84_TF359636040115576ALT3100_04_05_06_00_84_TFALT3100ALTAIR-SEMICONDUCTOR'
2013-11-19T12:40:29.202942-08:00 localhost ModemManager[1362]: <debug>
Device ID 'b3201292d9fa384120275b0dfd28fd717d3b4416'
2013-11-19T12:40:29.202956-08:00 localhost ModemManager[1362]: <debug>
loaded device identifier: b3201292d9fa384120275b0dfd28fd717d3b4416
2013-11-19T12:40:29.202968-08:00 localhost ModemManager[1362]: <debug>
loading supported modes...
2013-11-19T12:40:29.202979-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.202991-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.203006-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT*CNTI=2<CR><LF>'
2013-11-19T12:40:29.212040-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
2013-11-19T12:40:29.212077-08:00 localhost ModemManager[1362]: <debug> Got
failure code 4: Operation not supported
2013-11-19T12:40:29.212091-08:00 localhost ModemManager[1362]: <debug>
Generic query of supported 3GPP networks with *CNTI failed: 'Operation not
supported'
2013-11-19T12:40:29.212104-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.212116-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.212128-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+WS46=?<CR><LF>'
2013-11-19T12:40:29.221344-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>28<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.221468-08:00 localhost ModemManager[1362]: <debug>
Device allows (3GPP) 4G-only network mode
2013-11-19T12:40:29.221486-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.221502-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.221517-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%BANDCAP=<CR><LF>'
2013-11-19T12:40:29.232165-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%BANDCAP:  13<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.232208-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:29.232227-08:00 localhost ModemManager[1362]: <debug>
loading supported IP families...
2013-11-19T12:40:29.232243-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:29.232258-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CGDCONT=?<CR><LF>'
2013-11-19T12:40:29.275966-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CGDCONT:
(1-8),"IP",,,(0-3),(0-4),(0-2)<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.276010-08:00 localhost ModemManager[1362]: <debug>
loading power state...
2013-11-19T12:40:29.276026-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.276039-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.276054-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CFUN?<CR><LF>'
2013-11-19T12:40:29.435775-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CFUN: 4<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.436143-08:00 localhost ModemManager[1362]: <debug>
checking if unlock required...
2013-11-19T12:40:29.436173-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.436188-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.436201-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CPIN?<CR><LF>'
2013-11-19T12:40:29.443588-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.443947-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:29.444005-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:29.444020-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%CPININFO<CR><LF>'
2013-11-19T12:40:29.453567-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%CPININFO: 3,10,3,10<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.454958-08:00 localhost ModemManager[1362]: <debug> SIM
is ready, and no need for the after SIM unlock step...
2013-11-19T12:40:29.454996-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:29.455015-08:00 localhost ModemManager[1362]: <debug>
loading SIM identifier...
2013-11-19T12:40:29.455029-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:29.455046-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CRSM=176,12258,0,0,10<CR><LF>'
2013-11-19T12:40:29.531730-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CRSM:
145,16,98410800005032846923<CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.532096-08:00 localhost ModemManager[1362]: <debug>
loaded SIM identifier: 89148000000523489632
2013-11-19T12:40:29.532123-08:00 localhost ModemManager[1362]: <debug>
loading IMSI...
2013-11-19T12:40:29.532139-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.532157-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.532172-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CIMI<CR><LF>'
2013-11-19T12:40:29.684301-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>311480052905366<CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.684685-08:00 localhost ModemManager[1362]: <debug>
loaded IMSI: 311480052905366
2013-11-19T12:40:29.684719-08:00 localhost ModemManager[1362]: <debug>
loading Operator ID...
2013-11-19T12:40:29.684735-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.684748-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.684762-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CRSM=176,28589,0,0,4<CR><LF>'
2013-11-19T12:40:29.812944-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CRSM: 145,16,00000003<CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.812986-08:00 localhost ModemManager[1362]: <debug>
loading Operator Name...
2013-11-19T12:40:29.813004-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:29.813020-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:29.813035-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CRSM=176,28486,0,0,17<CR><LF>'
2013-11-19T12:40:29.941176-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CRSM:
145,16,FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF<CR><LF>OK<CR><LF>'
2013-11-19T12:40:29.941252-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:29.941272-08:00 localhost ModemManager[1362]: <debug>
loading own numbers...
2013-11-19T12:40:29.941288-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:29.941303-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CNUM<CR><LF>'
2013-11-19T12:40:30.076681-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.076728-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:30.076744-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:30.076761-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%GETCFG="BAND"<CR><LF>'
2013-11-19T12:40:30.086780-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>Bands:  13<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.086823-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:30.086871-08:00 localhost ModemManager[1362]: <debug>
Modem is LTE-only, assuming CS network is not supported
2013-11-19T12:40:30.086888-08:00 localhost ModemManager[1362]: <debug>
loading IMEI...
2013-11-19T12:40:30.086904-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:30.086919-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CGSN<CR><LF>'
2013-11-19T12:40:30.142059-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>359636040115576<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.142104-08:00 localhost ModemManager[1362]: <debug>
loaded IMEI: 359636040115576
2013-11-19T12:40:30.142118-08:00 localhost ModemManager[1362]: <debug>
loading enabled facility locks...
2013-11-19T12:40:30.142132-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:30.142145-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:30.142156-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CLCK=?<CR><LF>'
2013-11-19T12:40:30.152523-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CLCK:
("SC","PN","PU","PP","PC","PS")<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.152565-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:30.152581-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:30.152595-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CLCK="SC",2<CR><LF>'
2013-11-19T12:40:30.196715-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.196760-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:30.197559-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:30.197587-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CLCK="PS",2<CR><LF>'
2013-11-19T12:40:30.334374-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.334416-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:30.334431-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:30.334445-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CLCK="PN",2<CR><LF>'
2013-11-19T12:40:30.392742-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.392780-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:30.392793-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:30.392804-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CLCK="PU",2<CR><LF>'
2013-11-19T12:40:30.420367-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.420737-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:30.420764-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:30.420781-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CLCK="PP",2<CR><LF>'
2013-11-19T12:40:30.588835-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.589378-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:30.589404-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:30.589422-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CLCK="PC",2<CR><LF>'
2013-11-19T12:40:30.653568-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.656275-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:30.656335-08:00 localhost ModemManager[1362]: <debug>
Couldn't initialize interface: 'USSD not supported'
2013-11-19T12:40:30.656434-08:00 localhost ModemManager[1362]: <debug>
Couldn't initialize interface: 'Messaging not supported'
2013-11-19T12:40:30.656622-08:00 localhost ModemManager[1362]: <debug>
Couldn't initialize interface: 'Time not supported'
2013-11-19T12:40:30.656978-08:00 localhost ModemManager[1362]: <debug>
Couldn't initialize interface: 'Extended Signal information not supported'
2013-11-19T12:40:30.657384-08:00 localhost ModemManager[1362]: <debug>
Couldn't initialize interface: 'OMA not supported'
2013-11-19T12:40:30.657631-08:00 localhost ModemManager[1362]: <debug>
Couldn't initialize interface: 'Firmware not supported'
2013-11-19T12:40:30.657943-08:00 localhost ModemManager[1362]: <info>
 Modem: state changed (unknown -> disabled)
2013-11-19T12:40:30.658118-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 0 (close)
2013-11-19T12:40:30.658171-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) closing serial port...
2013-11-19T12:40:30.663763-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) serial port closed
2013-11-19T12:40:30.663803-08:00 localhost ModemManager[1362]: <debug>
modem properly initialized
2013-11-19T12:40:30.663817-08:00 localhost ModemManager[1362]: <debug>
Exported modem '/sys/devices/s5p-ehci/usb1/1-2/1-2.2' at path
'/org/freedesktop/ModemManager1/Modem/0'
2013-11-19T12:40:30.663829-08:00 localhost ModemManager[1362]: <debug>
(/org/freedesktop/ModemManager1/Modem/0): 'Altair LTE' modem, VID 0x216F
PID 0x0047 (usb)
2013-11-19T12:40:30.725473-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled ->
enabling)
2013-11-19T12:40:30.725525-08:00 localhost ModemManager[1362]: <debug>
Skipping modem initialization: device hotplugged
2013-11-19T12:40:30.725539-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) opening serial port...
2013-11-19T12:40:30.726981-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (open)
2013-11-19T12:40:30.727014-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): running init sequence...
2013-11-19T12:40:30.727028-08:00 localhost ModemManager[1362]: <debug>
Flashing primary AT port before enabling...
2013-11-19T12:40:30.727787-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CMEE=1<CR><LF>'
2013-11-19T12:40:30.829802-08:00 localhost ModemManager[1362]: <debug>
loading power state...
2013-11-19T12:40:30.829850-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:30.839662-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:30.839703-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CFUN?<CR><LF>'
2013-11-19T12:40:31.192563-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CFUN: 4<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:31.192645-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:31.192664-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:31.192678-08:00 localhost ModemManager[1362]: <debug>
Modem set in full-power mode...
2013-11-19T12:40:31.192691-08:00 localhost ModemManager[1362]: <debug>
Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
2013-11-19T12:40:31.192708-08:00 localhost ModemManager[1362]: <debug>
Checking indicator support...
2013-11-19T12:40:31.192722-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:31.192736-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CFUN=1<CR><LF>'
2013-11-19T12:40:31.858802-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:31.858849-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:31.858868-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CIND=?<CR><LF>'
2013-11-19T12:40:31.966577-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
2013-11-19T12:40:31.966666-08:00 localhost ModemManager[1362]: <debug> Got
failure code 4: Operation not supported
2013-11-19T12:40:31.966683-08:00 localhost ModemManager[1362]: <debug>
Marking indications as unsupported: 'Operation not supported'
2013-11-19T12:40:31.966698-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:31.966715-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:31.966729-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%STATCM=1<CR><LF>'
2013-11-19T12:40:32.542344-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:32.542385-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%NOTIFYEV="SIMREFRESH",1<CR><LF>'
2013-11-19T12:40:32.581692-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:32.581764-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) setting up 3GPP unsolicited registration messages handlers
2013-11-19T12:40:32.581786-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:32.581800-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:32.581813-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CEREG=2<CR><LF>'
2013-11-19T12:40:32.614054-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:32.614097-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:32.614118-08:00 localhost ModemManager[1362]: <debug>
Running registration checks (CS: 'no', PS: 'no', EPS: 'yes')
2013-11-19T12:40:32.614133-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:32.614145-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CEREG?<CR><LF>'
2013-11-19T12:40:32.667998-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CEREG: 2,2<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:32.668041-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
changed (unknown -> searching)
2013-11-19T12:40:32.668058-08:00 localhost ModemManager[1362]: <debug> Will
start keeping track of state for subsystem '3gpp'
2013-11-19T12:40:32.668072-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:32.668086-08:00 localhost ModemManager[1362]: <debug>
Registration not successful yet. Checking if SIM is unprovisioned.
2013-11-19T12:40:32.668100-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:32.668116-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CEER<CR><LF>'
2013-11-19T12:40:32.729191-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:32.729582-08:00 localhost ModemManager[1362]: <debug>
Failed to find a better reason for registration failure.
2013-11-19T12:40:32.729611-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:32.729628-08:00 localhost ModemManager[1362]: <debug>
Modem has location capabilities, enabling the Location interface...
2013-11-19T12:40:32.729642-08:00 localhost ModemManager[1362]: <debug> Need
to enable the following location sources: '3gpp-lac-ci'
2013-11-19T12:40:32.729655-08:00 localhost ModemManager[1362]: <debug>
Running registration checks (CS: 'no', PS: 'no', EPS: 'yes')
2013-11-19T12:40:32.730395-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:32.730418-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling ->
searching)
2013-11-19T12:40:32.731156-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CEREG?<CR><LF>'
2013-11-19T12:40:32.785001-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CEREG: 2,2<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:32.785311-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:32.785461-08:00 localhost ModemManager[1362]: <debug>
Registration not successful yet. Checking if SIM is unprovisioned.
2013-11-19T12:40:32.785566-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:32.785730-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CEER<CR><LF>'
2013-11-19T12:40:32.805718-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:32.805760-08:00 localhost ModemManager[1362]: <debug>
Failed to find a better reason for registration failure.
2013-11-19T12:40:32.805778-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:35.003425-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CEREG: 0, 1F00, 0079D803, 7<CR><LF>'
2013-11-19T12:40:35.003464-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
changed (searching -> unknown)
2013-11-19T12:40:36.482627-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CEREG: 1, 1F00, 0079D803, 7<CR><LF>'
2013-11-19T12:40:36.482670-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
changed (unknown -> registering)
2013-11-19T12:40:36.482686-08:00 localhost ModemManager[1362]: <debug>
loading Operator Code...
2013-11-19T12:40:36.482700-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:36.482713-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:36.482724-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed
(unknown -> lte)
2013-11-19T12:40:36.482736-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+COPS=3,2<CR><LF>'
2013-11-19T12:40:36.489706-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:36.489842-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:36.489914-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+COPS?<CR><LF>'
2013-11-19T12:40:36.498838-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+COPS: 0,2,"311480"<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:36.498919-08:00 localhost ModemManager[1362]: <debug>
loaded Operator Code: 311480
2013-11-19T12:40:36.498935-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC:
'311', MNC: '480', Location area code: '0', Cell ID: '0')
2013-11-19T12:40:36.498951-08:00 localhost ModemManager[1362]: <debug>
Loading Operator Name...
2013-11-19T12:40:36.498965-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:36.498978-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 4 (open)
2013-11-19T12:40:36.498991-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (close)
2013-11-19T12:40:36.499004-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+COPS=3,0<CR><LF>'
2013-11-19T12:40:36.505825-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:36.505895-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:36.505912-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+COPS?<CR><LF>'
2013-11-19T12:40:36.516495-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+COPS: 0,0,"Verizon
Wireless"<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:36.516543-08:00 localhost ModemManager[1362]: <debug>
loaded Operator Name: Verizon Wireless
2013-11-19T12:40:36.516565-08:00 localhost ModemManager[1362]: <debug> Load
subscription state: Marking the SIM as provisioned.
2013-11-19T12:40:36.516583-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:36.516598-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
changed (registering -> home)
2013-11-19T12:40:36.516617-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: state changed (searching ->
registered)
2013-11-19T12:40:36.516630-08:00 localhost ModemManager[1362]: <debug>
Periodic signal quality checks enabled (interval = 3s)
2013-11-19T12:40:36.518123-08:00 localhost ModemManager[1362]: <debug>
loading signal quality...
2013-11-19T12:40:36.518137-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:36.521314-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CSQ<CR><LF>'
2013-11-19T12:40:36.530469-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CSQ: 17,0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:40:36.530509-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:36.530527-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (54)
2013-11-19T12:40:36.530545-08:00 localhost ModemManager[1362]: <debug>
Periodic signal quality checks rescheduled (interval = 30s)
2013-11-19T12:40:36.571781-08:00 localhost ModemManager[1362]: <info>
 Simple connect started...
2013-11-19T12:40:36.571818-08:00 localhost ModemManager[1362]: <debug>
 PIN: unspecified
2013-11-19T12:40:36.571833-08:00 localhost ModemManager[1362]: <debug>
 Operator ID: unspecified
2013-11-19T12:40:36.571845-08:00 localhost ModemManager[1362]: <debug>
 Allowed roaming: no
2013-11-19T12:40:36.571858-08:00 localhost ModemManager[1362]: <debug>
 APN: vzwinternet
2013-11-19T12:40:36.571870-08:00 localhost ModemManager[1362]: <debug>
 IP family: unspecified
2013-11-19T12:40:36.571883-08:00 localhost ModemManager[1362]: <debug>
 Allowed authentication: unspecified
2013-11-19T12:40:36.571894-08:00 localhost ModemManager[1362]: <debug>
 User: unspecified
2013-11-19T12:40:36.573574-08:00 localhost ModemManager[1362]: <debug>
 Password: unspecified
2013-11-19T12:40:36.573604-08:00 localhost ModemManager[1362]: <debug>
 Number: *99#
2013-11-19T12:40:36.573622-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (4/8): Wait to get fully enabled
2013-11-19T12:40:36.573638-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (5/8): Register
2013-11-19T12:40:36.573653-08:00 localhost ModemManager[1362]: <debug>
Already registered in network '311480', automatic registration not
launched...
2013-11-19T12:40:36.573668-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (6/8): Bearer
2013-11-19T12:40:36.573684-08:00 localhost ModemManager[1362]: <debug>
Creating new bearer...
2013-11-19T12:40:36.573698-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:36.573714-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:36.573727-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (7/8): Connect
2013-11-19T12:40:36.573744-08:00 localhost ModemManager[1362]: <debug>
Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
2013-11-19T12:40:36.575069-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered ->
connecting)
2013-11-19T12:40:36.575163-08:00 localhost ModemManager[1362]: <debug>
Launching 3GPP connection attempt with APN 'vzwinternet'
2013-11-19T12:40:36.575181-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:40:36.575212-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%APNN="vzwinternet"<CR><LF>'
2013-11-19T12:40:37.361638-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%STATCM: 1<CR><LF>'
2013-11-19T12:40:37.361682-08:00 localhost ModemManager[1362]: <debug>
altair_statcm_changed 1
2013-11-19T12:40:37.380728-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:37.381193-08:00 localhost ModemManager[1362]: <debug> APN
set - connecting bearer
2013-11-19T12:40:37.381222-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:40:37.381236-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:40:37.381248-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%DPDNACT=1<CR><LF>'
2013-11-19T12:40:39.078414-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%STATCM: 3,0<CR><LF>'
2013-11-19T12:40:39.078458-08:00 localhost ModemManager[1362]: <debug>
altair_statcm_changed 3
2013-11-19T12:40:39.099884-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:40:39.099919-08:00 localhost ModemManager[1362]: <debug>
Connected
2013-11-19T12:40:39.099938-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:40:39.099955-08:00 localhost ModemManager[1362]: <debug>
(eth0): port now connected
2013-11-19T12:40:39.099971-08:00 localhost ModemManager[1362]: <debug>
Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
2013-11-19T12:40:39.099987-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting ->
connected)
2013-11-19T12:40:39.099999-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (8/8): All done
2013-11-19T12:41:12.479176-08:00 localhost ModemManager[1362]: <debug>
loading signal quality...
2013-11-19T12:41:12.479224-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:41:12.479248-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CSQ<CR><LF>'
2013-11-19T12:41:12.618809-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CSQ: 17,99<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:41:12.618863-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:41:12.618884-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (54)
2013-11-19T12:41:42.483018-08:00 localhost ModemManager[1362]: <debug>
loading signal quality...
2013-11-19T12:41:42.483201-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:41:42.483315-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CSQ<CR><LF>'
2013-11-19T12:41:42.545766-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CSQ: 16,99<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:41:42.545804-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:41:42.545820-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
2013-11-19T12:42:12.485407-08:00 localhost ModemManager[1362]: <debug>
loading signal quality...
2013-11-19T12:42:12.485590-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:42:12.485708-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CSQ<CR><LF>'
2013-11-19T12:42:12.623167-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CSQ: 17,99<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:42:12.623207-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:42:12.623222-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (54)
2013-11-19T12:42:42.485717-08:00 localhost ModemManager[1362]: <debug>
loading signal quality...
2013-11-19T12:42:42.485899-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:42:42.486015-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CSQ<CR><LF>'
2013-11-19T12:42:42.619719-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CSQ: 18,0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:42:42.619758-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:42:42.619775-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (58)
2013-11-19T12:43:12.486253-08:00 localhost ModemManager[1362]: <debug>
loading signal quality...
2013-11-19T12:43:12.486432-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:43:12.486536-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CSQ<CR><LF>'
2013-11-19T12:43:12.618239-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CSQ: 16,99<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:43:12.618278-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:43:12.618293-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
2013-11-19T12:43:21.988215-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%NOTIFYEV:SIMREFRESH,1<CR><LF>'
2013-11-19T12:43:21.988315-08:00 localhost ModemManager[1362]: <debug>
Received SIM refresh notification
2013-11-19T12:43:27.590139-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%NOTIFYEV:SIMREFRESH,1<CR><LF>'
2013-11-19T12:43:27.590322-08:00 localhost ModemManager[1362]: <debug>
Received SIM refresh notification
2013-11-19T12:43:33.609745-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%NOTIFYEV:SIMREFRESH,1<CR><LF>'
2013-11-19T12:43:33.609845-08:00 localhost ModemManager[1362]: <debug>
Received SIM refresh notification
2013-11-19T12:43:39.609660-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%NOTIFYEV:SIMREFRESH,1<CR><LF>'
2013-11-19T12:43:39.609755-08:00 localhost ModemManager[1362]: <debug>
Received SIM refresh notification
2013-11-19T12:43:42.459053-08:00 localhost ModemManager[1362]: <debug>
loading signal quality...
2013-11-19T12:43:42.459249-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:43:42.459354-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CSQ<CR><LF>'
2013-11-19T12:43:42.518619-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CSQ: 17,0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:43:42.518686-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:43:42.518758-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (54)
2013-11-19T12:43:49.460938-08:00 localhost ModemManager[1362]: <debug> No
more SIM refreshes, reloading Own Numbers and reregistering modem
2013-11-19T12:43:49.461127-08:00 localhost ModemManager[1362]: <debug>
loading own numbers...
2013-11-19T12:43:49.461224-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:43:49.461313-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CNUM<CR><LF>'
2013-11-19T12:43:49.736763-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CNUM:
,"+16503160225",145<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:43:49.736954-08:00 localhost ModemManager[1362]: <debug>
Reregistering modem
2013-11-19T12:43:49.737051-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:43:49.737139-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:43:49.751078-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%CMATT=0<CR><LF>'
2013-11-19T12:43:54.106496-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%STATCM: 4,0<CR><LF>'
2013-11-19T12:43:54.106689-08:00 localhost ModemManager[1362]: <debug>
altair_statcm_changed 4
2013-11-19T12:43:54.106800-08:00 localhost ModemManager[1362]: <debug>
(eth0): port now disconnected
2013-11-19T12:43:54.106891-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected ->
registered)
2013-11-19T12:43:54.181290-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CEREG: 0, 1F00, 0079D803, 7<CR><LF>'
2013-11-19T12:43:54.181328-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
changed (home -> unknown)
2013-11-19T12:43:54.181348-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC:
'0', MNC: '0', Location area code: '0', Cell ID: '0')
2013-11-19T12:43:54.181363-08:00 localhost ModemManager[1362]: <debug>
Bearer not allowed to connect, not registered in 3GPP network
2013-11-19T12:43:54.181374-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed
(lte -> unknown)
2013-11-19T12:43:54.186514-08:00 localhost ModemManager[1362]: <info>
 Simple connect started...
2013-11-19T12:43:54.186539-08:00 localhost ModemManager[1362]: <debug>
 PIN: unspecified
2013-11-19T12:43:54.186552-08:00 localhost ModemManager[1362]: <debug>
 Operator ID: unspecified
2013-11-19T12:43:54.186566-08:00 localhost ModemManager[1362]: <debug>
 Allowed roaming: no
2013-11-19T12:43:54.186579-08:00 localhost ModemManager[1362]: <debug>
 APN: vzwinternet
2013-11-19T12:43:54.186594-08:00 localhost ModemManager[1362]: <debug>
 IP family: unspecified
2013-11-19T12:43:54.186607-08:00 localhost ModemManager[1362]: <debug>
 Allowed authentication: unspecified
2013-11-19T12:43:54.186621-08:00 localhost ModemManager[1362]: <debug>
 User: unspecified
2013-11-19T12:43:54.186637-08:00 localhost ModemManager[1362]: <debug>
 Password: unspecified
2013-11-19T12:43:54.186651-08:00 localhost ModemManager[1362]: <debug>
 Number: *99#
2013-11-19T12:43:54.186664-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (4/8): Wait to get fully enabled
2013-11-19T12:43:54.186853-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (5/8): Register
2013-11-19T12:43:54.186870-08:00 localhost ModemManager[1362]: <debug>
Launching automatic network registration...
2013-11-19T12:43:54.186881-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:43:54.227497-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CREG: 0,1F00,0079D803,7<CR><LF>'
2013-11-19T12:43:54.315336-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%STATCM: 0<CR><LF>'
2013-11-19T12:43:54.315419-08:00 localhost ModemManager[1362]: <debug>
altair_statcm_changed 0
2013-11-19T12:43:54.357774-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:43:54.357843-08:00 localhost ModemManager[1362]: <debug>
Deregistered modem, now reregistering
2013-11-19T12:43:54.357886-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 4 (open)
2013-11-19T12:43:54.357926-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (close)
2013-11-19T12:43:54.357963-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%CMATT=1<CR><LF>'
2013-11-19T12:43:54.504030-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:43:54.504066-08:00 localhost ModemManager[1362]: <debug>
Running registration checks (CS: 'no', PS: 'no', EPS: 'yes')
2013-11-19T12:43:54.504102-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 4 (open)
2013-11-19T12:43:54.504115-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (close)
2013-11-19T12:43:54.504126-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%CMATT=1<CR><LF>'
2013-11-19T12:43:55.151349-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:43:55.151585-08:00 localhost ModemManager[1362]: <debug>
Modem reregistered successfully
2013-11-19T12:43:55.151682-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:43:55.151770-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CEREG?<CR><LF>'
2013-11-19T12:43:55.170258-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CEREG: 2, 0, 1F00, 0079D803,
7<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:43:55.170294-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:43:55.170307-08:00 localhost ModemManager[1362]: <debug>
Registration not successful yet. Checking if SIM is unprovisioned.
2013-11-19T12:43:55.170318-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:43:55.170328-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CEER<CR><LF>'
2013-11-19T12:43:55.184821-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:43:55.184858-08:00 localhost ModemManager[1362]: <debug>
Failed to find a better reason for registration failure.
2013-11-19T12:43:55.184871-08:00 localhost ModemManager[1362]: <debug>
Modem not yet registered in a 3GPP network... will recheck soon
2013-11-19T12:43:55.184883-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:43:55.891510-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CEREG: 1, 1F00, 0079D803, 7<CR><LF>'
2013-11-19T12:43:55.891702-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
changed (unknown -> registering)
2013-11-19T12:43:55.891799-08:00 localhost ModemManager[1362]: <debug>
loading Operator Code...
2013-11-19T12:43:55.891886-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:43:55.891971-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:43:55.892055-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed
(unknown -> lte)
2013-11-19T12:43:55.905197-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+COPS=3,2<CR><LF>'
2013-11-19T12:43:55.913033-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CREG: 1,1F00,0079D803,7<CR><LF>'
2013-11-19T12:43:55.920891-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:43:55.920936-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:43:55.920954-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+COPS?<CR><LF>'
2013-11-19T12:43:55.937156-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+COPS: 0,2,"311480"<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:43:55.937195-08:00 localhost ModemManager[1362]: <debug>
loaded Operator Code: 311480
2013-11-19T12:43:55.937237-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC:
'311', MNC: '480', Location area code: '0', Cell ID: '0')
2013-11-19T12:43:55.937250-08:00 localhost ModemManager[1362]: <debug>
Loading Operator Name...
2013-11-19T12:43:55.937261-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:43:55.937272-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 4 (open)
2013-11-19T12:43:55.937282-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (close)
2013-11-19T12:43:55.937292-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+COPS=3,0<CR><LF>'
2013-11-19T12:43:55.946896-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:43:55.946933-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:43:55.946951-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+COPS?<CR><LF>'
2013-11-19T12:43:56.042341-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+COPS: 0,0,"Verizon
Wireless"<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:43:56.042381-08:00 localhost ModemManager[1362]: <debug>
loaded Operator Name: Verizon Wireless
2013-11-19T12:43:56.042399-08:00 localhost ModemManager[1362]: <debug> Load
subscription state: Marking the SIM as provisioned.
2013-11-19T12:43:56.042417-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:43:56.042429-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
changed (registering -> home)
2013-11-19T12:43:56.090688-08:00 localhost ModemManager[1362]: <info>
 Simple connect started...
2013-11-19T12:43:56.090725-08:00 localhost ModemManager[1362]: <debug>
 PIN: unspecified
2013-11-19T12:43:56.090741-08:00 localhost ModemManager[1362]: <debug>
 Operator ID: unspecified
2013-11-19T12:43:56.090757-08:00 localhost ModemManager[1362]: <debug>
 Allowed roaming: no
2013-11-19T12:43:56.090771-08:00 localhost ModemManager[1362]: <debug>
 APN: vzwinternet
2013-11-19T12:43:56.090785-08:00 localhost ModemManager[1362]: <debug>
 IP family: unspecified
2013-11-19T12:43:56.090799-08:00 localhost ModemManager[1362]: <debug>
 Allowed authentication: unspecified
2013-11-19T12:43:56.090814-08:00 localhost ModemManager[1362]: <debug>
 User: unspecified
2013-11-19T12:43:56.090829-08:00 localhost ModemManager[1362]: <debug>
 Password: unspecified
2013-11-19T12:43:56.090843-08:00 localhost ModemManager[1362]: <debug>
 Number: *99#
2013-11-19T12:43:56.090857-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (4/8): Wait to get fully enabled
2013-11-19T12:43:56.092409-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (5/8): Register
2013-11-19T12:43:56.092437-08:00 localhost ModemManager[1362]: <debug>
Already registered in network '311480', automatic registration not
launched...
2013-11-19T12:43:56.092453-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (6/8): Bearer
2013-11-19T12:43:56.092469-08:00 localhost ModemManager[1362]: <debug>
Using already existing bearer at
'/org/freedesktop/ModemManager1/Bearer/0'...
2013-11-19T12:43:56.092484-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (7/8): Connect
2013-11-19T12:43:56.092498-08:00 localhost ModemManager[1362]: <debug>
Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
2013-11-19T12:43:56.092513-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered ->
connecting)
2013-11-19T12:43:56.092528-08:00 localhost ModemManager[1362]: <debug>
Launching 3GPP connection attempt with APN 'vzwinternet'
2013-11-19T12:43:56.092542-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:43:56.092555-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%APNN="vzwinternet"<CR><LF>'
2013-11-19T12:43:56.676464-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%STATCM: 1<CR><LF>'
2013-11-19T12:43:56.676520-08:00 localhost ModemManager[1362]: <debug>
altair_statcm_changed 1
2013-11-19T12:43:56.697787-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:43:56.697841-08:00 localhost ModemManager[1362]: <debug> APN
set - connecting bearer
2013-11-19T12:43:56.697866-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 3 (open)
2013-11-19T12:43:56.697889-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (close)
2013-11-19T12:43:56.697912-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT%DPDNACT=1<CR><LF>'
2013-11-19T12:43:58.208666-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>%STATCM: 3,0<CR><LF>'
2013-11-19T12:43:58.208776-08:00 localhost ModemManager[1362]: <debug>
altair_statcm_changed 3
2013-11-19T12:43:58.223309-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>OK<CR><LF>'
2013-11-19T12:43:58.223361-08:00 localhost ModemManager[1362]: <debug>
Connected
2013-11-19T12:43:58.223379-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:43:58.223397-08:00 localhost ModemManager[1362]: <debug>
(eth0): port now connected
2013-11-19T12:43:58.223417-08:00 localhost ModemManager[1362]: <debug>
Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
2013-11-19T12:43:58.223439-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting ->
connected)
2013-11-19T12:43:58.232691-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (8/8): All done
2013-11-19T12:43:58.454915-08:00 localhost ModemManager[1362]: <debug>
Running registration checks (CS: 'no', PS: 'no', EPS: 'yes')
2013-11-19T12:43:58.454951-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:43:58.454963-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CEREG?<CR><LF>'
2013-11-19T12:43:58.464753-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CEREG: 2, 1, 1F00, 0079D803,
7<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:43:58.464804-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC:
'311', MNC: '480', Location area code: '1F00', Cell ID: '79D803')
2013-11-19T12:43:58.464825-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:43:58.464839-08:00 localhost ModemManager[1362]: <debug>
Registration succeeded: Marking the SIM as provisioned.
2013-11-19T12:43:58.464855-08:00 localhost ModemManager[1362]: <debug>
Modem is currently registered in a 3GPP network
2013-11-19T12:43:58.464870-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (6/8): Bearer
2013-11-19T12:43:58.464883-08:00 localhost ModemManager[1362]: <debug>
Using already existing bearer at
'/org/freedesktop/ModemManager1/Bearer/0'...
2013-11-19T12:43:58.464897-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (7/8): Connect
2013-11-19T12:43:58.464910-08:00 localhost ModemManager[1362]: <debug>
Bearer at '/org/freedesktop/ModemManager1/Bearer/0' is already connected...
2013-11-19T12:43:58.464921-08:00 localhost ModemManager[1362]: <info>
 Simple connect state (8/8): All done
2013-11-19T12:44:12.470818-08:00 localhost ModemManager[1362]: <debug>
loading signal quality...
2013-11-19T12:44:12.470999-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 2 (open)
2013-11-19T12:44:12.471116-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): --> 'AT+CSQ<CR><LF>'
2013-11-19T12:44:12.485592-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0): <-- '<CR><LF>+CSQ: 16,0<CR><LF><CR><LF>OK<CR><LF>'
2013-11-19T12:44:12.485783-08:00 localhost ModemManager[1362]: <debug>
(ttyACM0) device open count is 1 (close)
2013-11-19T12:44:12.485908-08:00 localhost ModemManager[1362]: <info>
 Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freedesktop.org/archives/modemmanager-devel/attachments/20131119/45cdf4b5/attachment-0001.html>