Ticket #574 (closed defect: fixed)

Opened 6 years ago

Last modified 6 years ago

timing issues / buffer parsing issues in gsmd

Reported by: mickey@… Owned by: laforge@…
Priority: high Milestone:
Component: gsmd Version: unspecified
Severity: blocker Keywords:
Cc: buglog@…, cw@…, jserv@…, balrogg@…, cesarb@…, technil@… Blocked By:
Blocking: Estimated Completion (week):
HasPatchForReview: PatchReviewResult:
Reproducible:

Description

see attachement. What i did was:

calling power on
entering pin
trying to autoregister and then dial.

somehow the gsmd got stuck and/or started to confuse the buffers, e.g. see all
the "unsoliticed response to command 'foo'" messages, where 'foo' is not
completely visible, but the first two characters are truncated.

Attachments

gsmd.log (10.0 KB) - added by mickey@… 6 years ago.
gsmd log of openmoko-dialer session
foo (2.8 KB) - added by mickey@… 6 years ago.
gsmd log of libgsmd-tool -m shell session
lgsmdlongread.patch (1.9 KB) - added by balrogg@… 6 years ago.
Correctly segment usock reads into packets.

Change History

Changed 6 years ago by mickey@…

gsmd log of openmoko-dialer session

comment:1 Changed 6 years ago by mickey@…

  • Severity changed from normal to blocker

comment:2 Changed 6 years ago by mickey@…

ok, the 2 command truncation may be intended to strip out the "AT", however
something still doesn't look right or else we should have a carrier shouldn't we?

comment:3 Changed 6 years ago by mickey@…

I'll add a second attachement of a run using libgsmd-tool interactive mode
(which has much slower timing) -- this time it works fine, although the commands
sent to libgsmd are the same as in non-interactive mode (using openmoko-dialer).

Changed 6 years ago by mickey@…

gsmd log of libgsmd-tool -m shell session

comment:4 Changed 6 years ago by alphaone@…

Another thing I just noticed with regard to the gsmd timer:

