[Freeswitch-users] Can't call out more than once- long distance calls

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 