Hi all,<div>Am new to freeswitch and telephony. I installed freeswitch, and only changed acl.conf.xml to allow SIP calls from my IP address. So, my first test call to 9198 is not playing the song  back, here is the log. Anything more i need to do? </div>

<div>Thanks.</div><div><br></div><div><br clear="all"><br>-- [NOTICE] switch_channel.c:779 New Channel sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [8452afcd-75ae-438e-adfa-59aa569d2086]<div>

2010-09-10 08:29:26.774463 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Running State Change CS_NEW</div><div>2010-09-10 08:29:26.774463 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State NEW</div>

<div>2010-09-10 08:29:26.777617 [DEBUG] sofia.c:4331 Channel sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> entering state [received][100]</div><div>2010-09-10 08:29:26.777617 [DEBUG] sofia.c:4342 Remote SDP:</div>

<div>v=0</div><div>o=user1 53655765 2353687637 IN IP4 41.27.242.184</div><div>s=-</div><div>c=IN IP4 41.27.242.184</div><div>t=0 0</div><div>m=audio 8000 RTP/AVP 0</div><div>a=rtpmap:0 PCMU/8000</div><div><br></div><div>
2010-09-10 08:29:26.777617 [DEBUG] sofia_glue.c:3851 Audio Codec Compare [PCMU:0:8000:20]/[G7221:115:32000:20]</div>
<div>2010-09-10 08:29:26.777617 [DEBUG] sofia_glue.c:3851 Audio Codec Compare [PCMU:0:8000:20]/[G7221:107:16000:20]</div><div>2010-09-10 08:29:26.777617 [DEBUG] sofia_glue.c:3851 Audio Codec Compare [PCMU:0:8000:20]/[G722:9:8000:20]</div>

<div>2010-09-10 08:29:26.777617 [DEBUG] sofia_glue.c:3851 Audio Codec Compare [PCMU:0:8000:20]/[PCMU:0:8000:20]</div><div>2010-09-10 08:29:26.778598 [DEBUG] sofia_glue.c:2446 Set Codec sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> PCMU/8000 20 ms 160 samples</div>

<div>2010-09-10 08:29:26.778598 [DEBUG] sofia.c:4489 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State Change CS_NEW -&gt; CS_INIT</div><div>2010-09-10 08:29:26.778598 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [BREAK]</div>

<div>2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Running State Change CS_INIT</div><div>2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State INIT</div>

<div>2010-09-10 08:29:26.778598 [DEBUG] mod_sofia.c:83 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> SOFIA INIT</div><div>2010-09-10 08:29:26.778598 [DEBUG] mod_sofia.c:119 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State Change CS_INIT -&gt; CS_ROUTING</div>

<div>2010-09-10 08:29:26.778598 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [BREAK]</div><div>2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State INIT going to sleep</div>

<div>2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Running State Change CS_ROUTING</div><div>2010-09-10 08:29:26.778598 [DEBUG] switch_channel.c:1512 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Callstate Change DOWN -&gt; RINGING</div>

<div>2010-09-10 08:29:26.779586 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State ROUTING</div><div>2010-09-10 08:29:26.779586 [DEBUG] mod_sofia.c:142 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> SOFIA ROUTING</div>

<div>2010-09-10 08:29:26.779586 [DEBUG] switch_core_state_machine.c:77 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Standard ROUTING</div><div>2010-09-10 08:29:26.779586 [INFO] mod_dialplan_xml.c:331 Processing 1000 &lt;1000&gt;-&gt;9198 in context public</div>

<div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> parsing [public-&gt;unloop] continue=false</div><div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false</div>

<div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false</div><div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> parsing [public-&gt;outside_call] continue=true</div>

<div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Absolute Condition [outside_call]</div><div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Action set(outside_call=true) </div>

<div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) </div><div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> parsing [public-&gt;call_debug] continue=true</div>

