Ticket #911 (closed defect: fixed)

Opened 12 years ago

Last modified 11 years ago

GSM modem dies on registration with several different SIM cards on Rogers GSM network

Reported by: andrew@… Owned by: tick@…
Priority: high Milestone:
Component: GSM Modem Version: GTA01Bv4
Severity: major Keywords:
Cc: buglog@… Blocked By:
Blocking: Estimated Completion (week):
HasPatchForReview: PatchReviewResult:
Reproducible:

Description

The TI GSM modem ceases to respond when AT+CFUN=1 is submitted, and gsmd
eventually declares it dead.

gsm.log:

Fri Oct 5 13:19:07 2007 <1> machine.c:131:gsmd_machine_plugin_init() detected
'GTA01' hardware
Fri Oct 5 13:19:07 2007 <1> machine.c:72:gsmd_machine_plugin_load() loading
machine plugin "generic"
Fri Oct 5 13:19:07 2007 <1> vendor.c:75:gsmd_vendor_plugin_load() loading
vendor plugin "ti"
Fri Oct 5 13:19:07 2007 <1> machine.c:56:gsmd_machine_plugin_find() selecting
machine plugin "generic"
Fri Oct 5 13:19:08 2007 <1> uart.c:41:uart_drain() c_iflag = 0x00000500,
c_oflag = 0x00000005, c_cflag = 0x800018b2, c_lflag = 0x00008a33
Fri Oct 5 13:19:08 2007 <1> vendor.c:59:gsmd_vendor_plugin_find() selecting
vendor plugin "TI Calypso"
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command `ATZ'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`ATZ'(3)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:301:ml_parse() ignoring echo
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command `ATE0V1'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+CRC=1'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+CREG=2'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+CMEE=1'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+CLIP=1'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+COLP=1'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+CMGF=0'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+CMGF=0'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+CTZR=1'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT%CTZV=1'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT%CPRI=1'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT%CSQ=1'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT%CUNS=0'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT%CPI=?'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`ATE0V1'(6)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:301:ml_parse() ignoring echo
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() ATE0V1' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT+CRC=1' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT+CREG=2' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT+CMEE=1' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT+CLIP=1' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT+COLP=1' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT+CMGF=0' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <5> atcmd.c:480:ml_parse() command without cb!!!
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT+CTZR=1' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT%CTZV=1' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT%CPRI=1' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`%CSQ: 1 '(8)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:445:ml_parse() Appending buf to mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() `AT%CSQ=1' returned
`%CSQ: 1 '
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT%CUNS=0' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`%CPI: (0-4)'(11)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:445:ml_parse() Appending buf to mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT%CPI=3'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:08 2007 <1> atcmd.c:260:ml_parse() buf=`OK'(2)
Fri Oct 5 13:19:08 2007 <1> atcmd.c:482:ml_parse() Calling final cmd->cb()
Fri Oct 5 13:19:08 2007 <1> gsmd.c:153:gsmd_test_atcb() AT%CPI=3' returned OK'
Fri Oct 5 13:19:08 2007 <1> atcmd.c:491:ml_parse() Clearing mlbuf
Fri Oct 5 13:19:24 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+CFUN=1'
Fri Oct 5 13:19:44 2007 <1> atcmd.c:562:atcmd_submit() submitting command
`AT+COPS=0'
Fri Oct 5 13:24:08 2007 <1> gsmd.c:123:alive_interval_tmr_cb() interval
expired, starting next alive inquiry
Fri Oct 5 13:24:08 2007 <1> atcmd.c:562:atcmd_submit() submitting command `AT'
Fri Oct 5 13:24:38 2007 <1> gsmd.c:78:alive_tmr_cb() gsmd_alive timer expired
Fri Oct 5 13:24:38 2007 <8> gsmd.c:81:alive_tmr_cb() modem dead!
gsmd - (C) 2006-2007 by OpenMoko?, Inc. and contributors
This program is FREE SOFTWARE under the terms of GNU GPL

Change History

comment:1 Changed 12 years ago by andrew@…

While this is perhaps a bit unorthodox, placing the Neo on top of another device
with a speaker produces no noises. With another phone, I can hear the signature
GSM buzzing noises.

