Problem configuring OpenBTS2.8 with Freeswitch 1.0.6
Hi, I have configured OpenBTS with Freeswitch and registered 2 users using VBTS_New_User found here: http://wush.net/trac/rangepublic/wiki/freeswitchConfig I can see the 2 tuples in the sqlite3.db, however when i attempt to make a call between the 2 registered users, the log on Freeswitch is as follows: 2013-03-27 21:18:07.495839 [NOTICE] switch_channel.c:976 New Channel sofia/internal/IMSI410071190004419@127.0.0.1 [e9051208-96f9-11e2-8f70- 5979f626837d] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_NEW 2013-03-27 21:18:07.495839 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/IMSI410071190004419@127.0.0.1) State NEW 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:7697 IP 127.0.0.1 Approved by acl "domains[]". Access Granted. 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5597 Channel sofia/internal/ IMSI410071190004419@127.0.0.1 entering state [received][100] 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5608 Remote SDP: v=0 o=IMSI410071190004419 0 0 IN IP4 127.0.0.1 s=Talk Time t=0 0 m=audio 16502 RTP/AVP 3 c=IN IP4 127.0.0.1 a=rtpmap:3 GSM/8000 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5821 (sofia/internal/ IMSI410071190004419@127.0.0.1) State Change CS_NEW -> CS_INIT 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_INIT 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/IMSI410071190004419@127.0.0.1) State INIT 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:86 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA INIT 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:126 (sofia/internal/ IMSI410071190004419@127.0.0.1) State Change CS_INIT -> CS_ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/IMSI410071190004419@127.0.0.1) State INIT going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:2012 (sofia/internal/ IMSI410071190004419@127.0.0.1) Callstate Change DOWN -> RINGING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/IMSI410071190004419@127.0.0.1) State ROUTING 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:149 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:117 sofia/internal/IMSI410071190004419@127.0.0.1 Standard ROUTING 2013-03-27 21:18:07.515818 [INFO] mod_dialplan_xml.c:557 Processing IMSI410071190004419 <IMSI410071190004419>->2222 in context public Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->unloop] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->outside_call] continue=true Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Absolute Condition [outside_call] Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Action set(outside_call=true) Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->call_debug] continue=true Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->public_extensions] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [public_extensions] destination_number(2222) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->public_did] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [public_did] destination_number(2222) =~ /^(5551212)$/ break=on-false 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_ROUTING -> CS_EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/IMSI410071190004419@127.0.0.1) State ROUTING going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/IMSI410071190004419@127.0.0.1) State EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:242 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:209 sofia/internal/IMSI410071190004419@127.0.0.1 Standard EXECUTE EXECUTE sofia/internal/IMSI410071190004419@127.0.0.1 set(outside_call=true) 2013-03-27 21:18:07.515818 [DEBUG] mod_dptools.c:1367 sofia/internal/ IMSI410071190004419@127.0.0.1 SET [outside_call]=[true] EXECUTE sofia/internal/IMSI410071190004419@127.0.0.1export(RFC2822_DATE=Wed, 27 Mar 2013 21:18:07 +0500) 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:1143 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 27 Mar 2013 21:18:07 +0500] 2013-03-27 21:18:07.515818 [NOTICE] switch_core_state_machine.c:262 sofia/internal/IMSI410071190004419@127.0.0.1 has executed the last dialplan instruction, hanging up. 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:3011 (sofia/internal/ IMSI410071190004419@127.0.0.1) Callstate Change RINGING -> HANGUP 2013-03-27 21:18:07.515818 [NOTICE] switch_core_state_machine.c:264 Hangup sofia/internal/IMSI410071190004419@127.0.0.1 [CS_EXECUTE] [NORMAL_CLEARING] 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:3034 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [KILL] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/IMSI410071190004419@127.0.0.1) State EXECUTE going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_HANGUP 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/IMSI410071190004419@127.0.0.1) State HANGUP 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:503 Channel sofia/internal/ IMSI410071190004419@127.0.0.1 hanging up, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 480 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:48 sofia/internal/IMSI410071190004419@127.0.0.1 Standard HANGUP, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/IMSI410071190004419@127.0.0.1) State HANGUP going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_HANGUP -> CS_REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/IMSI410071190004419@127.0.0.1) State REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:92 sofia/internal/IMSI410071190004419@127.0.0.1 Standard REPORTING, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/IMSI410071190004419@127.0.0.1) State REPORTING going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_REPORTING -> CS_DESTROY 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1518 Session 44 (sofia/internal/IMSI410071190004419@127.0.0.1) Locked, Waiting on external entities 2013-03-27 21:18:07.515818 [NOTICE] switch_core_session.c:1536 Session 44 (sofia/internal/IMSI410071190004419@127.0.0.1) Ended 2013-03-27 21:18:07.515818 [NOTICE] switch_core_session.c:1540 Close Channel sofia/internal/IMSI410071190004419@127.0.0.1 [CS_DESTROY] 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/IMSI410071190004419@127.0.0.1) Callstate Change HANGUP -> DOWN 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:568 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/IMSI410071190004419@127.0.0.1) State DESTROY 2013-03-27 21:18:07.525838 [DEBUG] mod_sofia.c:396 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:99 sofia/internal/IMSI410071190004419@127.0.0.1 Standard DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/IMSI410071190004419@127.0.0.1) State DESTROY going to sleep Gratitude, Ibrahim
Your log shows that you're entering the dialplan with destination number 2222. However there are no extensions that match this number. You need to create a dialplan extension match this number and bridge the call to the registered user. Your log shows that you're using the default configuration. This is only intended as an example. I recommend you replace or modify it to only do what you need. FreeSWITCH 1.0.6 is also a very old unsupported release that contains known problems. I suggest you upgrade to either 1.2.7 or Git master. -Steve On 28 March 2013 14:19, Syed Ibrahim Ghaznavi <ibrahimghaznavi@gmail.com>wrote:
Hi, I have configured OpenBTS with Freeswitch and registered 2 users using VBTS_New_User found here: http://wush.net/trac/rangepublic/wiki/freeswitchConfig
I can see the 2 tuples in the sqlite3.db, however when i attempt to make a call between the 2 registered users, the log on Freeswitch is as follows:
2013-03-27 21:18:07.495839 [NOTICE] switch_channel.c:976 New Channel sofia/internal/IMSI410071190004419@127.0.0.1 [e9051208-96f9-11e2-8f70- 5979f626837d] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_NEW 2013-03-27 21:18:07.495839 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/IMSI410071190004419@127.0.0.1) State NEW 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:7697 IP 127.0.0.1 Approved by acl "domains[]". Access Granted. 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5597 Channel sofia/internal/ IMSI410071190004419@127.0.0.1 entering state [received][100] 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5608 Remote SDP: v=0 o=IMSI410071190004419 0 0 IN IP4 127.0.0.1 s=Talk Time t=0 0 m=audio 16502 RTP/AVP 3 c=IN IP4 127.0.0.1 a=rtpmap:3 GSM/8000
2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5821 (sofia/internal/ IMSI410071190004419@127.0.0.1) State Change CS_NEW -> CS_INIT 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_INIT 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/IMSI410071190004419@127.0.0.1) State INIT 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:86 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA INIT 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:126 (sofia/internal/ IMSI410071190004419@127.0.0.1) State Change CS_INIT -> CS_ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/IMSI410071190004419@127.0.0.1) State INIT going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:2012 (sofia/internal/ IMSI410071190004419@127.0.0.1) Callstate Change DOWN -> RINGING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/IMSI410071190004419@127.0.0.1) State ROUTING 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:149 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:117 sofia/internal/IMSI410071190004419@127.0.0.1 Standard ROUTING 2013-03-27 21:18:07.515818 [INFO] mod_dialplan_xml.c:557 Processing IMSI410071190004419 <IMSI410071190004419>->2222 in context public Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->unloop] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->outside_call] continue=true Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Absolute Condition [outside_call] Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Action set(outside_call=true) Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->call_debug] continue=true Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->public_extensions] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [public_extensions] destination_number(2222) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->public_did] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [public_did] destination_number(2222) =~ /^(5551212)$/ break=on-false 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_ROUTING -> CS_EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/IMSI410071190004419@127.0.0.1) State ROUTING going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/IMSI410071190004419@127.0.0.1) State EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:242 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:209 sofia/internal/IMSI410071190004419@127.0.0.1 Standard EXECUTE EXECUTE sofia/internal/IMSI410071190004419@127.0.0.1set(outside_call=true) 2013-03-27 21:18:07.515818 [DEBUG] mod_dptools.c:1367 sofia/internal/ IMSI410071190004419@127.0.0.1 SET [outside_call]=[true] EXECUTE sofia/internal/IMSI410071190004419@127.0.0.1export(RFC2822_DATE=Wed, 27 Mar 2013 21:18:07 +0500) 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:1143 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 27 Mar 2013 21:18:07 +0500] 2013-03-27 21:18:07.515818 [NOTICE] switch_core_state_machine.c:262 sofia/internal/IMSI410071190004419@127.0.0.1 has executed the last dialplan instruction, hanging up. 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:3011 (sofia/internal/ IMSI410071190004419@127.0.0.1) Callstate Change RINGING -> HANGUP 2013-03-27 21:18:07.515818 [NOTICE] switch_core_state_machine.c:264 Hangup sofia/internal/IMSI410071190004419@127.0.0.1 [CS_EXECUTE] [NORMAL_CLEARING] 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:3034 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [KILL] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/IMSI410071190004419@127.0.0.1) State EXECUTE going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_HANGUP 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/IMSI410071190004419@127.0.0.1) State HANGUP 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:503 Channel sofia/internal/ IMSI410071190004419@127.0.0.1 hanging up, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 480 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:48 sofia/internal/IMSI410071190004419@127.0.0.1 Standard HANGUP, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/IMSI410071190004419@127.0.0.1) State HANGUP going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_HANGUP -> CS_REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/IMSI410071190004419@127.0.0.1) State REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:92 sofia/internal/IMSI410071190004419@127.0.0.1 Standard REPORTING, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/IMSI410071190004419@127.0.0.1) State REPORTING going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_REPORTING -> CS_DESTROY 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1518 Session 44 (sofia/internal/IMSI410071190004419@127.0.0.1) Locked, Waiting on external entities 2013-03-27 21:18:07.515818 [NOTICE] switch_core_session.c:1536 Session 44 (sofia/internal/IMSI410071190004419@127.0.0.1) Ended 2013-03-27 21:18:07.515818 [NOTICE] switch_core_session.c:1540 Close Channel sofia/internal/IMSI410071190004419@127.0.0.1 [CS_DESTROY] 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/IMSI410071190004419@127.0.0.1) Callstate Change HANGUP -> DOWN 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:568 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/IMSI410071190004419@127.0.0.1) State DESTROY 2013-03-27 21:18:07.525838 [DEBUG] mod_sofia.c:396 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:99 sofia/internal/IMSI410071190004419@127.0.0.1 Standard DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/IMSI410071190004419@127.0.0.1) State DESTROY going to sleep
Gratitude, Ibrahim
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
Thanks steve for the prompt help ! I am pasting a line below from the log, i guess the context is public ? : mod_dialplan_xml.c:557 Processing IMSI410071190004419 <IMSI410071190004419>->2222 in *context public * You are right that i have no extension 2222. But i am confused as to how should i make an extension using IMSI. Like the sample extensions (1000-1019) looks like: <include> <user id="1000"> <params> <param name="password" value="$${default_password}"/> <param name="vm-password" value="1000"/> </params> <variables> <variable name="toll_allow" value="domestic,international,local"/> <variable name="accountcode" value="1000"/> <variable name="user_context" value="default"/> <variable name="effective_caller_id_name" value="Extension 1000"/> <variable name="effective_caller_id_number" value="1000"/> <variable name="outbound_caller_id_name" value="$${outbound_caller_name}"/> <variable name="outbound_caller_id_number" value="$${outbound_caller_id}"/> <variable name="callgroup" value="techsupport"/> </variables> </user> </include> Whereas i found one example of the extension using IMSI: <include> <user id="IMSI001011990032551" cidr="127.0.0.1/24"> <params> <param name="accept-blind-reg" value="true"/> <param name="accept-blind-auth" value="true"/> <param name="vm-password" value="1234"/> </params> <variables> <variable name="toll_allow" value="domestic,international,local"/> <variable name="accountcode" value="2100"/> <variable name="user_context" value="default"/> <variable name="effective_caller_id_name" value="Nexus One"/> <variable name="effective_caller_id_number" value="2125552344"/> <variable name="outbound_caller_id_name" value="Nexus One"/> <variable name="outbound_caller_id_number" value="2125552344"/> <variable name="callgroup" value="techsupport"/> </variables> </user> </include> Can anyone validate if the above syntax is perfect for adding an extension using the above syntax? Another confusion related to the workflow: Assuming the syntax is correct, should i follow the following steps to establish a call between the 2 users: - Add the extensions of the users in: /usr/local/freeswitch/conf/directory/default - Then reference that entry in the dialplan - public.xml or - default.xml ? - In the new extension added in pubic or default, i should bridge the call by adding the following extension right? - <condition field="destination_number" expression="2222"> <action application="export" data="dialed_extension=$1"/> <action application="bridge" data="user/${dialed_extension}@ ${domain_name}"/> <action application="answer"/> <action application="sleep" data="1000"/> <action application="bridge" data="loopback/app=voicemail:default ${domain_name} ${dialed_extension}"/> </condition> </extension> After this i should be able to make calls right? Steve- i just check my version of freeswitch it is : FreeSWITCH Version 1.3.13. Thanks for the suggestion though. Any help will be greatly appreciated !!! Thanks much ! Gratitude, Ibrahim On Thu, Mar 28, 2013 at 8:11 PM, Steven Ayre <steveayre@gmail.com> wrote:
Your log shows that you're entering the dialplan with destination number 2222. However there are no extensions that match this number. You need to create a dialplan extension match this number and bridge the call to the registered user.
Your log shows that you're using the default configuration. This is only intended as an example. I recommend you replace or modify it to only do what you need.
FreeSWITCH 1.0.6 is also a very old unsupported release that contains known problems. I suggest you upgrade to either 1.2.7 or Git master.
-Steve
On 28 March 2013 14:19, Syed Ibrahim Ghaznavi <ibrahimghaznavi@gmail.com>wrote:
Hi, I have configured OpenBTS with Freeswitch and registered 2 users using VBTS_New_User found here: http://wush.net/trac/rangepublic/wiki/freeswitchConfig
I can see the 2 tuples in the sqlite3.db, however when i attempt to make a call between the 2 registered users, the log on Freeswitch is as follows:
2013-03-27 21:18:07.495839 [NOTICE] switch_channel.c:976 New Channel sofia/internal/IMSI410071190004419@127.0.0.1 [e9051208-96f9-11e2-8f70- 5979f626837d] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_NEW 2013-03-27 21:18:07.495839 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/IMSI410071190004419@127.0.0.1) State NEW 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:7697 IP 127.0.0.1 Approved by acl "domains[]". Access Granted. 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5597 Channel sofia/internal/ IMSI410071190004419@127.0.0.1 entering state [received][100] 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5608 Remote SDP: v=0 o=IMSI410071190004419 0 0 IN IP4 127.0.0.1 s=Talk Time t=0 0 m=audio 16502 RTP/AVP 3 c=IN IP4 127.0.0.1 a=rtpmap:3 GSM/8000
2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5821 (sofia/internal/ IMSI410071190004419@127.0.0.1) State Change CS_NEW -> CS_INIT 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_INIT 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/IMSI410071190004419@127.0.0.1) State INIT 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:86 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA INIT 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:126 (sofia/internal/ IMSI410071190004419@127.0.0.1) State Change CS_INIT -> CS_ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/IMSI410071190004419@127.0.0.1) State INIT going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:2012 (sofia/internal/ IMSI410071190004419@127.0.0.1) Callstate Change DOWN -> RINGING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/IMSI410071190004419@127.0.0.1) State ROUTING 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:149 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:117 sofia/internal/IMSI410071190004419@127.0.0.1 Standard ROUTING 2013-03-27 21:18:07.515818 [INFO] mod_dialplan_xml.c:557 Processing IMSI410071190004419 <IMSI410071190004419>->2222 in context public Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->unloop] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->outside_call] continue=true Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Absolute Condition [outside_call] Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Action set(outside_call=true) Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->call_debug] continue=true Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->public_extensions] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [public_extensions] destination_number(2222) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->public_did] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [public_did] destination_number(2222) =~ /^(5551212)$/ break=on-false 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_ROUTING -> CS_EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/IMSI410071190004419@127.0.0.1) State ROUTING going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/IMSI410071190004419@127.0.0.1) State EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:242 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:209 sofia/internal/IMSI410071190004419@127.0.0.1 Standard EXECUTE EXECUTE sofia/internal/IMSI410071190004419@127.0.0.1set(outside_call=true) 2013-03-27 21:18:07.515818 [DEBUG] mod_dptools.c:1367 sofia/internal/ IMSI410071190004419@127.0.0.1 SET [outside_call]=[true] EXECUTE sofia/internal/IMSI410071190004419@127.0.0.1export(RFC2822_DATE=Wed, 27 Mar 2013 21:18:07 +0500) 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:1143 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 27 Mar 2013 21:18:07 +0500] 2013-03-27 21:18:07.515818 [NOTICE] switch_core_state_machine.c:262 sofia/internal/IMSI410071190004419@127.0.0.1 has executed the last dialplan instruction, hanging up. 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:3011 (sofia/internal/ IMSI410071190004419@127.0.0.1) Callstate Change RINGING -> HANGUP 2013-03-27 21:18:07.515818 [NOTICE] switch_core_state_machine.c:264 Hangup sofia/internal/IMSI410071190004419@127.0.0.1 [CS_EXECUTE] [NORMAL_CLEARING] 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:3034 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [KILL] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/IMSI410071190004419@127.0.0.1) State EXECUTE going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_HANGUP 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/IMSI410071190004419@127.0.0.1) State HANGUP 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:503 Channel sofia/internal/ IMSI410071190004419@127.0.0.1 hanging up, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 480 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:48 sofia/internal/IMSI410071190004419@127.0.0.1 Standard HANGUP, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/IMSI410071190004419@127.0.0.1) State HANGUP going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_HANGUP -> CS_REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/IMSI410071190004419@127.0.0.1) State REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:92 sofia/internal/IMSI410071190004419@127.0.0.1 Standard REPORTING, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/IMSI410071190004419@127.0.0.1) State REPORTING going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_REPORTING -> CS_DESTROY 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1518 Session 44 (sofia/internal/IMSI410071190004419@127.0.0.1) Locked, Waiting on external entities 2013-03-27 21:18:07.515818 [NOTICE] switch_core_session.c:1536 Session 44 (sofia/internal/IMSI410071190004419@127.0.0.1) Ended 2013-03-27 21:18:07.515818 [NOTICE] switch_core_session.c:1540 Close Channel sofia/internal/IMSI410071190004419@127.0.0.1 [CS_DESTROY] 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/IMSI410071190004419@127.0.0.1) Callstate Change HANGUP -> DOWN 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:568 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/IMSI410071190004419@127.0.0.1) State DESTROY 2013-03-27 21:18:07.525838 [DEBUG] mod_sofia.c:396 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:99 sofia/internal/IMSI410071190004419@127.0.0.1 Standard DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/IMSI410071190004419@127.0.0.1) State DESTROY going to sleep
Gratitude, Ibrahim
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
i felt neglected, can anyone still help me with the below mentioned problem. Gratitude, Ibrahim On Fri, Mar 29, 2013 at 3:49 PM, Syed Ibrahim Ghaznavi < ibrahimghaznavi@gmail.com> wrote:
Thanks steve for the prompt help !
I am pasting a line below from the log, i guess the context is public ? :
mod_dialplan_xml.c:557 Processing IMSI410071190004419 <IMSI410071190004419>->2222 in *context public
* You are right that i have no extension 2222. But i am confused as to how should i make an extension using IMSI. Like the sample extensions (1000-1019) looks like:
<include> <user id="1000"> <params> <param name="password" value="$${default_password}"/> <param name="vm-password" value="1000"/> </params> <variables> <variable name="toll_allow" value="domestic,international,local"/> <variable name="accountcode" value="1000"/> <variable name="user_context" value="default"/> <variable name="effective_caller_id_name" value="Extension 1000"/> <variable name="effective_caller_id_number" value="1000"/> <variable name="outbound_caller_id_name" value="$${outbound_caller_name}"/> <variable name="outbound_caller_id_number" value="$${outbound_caller_id}"/> <variable name="callgroup" value="techsupport"/> </variables> </user> </include>
Whereas i found one example of the extension using IMSI:
<include> <user id="IMSI001011990032551" cidr="127.0.0.1/24"> <params> <param name="accept-blind-reg" value="true"/> <param name="accept-blind-auth" value="true"/> <param name="vm-password" value="1234"/> </params> <variables> <variable name="toll_allow" value="domestic,international,local"/> <variable name="accountcode" value="2100"/> <variable name="user_context" value="default"/> <variable name="effective_caller_id_name" value="Nexus One"/> <variable name="effective_caller_id_number" value="2125552344"/> <variable name="outbound_caller_id_name" value="Nexus One"/> <variable name="outbound_caller_id_number" value="2125552344"/> <variable name="callgroup" value="techsupport"/> </variables> </user> </include>
Can anyone validate if the above syntax is perfect for adding an extension using the above syntax?
Another confusion related to the workflow: Assuming the syntax is correct, should i follow the following steps to establish a call between the 2 users:
- Add the extensions of the users in: /usr/local/freeswitch/conf/directory/default - Then reference that entry in the dialplan - public.xml or - default.xml ? - In the new extension added in pubic or default, i should bridge the call by adding the following extension right?
-
<condition field="destination_number" expression="2222"> <action application="export" data="dialed_extension=$1"/>
<action application="bridge" data="user/${dialed_extension}@ ${domain_name}"/> <action application="answer"/> <action application="sleep" data="1000"/> <action application="bridge" data="loopback/app=voicemail:default ${domain_name} ${dialed_extension}"/> </condition> </extension>
After this i should be able to make calls right?
Steve- i just check my version of freeswitch it is : FreeSWITCH Version 1.3.13. Thanks for the suggestion though.
Any help will be greatly appreciated !!! Thanks much !
Gratitude,
Ibrahim
On Thu, Mar 28, 2013 at 8:11 PM, Steven Ayre <steveayre@gmail.com> wrote:
Your log shows that you're entering the dialplan with destination number 2222. However there are no extensions that match this number. You need to create a dialplan extension match this number and bridge the call to the registered user.
Your log shows that you're using the default configuration. This is only intended as an example. I recommend you replace or modify it to only do what you need.
FreeSWITCH 1.0.6 is also a very old unsupported release that contains known problems. I suggest you upgrade to either 1.2.7 or Git master.
-Steve
On 28 March 2013 14:19, Syed Ibrahim Ghaznavi <ibrahimghaznavi@gmail.com>wrote:
Hi, I have configured OpenBTS with Freeswitch and registered 2 users using VBTS_New_User found here: http://wush.net/trac/rangepublic/wiki/freeswitchConfig
I can see the 2 tuples in the sqlite3.db, however when i attempt to make a call between the 2 registered users, the log on Freeswitch is as follows:
2013-03-27 21:18:07.495839 [NOTICE] switch_channel.c:976 New Channel sofia/internal/IMSI410071190004419@127.0.0.1 [e9051208-96f9-11e2-8f70- 5979f626837d] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_NEW 2013-03-27 21:18:07.495839 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.495839 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/IMSI410071190004419@127.0.0.1) State NEW 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:7697 IP 127.0.0.1 Approved by acl "domains[]". Access Granted. 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5597 Channel sofia/internal/ IMSI410071190004419@127.0.0.1 entering state [received][100] 2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5608 Remote SDP: v=0 o=IMSI410071190004419 0 0 IN IP4 127.0.0.1 s=Talk Time t=0 0 m=audio 16502 RTP/AVP 3 c=IN IP4 127.0.0.1 a=rtpmap:3 GSM/8000
2013-03-27 21:18:07.515818 [DEBUG] sofia.c:5821 (sofia/internal/ IMSI410071190004419@127.0.0.1) State Change CS_NEW -> CS_INIT 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_INIT 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/IMSI410071190004419@127.0.0.1) State INIT 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:86 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA INIT 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:126 (sofia/internal/ IMSI410071190004419@127.0.0.1) State Change CS_INIT -> CS_ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/IMSI410071190004419@127.0.0.1) State INIT going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:2012 (sofia/internal/ IMSI410071190004419@127.0.0.1) Callstate Change DOWN -> RINGING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/IMSI410071190004419@127.0.0.1) State ROUTING 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:149 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA ROUTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:117 sofia/internal/IMSI410071190004419@127.0.0.1 Standard ROUTING 2013-03-27 21:18:07.515818 [INFO] mod_dialplan_xml.c:557 Processing IMSI410071190004419 <IMSI410071190004419>->2222 in context public Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->unloop] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->outside_call] continue=true Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Absolute Condition [outside_call] Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Action set(outside_call=true) Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->call_debug] continue=true Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->public_extensions] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [public_extensions] destination_number(2222) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 parsing [public->public_did] continue=false Dialplan: sofia/internal/IMSI410071190004419@127.0.0.1 Regex (FAIL) [public_did] destination_number(2222) =~ /^(5551212)$/ break=on-false 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_ROUTING -> CS_EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/IMSI410071190004419@127.0.0.1) State ROUTING going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/IMSI410071190004419@127.0.0.1) State EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:242 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA EXECUTE 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:209 sofia/internal/IMSI410071190004419@127.0.0.1 Standard EXECUTE EXECUTE sofia/internal/IMSI410071190004419@127.0.0.1set(outside_call=true) 2013-03-27 21:18:07.515818 [DEBUG] mod_dptools.c:1367 sofia/internal/ IMSI410071190004419@127.0.0.1 SET [outside_call]=[true] EXECUTE sofia/internal/IMSI410071190004419@127.0.0.1export(RFC2822_DATE=Wed, 27 Mar 2013 21:18:07 +0500) 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:1143 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 27 Mar 2013 21:18:07 +0500] 2013-03-27 21:18:07.515818 [NOTICE] switch_core_state_machine.c:262 sofia/internal/IMSI410071190004419@127.0.0.1 has executed the last dialplan instruction, hanging up. 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:3011 (sofia/internal/ IMSI410071190004419@127.0.0.1) Callstate Change RINGING -> HANGUP 2013-03-27 21:18:07.515818 [NOTICE] switch_core_state_machine.c:264 Hangup sofia/internal/IMSI410071190004419@127.0.0.1 [CS_EXECUTE] [NORMAL_CLEARING] 2013-03-27 21:18:07.515818 [DEBUG] switch_channel.c:3034 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [KILL] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/IMSI410071190004419@127.0.0.1) State EXECUTE going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_HANGUP 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/IMSI410071190004419@127.0.0.1) State HANGUP 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:503 Channel sofia/internal/IMSI410071190004419@127.0.0.1 hanging up, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 480 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:48 sofia/internal/IMSI410071190004419@127.0.0.1 Standard HANGUP, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/IMSI410071190004419@127.0.0.1) State HANGUP going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_HANGUP -> CS_REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/IMSI410071190004419@127.0.0.1) State REPORTING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:92 sofia/internal/IMSI410071190004419@127.0.0.1 Standard REPORTING, cause: NORMAL_CLEARING 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/IMSI410071190004419@127.0.0.1) State REPORTING going to sleep 2013-03-27 21:18:07.515818 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/IMSI410071190004419@127.0.0.1) State Change CS_REPORTING -> CS_DESTROY 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/IMSI410071190004419@127.0.0.1 [BREAK] 2013-03-27 21:18:07.515818 [DEBUG] switch_core_session.c:1518 Session 44 (sofia/internal/IMSI410071190004419@127.0.0.1) Locked, Waiting on external entities 2013-03-27 21:18:07.515818 [NOTICE] switch_core_session.c:1536 Session 44 (sofia/internal/IMSI410071190004419@127.0.0.1) Ended 2013-03-27 21:18:07.515818 [NOTICE] switch_core_session.c:1540 Close Channel sofia/internal/IMSI410071190004419@127.0.0.1 [CS_DESTROY] 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/IMSI410071190004419@127.0.0.1) Callstate Change HANGUP -> DOWN 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:568 (sofia/internal/IMSI410071190004419@127.0.0.1) Running State Change CS_DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/IMSI410071190004419@127.0.0.1) State DESTROY 2013-03-27 21:18:07.525838 [DEBUG] mod_sofia.c:396 sofia/internal/ IMSI410071190004419@127.0.0.1 SOFIA DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:99 sofia/internal/IMSI410071190004419@127.0.0.1 Standard DESTROY 2013-03-27 21:18:07.525838 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/IMSI410071190004419@127.0.0.1) State DESTROY going to sleep
Gratitude, Ibrahim
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
participants (2)
-
Steven Ayre
-
Syed Ibrahim Ghaznavi