root@fic-gta01:~$ tail -f /tmp/gsm.log
Wed Feb 2 02:08:27 2000 <1> usock.c:69:usock_cmd_cb() entering(cmd=0x1a7e8,
gu=0x1a8d0)
Wed Feb 2 02:08:27 2000 <1> usock.c:53:usock_cmd_enqueue() enqueueing usock cmd
0x1a968 for user 0x1a8d0
Wed Feb 2 02:08:27 2000 <1> usock.c:940:gsmd_usock_user_cb() successfully sent
cmd 0x1a968 to user 0x1a8d0, freeing
Wed Feb 2 02:08:27 2000 <1> usock.c:96:usock_rcv_passthrough() submitting
cmd=0x1a7e8, gu=0x1a8d0
Wed Feb 2 02:08:27 2000 <1> atcmd.c:452:atcmd_submit() submitting command `at+creg'
Wed Feb 2 02:08:27 2000 <1> atcmd.c:180:ml_parse() buf=`OK'(2)
Wed Feb 2 02:08:27 2000 <1> atcmd.c:338:ml_parse() Calling cmd->cb()
Wed Feb 2 02:08:57 2000 <1> gsmd.c:74:alive_tmr_cb() gsmd_alive timer expired
Wed Feb 2 02:08:57 2000 <3> gsmd.c:80:alive_tmr_cb() modem alive!
Wed Feb 2 02:10:32 2000 <1> usock.c:904:gsmd_usock_user_cb() EOF, this client
has just vanished
Wed Feb 2 02:12:03 2000 <1> atcmd.c:180:ml_parse() buf=`%CSQ: 26, 99, 3'(16)
Wed Feb 2 02:12:03 2000 <1> atcmd.c:248:ml_parse() extd reply `%CSQ: 26, 99,
3' to cmd `+creg', must be unsolicited
Wed Feb 2 02:12:03 2000 <1> vendor_ti.c:78:csq_parse() entering csq_parse
param=` 26, 99, 3'
Wed Feb 2 02:12:03 2000 <1> unsolicited.c:69:usock_evt_send() entering evt=6
Wed Feb 2 02:12:06 2000 <1> atcmd.c:180:ml_parse() buf=`%CPRI: 1,2'(10)
Wed Feb 2 02:12:06 2000 <1> atcmd.c:248:ml_parse() extd reply `%CPRI: 1,2' to
cmd `+creg', must be unsolicited
Wed Feb 2 02:12:06 2000 <1> unsolicited.c:69:usock_evt_send() entering evt=13
Wed Feb 2 02:12:32 2000 <1> atcmd.c:180:ml_parse() buf=`%CSQ: 23, 99, 2'(16)
Wed Feb 2 02:12:32 2000 <1> atcmd.c:248:ml_parse() extd reply `%CSQ: 23, 99,
2' to cmd `+creg', must be unsolicited
Wed Feb 2 02:12:32 2000 <1> vendor_ti.c:78:csq_parse() entering csq_parse
param=` 23, 99, 2'
Wed Feb 2 02:12:32 2000 <1> unsolicited.c:69:usock_evt_send() entering evt=6
Wed Feb 2 02:13:27 2000 <1> gsmd.c:119:alive_interval_tmr_cb() interval
expired, starting next alive inquiry
Wed Feb 2 02:13:27 2000 <1> atcmd.c:452:atcmd_submit() submitting command `ATE0'
Wed Feb 2 02:13:27 2000 <1> atcmd.c:180:ml_parse() buf=`OK'(2)
Wed Feb 2 02:13:27 2000 <1> atcmd.c:338:ml_parse() Calling cmd->cb()
Wed Feb 2 02:13:27 2000 <1> usock.c:69:usock_cmd_cb() entering(cmd=0x1a7e8,
gu=0x1a8d0)
Wed Feb 2 02:13:27 2000 <1> usock.c:53:usock_cmd_enqueue() enqueueing usock cmd
0x1a9d8 for user 0x1a8d0
Wed Feb 2 02:13:57 2000 <1> gsmd.c:74:alive_tmr_cb() gsmd_alive timer expired
Wed Feb 2 02:13:57 2000 <8> gsmd.c:77:alive_tmr_cb() modem dead!
gsmd - (C) 2006-2007 by Harald Welte <laforge@…>
This program is FREE SOFTWARE under the terms of GNU GPL

I can't really make much sense of it, but it seems to me that the alive timer
steals the first OK from the usock_cb and the next time the alive timer sends
ATE0 the OK is not passed back to the alive timer.

Changed 6 years ago by balrogg@…

Correctly segment usock reads into packets.

comment:5 Changed 6 years ago by balrogg@…

  • Cc balrogg@… added

comment:6 Changed 6 years ago by jserv@…

  • Cc jserv@… added

comment:7 Changed 6 years ago by mickey@…

Any progress on this? To add more fuel to the fire... parsing still gets confused:

root@fic-gta01:~$ tail -f /tmp/gsm.log
Thu Jan 1 00:00:55 1970 <1> atcmd.c:508:atcmd_submit() submitting command `ATE0'
Thu Jan 1 00:00:55 1970 <1> atcmd.c:199:ml_parse() buf=`aterATE0V1'(10)
Thu Jan 1 00:00:55 1970 <1> atcmd.c:350:ml_parse() Appending buf to mlbuf
Thu Jan 1 00:00:55 1970 <1> atcmd.c:199:ml_parse() buf=`ERROR'(5)
Thu Jan 1 00:00:55 1970 <1> atcmd.c:316:ml_parse() unspecified error
Thu Jan 1 00:00:55 1970 <1> atcmd.c:369:ml_parse() Calling final cmd->cb()
Thu Jan 1 00:00:55 1970 <5> atcmd.c:375:ml_parse() the text discarded is ERROR
Thu Jan 1 00:00:55 1970 <8> gsmd.c:205:firstcmd_atcb() response 'aterATE0V1' to
initial command invalidgsmd - (C) 2006-2007 by Harald Welte <laforge@…>
This program is FREE SOFTWARE under the terms of GNU GPL