Therefore, I assume this means the GSM modem is dying before it even tries to
register on the network and thus problem lies with communicating with the SIM
smartcard itself.

comment:2 Changed 12 years ago by jserv@…

  • Owner changed from sean_chiang@… to ticktock35@…

comment:3 Changed 12 years ago by tick@…

  • Status changed from new to assigned

comment:4 Changed 11 years ago by exabyte@…

I have a similar problem with my phone. Using the GSM modem manually with AT
commands works, I can register with the network, receive and send messages,
maybe receive calls (I tried calling myself, it worked, but I didn't try talking).

When I'm trying to use gsmd, it fails in the same way. I'm using a my own build
of OpenMoko? from 26/10/07 (it actually finished compiling like four hours ago)
and the latest build that was linked on the wiki.

The result is:

Sun Oct 28 21:24:26 2007 <1> machine.c:131:gsmd_machine_plugin_init() detected
'GTA01' hardware
Sun Oct 28 21:24:26 2007 <1> machine.c:72:gsmd_machine_plugin_load() loading
machine plugin "generic"
Sun Oct 28 21:24:26 2007 <1> vendor.c:75:gsmd_vendor_plugin_load() loading
vendor plugin "ti"
Sun Oct 28 21:24:26 2007 <1> machine.c:56:gsmd_machine_plugin_find() selecting
machine plugin "generic"
Sun Oct 28 21:24:27 2007 <1> atcmd.c:561:atcmd_drain() c_iflag = 0x00000500,
c_oflag = 0x00000005, c_cflag = 0x800018b2, c_lflag = 0x00008a33
Sun Oct 28 21:24:27 2007 <1> vendor.c:59:gsmd_vendor_plugin_find() selecting
vendor plugin "TI Calypso"
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATZ'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`ATZ'(3)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:229:ml_parse() ignoring echo
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`ATZ'(3)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:229:ml_parse() ignoring echo
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATE0V1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CRC=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CREG=2'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMEE=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CLIP=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COLP=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CTZR=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CTZV=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPRI=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CSQ=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CUNS=0'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPI=?'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`ATE0V1'(6)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:229:ml_parse() ignoring echo
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`ATE0V1'(6)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:229:ml_parse() ignoring echo
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:27 2007 <1> gsmd.c:154:gsmd_test_atcb() ATE0V1' returned OK'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CRC=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CREG=2' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CMEE=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CLIP=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+COLP=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CMGF=0' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <5> atcmd.c:404:ml_parse() command without cb!!!
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CTZR=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT%CTZV=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT%CPRI=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`%CSQ: 1 '(8)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:372:ml_parse() Appending buf to mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT%CSQ=1' returned
`%CSQ: 1 '
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT%CUNS=0' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`%CPI: (0-4)'(11)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:372:ml_parse() Appending buf to mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPI=3'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT%CPI=3' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:45 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CFUN=1'
Sun Oct 28 21:24:48 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:48 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:48 2007 <1> usock.c:249:phone_powerup_cb() Radio powered-up
Sun Oct 28 21:24:48 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:25:05 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
root@fic-gta01:~$ cat /tmp/gsm.log
Sun Oct 28 21:24:26 2007 <1> machine.c:131:gsmd_machine_plugin_init() detected
'GTA01' hardware
Sun Oct 28 21:24:26 2007 <1> machine.c:72:gsmd_machine_plugin_load() loading
machine plugin "generic"
Sun Oct 28 21:24:26 2007 <1> vendor.c:75:gsmd_vendor_plugin_load() loading
vendor plugin "ti"
Sun Oct 28 21:24:26 2007 <1> machine.c:56:gsmd_machine_plugin_find() selecting
machine plugin "generic"
Sun Oct 28 21:24:27 2007 <1> atcmd.c:561:atcmd_drain() c_iflag = 0x00000500,
c_oflag = 0x00000005, c_cflag = 0x800018b2, c_lflag = 0x00008a33
Sun Oct 28 21:24:27 2007 <1> vendor.c:59:gsmd_vendor_plugin_find() selecting
vendor plugin "TI Calypso"
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATZ'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`ATZ'(3)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:229:ml_parse() ignoring echo
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`ATZ'(3)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:229:ml_parse() ignoring echo
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATE0V1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CRC=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CREG=2'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMEE=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CLIP=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COLP=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CTZR=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CTZV=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPRI=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CSQ=1'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CUNS=0'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPI=?'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`ATE0V1'(6)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:229:ml_parse() ignoring echo
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`ATE0V1'(6)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:229:ml_parse() ignoring echo
Sun Oct 28 21:24:27 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:27 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:27 2007 <1> gsmd.c:154:gsmd_test_atcb() ATE0V1' returned OK'
Sun Oct 28 21:24:27 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CRC=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CREG=2' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CMEE=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CLIP=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+COLP=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CMGF=0' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <5> atcmd.c:404:ml_parse() command without cb!!!
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT+CTZR=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT%CTZV=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT%CPRI=1' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`%CSQ: 1 '(8)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:372:ml_parse() Appending buf to mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT%CSQ=1' returned
`%CSQ: 1 '
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT%CUNS=0' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`%CPI: (0-4)'(11)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:372:ml_parse() Appending buf to mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPI=3'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:28 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:28 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:28 2007 <1> gsmd.c:154:gsmd_test_atcb() AT%CPI=3' returned OK'
Sun Oct 28 21:24:28 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:24:45 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CFUN=1'
Sun Oct 28 21:24:48 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Sun Oct 28 21:24:48 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Sun Oct 28 21:24:48 2007 <1> usock.c:249:phone_powerup_cb() Radio powered-up
Sun Oct 28 21:24:48 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Sun Oct 28 21:25:05 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Sun Oct 28 21:29:27 2007 <1> gsmd.c:124:alive_interval_tmr_cb() interval
expired, starting next alive inquiry
Sun Oct 28 21:29:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT'
Sun Oct 28 21:29:57 2007 <1> gsmd.c:79:alive_tmr_cb() gsmd_alive timer expired
Sun Oct 28 21:29:57 2007 <8> gsmd.c:82:alive_tmr_cb() modem dead!
gsmd - (C) 2006-2007 by OpenMoko?, Inc. and contributors
This program is FREE SOFTWARE under the terms of GNU GPL

comment:5 Changed 11 years ago by exabyte@…

A little update on this. It seems that commands that are called from the GUI
die. If I restart gsmd, and run them from libtool-gsmd, it's perfectly OK. Then
I restart X, and run the dialer. The result is:

Mon Oct 29 00:04:18 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CFUN=1'
Mon Oct 29 00:04:38 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'

But I'm already registered with the network. So I decide to call myself.

Mon Oct 29 00:04:50 2007 <1> atcmd.c:210:ml_parse() buf=`%CPI:
1,0,0,0,1,0,"+359XXXXXXXX",145,0'(40)
...
Mon Oct 29 00:04:51 2007 <1> atcmd.c:278:ml_parse() extd reply `+CLIP:
"+359XXXXXXXX",145
0' to cmd `+CFUN=1', must be unsolicited

And my dialer rings, I hang up from the dialer, it *works!*

Mon Oct 29 00:05:02 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATH'
Mon Oct 29 00:05:02 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)

Then I try to dial from my Neo, and bang, it fails as +CFUN and +COPS

Mon Oct 29 00:05:25 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`ATD+359XXXXXXXXXX;'
Mon Oct 29 00:05:53 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATH'

Then comes some more stuff from the modem:

Mon Oct 29 00:06:17 2007 <1> atcmd.c:210:ml_parse() buf=`%CSQ: 28, 99, 3'(16)
Mon Oct 29 00:06:17 2007 <1> atcmd.c:278:ml_parse() extd reply `%CSQ: 28, 99,
3' to cmd `H', must be unsolicited

comment:6 Changed 11 years ago by exabyte@…

Update: It works now for me with a build from today.

comment:7 Changed 11 years ago by andrew@…

  • Status changed from assigned to closed
  • Resolution set to fixed

I believe my problem was different from Milko's, but with images from the last
few weeks it now registers with my SIM fine.

Note: See TracTickets for help on using tickets.