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(a)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(a)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(a)109.74.198.135) State NEW
2010-09-10 08:29:26.777617 [DEBUG] sofia.c:4331 Channel sofia/internal/
1000(a)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(a)109.74.198.135 PCMU/8000 20 ms 160 samples
2010-09-10 08:29:26.778598 [DEBUG] sofia.c:4489 (sofia/internal/
1000(a)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(a)109.74.198.135 [BREAK]
2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000(a)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(a)109.74.198.135) State INIT
2010-09-10 08:29:26.778598 [DEBUG] mod_sofia.c:83 sofia/internal/
1000(a)109.74.198.135 SOFIA INIT
2010-09-10 08:29:26.778598 [DEBUG] mod_sofia.c:119 (sofia/internal/
1000(a)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(a)109.74.198.135 [BREAK]
2010-09-10 08:29:26.778598 [DEBUG] switch_core_state_machine.c:338
(sofia/internal/1000(a)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(a)109.74.198.135) Running State Change CS_ROUTING
2010-09-10 08:29:26.778598 [DEBUG] switch_channel.c:1512 (sofia/internal/
1000(a)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(a)109.74.198.135) State ROUTING
2010-09-10 08:29:26.779586 [DEBUG] mod_sofia.c:142 sofia/internal/
1000(a)109.74.198.135 SOFIA ROUTING
2010-09-10 08:29:26.779586 [DEBUG] switch_core_state_machine.c:77
sofia/internal/1000(a)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(a)109.74.198.135 parsing [public->unloop]
continue=false
Dialplan: sofia/internal/1000(a)109.74.198.135 Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000(a)109.74.198.135 Regex (FAIL) [unloop]
${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000(a)109.74.198.135 parsing [public->outside_call]
continue=true
Dialplan: sofia/internal/1000(a)109.74.198.135 Absolute Condition
[outside_call]
Dialplan: sofia/internal/1000(a)109.74.198.135 Action set(outside_call=true)
Dialplan: sofia/internal/1000(a)109.74.198.135 Action
set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1000(a)109.74.198.135 parsing [public->call_debug]
continue=true
Dialplan: sofia/internal/1000(a)109.74.198.135 Regex (FAIL) [call_debug]
${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1000(a)109.74.198.135 parsing
[public->public_extensions] continue=false
Dialplan: sofia/internal/1000(a)109.74.198.135 Regex (FAIL)
[public_extensions] destination_number(9198) =~ /^(10[01][0-9])$/
break=on-false
Dialplan: sofia/internal/1000(a)109.74.198.135 parsing [public->public_did]
continue=false
Dialplan: sofia/internal/1000(a)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(a)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(a)109.74.198.135 [BREAK]
2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:341
(sofia/internal/1000(a)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(a)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(a)109.74.198.135) State EXECUTE
2010-09-10 08:29:26.782533 [DEBUG] mod_sofia.c:235 sofia/internal/
1000(a)109.74.198.135 SOFIA EXECUTE
2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:157
sofia/internal/1000(a)109.74.198.135 Standard EXECUTE
EXECUTE sofia/internal/1000(a)109.74.198.135 set(outside_call=true)
2010-09-10 08:29:26.782533 [DEBUG] mod_dptools.c:854 sofia/internal/
1000(a)109.74.198.135 SET [outside_call]=[true]
EXECUTE sofia/internal/1000(a)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(a)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(a)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(a)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(a)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(a)109.74.198.135 [KILL]
2010-09-10 08:29:26.782533 [DEBUG] switch_core_session.c:1039 Send signal
sofia/internal/1000(a)109.74.198.135 [BREAK]
2010-09-10 08:29:26.782533 [DEBUG] switch_core_state_machine.c:348
(sofia/internal/1000(a)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(a)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(a)109.74.198.135) State HANGUP
2010-09-10 08:29:26.782533 [DEBUG] mod_sofia.c:453 Channel sofia/internal/
1000(a)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(a)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(a)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(a)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(a)109.74.198.135 [BREAK]
2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:314
(sofia/internal/1000(a)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(a)109.74.198.135) State REPORTING
2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:53
sofia/internal/1000(a)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(a)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(a)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(a)109.74.198.135 [BREAK]
2010-09-10 08:29:26.789460 [DEBUG] switch_core_session.c:1202 Session 22
(sofia/internal/1000(a)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(a)109.74.198.135) Ended
2010-09-10 08:29:26.789460 [NOTICE] switch_core_session.c:1222 Close Channel
sofia/internal/1000(a)109.74.198.135 [CS_DESTROY]
2010-09-10 08:29:26.789460 [DEBUG] switch_core_state_machine.c:427
(sofia/internal/1000(a)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(a)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(a)109.74.198.135) State DESTROY
2010-09-10 08:29:26.790435 [DEBUG] mod_sofia.c:358 sofia/internal/
1000(a)109.74.198.135 SOFIA DESTROY
2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:60
sofia/internal/1000(a)109.74.198.135 Standard DESTROY
2010-09-10 08:29:26.790435 [DEBUG] switch_core_state_machine.c:440
(sofia/internal/1000(a)109.74.198.135) State DESTROY going to sleep
David Wafula