Hi all, 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? Thanks. -- [NOTICE] switch_channel.c:779 New Channel sofia/internal/ 1000@109.74.198.135 [8452afcd-75ae-438e-adfa-59aa569d2086] 2010-09-10 08:29:26.774463 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1000@109.74.198.135) Running State Change CS_NEW 2010-09-10 08:29:26.774463 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/1000@109.74.198.135) State NEW 2010-09-10 08:29:26.777617 [DEBUG] sofia.c:4331 Channel sofia/internal/ 1000@109.74.198.135 entering state [received][100] 2010-09-10 08:29:26.777617 [DEBUG] sofia.c:4342 Remote SDP: v=0 o=user1 53655765 2353687637 IN IP4 41.27.242.184 s=- c=IN IP4 41.27.242.184 t=0 0 m=audio 8000 RTP/AVP 0 a=rtpmap:0 PCMU/8000 2010-09-10 08:29:26.777617 [DEBUG] sofia_glue.c:3851 Audio Codec Compare [PCMU:0:8000:20]/[G7221:115:32000:20] 2010-09-10 08:29:26.777617 [DEBUG] sofia_glue.c:3851 Audio Codec Compare [PCMU:0:8000:20]/[G7221:107:16000:20] 2010-09-10 08:29:26.777617 [DEBUG] sofia_glue.c:3851 Audio Codec Compare [PCMU:0:8000:20]/[G722:9:8000:20] 2010-09-10 08:29:26.777617 [DEBUG] sofia_glue.c:3851 Audio Codec Compare [PCMU:0:8000:20]/[PCMU:0:8000:20] 2010-09-10 08:29:26.778598 [DEBUG] sofia_glue.c:2446 Set Codec sofia/internal/1000@109.74.198.135 PCMU/8000 20 ms 160 samples 2010-09-10 08:29:26.778598 [DEBUG] sofia.c:4489 (sofia/internal/ 1000@109.74.198.135) State Change CS_NEW -> CS_INIT 2010-09-10 08:29:26.778598 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/1000@109.74.198.135 [BREAK] 2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1000@109.74.198.135) Running State Change CS_INIT 2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/1000@109.74.198.135) State INIT 2010-09-10 08:29:26.778598 [DEBUG] mod_sofia.c:83 sofia/internal/ 1000@109.74.198.135 SOFIA INIT 2010-09-10 08:29:26.778598 [DEBUG] mod_sofia.c:119 (sofia/internal/ 1000@109.74.198.135) State Change CS_INIT -> CS_ROUTING 2010-09-10 08:29:26.778598 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/1000@109.74.198.135 [BREAK] 2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/1000@109.74.198.135) State INIT going to sleep 2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1000@109.74.198.135) Running State Change CS_ROUTING 2010-09-10 08:29:26.778598 [DEBUG] switch_channel.c:1512 (sofia/internal/ 1000@109.74.198.135) Callstate Change DOWN -> RINGING 2010-09-10 08:29:26.779586 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/1000@109.74.198.135) State ROUTING 2010-09-10 08:29:26.779586 [DEBUG] mod_sofia.c:142 sofia/internal/ 1000@109.74.198.135 SOFIA ROUTING 2010-09-10 08:29:26.779586 [DEBUG] switch_core_state_machine.c:77 sofia/internal/1000@109.74.198.135 Standard ROUTING 2010-09-10 08:29:26.779586 [INFO] mod_dialplan_xml.c:331 Processing 1000 <1000>->9198 in context public Dialplan: sofia/internal/1000@109.74.198.135 parsing [public->unloop] continue=false Dialplan: sofia/internal/1000@109.74.198.135 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/1000@109.74.198.135 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/1000@109.74.198.135 parsing [public->outside_call] continue=true Dialplan: sofia/internal/1000@109.74.198.135 Absolute Condition [outside_call] Dialplan: sofia/internal/1000@109.74.198.135 Action set(outside_call=true) Dialplan: sofia/internal/1000@109.74.198.135 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/1000@109.74.198.135 parsing [public->call_debug] continue=true Dialplan: sofia/internal/1000@109.74.198.135 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/1000@109.74.198.135 parsing [public->public_extensions] continue=false Dialplan: sofia/internal/1000@109.74.198.135 Regex (FAIL) [public_extensions] destination_number(9198) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/1000@109.74.198.135 parsing [public->public_did] continue=false Dialplan: sofia/internal/1000@109.74.198.135 Regex (FAIL) [public_did] destination_number(9198) =~ /^(5551212)$/ break=on-false 2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/1000@109.74.198.135) State Change CS_ROUTING -> CS_EXECUTE 2010-09-10 08:29:26.782533 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/1000@109.74.198.135 [BREAK] 2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/1000@109.74.198.135) State ROUTING going to sleep 2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1000@109.74.198.135) Running State Change CS_EXECUTE 2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/1000@109.74.198.135) State EXECUTE 2010-09-10 08:29:26.782533 [DEBUG] mod_sofia.c:235 sofia/internal/ 1000@109.74.198.135 SOFIA EXECUTE 2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:157 sofia/internal/1000@109.74.198.135 Standard EXECUTE EXECUTE sofia/internal/1000@109.74.198.135 set(outside_call=true) 2010-09-10 08:29:26.782533 [DEBUG] mod_dptools.c:854 sofia/internal/ 1000@109.74.198.135 SET [outside_call]=[true] EXECUTE sofia/internal/1000@109.74.198.135 set(RFC2822_DATE=Fri, 10 Sep 2010 08:29:26 +0000) 2010-09-10 08:29:26.782533 [DEBUG] mod_dptools.c:854 sofia/internal/ 1000@109.74.198.135 SET [RFC2822_DATE]=[Fri, 10 Sep 2010 08:29:26 +0000] 2010-09-10 08:29:26.782533 [NOTICE] switch_core_state_machine.c:185 sofia/internal/1000@109.74.198.135 has executed the last dialplan instruction, hanging up. 2010-09-10 08:29:26.782533 [DEBUG] switch_channel.c:2317 (sofia/internal/ 1000@109.74.198.135) Callstate Change RINGING -> HANGUP 2010-09-10 08:29:26.782533 [NOTICE] switch_core_state_machine.c:187 Hangup sofia/internal/1000@109.74.198.135 [CS_EXECUTE] [NORMAL_CLEARING] 2010-09-10 08:29:26.782533 [DEBUG] switch_channel.c:2333 Send signal sofia/internal/1000@109.74.198.135 [KILL] 2010-09-10 08:29:26.782533 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/1000@109.74.198.135 [BREAK] 2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/1000@109.74.198.135) State EXECUTE going to sleep 2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1000@109.74.198.135) Running State Change CS_HANGUP 2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/1000@109.74.198.135) State HANGUP 2010-09-10 08:29:26.782533 [DEBUG] mod_sofia.c:453 Channel sofia/internal/ 1000@109.74.198.135 hanging up, cause: NORMAL_CLEARING 2010-09-10 08:29:26.789460 [DEBUG] mod_sofia.c:515 Responding to INVITE with: 480 2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:46 sofia/internal/1000@109.74.198.135 Standard HANGUP, cause: NORMAL_CLEARING 2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/1000@109.74.198.135) State HANGUP going to sleep 2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/1000@109.74.198.135) State Change CS_HANGUP -> CS_REPORTING 2010-09-10 08:29:26.789460 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/1000@109.74.198.135 [BREAK] 2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/1000@109.74.198.135) Running State Change CS_REPORTING 2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:595 (sofia/internal/1000@109.74.198.135) State REPORTING 2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1000@109.74.198.135 Standard REPORTING, cause: NORMAL_CLEARING 2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:595 (sofia/internal/1000@109.74.198.135) State REPORTING going to sleep 2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:327 (sofia/internal/1000@109.74.198.135) State Change CS_REPORTING -> CS_DESTROY 2010-09-10 08:29:26.789460 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/1000@109.74.198.135 [BREAK] 2010-09-10 08:29:26.789460 [DEBUG] switch_core_session.c:1202 Session 22 (sofia/internal/1000@109.74.198.135) Locked, Waiting on external entities 2010-09-10 08:29:26.789460 [NOTICE] switch_core_session.c:1220 Session 22 (sofia/internal/1000@109.74.198.135) Ended 2010-09-10 08:29:26.789460 [NOTICE] switch_core_session.c:1222 Close Channel sofia/internal/1000@109.74.198.135 [CS_DESTROY] 2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:427 (sofia/internal/1000@109.74.198.135) Callstate Change HANGUP -> DOWN 2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:430 (sofia/internal/1000@109.74.198.135) Running State Change CS_DESTROY 2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/1000@109.74.198.135) State DESTROY 2010-09-10 08:29:26.790435 [DEBUG] mod_sofia.c:358 sofia/internal/ 1000@109.74.198.135 SOFIA DESTROY 2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@109.74.198.135 Standard DESTROY 2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/1000@109.74.198.135) State DESTROY going to sleep David Wafula