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@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@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@192.168.250.20) State NEW 2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5508 Channel sofia/internal/250@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@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@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@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@192.168.250.20 [BREAK] 2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/250@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@192.168.250.20) State INIT 2012-02-10 14:35:29.082815 [DEBUG] mod_sofia.c:85 sofia/internal/250@192.168.250.20 SOFIA INIT 2012-02-10 14:35:29.082815 [DEBUG] mod_sofia.c:125 (sofia/internal/250@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@192.168.250.20 [BREAK] 2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/250@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@192.168.250.20) Running State Change CS_ROUTING 2012-02-10 14:35:29.082815 [DEBUG] switch_channel.c:1890 (sofia/internal/250@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@192.168.250.20) State ROUTING 2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:148 sofia/internal/250@192.168.250.20 SOFIA ROUTING 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:104 sofia/internal/250@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@192.168.250.20 parsing [default->unloop] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call_direction] continue=true Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call_direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 ANTI-Action set(call_direction=local) Dialplan: sofia/internal/250@192.168.250.20 parsing [default->tod_example] continue=true Dialplan: sofia/internal/250@192.168.250.20 Date/Time Match (PASS) [tod_example] break=on-false Dialplan: sofia/internal/250@192.168.250.20 Action set(open=true) Dialplan: sofia/internal/250@192.168.250.20 parsing [default->holiday_example] continue=true Dialplan: sofia/internal/250@192.168.250.20 Date/TimeMatch (FAIL) [holiday_example] break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->group-intercept] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [group-intercept] destination_number(18xxxxxxxxx) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->redial] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [redial] destination_number(18xxxxxxxxx) =~ /^(redial|\*870)$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->global] continue=true Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/250@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@192.168.250.20 Absolute Condition [global] Dialplan: sofia/internal/250@192.168.250.20 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: sofia/internal/250@192.168.250.20 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_numbe r}) Dialplan: sofia/internal/250@192.168.250.20 Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: sofia/internal/250@192.168.250.20 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/250@192.168.250.20 parsing [default->snom-demo-2] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [snom-demo-2] destination_number(18xxxxxxxxx) =~ /^\*9001$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->snom-demo-1] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [snom-demo-1] destination_number(18xxxxxxxxx) =~ /^\*9000$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call_privacy] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call_privacy] destination_number(18xxxxxxxxx) =~ /^\*67(\d+)$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call_return] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call_return] destination_number(18xxxxxxxxx) =~ /^\*69$|^869$|^lcr$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->del-group] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [del-group] destination_number(18xxxxxxxxx) =~ /^\*\*80(\d{2})$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->add-group] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [add-group] destination_number(18xxxxxxxxx) =~ /^\*\*81(\d{2})$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call-group-simo] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call-group-simo] destination_number(18xxxxxxxxx) =~ /^\*\*82(\d{2})$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call-group-order] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call-group-order] destination_number(18xxxxxxxxx) =~ /^\*83(\d{2})$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->intercept-ext] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [intercept-ext] destination_number(18xxxxxxxxx) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->Local_Extension_Skinny] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [Local_Extension_Skinny] destination_number(18xxxxxxxxx) =~ /^(11[01][0-9])$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->send_to_voicemail] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [send_to_voicemail] destination_number(18xxxxxxxxx) =~ /^\*99(\d{2,7})$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->VoiceMeUp.911] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [VoiceMeUp.911] destination_number(18xxxxxxxxx) =~ /^911$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->VoiceMeUp.7d] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [VoiceMeUp.7d] destination_number(18xxxxxxxxx) =~ /^(\d{7})$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 parsing [default->VoiceMeUp.tollfree] continue=false Dialplan: sofia/internal/250@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@192.168.250.20 parsing [default->VoiceMeUp.11d] continue=false Dialplan: sofia/internal/250@192.168.250.20 Regex (PASS) [VoiceMeUp.11d] destination_number(18xxxxxxxxx) =~ /^\+?(\d{11})$/ break=on-false Dialplan: sofia/internal/250@192.168.250.20 Action set(sip_h_X-accountcode=${accountcode}) Dialplan: sofia/internal/250@192.168.250.20 Action set(call_direction=outbound) Dialplan: sofia/internal/250@192.168.250.20 Action set(hangup_after_bridge=true) Dialplan: sofia/internal/250@192.168.250.20 Action set(effective_caller_id_name=${outbound_caller_id_name}) Dialplan: sofia/internal/250@192.168.250.20 Action set(effective_caller_id_number=${outbound_caller_id_number}) Dialplan: sofia/internal/250@192.168.250.20 Action set(inherit_codec=true) Dialplan: sofia/internal/250@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@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@192.168.250.20 [BREAK] 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/250@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@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@192.168.250.20) State EXECUTE 2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:241 sofia/internal/250@192.168.250.20 SOFIA EXECUTE 2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:192 sofia/internal/250@192.168.250.20 Standard EXECUTE EXECUTE sofia/internal/250@192.168.250.20 set(call_direction=local) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [call_direction]=[local] EXECUTE sofia/internal/250@192.168.250.20 set(open=true) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [open]=[true] EXECUTE sofia/internal/250@192.168.250.20 hash(insert/192.168.250.20-spymap/250/36c7a641-bb72-403b-99f4-a2783a8898b8) EXECUTE sofia/internal/250@192.168.250.20 hash(insert/192.168.250.20-last_dial/250/18xxxxxxxxx) EXECUTE sofia/internal/250@192.168.250.20 hash(insert/192.168.250.20-last_dial/global/36c7a641-bb72-403b-99f4-a2783a88 98b8) EXECUTE sofia/internal/250@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@192.168.250.20 SET [RFC2822_DATE]=[Fri, 10 Feb 2012 14:35:29 -0400] EXECUTE sofia/internal/250@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@192.168.250.20 SET [sip_h_X-accountcode]=[1000] EXECUTE sofia/internal/250@192.168.250.20 set(call_direction=outbound) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [call_direction]=[outbound] EXECUTE sofia/internal/250@192.168.250.20 set(hangup_after_bridge=true) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [hangup_after_bridge]=[true] EXECUTE sofia/internal/250@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@192.168.250.20 SET [effective_caller_id_name]=[Tel1] EXECUTE sofia/internal/250@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@192.168.250.20 SET [effective_caller_id_number]=[5xxxxxxxxx] EXECUTE sofia/internal/250@192.168.250.20 set(inherit_codec=true) 2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [inherit_codec]=[true] EXECUTE sofia/internal/250@192.168.250.20 bridge(sofia/gateway/VoiceMeUp/18xxxxxxxxx) 2012-02-10 14:35:29.102799 [DEBUG] switch_channel.c:1051 sofia/internal/250@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@192.168.250.20) Callstate Change RINGING -> HANGUP 2012-02-10 14:35:29.222819 [NOTICE] mod_dptools.c:3035 Hangup sofia/internal/250@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@192.168.250.20 [KILL] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250@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@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@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@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@192.168.250.20) State HANGUP 2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:463 sofia/internal/250@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@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@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@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@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@192.168.250.20 [BREAK] 2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/250@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@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@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@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@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@192.168.250.20 [BREAK] 2012-02-10 14:35:29.422846 [DEBUG] switch_core_session.c:1380 Session 6 (sofia/internal/250@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@192.168.250.20) Ended 2012-02-10 14:35:29.422846 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/250@192.168.250.20 [CS_DESTROY] 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/250@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@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@192.168.250.20) State DESTROY 2012-02-10 14:35:29.422846 [DEBUG] mod_sofia.c:374 sofia/internal/250@192.168.250.20 SOFIA DESTROY 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:86 sofia/internal/250@192.168.250.20 Standard DESTROY 2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/250@192.168.250.20) State DESTROY going to sleep