comment:8 Changed 6 years ago by mickey@…

command without cb?

cat /tmp/gsm.log
Tue Feb 1 00:31:54 2000 <1> machine.c:131:gsmd_machine_plugin_init() detected
'GTA01' hardware
Tue Feb 1 00:31:54 2000 <1> machine.c:72:gsmd_machine_plugin_load() loading
machine plugin "generic"
Tue Feb 1 00:31:54 2000 <1> vendor.c:75:gsmd_vendor_plugin_load() loading
vendor plugin "ti"
Tue Feb 1 00:31:54 2000 <1> machine.c:56:gsmd_machine_plugin_find() selecting
machine plugin "generic"
Tue Feb 1 00:31:55 2000 <1> atcmd.c:561:atcmd_drain() c_iflag = 0x00000000,
c_oflag = 0x00000000, c_cflag = 0x800018b2, c_lflag = 0x00000a30
Tue Feb 1 00:31:55 2000 <1> vendor.c:59:gsmd_vendor_plugin_find() selecting
vendor plugin "TI Calypso"
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command `ATZ'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`ATZ'(3)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:229:ml_parse() ignoring echo
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`ATZ'(3)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:229:ml_parse() ignoring echo
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command `ATE0V1'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CRC=1'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CREG=2'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMEE=1'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CLIP=1'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COLP=1'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CTZR=1'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CTZV=1'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPRI=1'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CSQ=1'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CUNS=0'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPI=?'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`ATE0V1'(6)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:229:ml_parse() ignoring echo
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`ATE0V1'(6)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:229:ml_parse() ignoring echo
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Feb 1 00:31:55 2000 <1> gsmd.c:154:gsmd_test_atcb() ATE0V1' returned OK'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Feb 1 00:31:55 2000 <1> gsmd.c:154:gsmd_test_atcb() AT+CRC=1' returned OK'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Feb 1 00:31:55 2000 <1> gsmd.c:154:gsmd_test_atcb() AT+CREG=2' returned OK'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Feb 1 00:31:55 2000 <1> gsmd.c:154:gsmd_test_atcb() AT+CMEE=1' returned OK'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Feb 1 00:31:55 2000 <1> gsmd.c:154:gsmd_test_atcb() AT+CLIP=1' returned OK'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Feb 1 00:31:55 2000 <1> gsmd.c:154:gsmd_test_atcb() AT+COLP=1' returned OK'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Feb 1 00:31:55 2000 <1> gsmd.c:154:gsmd_test_atcb() AT+CMGF=0' returned OK'
Tue Feb 1 00:31:55 2000 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <5> atcmd.c:404:ml_parse() command without cb!!!
Tue Feb 1 00:31:55 2000 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Feb 1 00:31:55 2000 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()

comment:9 Changed 6 years ago by alphaone@…

I don't know if that has anything to do with it, but the command AT+CMGF=0 is
submitted twice so maybe handling the first answer also removes callback for the
second command?
(Disclaimer: I haven't looked at the code in gsmd yet where that occurs, this is
just a hinch)

comment:10 Changed 6 years ago by giles.jones@…

A log from my Neo shows similar, but it submits the same command loads of times?


Tue Aug 28 18:14:33 2007 <1> machine.c:131:gsmd_machine_plugin_init() detected
'GTA01' hardware
Tue Aug 28 18:14:33 2007 <1> machine.c:72:gsmd_machine_plugin_load() loading
machine plugin "generic"
Tue Aug 28 18:14:33 2007 <1> vendor.c:75:gsmd_vendor_plugin_load() loading
vendor plugin "ti"
Tue Aug 28 18:14:33 2007 <1> machine.c:56:gsmd_machine_plugin_find() selecting
machine plugin "generic"
Tue Aug 28 18:14:34 2007 <1> atcmd.c:561:atcmd_drain() c_iflag = 0x00000500,
c_oflag = 0x00000005, c_cflag = 0x800018b2, c_lflag = 0x00008a3b
Tue Aug 28 18:14:34 2007 <1> vendor.c:59:gsmd_vendor_plugin_find() selecting
vendor plugin "TI Calypso"
Tue Aug 28 18:14:34 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATZ'