<div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never</div><div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> parsing [public-&gt;public_extensions] continue=false</div>

<div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Regex (FAIL) [public_extensions] destination_number(9198) =~ /^(10[01][0-9])$/ break=on-false</div><div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> parsing [public-&gt;public_did] continue=false</div>

<div>Dialplan: sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Regex (FAIL) [public_did] destination_number(9198) =~ /^(5551212)$/ break=on-false</div><div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State Change CS_ROUTING -&gt; CS_EXECUTE</div>

<div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [BREAK]</div><div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State ROUTING going to sleep</div>

<div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Running State Change CS_EXECUTE</div><div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State EXECUTE</div>

<div>2010-09-10 08:29:26.782533 [DEBUG] mod_sofia.c:235 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> SOFIA EXECUTE</div><div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:157 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Standard EXECUTE</div>

<div>EXECUTE sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> set(outside_call=true)</div><div>2010-09-10 08:29:26.782533 [DEBUG] mod_dptools.c:854 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> SET [outside_call]=[true]</div>

<div>EXECUTE sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> set(RFC2822_DATE=Fri, 10 Sep 2010 08:29:26 +0000)</div><div>2010-09-10 08:29:26.782533 [DEBUG] mod_dptools.c:854 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> SET [RFC2822_DATE]=[Fri, 10 Sep 2010 08:29:26 +0000]</div>

<div>2010-09-10 08:29:26.782533 [NOTICE] switch_core_state_machine.c:185 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> has executed the last dialplan instruction, hanging up.</div><div>2010-09-10 08:29:26.782533 [DEBUG] switch_channel.c:2317 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Callstate Change RINGING -&gt; HANGUP</div>

<div>2010-09-10 08:29:26.782533 [NOTICE] switch_core_state_machine.c:187 Hangup sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [CS_EXECUTE] [NORMAL_CLEARING]</div><div>2010-09-10 08:29:26.782533 [DEBUG] switch_channel.c:2333 Send signal sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [KILL]</div>

<div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [BREAK]</div><div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State EXECUTE going to sleep</div>

<div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Running State Change CS_HANGUP</div><div>2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State HANGUP</div>

<div>2010-09-10 08:29:26.782533 [DEBUG] mod_sofia.c:453 Channel sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> hanging up, cause: NORMAL_CLEARING</div><div>2010-09-10 08:29:26.789460 [DEBUG] mod_sofia.c:515 Responding to INVITE with: 480</div>

<div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:46 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Standard HANGUP, cause: NORMAL_CLEARING</div><div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State HANGUP going to sleep</div>

<div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State Change CS_HANGUP -&gt; CS_REPORTING</div><div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [BREAK]</div>

<div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Running State Change CS_REPORTING</div><div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:595 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State REPORTING</div>

<div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:53 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Standard REPORTING, cause: NORMAL_CLEARING</div><div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:595 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State REPORTING going to sleep</div>

<div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:327 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State Change CS_REPORTING -&gt; CS_DESTROY</div><div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [BREAK]</div>

<div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_session.c:1202 Session 22 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Locked, Waiting on external entities</div><div>2010-09-10 08:29:26.789460 [NOTICE] switch_core_session.c:1220 Session 22 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Ended</div>

<div>2010-09-10 08:29:26.789460 [NOTICE] switch_core_session.c:1222 Close Channel sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> [CS_DESTROY]</div><div>2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:427 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Callstate Change HANGUP -&gt; DOWN</div>

<div>2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:430 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) Running State Change CS_DESTROY</div><div>2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State DESTROY</div>

<div>2010-09-10 08:29:26.790435 [DEBUG] mod_sofia.c:358 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> SOFIA DESTROY</div><div>2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:60 sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a> Standard DESTROY</div>

<div>2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/<a href="mailto:1000@109.74.198.135">1000@109.74.198.135</a>) State DESTROY going to sleep</div><div><br></div>David Wafula<br>
</div>