[Freeswitch-users] Can't call out more than once- long distance calls
Charles Bujold
cjbujold at accra.ca
Fri Feb 10 21:48:26 MSK 2012
The first long distance call going out, works. If I try to make a second
call immediately after I get call "Forbidden", I presume it is an incorrect
setting but do not know where to start. Here is a log of the call being
rejected. The error 403 says the server gets the call, but will not
process it, if I interpret the error message properly. Question is what
can say to Freeswitch not to process the call?
Thanks for the help
cb
2012-02-10 14:35:29.082815 [DEBUG] sofia.c:7538 IP 192.168.20.80 Rejected by
acl "domains". Falling back to Digest auth.
2012-02-10 14:35:29.082815 [NOTICE] switch_channel.c:930 New Channel
sofia/internal/250 at 192.168.250.20 [36c7a641-bb72-403b-99f4-a2783a8898b8]
2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/250 at 192.168.250.20) Running State Change CS_NEW
2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:380
(sofia/internal/250 at 192.168.250.20) State NEW
2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5508 Channel
sofia/internal/250 at 192.168.250.20 entering state [received][100]
2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5519 Remote SDP:
v=0
o=- 20015 20015 IN IP4 192.168.20.80
s=SDP data
c=IN IP4 192.168.20.80
t=0 0
m=audio 11796 RTP/AVP 0 8 18 4 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
2012-02-10 14:35:29.082815 [DEBUG] sofia_glue.c:4798 Audio Codec Compare
[PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2012-02-10 14:35:29.082815 [DEBUG] sofia_glue.c:2919 Set Codec
sofia/internal/250 at 192.168.250.20 PCMU/8000 20 ms 160 samples 64000 bits
2012-02-10 14:35:29.082815 [DEBUG] switch_core_codec.c:111
sofia/internal/250 at 192.168.250.20 Original read codec set to PCMU:0
2012-02-10 14:35:29.082815 [DEBUG] sofia_glue.c:4919 Set 2833 dtmf send/recv
payload to 101
2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5731
(sofia/internal/250 at 192.168.250.20) State Change CS_NEW -> CS_INIT
2012-02-10 14:35:29.082815 [DEBUG] switch_core_session.c:1180 Send signal
sofia/internal/250 at 192.168.250.20 [BREAK]
2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/250 at 192.168.250.20) Running State Change CS_INIT
2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:401
(sofia/internal/250 at 192.168.250.20) State INIT
2012-02-10 14:35:29.082815 [DEBUG] mod_sofia.c:85
sofia/internal/250 at 192.168.250.20 SOFIA INIT
2012-02-10 14:35:29.082815 [DEBUG] mod_sofia.c:125
(sofia/internal/250 at 192.168.250.20) State Change CS_INIT -> CS_ROUTING
2012-02-10 14:35:29.082815 [DEBUG] switch_core_session.c:1180 Send signal
sofia/internal/250 at 192.168.250.20 [BREAK]
2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:401
(sofia/internal/250 at 192.168.250.20) State INIT going to sleep
2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/250 at 192.168.250.20) Running State Change CS_ROUTING
2012-02-10 14:35:29.082815 [DEBUG] switch_channel.c:1890
(sofia/internal/250 at 192.168.250.20) Callstate Change DOWN -> RINGING
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/250 at 192.168.250.20) State ROUTING
2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:148
sofia/internal/250 at 192.168.250.20 SOFIA ROUTING
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:104
sofia/internal/250 at 192.168.250.20 Standard ROUTING
2012-02-10 14:35:29.102799 [INFO] mod_dialplan_xml.c:481 Processing User1
<250>->18xxxxxxxxx in context default
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->unloop]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (PASS) [unloop]
${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [unloop]
${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing
[default->call_direction] continue=true
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [call_direction]
${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 ANTI-Action
set(call_direction=local)
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->tod_example]
continue=true
Dialplan: sofia/internal/250 at 192.168.250.20 Date/Time Match (PASS)
[tod_example] break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 Action set(open=true)
Dialplan: sofia/internal/250 at 192.168.250.20 parsing
[default->holiday_example] continue=true
Dialplan: sofia/internal/250 at 192.168.250.20 Date/TimeMatch (FAIL)
[holiday_example] break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing
[default->group-intercept] continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [group-intercept]
destination_number(18xxxxxxxxx) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->redial]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [redial]
destination_number(18xxxxxxxxx) =~ /^(redial|\*870)$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->global]
continue=true
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [global]
${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [global]
${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/
break=never
Dialplan: sofia/internal/250 at 192.168.250.20 Absolute Condition [global]
Dialplan: sofia/internal/250 at 192.168.250.20 Action
hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/250 at 192.168.250.20 Action
hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_numbe
r})
Dialplan: sofia/internal/250 at 192.168.250.20 Action
hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/250 at 192.168.250.20 Action
set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->snom-demo-2]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [snom-demo-2]
destination_number(18xxxxxxxxx) =~ /^\*9001$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->snom-demo-1]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [snom-demo-1]
destination_number(18xxxxxxxxx) =~ /^\*9000$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->call_privacy]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [call_privacy]
destination_number(18xxxxxxxxx) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->call_return]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [call_return]
destination_number(18xxxxxxxxx) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->del-group]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [del-group]
destination_number(18xxxxxxxxx) =~ /^\*\*80(\d{2})$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->add-group]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [add-group]
destination_number(18xxxxxxxxx) =~ /^\*\*81(\d{2})$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing
[default->call-group-simo] continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [call-group-simo]
destination_number(18xxxxxxxxx) =~ /^\*\*82(\d{2})$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing
[default->call-group-order] continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [call-group-order]
destination_number(18xxxxxxxxx) =~ /^\*83(\d{2})$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->intercept-ext]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [intercept-ext]
destination_number(18xxxxxxxxx) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing
[default->Local_Extension_Skinny] continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL)
[Local_Extension_Skinny] destination_number(18xxxxxxxxx) =~
/^(11[01][0-9])$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing
[default->send_to_voicemail] continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [send_to_voicemail]
destination_number(18xxxxxxxxx) =~ /^\*99(\d{2,7})$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->VoiceMeUp.911]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [VoiceMeUp.911]
destination_number(18xxxxxxxxx) =~ /^911$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->VoiceMeUp.7d]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL) [VoiceMeUp.7d]
destination_number(18xxxxxxxxx) =~ /^(\d{7})$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing
[default->VoiceMeUp.tollfree] continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (FAIL)
[VoiceMeUp.tollfree] destination_number(18xxxxxxxxx) =~
/^1?(8(00|55|66|77|88)[2-9]\d{6})$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 parsing [default->VoiceMeUp.11d]
continue=false
Dialplan: sofia/internal/250 at 192.168.250.20 Regex (PASS) [VoiceMeUp.11d]
destination_number(18xxxxxxxxx) =~ /^\+?(\d{11})$/ break=on-false
Dialplan: sofia/internal/250 at 192.168.250.20 Action
set(sip_h_X-accountcode=${accountcode})
Dialplan: sofia/internal/250 at 192.168.250.20 Action
set(call_direction=outbound)
Dialplan: sofia/internal/250 at 192.168.250.20 Action
set(hangup_after_bridge=true)
Dialplan: sofia/internal/250 at 192.168.250.20 Action
set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/internal/250 at 192.168.250.20 Action
set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/250 at 192.168.250.20 Action set(inherit_codec=true)
Dialplan: sofia/internal/250 at 192.168.250.20 Action
bridge(sofia/gateway/VoiceMeUp/18xxxxxxxxx)
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:154
(sofia/internal/250 at 192.168.250.20) State Change CS_ROUTING -> CS_EXECUTE
2012-02-10 14:35:29.102799 [DEBUG] switch_core_session.c:1180 Send signal
sofia/internal/250 at 192.168.250.20 [BREAK]
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:410
(sofia/internal/250 at 192.168.250.20) State ROUTING going to sleep
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/250 at 192.168.250.20) Running State Change CS_EXECUTE
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:417
(sofia/internal/250 at 192.168.250.20) State EXECUTE
2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:241
sofia/internal/250 at 192.168.250.20 SOFIA EXECUTE
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:192
sofia/internal/250 at 192.168.250.20 Standard EXECUTE
EXECUTE sofia/internal/250 at 192.168.250.20 set(call_direction=local)
2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281
sofia/internal/250 at 192.168.250.20 SET [call_direction]=[local]
EXECUTE sofia/internal/250 at 192.168.250.20 set(open=true)
2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281
sofia/internal/250 at 192.168.250.20 SET [open]=[true]
EXECUTE sofia/internal/250 at 192.168.250.20
hash(insert/192.168.250.20-spymap/250/36c7a641-bb72-403b-99f4-a2783a8898b8)
EXECUTE sofia/internal/250 at 192.168.250.20
hash(insert/192.168.250.20-last_dial/250/18xxxxxxxxx)
EXECUTE sofia/internal/250 at 192.168.250.20
hash(insert/192.168.250.20-last_dial/global/36c7a641-bb72-403b-99f4-a2783a88
98b8)
EXECUTE sofia/internal/250 at 192.168.250.20 set(RFC2822_DATE=Fri, 10 Feb 2012
14:35:29 -0400)
2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281
sofia/internal/250 at 192.168.250.20 SET [RFC2822_DATE]=[Fri, 10 Feb 2012
14:35:29 -0400]
EXECUTE sofia/internal/250 at 192.168.250.20 set(sip_h_X-accountcode=1000)
2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281
sofia/internal/250 at 192.168.250.20 SET [sip_h_X-accountcode]=[1000]
EXECUTE sofia/internal/250 at 192.168.250.20 set(call_direction=outbound)
2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281
sofia/internal/250 at 192.168.250.20 SET [call_direction]=[outbound]
EXECUTE sofia/internal/250 at 192.168.250.20 set(hangup_after_bridge=true)
2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281
sofia/internal/250 at 192.168.250.20 SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/250 at 192.168.250.20 set(effective_caller_id_name=Tel1)
2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281
sofia/internal/250 at 192.168.250.20 SET [effective_caller_id_name]=[Tel1]
EXECUTE sofia/internal/250 at 192.168.250.20
set(effective_caller_id_number=5xxxxxxxxx)
2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281
sofia/internal/250 at 192.168.250.20 SET
[effective_caller_id_number]=[5xxxxxxxxx]
EXECUTE sofia/internal/250 at 192.168.250.20 set(inherit_codec=true)
2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281
sofia/internal/250 at 192.168.250.20 SET [inherit_codec]=[true]
EXECUTE sofia/internal/250 at 192.168.250.20
bridge(sofia/gateway/VoiceMeUp/18xxxxxxxxx)
2012-02-10 14:35:29.102799 [DEBUG] switch_channel.c:1051
sofia/internal/250 at 192.168.250.20 EXPORTING[export_vars]
[domain_name]=[192.168.250.20] to event
2012-02-10 14:35:29.102799 [DEBUG] switch_ivr_originate.c:1884 Parsing
global variables
2012-02-10 14:35:29.102799 [NOTICE] switch_channel.c:930 New Channel
sofia/external/18xxxxxxxxx [5abef3bf-1aa0-4616-89c5-4e134df9328d]
2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:4670
(sofia/external/18xxxxxxxxx) State Change CS_NEW -> CS_INIT
2012-02-10 14:35:29.102799 [DEBUG] switch_core_session.c:1180 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:362
(sofia/external/18xxxxxxxxx) Running State Change CS_INIT
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:401
(sofia/external/18xxxxxxxxx) State INIT
2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:85 sofia/external/18xxxxxxxxx
SOFIA INIT
2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:125
(sofia/external/18xxxxxxxxx) State Change CS_INIT -> CS_ROUTING
2012-02-10 14:35:29.102799 [DEBUG] switch_core_session.c:1180 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:401
(sofia/external/18xxxxxxxxx) State INIT going to sleep
2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:362
(sofia/external/18xxxxxxxxx) Running State Change CS_ROUTING
2012-02-10 14:35:29.122800 [DEBUG] switch_channel.c:1890
(sofia/external/18xxxxxxxxx) Callstate Change DOWN -> RINGING
2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:410
(sofia/external/18xxxxxxxxx) State ROUTING
2012-02-10 14:35:29.122800 [DEBUG] mod_sofia.c:148
sofia/external/18xxxxxxxxx SOFIA ROUTING
2012-02-10 14:35:29.122800 [DEBUG] switch_ivr_originate.c:66
(sofia/external/18xxxxxxxxx) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2012-02-10 14:35:29.122800 [DEBUG] switch_core_session.c:1180 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:410
(sofia/external/18xxxxxxxxx) State ROUTING going to sleep
2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:362
(sofia/external/18xxxxxxxxx) Running State Change CS_CONSUME_MEDIA
2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:429
(sofia/external/18xxxxxxxxx) State CONSUME_MEDIA
2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:429
(sofia/external/18xxxxxxxxx) State CONSUME_MEDIA going to sleep
2012-02-10 14:35:29.122800 [DEBUG] switch_core_session.c:875 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.122800 [DEBUG] sofia.c:5508 Channel
sofia/external/18xxxxxxxxx entering state [calling][0]
2012-02-10 14:35:29.162820 [DEBUG] switch_core_session.c:875 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.162820 [DEBUG] switch_core_session.c:875 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.162820 [DEBUG] sofia.c:5508 Channel
sofia/external/18xxxxxxxxx entering state [calling][0]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:875 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:875 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:875 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.222819 [DEBUG] sofia.c:5508 Channel
sofia/external/18xxxxxxxxx entering state [terminated][403]
2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2852
(sofia/external/18xxxxxxxxx) Callstate Change RINGING -> HANGUP
2012-02-10 14:35:29.222819 [NOTICE] sofia.c:6272 Hangup
sofia/external/18xxxxxxxxx [CS_CONSUME_MEDIA] [CALL_REJECTED]
2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2875 Send signal
sofia/external/18xxxxxxxxx [KILL]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362
(sofia/external/18xxxxxxxxx) Running State Change CS_HANGUP
2012-02-10 14:35:29.222819 [DEBUG] switch_ivr_originate.c:3364 Originate
Resulted in Error Cause: 21 [CALL_REJECTED]
2012-02-10 14:35:29.222819 [INFO] mod_dptools.c:2916 Originate Failed.
Cause: CALL_REJECTED
2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2852
(sofia/internal/250 at 192.168.250.20) Callstate Change RINGING -> HANGUP
2012-02-10 14:35:29.222819 [NOTICE] mod_dptools.c:3035 Hangup
sofia/internal/250 at 192.168.250.20 [CS_EXECUTE] [CALL_REJECTED]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602
(sofia/external/18xxxxxxxxx) State HANGUP
2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:469 Channel
sofia/external/18xxxxxxxxx hanging up, cause: CALL_REJECTED
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:47
sofia/external/18xxxxxxxxx Standard HANGUP, cause: CALL_REJECTED
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602
(sofia/external/18xxxxxxxxx) State HANGUP going to sleep
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:393
(sofia/external/18xxxxxxxxx) State Change CS_HANGUP -> CS_REPORTING
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362
(sofia/external/18xxxxxxxxx) Running State Change CS_REPORTING
2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2875 Send signal
sofia/internal/250 at 192.168.250.20 [KILL]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal
sofia/internal/250 at 192.168.250.20 [BREAK]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:662
(sofia/external/18xxxxxxxxx) State REPORTING
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:79
sofia/external/18xxxxxxxxx Standard REPORTING, cause: CALL_REJECTED
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:662
(sofia/external/18xxxxxxxxx) State REPORTING going to sleep
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:2285
sofia/internal/250 at 192.168.250.20 skip receive message
[APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:417
(sofia/internal/250 at 192.168.250.20) State EXECUTE going to sleep
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/250 at 192.168.250.20) Running State Change CS_HANGUP
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:387
(sofia/external/18xxxxxxxxx) State Change CS_REPORTING -> CS_DESTROY
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal
sofia/external/18xxxxxxxxx [BREAK]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1380 Session 7
(sofia/external/18xxxxxxxxx) Locked, Waiting on external entities
2012-02-10 14:35:29.222819 [NOTICE] switch_core_session.c:1398 Session 7
(sofia/external/18xxxxxxxxx) Ended
2012-02-10 14:35:29.222819 [NOTICE] switch_core_session.c:1400 Close Channel
sofia/external/18xxxxxxxxx [CS_DESTROY]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:491
(sofia/external/18xxxxxxxxx) Callstate Change HANGUP -> DOWN
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:494
(sofia/external/18xxxxxxxxx) Running State Change CS_DESTROY
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:504
(sofia/external/18xxxxxxxxx) State DESTROY
2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:374
sofia/external/18xxxxxxxxx SOFIA DESTROY
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/250 at 192.168.250.20) State HANGUP
2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:463
sofia/internal/250 at 192.168.250.20 Overriding SIP cause 603 with 403 from the
other leg
2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:469 Channel
sofia/internal/250 at 192.168.250.20 hanging up, cause: CALL_REJECTED
2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:534 Responding to INVITE
with: 403
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:47
sofia/internal/250 at 192.168.250.20 Standard HANGUP, cause: CALL_REJECTED
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602
(sofia/internal/250 at 192.168.250.20) State HANGUP going to sleep
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:393
(sofia/internal/250 at 192.168.250.20) State Change CS_HANGUP -> CS_REPORTING
2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal
sofia/internal/250 at 192.168.250.20 [BREAK]
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362
(sofia/internal/250 at 192.168.250.20) Running State Change CS_REPORTING
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/250 at 192.168.250.20) State REPORTING
2012-02-10 14:35:29.222819 [DEBUG] switch_nat.c:545 unmapped public port
26608 protocol UDP to localport 26608
2012-02-10 14:35:29.222819 [DEBUG] switch_nat.c:545 unmapped public port
16966 protocol UDP to localport 16966
2012-02-10 14:35:29.222819 [DEBUG] switch_nat.c:545 unmapped public port
16967 protocol UDP to localport 16967
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:86
sofia/external/18xxxxxxxxx Standard DESTROY
2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:504
(sofia/external/18xxxxxxxxx) State DESTROY going to sleep
2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:79
sofia/internal/250 at 192.168.250.20 Standard REPORTING, cause: CALL_REJECTED
2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:662
(sofia/internal/250 at 192.168.250.20) State REPORTING going to sleep
2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:387
(sofia/internal/250 at 192.168.250.20) State Change CS_REPORTING -> CS_DESTROY
2012-02-10 14:35:29.422846 [DEBUG] switch_core_session.c:1180 Send signal
sofia/internal/250 at 192.168.250.20 [BREAK]
2012-02-10 14:35:29.422846 [DEBUG] switch_core_session.c:1380 Session 6
(sofia/internal/250 at 192.168.250.20) Locked, Waiting on external entities
2012-02-10 14:35:29.422846 [NOTICE] switch_core_session.c:1398 Session 6
(sofia/internal/250 at 192.168.250.20) Ended
2012-02-10 14:35:29.422846 [NOTICE] switch_core_session.c:1400 Close Channel
sofia/internal/250 at 192.168.250.20 [CS_DESTROY]
2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:491
(sofia/internal/250 at 192.168.250.20) Callstate Change HANGUP -> DOWN
2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:494
(sofia/internal/250 at 192.168.250.20) Running State Change CS_DESTROY
2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/250 at 192.168.250.20) State DESTROY
2012-02-10 14:35:29.422846 [DEBUG] mod_sofia.c:374
sofia/internal/250 at 192.168.250.20 SOFIA DESTROY
2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:86
sofia/internal/250 at 192.168.250.20 Standard DESTROY
2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:504
(sofia/internal/250 at 192.168.250.20) State DESTROY going to sleep
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20120210/1ff1fba6/attachment-0001.html
Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users
mailing list