root@fic-gta01:~$ cat /tmp/gsm.log
Tue Aug 28 00:39:45 2007 <1> machine.c:131:gsmd_machine_plugin_init() detected 'GTA01' hardware
Tue Aug 28 00:39:45 2007 <1> machine.c:72:gsmd_machine_plugin_load() loading machine plugin "generic"
Tue Aug 28 00:39:45 2007 <1> vendor.c:75:gsmd_vendor_plugin_load() loading vendor plugin "ti"
Tue Aug 28 00:39:45 2007 <1> machine.c:56:gsmd_machine_plugin_find() selecting machine plugin "generic"
Tue Aug 28 00:39:46 2007 <1> atcmd.c:561:atcmd_drain() c_iflag = 0x00000000, c_oflag = 0x00000000, c_cflag = 0x800018b2, c_lflag = 0x00000a30
Tue Aug 28 00:39:46 2007 <1> vendor.c:59:gsmd_vendor_plugin_find() selecting vendor plugin "TI Calypso"
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATZ'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `ATE0V1'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+CRC=1'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+CREG=2'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+CMEE=1'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+CLIP=1'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+COLP=1'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+CMGF=0'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+CMGF=0'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+CTZR=1'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT%CTZV=1'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT%CPRI=1'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT%CSQ=1'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT%CUNS=0'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT%CPI=?'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `ATE0V1' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT+CRC=1' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT+CREG=2' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT+CMEE=1' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT+CLIP=1' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT+COLP=1' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT+CMGF=0' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <5> atcmd.c:404:ml_parse() command without cb!!!
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT+CTZR=1' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT%CTZV=1' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:46 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:46 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:46 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT%CPRI=1' returned `OK'
Tue Aug 28 00:39:46 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:47 2007 <1> atcmd.c:210:ml_parse() buf=`%CSQ: 1 '(8)
Tue Aug 28 00:39:47 2007 <1> atcmd.c:372:ml_parse() Appending buf to mlbuf
Tue Aug 28 00:39:47 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:47 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:47 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT%CSQ=1' returned `%CSQ: 1 '
Tue Aug 28 00:39:47 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:47 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:47 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:47 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT%CUNS=0' returned `OK'
Tue Aug 28 00:39:47 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:47 2007 <1> atcmd.c:210:ml_parse() buf=`%CPI: (0-4)'(11)
Tue Aug 28 00:39:47 2007 <1> atcmd.c:372:ml_parse() Appending buf to mlbuf
Tue Aug 28 00:39:47 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:47 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:47 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT%CPI=3'
Tue Aug 28 00:39:47 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:47 2007 <1> atcmd.c:210:ml_parse() buf=`OK'(2)
Tue Aug 28 00:39:47 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:47 2007 <1> gsmd.c:154:gsmd_test_atcb() `AT%CPI=3' returned `OK'
Tue Aug 28 00:39:47 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:39:58 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+CFUN=1'
Tue Aug 28 00:39:59 2007 <1> atcmd.c:210:ml_parse() buf=`+CME ERROR: 10'(14)
Tue Aug 28 00:39:59 2007 <1> atcmd.c:259:ml_parse() error number 10
Tue Aug 28 00:39:59 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:39:59 2007 <1> usock.c:254:phone_powerup_cb() Radio power-up failed
Tue Aug 28 00:39:59 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:40:18 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+COPS=0'
Tue Aug 28 00:40:18 2007 <1> atcmd.c:210:ml_parse() buf=`+CREG: 2'(8)
Tue Aug 28 00:40:18 2007 <1> atcmd.c:278:ml_parse() extd reply `+CREG: 2' to cmd `+COPS=0', must be unsolicited
Tue Aug 28 00:40:18 2007 <1> unsolicited.c:69:usock_evt_send() entering evt=5
Tue Aug 28 00:40:18 2007 <1> usock.c:54:usock_cmd_enqueue() enqueueing usock cmd 0x2e9c8 for user 0x2e908
Tue Aug 28 00:40:18 2007 <1> usock.c:54:usock_cmd_enqueue() enqueueing usock cmd 0x2ea30 for user 0x2e8a8
Tue Aug 28 00:40:18 2007 <1> usock.c:1435:gsmd_usock_user_cb() successfully sent cmd 0x2ea30 to user 0x2e8a8, freeing
Tue Aug 28 00:40:18 2007 <1> usock.c:1435:gsmd_usock_user_cb() successfully sent cmd 0x2e9c8 to user 0x2e908, freeing
Tue Aug 28 00:40:23 2007 <1> atcmd.c:210:ml_parse() buf=`+CREG: 3'(8)
Tue Aug 28 00:40:23 2007 <1> atcmd.c:278:ml_parse() extd reply `+CREG: 3' to cmd `+COPS=0', must be unsolicited
Tue Aug 28 00:40:23 2007 <1> unsolicited.c:69:usock_evt_send() entering evt=5
Tue Aug 28 00:40:23 2007 <1> usock.c:54:usock_cmd_enqueue() enqueueing usock cmd 0x2e9c8 for user 0x2e908
Tue Aug 28 00:40:23 2007 <1> usock.c:54:usock_cmd_enqueue() enqueueing usock cmd 0x2ea30 for user 0x2e8a8
Tue Aug 28 00:40:23 2007 <1> usock.c:1435:gsmd_usock_user_cb() successfully sent cmd 0x2ea30 to user 0x2e8a8, freeing
Tue Aug 28 00:40:23 2007 <1> usock.c:1435:gsmd_usock_user_cb() successfully sent cmd 0x2e9c8 to user 0x2e908, freeing
Tue Aug 28 00:40:23 2007 <1> atcmd.c:210:ml_parse() buf=`+CME ERROR: 32'(14)
Tue Aug 28 00:40:23 2007 <1> atcmd.c:259:ml_parse() error number 32
Tue Aug 28 00:40:23 2007 <1> atcmd.c:406:ml_parse() Calling final cmd->cb()
Tue Aug 28 00:40:23 2007 <1> usock.c:175:null_cmd_cb() null cmd cb
Tue Aug 28 00:40:23 2007 <1> atcmd.c:415:ml_parse() Clearing mlbuf
Tue Aug 28 00:40:23 2007 <1> atcmd.c:210:ml_parse() buf=`%CSQ:  26, 99, 3'(16)
Tue Aug 28 00:40:23 2007 <1> atcmd.c:278:ml_parse() extd reply `%CSQ:  26, 99, 3' to cmd `NONE', must be unsolicited
Tue Aug 28 00:40:23 2007 <1> vendor_ti.c:78:csq_parse() entering csq_parse param=` 26, 99, 3'
Tue Aug 28 00:40:23 2007 <1> unsolicited.c:69:usock_evt_send() entering evt=6
Tue Aug 28 00:40:23 2007 <1> usock.c:54:usock_cmd_enqueue() enqueueing usock cmd 0x2e968 for user 0x2e908
Tue Aug 28 00:40:23 2007 <1> usock.c:54:usock_cmd_enqueue() enqueueing usock cmd 0x2e9d0 for user 0x2e8a8
Tue Aug 28 00:40:23 2007 <1> usock.c:1435:gsmd_usock_user_cb() successfully sent cmd 0x2e9d0 to user 0x2e8a8, freeing
Tue Aug 28 00:40:23 2007 <1> usock.c:1435:gsmd_usock_user_cb() successfully sent cmd 0x2e968 to user 0x2e908, freeing
Tue Aug 28 00:40:38 2007 <1> atcmd.c:545:atcmd_submit() submitting command `AT+COPS=0'
Tue Aug 28 00:40:38 2007 <1> atcmd.c:210:ml_parse() buf=`+CREG: 2'(8)
Tue Aug 28 00:40:38 2007 <1> atcmd.c:278:ml_parse() extd reply `+CREG: 2' to cmd `+COPS=0', must be unsolicited
Tue Aug 28 00:40:38 2007 <1> unsolicited.c:69:usock_evt_send() entering evt=5
Tue Aug 28 00:40:38 2007 <1> usock.c:54:usock_cmd_enqueue() enqueueing usock cmd 0x2e9c8 for user 0x2e908
Tue Aug 28 00:40:38 2007 <1> usock.c:54:usock_cmd_enqueue() enqueueing usock cmd 0x2ea30 for user 0x2e8a8
Tue Aug 28 00:40:38 2007 <1> usock.c:1435:gsmd_usock_user_cb() successfully sent cmd 0x2ea30 to user 0x2e8a8, freeing
Tue Aug 28 00:40:38 2007 <1> usock.c:1435:gsmd_usock_user_cb() successfully sent cmd 0x2e9c8 to user 0x2e908, freeing