[Freeswitch-users] New to freeswitch: Tetris

Fri Sep 10 01:36:12 PDT 2010

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

David Wafula
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20100910/43585206/attachment-0001.html 