Tue Aug 28 18:14:35 2007 <1> atcmd.c:210:ml_parse() buf=`AT-Command Interpreter
ready'(28)
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATZ'

Tue Aug 28 18:14:35 2007 <1> atcmd.c:210:ml_parse() buf=`ATZ'(3)

Tue Aug 28 18:14:35 2007 <1> atcmd.c:229:ml_parse() ignoring echo

Tue Aug 28 18:14:35 2007 <1> atcmd.c:210:ml_parse() buf=`ATZ'(3)

Tue Aug 28 18:14:35 2007 <1> atcmd.c:229:ml_parse() ignoring echo

Tue Aug 28 18:14:35 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)

Tue Aug 28 18:14:35 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()

Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`ATE0V1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CRC=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CREG=2'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMEE=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CLIP=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COLP=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CTZR=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CTZV=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPRI=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CSQ=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CUNS=0'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPI=?'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf

Tue Aug 28 18:14:35 2007 <1> atcmd.c:210:ml_parse() buf=`ATE0V1'(6)

Tue Aug 28 18:14:35 2007 <1> atcmd.c:372:ml_parse() Appending buf to mlbuf

Tue Aug 28 18:14:35 2007 <1> atcmd.c:210:ml_parse() buf=`ATE0V1'(6)

Tue Aug 28 18:14:35 2007 <1> atcmd.c:372:ml_parse() Appending buf to mlbuf

Tue Aug 28 18:14:35 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)

Tue Aug 28 18:14:35 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()

Tue Aug 28 18:14:35 2007 <7> gsmd.c:210:firstcmd_atcb() response 'ATE0V1

ATE0V1' to initial command invalidTue Aug 28 18:14:35 2007 <1>
atcmd.c:545:atcmd_submit() submitting command `ATE0V1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CRC=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CREG=2'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMEE=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CLIP=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COLP=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CMGF=0'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CTZR=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CTZV=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPRI=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CSQ=1'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CUNS=0'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT%CPI=?'
Tue Aug 28 18:14:35 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf

Tue Aug 28 18:14:56 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+CFUN=1'
Tue Aug 28 18:15:02 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:07 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:12 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:17 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:22 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:32 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:37 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:37 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:42 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:47 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:52 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:15:57 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:02 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:07 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:12 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:17 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:22 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:32 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:37 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:42 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:47 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:16:57 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:02 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:07 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:12 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:17 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:22 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:32 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:37 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:42 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:47 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:52 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:17:57 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:02 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:07 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:12 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:17 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:22 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:32 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:37 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:42 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:47 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:52 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:18:57 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:02 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:07 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:12 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:17 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:22 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:27 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:32 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:34 2007 <1> gsmd.c:124:alive_interval_tmr_cb() interval
expired, starting next alive inquiry
Tue Aug 28 18:19:34 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT'

Tue Aug 28 18:19:35 2007 <1> gsmd.c:124:alive_interval_tmr_cb() interval
expired, starting next alive inquiry
Tue Aug 28 18:19:35 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT'

Tue Aug 28 18:19:37 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:42 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:47 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:52 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:19:57 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:20:02 2007 <1> atcmd.c:545:atcmd_submit() submitting command
`AT+COPS=0'
Tue Aug 28 18:20:04 2007 <1> gsmd.c:79:alive_tmr_cb() gsmd_alive timer expired

Tue Aug 28 18:20:04 2007 <8> gsmd.c:82:alive_tmr_cb() modem dead!

comment:11 Changed 6 years ago by cesarb@…

  • Cc cesarb@… added

comment:12 Changed 6 years ago by mickey@…

  • blocked set to 766

comment:13 Changed 6 years ago by cw@…

  • Cc cw@… added

comment:14 Changed 6 years ago by technil@…

  • Cc technil@… added

comment:15 Changed 6 years ago by jserv@…

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

Confirmed and Fixed in revision 2724. Thanks!

Note: See TracTickets for help on using tickets.