<html xmlns:v="urn:schemas-microsoft-com:vml" xmlns:o="urn:schemas-microsoft-com:office:office" xmlns:w="urn:schemas-microsoft-com:office:word" xmlns:m="http://schemas.microsoft.com/office/2004/12/omml" xmlns="http://www.w3.org/TR/REC-html40"><head><meta http-equiv=Content-Type content="text/html; charset=us-ascii"><meta name=Generator content="Microsoft Word 14 (filtered medium)"><style><!--
/* Font Definitions */
@font-face
{font-family:Calibri;
panose-1:2 15 5 2 2 2 4 3 2 4;}
@font-face
{font-family:Tahoma;
panose-1:2 11 6 4 3 5 4 4 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
{margin:0cm;
margin-bottom:.0001pt;
font-size:11.0pt;
font-family:"Calibri","sans-serif";
mso-fareast-language:EN-US;}
a:link, span.MsoHyperlink
{mso-style-priority:99;
color:blue;
text-decoration:underline;}
a:visited, span.MsoHyperlinkFollowed
{mso-style-priority:99;
color:purple;
text-decoration:underline;}
p.MsoAcetate, li.MsoAcetate, div.MsoAcetate
{mso-style-priority:99;
mso-style-link:"Balloon Text Char";
margin:0cm;
margin-bottom:.0001pt;
font-size:8.0pt;
font-family:"Tahoma","sans-serif";
mso-fareast-language:EN-US;}
span.EmailStyle17
{mso-style-type:personal-compose;
font-family:"Calibri","sans-serif";
color:windowtext;}
span.BalloonTextChar
{mso-style-name:"Balloon Text Char";
mso-style-priority:99;
mso-style-link:"Balloon Text";
font-family:"Tahoma","sans-serif";}
.MsoChpDefault
{mso-style-type:export-only;
font-family:"Calibri","sans-serif";
mso-fareast-language:EN-US;}
@page WordSection1
{size:612.0pt 792.0pt;
margin:72.0pt 72.0pt 72.0pt 72.0pt;}
div.WordSection1
{page:WordSection1;}
--></style><!--[if gte mso 9]><xml>
<o:shapedefaults v:ext="edit" spidmax="1026" />
</xml><![endif]--><!--[if gte mso 9]><xml>
<o:shapelayout v:ext="edit">
<o:idmap v:ext="edit" data="1" />
</o:shapelayout></xml><![endif]--></head><body lang=EN-CA link=blue vlink=purple><div class=WordSection1><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal>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?<o:p></o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal>Thanks for the help<o:p></o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal>cb<o:p></o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal>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.<o:p></o:p></p><p class=MsoNormal>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]<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:380 (sofia/internal/250@192.168.250.20) State NEW<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5508 Channel sofia/internal/250@192.168.250.20 entering state [received][100]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5519 Remote SDP:<o:p></o:p></p><p class=MsoNormal>v=0<o:p></o:p></p><p class=MsoNormal>o=- 20015 20015 IN IP4 192.168.20.80<o:p></o:p></p><p class=MsoNormal>s=SDP data<o:p></o:p></p><p class=MsoNormal>c=IN IP4 192.168.20.80<o:p></o:p></p><p class=MsoNormal>t=0 0<o:p></o:p></p><p class=MsoNormal>m=audio 11796 RTP/AVP 0 8 18 4 9 101<o:p></o:p></p><p class=MsoNormal>a=rtpmap:0 PCMU/8000<o:p></o:p></p><p class=MsoNormal>a=rtpmap:8 PCMA/8000<o:p></o:p></p><p class=MsoNormal>a=rtpmap:18 G729/8000<o:p></o:p></p><p class=MsoNormal>a=fmtp:18 annexb=no<o:p></o:p></p><p class=MsoNormal>a=rtpmap:4 G723/8000<o:p></o:p></p><p class=MsoNormal>a=rtpmap:9 G722/8000<o:p></o:p></p><p class=MsoNormal>a=rtpmap:101 telephone-event/8000<o:p></o:p></p><p class=MsoNormal>a=fmtp:101 0-15<o:p></o:p></p><p class=MsoNormal>a=ptime:20<o:p></o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal>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]<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] sofia_glue.c:4919 Set 2833 dtmf send/recv payload to 101<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] sofia.c:5731 (sofia/internal/250@192.168.250.20) State Change CS_NEW -> CS_INIT<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250@192.168.250.20 [BREAK]<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/250@192.168.250.20) State INIT<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] mod_sofia.c:85 sofia/internal/250@192.168.250.20 SOFIA INIT<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250@192.168.250.20 [BREAK]<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.082815 [DEBUG] switch_channel.c:1890 (sofia/internal/250@192.168.250.20) Callstate Change DOWN -> RINGING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/250@192.168.250.20) State ROUTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:148 sofia/internal/250@192.168.250.20 SOFIA ROUTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:104 sofia/internal/250@192.168.250.20 Standard ROUTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [INFO] mod_dialplan_xml.c:481 Processing User1 <250>->18xxxxxxxxx in context default<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->unloop] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call_direction] continue=true<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call_direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 ANTI-Action set(call_direction=local)<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->tod_example] continue=true<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Date/Time Match (PASS) [tod_example] break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action set(open=true)<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->holiday_example] continue=true<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Date/TimeMatch (FAIL) [holiday_example] break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->group-intercept] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [group-intercept] destination_number(18xxxxxxxxx) =~ /^\*8$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->redial] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [redial] destination_number(18xxxxxxxxx) =~ /^(redial|\*870)$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->global] continue=true<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Absolute Condition [global]<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action hash(insert/${domain_name}-last_dial/global/${uuid})<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->snom-demo-2] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [snom-demo-2] destination_number(18xxxxxxxxx) =~ /^\*9001$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->snom-demo-1] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [snom-demo-1] destination_number(18xxxxxxxxx) =~ /^\*9000$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call_privacy] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call_privacy] destination_number(18xxxxxxxxx) =~ /^\*67(\d+)$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call_return] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call_return] destination_number(18xxxxxxxxx) =~ /^\*69$|^869$|^lcr$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->del-group] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [del-group] destination_number(18xxxxxxxxx) =~ /^\*\*80(\d{2})$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->add-group] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [add-group] destination_number(18xxxxxxxxx) =~ /^\*\*81(\d{2})$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call-group-simo] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call-group-simo] destination_number(18xxxxxxxxx) =~ /^\*\*82(\d{2})$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->call-group-order] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [call-group-order] destination_number(18xxxxxxxxx) =~ /^\*83(\d{2})$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->intercept-ext] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [intercept-ext] destination_number(18xxxxxxxxx) =~ /^\*\*(\d+)$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->Local_Extension_Skinny] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [Local_Extension_Skinny] destination_number(18xxxxxxxxx) =~ /^(11[01][0-9])$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->send_to_voicemail] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [send_to_voicemail] destination_number(18xxxxxxxxx) =~ /^\*99(\d{2,7})$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->VoiceMeUp.911] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [VoiceMeUp.911] destination_number(18xxxxxxxxx) =~ /^911$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->VoiceMeUp.7d] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (FAIL) [VoiceMeUp.7d] destination_number(18xxxxxxxxx) =~ /^(\d{7})$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->VoiceMeUp.tollfree] continue=false<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 parsing [default->VoiceMeUp.11d] continue=false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Regex (PASS) [VoiceMeUp.11d] destination_number(18xxxxxxxxx) =~ /^\+?(\d{11})$/ break=on-false<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action set(sip_h_X-accountcode=${accountcode})<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action set(call_direction=outbound)<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action set(hangup_after_bridge=true)<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action set(effective_caller_id_name=${outbound_caller_id_name})<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action set(effective_caller_id_number=${outbound_caller_id_number})<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action set(inherit_codec=true)<o:p></o:p></p><p class=MsoNormal>Dialplan: sofia/internal/250@192.168.250.20 Action bridge(sofia/gateway/VoiceMeUp/18xxxxxxxxx)<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250@192.168.250.20 [BREAK]<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/250@192.168.250.20) State EXECUTE<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:241 sofia/internal/250@192.168.250.20 SOFIA EXECUTE<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:192 sofia/internal/250@192.168.250.20 Standard EXECUTE<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 set(call_direction=local)<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [call_direction]=[local]<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 set(open=true)<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [open]=[true]<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 hash(insert/192.168.250.20-spymap/250/36c7a641-bb72-403b-99f4-a2783a8898b8)<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 hash(insert/192.168.250.20-last_dial/250/18xxxxxxxxx)<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 hash(insert/192.168.250.20-last_dial/global/36c7a641-bb72-403b-99f4-a2783a8898b8)<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 set(RFC2822_DATE=Fri, 10 Feb 2012 14:35:29 -0400)<o:p></o:p></p><p class=MsoNormal>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]<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 set(sip_h_X-accountcode=1000)<o:p></o:p></p><p class=MsoNormal>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]<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 set(call_direction=outbound)<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [call_direction]=[outbound]<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 set(hangup_after_bridge=true)<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [hangup_after_bridge]=[true]<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 set(effective_caller_id_name=Tel1)<o:p></o:p></p><p class=MsoNormal>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]<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 set(effective_caller_id_number=5xxxxxxxxx)<o:p></o:p></p><p class=MsoNormal>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]<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 set(inherit_codec=true)<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_dptools.c:1281 sofia/internal/250@192.168.250.20 SET [inherit_codec]=[true]<o:p></o:p></p><p class=MsoNormal>EXECUTE sofia/internal/250@192.168.250.20 bridge(sofia/gateway/VoiceMeUp/18xxxxxxxxx)<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [NOTICE] switch_channel.c:930 New Channel sofia/external/18xxxxxxxxx [5abef3bf-1aa0-4616-89c5-4e134df9328d]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:4670 (sofia/external/18xxxxxxxxx) State Change CS_NEW -> CS_INIT<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_INIT<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:401 (sofia/external/18xxxxxxxxx) State INIT<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:85 sofia/external/18xxxxxxxxx SOFIA INIT<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] mod_sofia.c:125 (sofia/external/18xxxxxxxxx) State Change CS_INIT -> CS_ROUTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.102799 [DEBUG] switch_core_state_machine.c:401 (sofia/external/18xxxxxxxxx) State INIT going to sleep<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_ROUTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_channel.c:1890 (sofia/external/18xxxxxxxxx) Callstate Change DOWN -> RINGING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:410 (sofia/external/18xxxxxxxxx) State ROUTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] mod_sofia.c:148 sofia/external/18xxxxxxxxx SOFIA ROUTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_ivr_originate.c:66 (sofia/external/18xxxxxxxxx) State Change CS_ROUTING -> CS_CONSUME_MEDIA<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:410 (sofia/external/18xxxxxxxxx) State ROUTING going to sleep<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_CONSUME_MEDIA<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:429 (sofia/external/18xxxxxxxxx) State CONSUME_MEDIA<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_core_state_machine.c:429 (sofia/external/18xxxxxxxxx) State CONSUME_MEDIA going to sleep<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.122800 [DEBUG] sofia.c:5508 Channel sofia/external/18xxxxxxxxx entering state [calling][0]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.162820 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.162820 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.162820 [DEBUG] sofia.c:5508 Channel sofia/external/18xxxxxxxxx entering state [calling][0]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal><span style='color:red'>2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:875 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></span></p><p class=MsoNormal><span style='color:red'>2012-02-10 14:35:29.222819 [DEBUG] sofia.c:5508 Channel sofia/external/18xxxxxxxxx entering state [terminated][403]<o:p></o:p></span></p><p class=MsoNormal><span style='color:red'>2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2852 (sofia/external/18xxxxxxxxx) Callstate Change RINGING -> HANGUP<o:p></o:p></span></p><p class=MsoNormal><span style='color:red'>2012-02-10 14:35:29.222819 [NOTICE] sofia.c:6272 Hangup sofia/external/18xxxxxxxxx [CS_CONSUME_MEDIA] [CALL_REJECTED]<o:p></o:p></span></p><p class=MsoNormal><span style='color:red'>2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2875 Send signal sofia/external/18xxxxxxxxx [KILL]<o:p></o:p></span></p><p class=MsoNormal><span style='color:red'>2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></span></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_HANGUP<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_ivr_originate.c:3364 Originate Resulted in Error Cause: 21 [CALL_REJECTED]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [INFO] mod_dptools.c:2916 Originate Failed. Cause: CALL_REJECTED<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2852 (sofia/internal/250@192.168.250.20) Callstate Change RINGING -> HANGUP<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [NOTICE] mod_dptools.c:3035 Hangup sofia/internal/250@192.168.250.20 [CS_EXECUTE] [CALL_REJECTED]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602 (sofia/external/18xxxxxxxxx) State HANGUP<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:469 Channel sofia/external/18xxxxxxxxx hanging up, cause: CALL_REJECTED<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:47 sofia/external/18xxxxxxxxx Standard HANGUP, cause: CALL_REJECTED<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602 (sofia/external/18xxxxxxxxx) State HANGUP going to sleep<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:393 (sofia/external/18xxxxxxxxx) State Change CS_HANGUP -> CS_REPORTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:362 (sofia/external/18xxxxxxxxx) Running State Change CS_REPORTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_channel.c:2875 Send signal sofia/internal/250@192.168.250.20 [KILL]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250@192.168.250.20 [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:662 (sofia/external/18xxxxxxxxx) State REPORTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:79 sofia/external/18xxxxxxxxx Standard REPORTING, cause: CALL_REJECTED<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:662 (sofia/external/18xxxxxxxxx) State REPORTING going to sleep<o:p></o:p></p><p class=MsoNormal>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)<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:387 (sofia/external/18xxxxxxxxx) State Change CS_REPORTING -> CS_DESTROY<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/external/18xxxxxxxxx [BREAK]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1380 Session 7 (sofia/external/18xxxxxxxxx) Locked, Waiting on external entities<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [NOTICE] switch_core_session.c:1398 Session 7 (sofia/external/18xxxxxxxxx) Ended<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [NOTICE] switch_core_session.c:1400 Close Channel sofia/external/18xxxxxxxxx [CS_DESTROY]<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:491 (sofia/external/18xxxxxxxxx) Callstate Change HANGUP -> DOWN<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:494 (sofia/external/18xxxxxxxxx) Running State Change CS_DESTROY<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:504 (sofia/external/18xxxxxxxxx) State DESTROY<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:374 sofia/external/18xxxxxxxxx SOFIA DESTROY<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/250@192.168.250.20) State HANGUP<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] mod_sofia.c:534 Responding to INVITE with: 403<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250@192.168.250.20 [BREAK]<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/250@192.168.250.20) State REPORTING<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_nat.c:545 unmapped public port 26608 protocol UDP to localport 26608<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_nat.c:545 unmapped public port 16966 protocol UDP to localport 16966<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_nat.c:545 unmapped public port 16967 protocol UDP to localport 16967<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:86 sofia/external/18xxxxxxxxx Standard DESTROY<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.222819 [DEBUG] switch_core_state_machine.c:504 (sofia/external/18xxxxxxxxx) State DESTROY going to sleep<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.422846 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/250@192.168.250.20 [BREAK]<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.422846 [NOTICE] switch_core_session.c:1398 Session 6 (sofia/internal/250@192.168.250.20) Ended<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.422846 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/250@192.168.250.20 [CS_DESTROY]<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/250@192.168.250.20) State DESTROY<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.422846 [DEBUG] mod_sofia.c:374 sofia/internal/250@192.168.250.20 SOFIA DESTROY<o:p></o:p></p><p class=MsoNormal>2012-02-10 14:35:29.422846 [DEBUG] switch_core_state_machine.c:86 sofia/internal/250@192.168.250.20 Standard DESTROY<o:p></o:p></p><p class=MsoNormal>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<o:p></o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal><b><o:p> </o:p></b></p></div></body></html>