Hello all,<div>Maybe you can help with an issue I have had from about day 2 of my fusion/freeswitch install. </div><div><br></div><div>I am able to receive calls inbound to my softphone (counterpath eyebeam V1.5.19.4) When I try to dial out, or any extensions I am unable to connect. Watching the CLI it the softphone seems to connect and talk with freeswitch, then hangs up the call. Below is CLI of the call I am attempting. Any input to correct this issue will greatly help.</div>
<div>Ben</div><div><br></div><div><div>+OK log level [7]</div><div>freeswitch@internal> 2012-08-23 10:45:42.332691 [DEBUG] sofia.c:6466 IP 10.0.1.151 Approved by acl "domains[]". Access Granted.</div><div>2012-08-23 10:45:42.333693 [NOTICE] switch_channel.c:812 New Channel sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [c915f249-a2d1-4c5e-8900-8ae47f3deea1]</div>
<div>2012-08-23 10:45:42.333693 [DEBUG] sofia.c:4744 Channel sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> entering state [received][100]</div><div>2012-08-23 10:45:42.333693 [DEBUG] sofia.c:4755 Remote SDP:</div>
<div>v=0</div><div>o=- 7 2 IN IP4 10.0.1.151</div><div>s=CounterPath eyeBeam 1.5</div><div>c=IN IP4 10.0.1.151</div><div>t=0 0</div><div>m=audio 51938 RTP/AVP 107 0 8 18 101</div><div>a=rtpmap:107 BV32/16000</div><div>a=rtpmap:18 G729/8000</div>
<div>a=fmtp:18 annexb=yes</div><div>a=rtpmap:101 telephone-event/8000</div><div>a=fmtp:101 0-15</div><div>a=alt:1 3 : RQXbULH6 jeWr1bhp 10.0.1.151 51938</div><div>a=alt:2 2 : lIrXnsi1 yeNfshsQ 192.168.238.1 51938</div><div>
a=alt:3 1 : np3FXIkK K342ICWN 192.168.64.1 51938</div><div><br></div><div>2012-08-23 10:45:42.333693 [DEBUG] sofia_glue.c:4619 Audio Codec Compare [BV32:107:16000:20:0]/[G722:9:8000:20:64000]</div><div>2012-08-23 10:45:42.333693 [DEBUG] sofia_glue.c:4619 Audio Codec Compare [BV32:107:16000:20:0]/[PCMU:0:8000:20:64000]</div>
<div>2012-08-23 10:45:42.333693 [DEBUG] sofia_glue.c:4619 Audio Codec Compare [BV32:107:16000:20:0]/[PCMA:8:8000:20:64000]</div><div>2012-08-23 10:45:42.333693 [DEBUG] sofia_glue.c:4619 Audio Codec Compare [BV32:107:16000:20:0]/[GSM:3:8000:20:13200]</div>
<div>2012-08-23 10:45:42.333693 [DEBUG] sofia_glue.c:4619 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]</div><div>2012-08-23 10:45:42.333693 [DEBUG] sofia_glue.c:4619 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]</div>
<div>2012-08-23 10:45:42.334691 [DEBUG] sofia_glue.c:2760 Set Codec sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> PCMU/8000 20 ms 160 samples 64000 bits</div><div>2012-08-23 10:45:42.334691 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Running State Change CS_NEW</div>
<div>2012-08-23 10:45:42.334691 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State NEW</div><div>2012-08-23 10:45:42.334691 [DEBUG] sofia_glue.c:4733 Set 2833 dtmf send/recv payload to 101</div>
<div>2012-08-23 10:45:42.334691 [DEBUG] sofia.c:4922 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State Change CS_NEW -> CS_INIT</div><div>2012-08-23 10:45:42.334691 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [BREAK]</div>
<div>2012-08-23 10:45:42.335694 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Running State Change CS_INIT</div><div>2012-08-23 10:45:42.335694 [DEBUG] switch_core_state_machine.c:356 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State INIT</div>
<div>2012-08-23 10:45:42.335694 [DEBUG] mod_sofia.c:84 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> SOFIA INIT</div><div>2012-08-23 10:45:42.335694 [DEBUG] mod_sofia.c:124 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State Change CS_INIT -> CS_ROUTING</div>
<div>2012-08-23 10:45:42.335694 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [BREAK]</div><div>2012-08-23 10:45:42.335694 [DEBUG] switch_core_state_machine.c:356 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State INIT going to sleep</div>
<div>2012-08-23 10:45:42.335694 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Running State Change CS_ROUTING</div><div>2012-08-23 10:45:42.335694 [DEBUG] switch_channel.c:1664 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Callstate Change DOWN -> RINGING</div>
<div>2012-08-23 10:45:42.335694 [DEBUG] switch_core_state_machine.c:359 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State ROUTING</div><div>2012-08-23 10:45:42.335694 [DEBUG] mod_sofia.c:147 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> SOFIA ROUTING</div>
<div>2012-08-23 10:45:42.335694 [DEBUG] switch_core_state_machine.c:77 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Standard ROUTING</div><div>2012-08-23 10:45:42.335694 [INFO] mod_dialplan_xml.c:331 Processing Ben <5555>->18002267611 in context public</div>
<div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> parsing [public->unloop] continue=false</div><div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false</div>
<div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false</div><div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> parsing [public->outside_call] continue=true</div>
<div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Absolute Condition [outside_call]</div><div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Action set(outside_call=true)</div>
<div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})</div><div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> parsing [public->call_debug] continue=true</div>
<div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never</div><div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> parsing [public->public_extensions] continue=false</div>
<div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Regex (FAIL) [public_extensions] destination_number(18002267611) =~ /^(10[01][0-9])$/ break=on-false</div><div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> parsing [public->inbound to ext 55] continue=false</div>
<div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Regex (PASS) [inbound to ext 55] context(public) =~ /public/ break=on-false</div><div>Dialplan: sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Regex (FAIL) [inbound to ext 55] destination_number(18002267611) =~ /4049371797/ break=on-false</div>
<div>2012-08-23 10:45:42.337693 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State Change CS_ROUTING -> CS_EXECUTE</div><div>2012-08-23 10:45:42.337693 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [BREAK]</div>
<div>2012-08-23 10:45:42.337693 [DEBUG] switch_core_state_machine.c:359 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State ROUTING going to sleep</div><div>2012-08-23 10:45:42.337693 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Running State Change CS_EXECUTE</div>
<div>2012-08-23 10:45:42.337693 [DEBUG] switch_core_state_machine.c:366 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State EXECUTE</div><div>2012-08-23 10:45:42.337693 [DEBUG] mod_sofia.c:240 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> SOFIA EXECUTE</div>
<div>2012-08-23 10:45:42.337693 [DEBUG] switch_core_state_machine.c:157 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Standard EXECUTE</div><div>EXECUTE sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> set(outside_call=true)</div>
<div>2012-08-23 10:45:42.337693 [DEBUG] mod_dptools.c:1059 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> SET [outside_call]=[true]</div><div>EXECUTE sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> set(RFC2822_DATE=Thu, 23 Aug 2012 10:45:42 +0530)</div>
<div>2012-08-23 10:45:42.338693 [DEBUG] mod_dptools.c:1059 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> SET [RFC2822_DATE]=[Thu, 23 Aug 2012 10:45:42 +0530]</div><div>2012-08-23 10:45:42.338693 [NOTICE] switch_core_state_machine.c:189 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> has executed the last dialplan instruction, hanging up.</div>
<div>2012-08-23 10:45:42.338693 [DEBUG] switch_channel.c:2559 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Callstate Change RINGING -> HANGUP</div><div>2012-08-23 10:45:42.338693 [NOTICE] switch_core_state_machine.c:191 Hangup sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [CS_EXECUTE] [NORMAL_CLEARING]</div>
<div>2012-08-23 10:45:42.338693 [DEBUG] switch_channel.c:2575 Send signal sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [KILL]</div><div>2012-08-23 10:45:42.338693 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [BREAK]</div>
<div>2012-08-23 10:45:42.338693 [DEBUG] switch_core_state_machine.c:366 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State EXECUTE going to sleep</div><div>2012-08-23 10:45:42.338693 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Running State Change CS_HANGUP</div>
<div>2012-08-23 10:45:42.339694 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State HANGUP</div><div>2012-08-23 10:45:42.339694 [DEBUG] mod_sofia.c:457 Channel sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> hanging up, cause: NORMAL_CLEARING</div>
<div>2012-08-23 10:45:42.339694 [DEBUG] mod_sofia.c:519 Responding to INVITE with: 480</div><div>2012-08-23 10:45:42.340695 [DEBUG] switch_core_state_machine.c:46 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Standard HANGUP, cause: NORMAL_CLEARING</div>
<div>2012-08-23 10:45:42.340695 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State HANGUP going to sleep</div><div>2012-08-23 10:45:42.340695 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State Change CS_HANGUP -> CS_REPORTING</div>
<div>2012-08-23 10:45:42.340695 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [BREAK]</div><div>2012-08-23 10:45:42.340695 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Running State Change CS_REPORTING</div>
<div>2012-08-23 10:45:42.340695 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State REPORTING</div><div>2012-08-23 10:45:42.489418 [DEBUG] switch_core_state_machine.c:53 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Standard REPORTING, cause: NORMAL_CLEARING</div>
<div>2012-08-23 10:45:42.489418 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State REPORTING going to sleep</div><div>2012-08-23 10:45:42.489418 [DEBUG] switch_core_state_machine.c:345 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State Change CS_REPORTING -> CS_DESTROY</div>
<div>2012-08-23 10:45:42.489418 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [BREAK]</div><div>2012-08-23 10:45:42.489418 [DEBUG] switch_core_session.c:1288 Session 22 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Locked, Waiting on external entities</div>
<div>2012-08-23 10:45:42.489418 [NOTICE] switch_core_session.c:1306 Session 22 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Ended</div><div>2012-08-23 10:45:42.489418 [NOTICE] switch_core_session.c:1308 Close Channel sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> [CS_DESTROY]</div>
<div>2012-08-23 10:45:42.489418 [DEBUG] switch_core_state_machine.c:449 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Callstate Change HANGUP -> DOWN</div><div>2012-08-23 10:45:42.489418 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) Running State Change CS_DESTROY</div>
<div>2012-08-23 10:45:42.489418 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State DESTROY</div><div>2012-08-23 10:45:42.491481 [DEBUG] mod_sofia.c:362 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> SOFIA DESTROY</div>
<div>2012-08-23 10:45:42.491481 [DEBUG] switch_core_state_machine.c:60 sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a> Standard DESTROY</div><div>2012-08-23 10:45:42.491481 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/<a href="mailto:5555@10.0.1.162">5555@10.0.1.162</a>) State DESTROY going to sleep</div>
</div><div><br></div>