[Openbts-discuss] voice call faillure

[gm p. <gm...@ya...>]

hi

i have installed the openbts and freeswitch. i copied the scripts (http://wush.net/trac/rangepublic/wiki/freeswitchConfig) to the frreswitch/dialplan/public.xml. but when i tried to make it hangups.

here is the  log of the freeswitch.


2012-09-22 22:26:20.833170 [NOTICE] switch_channel.c:951 New
Channel sofia/internal/IMSI234201101741548@192.168.10.1
[2712c7d8-04fc-11e2-b5e3-fd40f716cac6]
2012-09-22 22:26:20.833170 [DEBUG] switch_core_session.c:905
Send signal sofia/internal/IMSI234201101741548@192.168.10.1 [BREAK]
2012-09-22 22:26:20.833170 [DEBUG]
switch_core_state_machine.c:398
(sofia/internal/IMSI234201101741548@192.168.10.1) Running State Change CS_NEW
2012-09-22 22:26:20.833170 [DEBUG]
switch_core_state_machine.c:416 (sofia/internal/IMSI234201101741548@192.168.10.1)
State NEW
2012-09-22 22:26:20.852932 [DEBUG] switch_core_session.c:905
Send signal sofia/internal/IMSI234201101741548@192.168.10.1 [BREAK]
2012-09-22 22:26:20.872829 [DEBUG] sofia.c:8353 IP
192.168.10.1 Approved by acl "domains[]". Access Granted.
2012-09-22 22:26:20.872829 [DEBUG] sofia.c:6282 Channel
sofia/internal/IMSI234201101741548@192.168.10.1 entering state [received][100]
2012-09-22 22:26:20.872829 [DEBUG] sofia.c:6293 Remote SDP:
v=0
o=IMSI234201101741548 0 0 IN IP4 192.168.10.1
s=Talk Time
t=0 0
m=audio 16566 RTP/AVP 3
c=IN IP4 192.168.10.1
a=rtpmap:3 GSM/8000
 
 
2012-09-22 22:26:20.872829 [DEBUG] sofia.c:6506
(sofia/internal/IMSI234201101741548@192.168.10.1) State Change CS_NEW ->
CS_INIT
2012-09-22 22:26:20.872829 [DEBUG]
switch_core_session.c:1210 Send signal
sofia/internal/IMSI234201101741548@192.168.10.1 [BREAK]
2012-09-22 22:26:20.872829 [DEBUG]
switch_core_state_machine.c:398
(sofia/internal/IMSI234201101741548@192.168.10.1) Running State Change CS_INIT
2012-09-22 22:26:20.872829 [DEBUG]
switch_core_state_machine.c:437
(sofia/internal/IMSI234201101741548@192.168.10.1) State INIT
2012-09-22 22:26:20.872829 [DEBUG] mod_sofia.c:86
sofia/internal/IMSI234201101741548@192.168.10.1 SOFIA INIT
2012-09-22 22:26:20.872829 [DEBUG] mod_sofia.c:126
(sofia/internal/IMSI234201101741548@192.168.10.1) State Change CS_INIT ->
CS_ROUTING
2012-09-22 22:26:20.872829 [DEBUG]
switch_core_session.c:1210 Send signal
sofia/internal/IMSI234201101741548@192.168.10.1 [BREAK]
2012-09-22 22:26:20.872829 [DEBUG]
switch_core_state_machine.c:437
(sofia/internal/IMSI234201101741548@192.168.10.1) State INIT going to sleep
2012-09-22 22:26:20.872829 [DEBUG]
switch_core_state_machine.c:398 (sofia/internal/IMSI234201101741548@192.168.10.1)
Running State Change CS_ROUTING
2012-09-22 22:26:20.892848 [DEBUG] switch_channel.c:1964
(sofia/internal/IMSI234201101741548@192.168.10.1) Callstate Change DOWN ->
RINGING
2012-09-22 22:26:20.892848 [DEBUG]
switch_core_state_machine.c:446 (sofia/internal/IMSI234201101741548@192.168.10.1)
State ROUTING
2012-09-22 22:26:20.892848 [DEBUG] mod_sofia.c:149
sofia/internal/IMSI234201101741548@192.168.10.1 SOFIA ROUTING
2012-09-22 22:26:20.892848 [DEBUG]
switch_core_state_machine.c:117 sofia/internal/IMSI234201101741548@192.168.10.1
Standard ROUTING
2012-09-22 22:26:20.892848 [INFO] mod_dialplan_xml.c:485
Processing IMSI234201101741548 <IMSI234201101741548>->100020 in
context public
Dialplan: sofia/internal/IMSI234201101741548@192.168.10.1
parsing [public->unloop] continue=false
Dialplan: sofia/internal/IMSI234201101741548@192.168.10.1
Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/IMSI234201101741548@192.168.10.1
Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/IMSI234201101741548@192.168.10.1
parsing [public->local_call] continue=false
2012-09-22 22:26:20.912841 [NOTICE] mod_python.c:212
Invoking py module: OpenBTS_DB
2012-09-22 22:26:20.933911 [DEBUG] mod_python.c:281 Call
python script 
2012-09-22 22:26:20.955483 [INFO] switch_cpp.cpp:1227
Returned: INSI2342072007852422012-09-22 22:26:20.955483 [DEBUG]
mod_python.c:284 Finished calling python script 
2012-09-22 22:26:20.955483 [DEBUG] switch_cpp.cpp:1007
sofia/internal/IMSI234201101741548@192.168.10.1 destroy/unlink session from
object
Dialplan: sofia/internal/IMSI234201101741548@192.168.10.1
Regex (FAIL) [local_call] ${python(OpenBTS_DB SELECT name FROM sip_buddies
WHERE callerid="${destination_number}")}(INSI234207200785242) =~
/IMSI\d{15}/ break=on-false
Dialplan: sofia/internal/IMSI234201101741548@192.168.10.1
parsing [public->forward] continue=false
Dialplan: sofia/internal/IMSI234201101741548@192.168.10.1
Regex (PASS) [forward] openbts_tp_dest_address() =~ /^(.*)$/ break=on-false
Dialplan: sofia/internal/IMSI234201101741548@192.168.10.1
Action python(OpenBTS_Send_SMS
${openbts_tp_dest_address}|${from_user}|${openbts_text}) 
2012-09-22 22:26:20.955483 [DEBUG]
switch_core_state_machine.c:167
(sofia/internal/IMSI234201101741548@192.168.10.1) State Change CS_ROUTING ->
CS_EXECUTE
2012-09-22 22:26:20.955483 [DEBUG]
switch_core_session.c:1210 Send signal
sofia/internal/IMSI234201101741548@192.168.10.1 [BREAK]
2012-09-22 22:26:20.955483 [DEBUG]
switch_core_state_machine.c:446
(sofia/internal/IMSI234201101741548@192.168.10.1) State ROUTING going to sleep
2012-09-22 22:26:20.955483 [DEBUG]
switch_core_state_machine.c:398
(sofia/internal/IMSI234201101741548@192.168.10.1) Running State Change CS_EXECUTE
2012-09-22 22:26:20.955483 [DEBUG]
switch_core_state_machine.c:453
(sofia/internal/IMSI234201101741548@192.168.10.1) State EXECUTE
2012-09-22 22:26:20.955483 [DEBUG] mod_sofia.c:242
sofia/internal/IMSI234201101741548@192.168.10.1 SOFIA EXECUTE
2012-09-22 22:26:20.955483 [DEBUG]
switch_core_state_machine.c:209 sofia/internal/IMSI234201101741548@192.168.10.1
Standard EXECUTE
EXECUTE sofia/internal/IMSI234201101741548@192.168.10.1
python(OpenBTS_Send_SMS ||)
2012-09-22 22:26:20.955483 [NOTICE] mod_python.c:212
Invoking py module: OpenBTS_Send_SMS
2012-09-22 22:26:21.152847 [ERR] mod_python.c:247 Module
does not define handler
2012-09-22 22:26:21.152847 [ERR] mod_python.c:164 Python
Error by calling script "OpenBTS_Send_SMS": <type
'exceptions.AttributeError'>
Message: 'module' object has no attribute 'handler'
2012-09-22 22:26:21.152847 [NOTICE]
switch_core_state_machine.c:262 sofia/internal/IMSI234201101741548@192.168.10.1
has executed the last dialplan instruction, hanging up.
2012-09-22 22:26:21.152847 [DEBUG] switch_channel.c:2950
(sofia/internal/IMSI234201101741548@192.168.10.1) Callstate Change RINGING
-> HANGUP
2012-09-22 22:26:21.152847 [NOTICE]
switch_core_state_machine.c:264 Hangup
sofia/internal/IMSI234201101741548@192.168.10.1 [CS_EXECUTE] [NORMAL_CLEARING]
2012-09-22 22:26:21.152847 [DEBUG] switch_channel.c:2973
Send signal sofia/internal/IMSI234201101741548@192.168.10.1 [KILL]
2012-09-22 22:26:21.152847 [DEBUG]
switch_core_session.c:1210 Send signal sofia/internal/IMSI234201101741548@192.168.10.1
[BREAK]
2012-09-22 22:26:21.152847 [DEBUG]
switch_core_state_machine.c:453
(sofia/internal/IMSI234201101741548@192.168.10.1) State EXECUTE going to sleep
2012-09-22 22:26:21.152847 [DEBUG]
switch_core_state_machine.c:398 (sofia/internal/IMSI234201101741548@192.168.10.1)
Running State Change CS_HANGUP
2012-09-22 22:26:21.152847 [DEBUG]
switch_core_state_machine.c:638
(sofia/internal/IMSI234201101741548@192.168.10.1) State HANGUP
2012-09-22 22:26:21.212848 [DEBUG] mod_sofia.c:483 Channel sofia/internal/IMSI234201101741548@192.168.10.1
hanging up, cause: NORMAL_CLEARING
2012-09-22 22:26:21.212848 [DEBUG] mod_sofia.c:613
Responding to INVITE with: 480
2012-09-22 22:26:21.212848 [DEBUG]
switch_core_state_machine.c:48 sofia/internal/IMSI234201101741548@192.168.10.1
Standard HANGUP, cause: NORMAL_CLEARING
2012-09-22 22:26:21.212848 [DEBUG]
switch_core_state_machine.c:638
(sofia/internal/IMSI234201101741548@192.168.10.1) State HANGUP going to sleep
2012-09-22 22:26:21.212848 [DEBUG] switch_core_state_machine.c:429
(sofia/internal/IMSI234201101741548@192.168.10.1) State Change CS_HANGUP ->
CS_REPORTING
2012-09-22 22:26:21.212848 [DEBUG]
switch_core_session.c:1210 Send signal
sofia/internal/IMSI234201101741548@192.168.10.1 [BREAK]
2012-09-22 22:26:21.212848 [DEBUG]
switch_core_state_machine.c:398
(sofia/internal/IMSI234201101741548@192.168.10.1) Running State Change
CS_REPORTING
2012-09-22 22:26:21.212848 [DEBUG]
switch_core_state_machine.c:703
(sofia/internal/IMSI234201101741548@192.168.10.1) State REPORTING
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_state_machine.c:92 sofia/internal/IMSI234201101741548@192.168.10.1
Standard REPORTING, cause: NORMAL_CLEARING
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_state_machine.c:703 (sofia/internal/IMSI234201101741548@192.168.10.1)
State REPORTING going to sleep
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_state_machine.c:423
(sofia/internal/IMSI234201101741548@192.168.10.1) State Change CS_REPORTING
-> CS_DESTROY
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_session.c:1210 Send signal
sofia/internal/IMSI234201101741548@192.168.10.1 [BREAK]
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_session.c:1415 Session 1
(sofia/internal/IMSI234201101741548@192.168.10.1) Locked, Waiting on external
entities
2012-09-22 22:26:21.252848 [NOTICE]
switch_core_session.c:1433 Session 1
(sofia/internal/IMSI234201101741548@192.168.10.1) Ended
2012-09-22 22:26:21.252848 [NOTICE]
switch_core_session.c:1437 Close Channel
sofia/internal/IMSI234201101741548@192.168.10.1 [CS_DESTROY]
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_state_machine.c:527
(sofia/internal/IMSI234201101741548@192.168.10.1) Callstate Change HANGUP ->
DOWN
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_state_machine.c:530 (sofia/internal/IMSI234201101741548@192.168.10.1)
Running State Change CS_DESTROY
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_state_machine.c:540
(sofia/internal/IMSI234201101741548@192.168.10.1) State DESTROY
2012-09-22 22:26:21.252848 [DEBUG] mod_sofia.c:376
sofia/internal/IMSI234201101741548@192.168.10.1 SOFIA DESTROY
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_state_machine.c:99 sofia/internal/IMSI234201101741548@192.168.10.1
Standard DESTROY
2012-09-22 22:26:21.252848 [DEBUG]
switch_core_state_machine.c:540 (sofia/internal/IMSI234201101741548@192.168.10.1)
State DESTROY going to sleep


is that correct the routing plan is placed in the public.xml?