<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=iso-8859-9"><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;}
@font-face
{font-family:Verdana;
panose-1:2 11 6 4 3 5 4 4 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
{margin:0in;
margin-bottom:.0001pt;
font-size:12.0pt;
font-family:"Times New Roman","serif";}
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:0in;
margin-bottom:.0001pt;
font-size:8.0pt;
font-family:"Tahoma","sans-serif";}
span.BalloonTextChar
{mso-style-name:"Balloon Text Char";
mso-style-priority:99;
mso-style-link:"Balloon Text";
font-family:"Tahoma","sans-serif";}
span.EmailStyle19
{mso-style-type:personal-reply;
font-family:"Verdana","sans-serif";
color:#1F497D;}
.MsoChpDefault
{mso-style-type:export-only;}
@page WordSection1
{size:8.5in 11.0in;
margin:1.0in 1.0in 1.0in 1.0in;}
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-US link=blue vlink=purple><div class=WordSection1><p class=MsoNormal><span style='font-family:"Verdana","sans-serif";color:#1F497D'>I cannot tell you why this is only a problem with extensions w/ 5 digits, but I can tell you what failed here. Your underlying error is:<o:p></o:p></span></p><p class=MsoNormal style='text-indent:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>2013-05-11 12:14:14.980444 <span style='background:yellow;mso-highlight:yellow'>[ERR] sofia_glue.c:4927 a=crypto in RTP/AVP, refer to rfc3711</span><o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>2013-05-11 12:14:14.980444 <span style='background:yellow;mso-highlight:yellow'>[ERR] mod_sofia.c:2789 CODEC NEGOTIATION ERROR. SDP:</span> <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>v=0 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>o=- 3577252345 3577252345 IN IP4 141.196.174.60 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>s=pjmedia <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>c=IN IP4 141.196.174.60 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>t=0 0 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>m=audio 4010 RTP/AVP 8 0 3 101 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>c=IN IP4 141.196.174.60 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>a=rtpmap:8 PCMA/8000 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>a=rtpmap:0 PCMU/8000 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>a=rtpmap:3 GSM/8000 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>a=rtpmap:101 telephone-event/8000 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>a=fmtp:101 0-15 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>a=rtcp:4011 IN IP4 192.168.43.10 <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fig56WojEoKmN07gnvdJZ9Mk6lznskMJszpBOqik <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:HUiy486/260zwSkQ0Z771fKC+g48P9cYEXNqlEYO <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'><o:p> </o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:830 Send signal sofia/internal/60021@my.server.ip.address [BREAK]<o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>2013-05-11 12:14:14.980444 [DEBUG] switch_channel.c:2994 (sofia/internal/60021@my.server.ip.address) Callstate Change <span style='background:yellow;mso-highlight:yellow'>RINGING -> HANGUP</span> <o:p></o:p></span></p><p class=MsoNormal style='margin-left:.5in'><span style='font-family:"Verdana","sans-serif";color:#1F497D'>2013-05-11 12:14:14.980444 [NOTICE] switch_channel.c:3216 Hangup sofia/internal/60021@my.server.ip.address <span style='background:yellow;mso-highlight:yellow'>[CS_EXECUTE] [INCOMPATIBLE_DESTINATION]</span><o:p></o:p></span></p><p class=MsoNormal><span style='font-family:"Verdana","sans-serif";color:#1F497D'><o:p> </o:p></span></p><p class=MsoNormal><span style='font-family:"Verdana","sans-serif";color:#1F497D'>Can you try it without the crypto stuff and paste the same output to pastebin.freeswitch.org (not here in email)?<o:p></o:p></span></p><p class=MsoNormal><span style='font-family:"Verdana","sans-serif";color:#1F497D'><o:p> </o:p></span></p><p class=MsoNormal><span style='font-family:"Verdana","sans-serif";color:#1F497D'><o:p> </o:p></span></p><p class=MsoNormal><b><span style='font-size:10.0pt;font-family:"Tahoma","sans-serif"'>From:</span></b><span style='font-size:10.0pt;font-family:"Tahoma","sans-serif"'> freeswitch-users-bounces@lists.freeswitch.org [mailto:freeswitch-users-bounces@lists.freeswitch.org] <b>On Behalf Of </b>Burak BorYazilim<br><b>Sent:</b> Saturday, May 11, 2013 7:28 AM<br><b>To:</b> FreeSWITCH Users Help<br><b>Subject:</b> Re: [Freeswitch-users] Freeswitch User Adding<o:p></o:p></span></p><p class=MsoNormal><o:p> </o:p></p><div><div><p class=MsoNormal>First off all thanks for your kind helps.<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>Let me tell you the situation more clearly.<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>Firstly I used Android phones as sip clients with the open source sip client program, CSipSimple. My server computer has Ubuntu 12.04 LTS with one static ip address.<o:p></o:p></p></div><div><p class=MsoNormal>freeswitch version output: FreeSWITCH Version 1.3.13b+git~20130205T003128Z~70a9560306 (git 70a9560 2013-02-05 00:31:28Z)<o:p></o:p></p></div><div><p class=MsoNormal>We were using the system with 4 digits number sip account names without any problem. Also the account name will be used as dial number. An example of the user xml file is below. I changed the regex in dialpan/default.xml. The changings were perfectly succesfull with, again, 4 digits number. But when trying to include any other number of digits (3, 5 and 6 were tested), it doesn't work(When I change the regex to accept only 5 digits numbers, 4 digits ones didnt work as expected). I really could not understand why it is happening. Why there was no problem with 4 digits number and why the exact same system does not work with this basic change.<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>To explain the errors more, I want to talk about my tests. Firstly I used 4 digits number user. This test repeated with tls and srtp. These two test were succesfull. <o:p></o:p></p></div><div><p class=MsoNormal>And the secand test is same with first test but with 5 digits numbers without tls and srtp. Registration was succesfull but cant call. (or the call could not be forwarded) Of course I changed the regex and execute reloadxml in this test. GSM (8kHz) and G722(16kHz) codecs were used. As you see below, CODEC NEGOTIATION ERROR occured. After getting this error I changed the codes as SILK(16kHz). With this change I made the third test. Third test result is also below. <o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>show registretions output: <o:p></o:p></p></div><div><p class=MsoNormal>reg_user,realm,token,url,expires,network_ip,network_port,network_proto,hostname,metadata<o:p></o:p></p></div><div><p class=MsoNormal>60022,my.server.ip.address,oOnxiVJFAgQWLABrD01UsYTVOY3TVSlx,sofia/internal/sip:60022@141.196.174.60:52245;ob,1368271435,141.196.174.60,52245,udp,server,<o:p></o:p></p></div><div><p class=MsoNormal>60021,my.server.ip.address,5r4MTDiZPhs5qzdin9A3hEUh1zZsdqqk,sofia/internal/sip:60021@141.196.174.60:57938;ob,1368271446,141.196.174.60,57938,udp,server,<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>@Sean, sorry but I cant post my whole log file because my server has got a network attack so I am quite busy with this attacker. And also I dont think CSipSimple has a problem with 5 digits because this system (with 5 digits) was succesfull with Kamailio and CSipSimple.<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>If I could not be clear please let me know. Thanks...<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>user xml example: This was exact same with the default users in freeswitch. Only the number changed.<o:p></o:p></p></div><div><p class=MsoNormal><include><o:p></o:p></p></div><div><p class=MsoNormal> <user id="60022"><o:p></o:p></p></div><div><p class=MsoNormal> <params><o:p></o:p></p></div><div><p class=MsoNormal> <param name="password" value="$${default_password}"/><o:p></o:p></p></div><div><p class=MsoNormal> <param name="vm-password" value="60022"/><o:p></o:p></p></div><div><p class=MsoNormal> </params><o:p></o:p></p></div><div><p class=MsoNormal> <variables><o:p></o:p></p></div><div><p class=MsoNormal> <variable name="toll_allow" value="domestic,international,local"/><o:p></o:p></p></div><div><p class=MsoNormal> <variable name="accountcode" value="60022"/><o:p></o:p></p></div><div><p class=MsoNormal> <variable name="user_context" value="default"/><o:p></o:p></p></div><div><p class=MsoNormal> <variable name="effective_caller_id_name" value="Extension 60022"/><o:p></o:p></p></div><div><p class=MsoNormal> <variable name="effective_caller_id_number" value="60022"/><o:p></o:p></p></div><div><p class=MsoNormal> <variable name="outbound_caller_id_name" value="$${outbound_caller_name}"/><o:p></o:p></p></div><div><p class=MsoNormal> <variable name="outbound_caller_id_number" value="$${outbound_caller_id}"/><o:p></o:p></p></div><div><p class=MsoNormal> <variable name="callgroup" value="techsupport"/><o:p></o:p></p></div><div><p class=MsoNormal> </variables><o:p></o:p></p></div><div><p class=MsoNormal> </user><o:p></o:p></p></div><div><p class=MsoNormal></include><o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>The second test error:<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.800443 [DEBUG] sofia_reg.c:1511 Send challenge for [60021@my.server.ip.address]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.900444 [DEBUG] sofia_reg.c:1511 Send challenge for [60021@my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_gateway' = '<a href="http://example.com">example.com</a>' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60021' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60021' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60021' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_gateway' = '<a href="http://example.com">example.com</a>' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60021' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60021' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60021' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:1683 Register: <o:p></o:p></p></div><div><p class=MsoNormal>From: [60021@my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>Contact: ["user" <<a href="sip:60021@141.196.174.60:54008;ob">sip:60021@141.196.174.60:54008;ob</a>>] <o:p></o:p></p></div><div><p class=MsoNormal>Expires: [900] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.820443 [DEBUG] sofia_reg.c:1511 Send challenge for [60022@my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.900444 [DEBUG] sofia_reg.c:1511 Send challenge for [60022@my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_gateway' = '<a href="http://example.com">example.com</a>' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60022' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60022' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60022' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_gateway' = '<a href="http://example.com">example.com</a>' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60022' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60022' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60022' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:1683 Register: <o:p></o:p></p></div><div><p class=MsoNormal>From: [60022@my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>Contact: ["user" <<a href="sip:60022@141.196.174.60:33822;ob">sip:60022@141.196.174.60:33822;ob</a>>] <o:p></o:p></p></div><div><p class=MsoNormal>Expires: [900] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.640437 [NOTICE] switch_channel.c:968 New Channel <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> [26730cd2-ba1b-11e2-acc5-bda7cbfd9554]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.640437 [DEBUG] switch_core_session.c:975 Send signal <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> [BREAK]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.640437 [DEBUG] switch_core_session.c:975 Send signal <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.640437 [DEBUG] switch_core_state_machine.c:415 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) Running State Change CS_NEW <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.640437 [DEBUG] switch_core_state_machine.c:433 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) State NEW <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.660438 [DEBUG] sofia.c:7733 IP 141.196.174.60 Rejected by acl "domains". Falling back to Digest auth. <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.660438 [DEBUG] sofia_reg.c:1511 Send challenge for [60022@my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.660438 [DEBUG] switch_core_session.c:975 Send signal <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.660438 [DEBUG] sofia.c:1719 detaching session 26730cd2-ba1b-11e2-acc5-bda7cbfd9554 <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.780439 [DEBUG] sofia.c:1811 Re-attaching to session 26730cd2-ba1b-11e2-acc5-bda7cbfd9554 <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.780439 [DEBUG] switch_core_session.c:975 Send signal <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.780439 [DEBUG] switch_core_session.c:975 Send signal <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia.c:7733 IP 141.196.174.60 Rejected by acl "domains". Falling back to Digest auth. <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_gateway' = '<a href="http://example.com">example.com</a>' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60021' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60021' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60021' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia.c:5578 Channel <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> entering state [received][100] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia.c:5589 Remote SDP: <o:p></o:p></p></div><div><p class=MsoNormal>v=0 <o:p></o:p></p></div><div><p class=MsoNormal>o=- 3577252345 3577252345 IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>s=pjmedia <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>t=0 0 <o:p></o:p></p></div><div><p class=MsoNormal>m=audio 4010 RTP/AVP 8 0 3 101 <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:8 PCMA/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:0 PCMU/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:3 GSM/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:101 telephone-event/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:101 0-15 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtcp:4011 IN IP4 192.168.43.10 <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fig56WojEoKmN07gnvdJZ9Mk6lznskMJszpBOqik <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:HUiy486/260zwSkQ0Z771fKC+g48P9cYEXNqlEYO <o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia.c:5802 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) State Change CS_NEW -> CS_INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:1291 Send signal <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:415 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) Running State Change CS_INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:454 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) State INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:86 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SOFIA INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:126 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) State Change CS_INIT -> CS_ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:1291 Send signal <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:454 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) State INIT going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:415 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) Running State Change CS_ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:2003 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) Callstate Change DOWN -> RINGING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:470 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) State ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:149 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SOFIA ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:117 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Standard ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [INFO] mod_dialplan_xml.c:557 Processing 60021 <60021>->60022 in context default<o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->unloop] continue=false<o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->tod_example] continue=true <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Date/TimeMatch (FAIL) [tod_example] break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->holiday_example] continue=true <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Date/TimeMatch (FAIL) [holiday_example] break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->global-intercept] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [global-intercept] destination_number(60022) =~ /^886$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->group-intercept] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [group-intercept] destination_number(60022) =~ /^\*8$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->intercept-ext] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [intercept-ext] destination_number(60022) =~ /^\*\*(\d+)$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->redial] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [redial] destination_number(60022) =~ /^(redial|870)$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->global] continue=true <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> 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></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (PASS) [global] ${switch_r_sdp}(v=0 <o:p></o:p></p></div><div><p class=MsoNormal>o=- 3577252345 3577252345 IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>s=pjmedia <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>t=0 0 <o:p></o:p></p></div><div><p class=MsoNormal>m=audio 4010 RTP/AVP 8 0 3 101 <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:8 PCMA/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:0 PCMU/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:3 GSM/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:101 telephone-event/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:101 0-15 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtcp:4011 IN IP4 192.168.43.10 <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fig56WojEoKmN07gnvdJZ9Mk6lznskMJszpBOqik <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:HUiy486/260zwSkQ0Z771fKC+g48P9cYEXNqlEYO <o:p></o:p></p></div><div><p class=MsoNormal>) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action set(sip_secure_media=true) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action export(sip_secure_media=true) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Absolute Condition [global] <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action hash(insert/${domain_name}-last_dial/global/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->snom-demo-2] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [snom-demo-2] destination_number(60022) =~ /^9001$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->snom-demo-1] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [snom-demo-1] destination_number(60022) =~ /^9000$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->eavesdrop] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [eavesdrop] destination_number(60022) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->eavesdrop] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [eavesdrop] destination_number(60022) =~ /^779$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->call_return] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [call_return] destination_number(60022) =~ /^\*69$|^869$|^lcr$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->del-group] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [del-group] destination_number(60022) =~ /^80(\d{2})$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->add-group] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [add-group] destination_number(60022) =~ /^81(\d{2})$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->call-group-simo] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [call-group-simo] destination_number(60022) =~ /^82(\d{2})$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->call-group-order] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [call-group-order] destination_number(60022) =~ /^83(\d{2})$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->extension-intercom] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (FAIL) [extension-intercom] destination_number(60022) =~ /^8(10[01][0-9])$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> parsing [default->Local_Extension] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Regex (PASS) [Local_Extension] destination_number(60022) =~ /^([0-9][0-9][0-9][0-9]|[0-9][0-9][0-9][0-9][0-9])$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action export(dialed_extension=60022) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action bind_meta_app(1 b s execute_extension::dx XML features) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action bind_meta_app(3 b s execute_extension::cf XML features) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action bind_meta_app(4 b s execute_extension::att_xfer XML features) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action set(ringback=${us-ring}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action set(transfer_ringback=local_stream://moh) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action set(call_timeout=30) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action set(hangup_after_bridge=true) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action set(continue_on_fail=true) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action hash(insert/${domain_name}-last_dial_ext/global/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action bridge(user/${dialed_extension}@${domain_name}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action answer() <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action sleep(1000) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension}) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:167 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) State Change CS_ROUTING -> CS_EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:1291 Send signal <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:470 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) State ROUTING going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:415 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) Running State Change CS_EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:477 (<a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a>) State EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:242 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SOFIA EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:209 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> Standard EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> set(sip_secure_media=true) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SET [sip_secure_media]=[true] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> export(sip_secure_media=true) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [sip_secure_media]=[true] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> hash(insert/my.server.ip.address-spymap/60021/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> hash(insert/my.server.ip.address-last_dial/60021/60022) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> hash(insert/my.server.ip.address-last_dial/global/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> export(RFC2822_DATE=Sat, 11 May 2013 12:14:14 +0300) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [RFC2822_DATE]=[Sat, 11 May 2013 12:14:14 +0300] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> export(dialed_extension=60022) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [dialed_extension]=[60022] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> bind_meta_app(1 b s execute_extension::dx XML features) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *1 execute_extension::dx XML features<o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/60021.2013-05-11-12-14-14.wav)<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/60021.2013-05-11-12-14-14.wav<o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> bind_meta_app(3 b s execute_extension::cf XML features)<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *3 execute_extension::cf XML features<o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> bind_meta_app(4 b s execute_extension::att_xfer XML features)<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *4 execute_extension::att_xfer XML features<o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> set(ringback=%(2000,4000,440,480))<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SET [ringback]=[%(2000,4000,440,480)] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> set(transfer_ringback=local_stream://moh) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SET [transfer_ringback]=[local_stream://moh] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> set(call_timeout=30) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SET [call_timeout]=[30] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> set(hangup_after_bridge=true) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SET [hangup_after_bridge]=[true] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> set(continue_on_fail=true) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SET [continue_on_fail]=[true] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> hash(insert/my.server.ip.address-call_return/60022/60021) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> hash(insert/my.server.ip.address-last_dial_ext/60022/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> set(called_party_callgroup=techsupport) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> SET [called_party_callgroup]=[techsupport] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> hash(insert/my.server.ip.address-last_dial_ext/techsupport/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> hash(insert/my.server.ip.address-last_dial_ext/global/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> hash(insert/my.server.ip.address-last_dial/techsupport/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> bridge(<a href="mailto:user/60022@my.server.ip.address">user/60022@my.server.ip.address</a>) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> EXPORTING[export_vars] [sip_secure_media]=[true] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 11 May 2013 12:14:14 +0300] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> EXPORTING[export_vars] [dialed_extension]=[60022] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> EXPORTING[export_vars] [sip_secure_media]=[true] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 11 May 2013 12:14:14 +0300] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 <a href="mailto:sofia/internal/60021@my.server.ip.address">sofia/internal/60021@my.server.ip.address</a> EXPORTING[export_vars] [dialed_extension]=[60022] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_event.c:1608 Parsing variable [sip_invite_domain]=[my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_event.c:1608 Parsing variable [presence_id]=[60022@my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [NOTICE] switch_channel.c:968 New Channel sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [268c95ee-ba1b-11e2-ace5-bda7cbfd9554]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:4961 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State Change CS_NEW -> CS_INIT<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:5031 [zrtp_passthru] Setting a-leg inherit_codec=true <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:5034 [zrtp_passthru] Setting b-leg absolute_codec_string='PCMA@8000h@20i@64000b,PCMU@8000h@20i@64000b,GSM@8000h@20i@13200b' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Running State Change CS_INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:86 sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> SOFIA INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_glue.c:3157 Set Local Key [1 AES_CM_128_HMAC_SHA1_32 inline:jy7Mnu44PUrnS4nFSUGkaIsFNftmZRTnE61m4sui] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia_glue.c:2649 Local SDP: <o:p></o:p></p></div><div><p class=MsoNormal>v=0 <o:p></o:p></p></div><div><p class=MsoNormal>o=FreeSWITCH 1368242802 1368242803 IN IP4 my.server.ip.address <o:p></o:p></p></div><div><p class=MsoNormal>s=FreeSWITCH <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 my.server.ip.address <o:p></o:p></p></div><div><p class=MsoNormal>t=0 0 <o:p></o:p></p></div><div><p class=MsoNormal>m=audio 20852 RTP/SAVP 8 0 3 101 13 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:101 telephone-event/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:101 0-16 <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:jy7Mnu44PUrnS4nFSUGkaIsFNftmZRTnE61m4sui <o:p></o:p></p></div><div><p class=MsoNormal>a=ptime:20 <o:p></o:p></p></div><div><p class=MsoNormal>a=sendrecv <o:p></o:p></p></div><div><p class=MsoNormal>m=audio 20852 RTP/AVP 8 0 3 101 13 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:101 telephone-event/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:101 0-16 <o:p></o:p></p></div><div><p class=MsoNormal>a=ptime:20 <o:p></o:p></p></div><div><p class=MsoNormal>a=sendrecv <o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:126 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State Change CS_INIT -> CS_ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State INIT going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Running State Change CS_ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:2003 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Callstate Change DOWN -> RINGING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:149 sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> SOFIA ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State Change CS_ROUTING -> CS_CONSUME_MEDIA <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State ROUTING going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Running State Change CS_CONSUME_MEDIA <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:489 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State CONSUME_MEDIA <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:489 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State CONSUME_MEDIA going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.800439 [DEBUG] sofia.c:5578 Channel sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> entering state [calling][0] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] sofia.c:5578 Channel sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> entering state [proceeding][180] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [NOTICE] sofia.c:5670 Ring-Ready sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>!<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [INFO] switch_ivr_originate.c:1185 Sending early media<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [ERR] sofia_glue.c:4927 a=crypto in RTP/AVP, refer to rfc3711<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [ERR] mod_sofia.c:2789 CODEC NEGOTIATION ERROR. SDP: <o:p></o:p></p></div><div><p class=MsoNormal>v=0 <o:p></o:p></p></div><div><p class=MsoNormal>o=- 3577252345 3577252345 IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>s=pjmedia <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>t=0 0 <o:p></o:p></p></div><div><p class=MsoNormal>m=audio 4010 RTP/AVP 8 0 3 101 <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:8 PCMA/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:0 PCMU/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:3 GSM/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:101 telephone-event/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:101 0-15 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtcp:4011 IN IP4 192.168.43.10 <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fig56WojEoKmN07gnvdJZ9Mk6lznskMJszpBOqik <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:HUiy486/260zwSkQ0Z771fKC+g48P9cYEXNqlEYO <o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:830 Send signal sofia/internal/60021@my.server.ip.address [BREAK]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_channel.c:2994 (sofia/internal/60021@my.server.ip.address) Callstate Change RINGING -> HANGUP <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [NOTICE] switch_channel.c:3216 Hangup sofia/internal/60021@my.server.ip.address [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/60021@my.server.ip.address [KILL]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60021@my.server.ip.address [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_ivr_originate.c:1186 sofia/internal/60021@my.server.ip.address Media Establishment Failed. <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_channel.c:2994 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Callstate Change RINGING -> HANGUP <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [NOTICE] switch_ivr_originate.c:3620 Hangup sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Running State Change CS_HANGUP<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [KILL] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State HANGUP <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] mod_sofia.c:503 Channel sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> hanging up, cause: ORIGINATOR_CANCEL <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [NOTICE] switch_ivr_originate.c:2608 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [INFO] mod_dptools.c:3060 Originate Failed. Cause: ORIGINATOR_CANCEL<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] mod_sofia.c:562 Sending CANCEL to sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a><o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:48 sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> Standard HANGUP, cause: ORIGINATOR_CANCEL <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State HANGUP going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State Change CS_HANGUP -> CS_REPORTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:2689 sofia/internal/60021@my.server.ip.address skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/60021@my.server.ip.address) State EXECUTE going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60021@my.server.ip.address) Running State Change CS_HANGUP <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Running State Change CS_REPORTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State REPORTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:92 sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> Standard REPORTING, cause: ORIGINATOR_CANCEL <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State REPORTING going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/60021@my.server.ip.address) State HANGUP <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] mod_sofia.c:503 Channel sofia/internal/60021@my.server.ip.address hanging up, cause: INCOMPATIBLE_DESTINATION <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State Change CS_REPORTING -> CS_DESTROY <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:1499 Session 23 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Locked, Waiting on external entities <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [NOTICE] switch_core_session.c:1517 Session 23 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Ended<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:14.980444 [NOTICE] switch_core_session.c:1521 Close Channel sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> [CS_DESTROY] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 488<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Callstate Change HANGUP -> DOWN <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:48 sofia/internal/60021@my.server.ip.address Standard HANGUP, cause: INCOMPATIBLE_DESTINATION <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/60021@my.server.ip.address) State HANGUP going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/60021@my.server.ip.address) State Change CS_HANGUP -> CS_REPORTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60021@my.server.ip.address [BREAK]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60021@my.server.ip.address) Running State Change CS_REPORTING<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:559 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) Running State Change CS_DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/60021@my.server.ip.address) State REPORTING<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] mod_sofia.c:396 sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> SOFIA DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:99 sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a> Standard DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/<a href="http://sip:60022@141.196.174.60:33822">sip:60022@141.196.174.60:33822</a>) State DESTROY going to sleep<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:92 sofia/internal/60021@my.server.ip.address Standard REPORTING, cause: INCOMPATIBLE_DESTINATION<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/60021@my.server.ip.address) State REPORTING going to sleep<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/60021@my.server.ip.address) State Change CS_REPORTING -> CS_DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60021@my.server.ip.address [BREAK]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_session.c:1499 Session 22 (sofia/internal/60021@my.server.ip.address) Locked, Waiting on external entities<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [NOTICE] switch_core_session.c:1517 Session 22 (sofia/internal/60021@my.server.ip.address) Ended<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [NOTICE] switch_core_session.c:1521 Close Channel sofia/internal/60021@my.server.ip.address [CS_DESTROY]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/60021@my.server.ip.address) Callstate Change HANGUP -> DOWN<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:559 (sofia/internal/60021@my.server.ip.address) Running State Change CS_DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/60021@my.server.ip.address) State DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] mod_sofia.c:396 sofia/internal/60021@my.server.ip.address SOFIA DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:99 sofia/internal/60021@my.server.ip.address Standard DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/60021@my.server.ip.address) State DESTROY going to sleep<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:03.100449 [DEBUG] sofia_reg.c:1511 Send challenge for [60022@my.server.ip.address]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_gateway' = '<a href="http://example.com">example.com</a>'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60022'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60022'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60022'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport'<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:1683 Register:<o:p></o:p></p></div><div><p class=MsoNormal>From: [60022@my.server.ip.address]<o:p></o:p></p></div><div><p class=MsoNormal>Contact: ["user" <sip:60022@141.196.174.60:33822;ob>]<o:p></o:p></p></div><div><p class=MsoNormal>Expires: [900]<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>third test<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:17.980434 [NOTICE] switch_channel.c:968 New Channel sofia/internal/60022@my.server.ip.address [156488c0-ba2b-11e2-ad18-bda7cbfd9554]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:17.980434 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:17.980434 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:17.980434 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60022@my.server.ip.address) Running State Change CS_NEW <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:17.980434 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/60022@my.server.ip.address) State NEW <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.000438 [DEBUG] sofia.c:7733 IP 141.196.174.60 Rejected by acl "domains". Falling back to Digest auth. <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.000438 [DEBUG] sofia_reg.c:1511 Send challenge for [60021@my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.000438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.000438 [DEBUG] sofia.c:1719 detaching session 156488c0-ba2b-11e2-ad18-bda7cbfd9554 <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.120438 [DEBUG] sofia.c:1811 Re-attaching to session 156488c0-ba2b-11e2-ad18-bda7cbfd9554 <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.120438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.120438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia.c:7733 IP 141.196.174.60 Rejected by acl "domains". Falling back to Digest auth. <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_gateway' = '<a href="http://example.com">example.com</a>' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60022' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60022' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60022' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia.c:5578 Channel sofia/internal/60022@my.server.ip.address entering state [received][100] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia.c:5589 Remote SDP: <o:p></o:p></p></div><div><p class=MsoNormal>v=0 <o:p></o:p></p></div><div><p class=MsoNormal>o=- 3577259178 3577259178 IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>s=pjmedia <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>t=0 0 <o:p></o:p></p></div><div><p class=MsoNormal>m=audio 4000 RTP/AVP 97 101 <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:97 SILK/16000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:97 useinbandfec=0 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:101 telephone-event/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:101 0-15 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtcp:4001 IN IP4 192.168.43.193 <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:+8RV0fxAu+3s1Fc8BQxiMa9ras5u/JDmQ9uCVagu <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:CQ25iu0Z418+mKdV+nRcwXqkd5z+gUxuXsqQt40P <o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia.c:5802 (sofia/internal/60022@my.server.ip.address) State Change CS_NEW -> CS_INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60022@my.server.ip.address) Running State Change CS_INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/60022@my.server.ip.address) State INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:86 sofia/internal/60022@my.server.ip.address SOFIA INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:126 (sofia/internal/60022@my.server.ip.address) State Change CS_INIT -> CS_ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/60022@my.server.ip.address) State INIT going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60022@my.server.ip.address) Running State Change CS_ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:2003 (sofia/internal/60022@my.server.ip.address) Callstate Change DOWN -> RINGING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/60022@my.server.ip.address) State ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:149 sofia/internal/60022@my.server.ip.address SOFIA ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:117 sofia/internal/60022@my.server.ip.address Standard ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [INFO] mod_dialplan_xml.c:557 Processing 60022 <60022>->60021 in context default<o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->unloop] continue=false<o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->tod_example] continue=true <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Date/TimeMatch (FAIL) [tod_example] break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->holiday_example] continue=true <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Date/TimeMatch (FAIL) [holiday_example] break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->global-intercept] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [global-intercept] destination_number(60021) =~ /^886$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->group-intercept] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [group-intercept] destination_number(60021) =~ /^\*8$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->intercept-ext] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [intercept-ext] destination_number(60021) =~ /^\*\*(\d+)$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->redial] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [redial] destination_number(60021) =~ /^(redial|870)$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->global] continue=true <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address 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></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (PASS) [global] ${switch_r_sdp}(v=0 <o:p></o:p></p></div><div><p class=MsoNormal>o=- 3577259178 3577259178 IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>s=pjmedia <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>t=0 0 <o:p></o:p></p></div><div><p class=MsoNormal>m=audio 4000 RTP/AVP 97 101 <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 141.196.174.60 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:97 SILK/16000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:97 useinbandfec=0 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:101 telephone-event/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:101 0-15 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtcp:4001 IN IP4 192.168.43.193 <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:+8RV0fxAu+3s1Fc8BQxiMa9ras5u/JDmQ9uCVagu <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:CQ25iu0Z418+mKdV+nRcwXqkd5z+gUxuXsqQt40P <o:p></o:p></p></div><div><p class=MsoNormal>) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action set(sip_secure_media=true) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action export(sip_secure_media=true) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Absolute Condition [global] <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial/global/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->snom-demo-2] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [snom-demo-2] destination_number(60021) =~ /^9001$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->snom-demo-1] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [snom-demo-1] destination_number(60021) =~ /^9000$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->eavesdrop] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [eavesdrop] destination_number(60021) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->eavesdrop] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [eavesdrop] destination_number(60021) =~ /^779$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->call_return] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [call_return] destination_number(60021) =~ /^\*69$|^869$|^lcr$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->del-group] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [del-group] destination_number(60021) =~ /^80(\d{2})$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->add-group] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [add-group] destination_number(60021) =~ /^81(\d{2})$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->call-group-simo] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [call-group-simo] destination_number(60021) =~ /^82(\d{2})$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->call-group-order] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [call-group-order] destination_number(60021) =~ /^83(\d{2})$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->extension-intercom] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [extension-intercom] destination_number(60021) =~ /^8(10[01][0-9])$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->Local_Extension] continue=false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Regex (PASS) [Local_Extension] destination_number(60021) =~ /^([0-9][0-9][0-9][0-9]|[0-9][0-9][0-9][0-9][0-9])$/ break=on-false <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action export(dialed_extension=60021) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action bind_meta_app(1 b s execute_extension::dx XML features) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action bind_meta_app(3 b s execute_extension::cf XML features) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action bind_meta_app(4 b s execute_extension::att_xfer XML features) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action set(ringback=${us-ring}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action set(transfer_ringback=local_stream://moh) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action set(call_timeout=30) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action set(hangup_after_bridge=true) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action set(continue_on_fail=true) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial_ext/global/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action bridge(user/${dialed_extension}@${domain_name}) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action answer() <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action sleep(1000) <o:p></o:p></p></div><div><p class=MsoNormal>Dialplan: sofia/internal/60022@my.server.ip.address Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension}) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/60022@my.server.ip.address) State Change CS_ROUTING -> CS_EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/60022@my.server.ip.address) State ROUTING going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60022@my.server.ip.address) Running State Change CS_EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/60022@my.server.ip.address) State EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:242 sofia/internal/60022@my.server.ip.address SOFIA EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:209 sofia/internal/60022@my.server.ip.address Standard EXECUTE <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address set(sip_secure_media=true) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [sip_secure_media]=[true] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address export(sip_secure_media=true) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [sip_secure_media]=[true] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-spymap/60022/156488c0-ba2b-11e2-ad18-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial/60022/60021) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial/global/156488c0-ba2b-11e2-ad18-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address export(RFC2822_DATE=Sat, 11 May 2013 14:08:18 +0300) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [RFC2822_DATE]=[Sat, 11 May 2013 14:08:18 +0300] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address export(dialed_extension=60021) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [dialed_extension]=[60021] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address bind_meta_app(1 b s execute_extension::dx XML features) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *1 execute_extension::dx XML features<o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/60022.2013-05-11-14-08-18.wav)<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/60022.2013-05-11-14-08-18.wav<o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address bind_meta_app(3 b s execute_extension::cf XML features)<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *3 execute_extension::cf XML features<o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address bind_meta_app(4 b s execute_extension::att_xfer XML features)<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *4 execute_extension::att_xfer XML features<o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address set(ringback=%(2000,4000,440,480))<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [ringback]=[%(2000,4000,440,480)] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address set(transfer_ringback=local_stream://moh) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [transfer_ringback]=[local_stream://moh] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address set(call_timeout=30) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [call_timeout]=[30] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address set(hangup_after_bridge=true) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [hangup_after_bridge]=[true] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address set(continue_on_fail=true) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [continue_on_fail]=[true] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-call_return/60021/60022) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial_ext/60021/156488c0-ba2b-11e2-ad18-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address set(called_party_callgroup=techsupport) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [called_party_callgroup]=[techsupport] <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial_ext/techsupport/156488c0-ba2b-11e2-ad18-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial_ext/global/156488c0-ba2b-11e2-ad18-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial/techsupport/156488c0-ba2b-11e2-ad18-bda7cbfd9554) <o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address bridge(user/60021@my.server.ip.address) <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1089 sofia/internal/60022@my.server.ip.address EXPORTING[export_vars] [sip_secure_media]=[true] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1089 sofia/internal/60022@my.server.ip.address EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 11 May 2013 14:08:18 +0300] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1089 sofia/internal/60022@my.server.ip.address EXPORTING[export_vars] [dialed_extension]=[60021] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1089 sofia/internal/60022@my.server.ip.address EXPORTING[export_vars] [sip_secure_media]=[true] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1089 sofia/internal/60022@my.server.ip.address EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 11 May 2013 14:08:18 +0300] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1089 sofia/internal/60022@my.server.ip.address EXPORTING[export_vars] [dialed_extension]=[60021] to event <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_event.c:1608 Parsing variable [sip_invite_domain]=[my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_event.c:1608 Parsing variable [presence_id]=[60021@my.server.ip.address] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [NOTICE] switch_channel.c:968 New Channel sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [157eb0a6-ba2b-11e2-ad38-bda7cbfd9554]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:4961 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State Change CS_NEW -> CS_INIT<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:5031 [zrtp_passthru] Setting a-leg inherit_codec=true <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Running State Change CS_INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:86 sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> SOFIA INIT <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_glue.c:3157 Set Local Key [1 AES_CM_128_HMAC_SHA1_32 inline:FGwgO9qNK7dbHa/ZYQcA2fWT17ktjsvjEt5fYXf4] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia_glue.c:2649 Local SDP: <o:p></o:p></p></div><div><p class=MsoNormal>v=0 <o:p></o:p></p></div><div><p class=MsoNormal>o=FreeSWITCH 1368243506 1368243507 IN IP4 my.server.ip.address <o:p></o:p></p></div><div><p class=MsoNormal>s=FreeSWITCH <o:p></o:p></p></div><div><p class=MsoNormal>c=IN IP4 my.server.ip.address <o:p></o:p></p></div><div><p class=MsoNormal>t=0 0 <o:p></o:p></p></div><div><p class=MsoNormal>m=audio 26992 RTP/SAVP 9 0 8 3 101 13 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:101 telephone-event/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:101 0-16 <o:p></o:p></p></div><div><p class=MsoNormal>a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:FGwgO9qNK7dbHa/ZYQcA2fWT17ktjsvjEt5fYXf4 <o:p></o:p></p></div><div><p class=MsoNormal>a=ptime:20 <o:p></o:p></p></div><div><p class=MsoNormal>a=sendrecv <o:p></o:p></p></div><div><p class=MsoNormal>m=audio 26992 RTP/AVP 9 0 8 3 101 13 <o:p></o:p></p></div><div><p class=MsoNormal>a=rtpmap:101 telephone-event/8000 <o:p></o:p></p></div><div><p class=MsoNormal>a=fmtp:101 0-16 <o:p></o:p></p></div><div><p class=MsoNormal>a=ptime:20 <o:p></o:p></p></div><div><p class=MsoNormal>a=sendrecv <o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:126 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State Change CS_INIT -> CS_ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State INIT going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Running State Change CS_ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:2003 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Callstate Change DOWN -> RINGING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:149 sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> SOFIA ROUTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State Change CS_ROUTING -> CS_CONSUME_MEDIA <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State ROUTING going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Running State Change CS_CONSUME_MEDIA <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:489 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State CONSUME_MEDIA <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:489 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State CONSUME_MEDIA going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.140456 [DEBUG] sofia.c:5578 Channel sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> entering state [calling][0] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] sofia.c:5578 Channel sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> entering state [terminated][406] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_channel.c:2994 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Callstate Change RINGING -> HANGUP <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [NOTICE] sofia.c:6385 Hangup sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [CS_CONSUME_MEDIA] [SERVICE_NOT_IMPLEMENTED]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [KILL]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Running State Change CS_HANGUP <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State HANGUP <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] mod_sofia.c:503 Channel sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> hanging up, cause: SERVICE_NOT_IMPLEMENTED <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:48 sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State HANGUP going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State Change CS_HANGUP -> CS_REPORTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Running State Change CS_REPORTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State REPORTING <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:92 sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State REPORTING going to sleep <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State Change CS_REPORTING -> CS_DESTROY <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [BREAK] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1499 Session 27 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Locked, Waiting on external entities <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [NOTICE] switch_core_session.c:1517 Session 27 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Ended<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [NOTICE] switch_core_session.c:1521 Close Channel sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> [CS_DESTROY] <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Callstate Change HANGUP -> DOWN<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [NOTICE] switch_ivr_originate.c:2608 Cannot create outgoing channel of type [user] cause: [SERVICE_NOT_IMPLEMENTED]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:559 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) Running State Change CS_DESTROY <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State DESTROY <o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] mod_sofia.c:396 sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> SOFIA DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:99 sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a> Standard DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [INFO] mod_dptools.c:3060 Originate Failed. Cause: SERVICE_NOT_IMPLEMENTED<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/<a href="http://sip:60021@141.196.174.60:57938">sip:60021@141.196.174.60:57938</a>) State DESTROY going to sleep<o:p></o:p></p></div><div><p class=MsoNormal>EXECUTE sofia/internal/60022@my.server.ip.address answer()<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [ERR] sofia_glue.c:4927 a=crypto in RTP/AVP, refer to rfc3711<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:830 Send signal sofia/internal/60022@my.server.ip.address [BREAK]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_channel.c:2994 (sofia/internal/60022@my.server.ip.address) Callstate Change RINGING -> HANGUP<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [NOTICE] switch_channel.c:3484 Hangup sofia/internal/60022@my.server.ip.address [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/60022@my.server.ip.address [KILL]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:2689 sofia/internal/60022@my.server.ip.address skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/60022@my.server.ip.address) State EXECUTE going to sleep<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60022@my.server.ip.address) Running State Change CS_HANGUP<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/60022@my.server.ip.address) State HANGUP<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] mod_sofia.c:497 sofia/internal/60022@my.server.ip.address Overriding SIP cause 488 with 406 from the other leg<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] mod_sofia.c:503 Channel sofia/internal/60022@my.server.ip.address hanging up, cause: INCOMPATIBLE_DESTINATION<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 406<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:48 sofia/internal/60022@my.server.ip.address Standard HANGUP, cause: INCOMPATIBLE_DESTINATION<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/60022@my.server.ip.address) State HANGUP going to sleep<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/60022@my.server.ip.address) State Change CS_HANGUP -> CS_REPORTING<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60022@my.server.ip.address) Running State Change CS_REPORTING<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/60022@my.server.ip.address) State REPORTING<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:92 sofia/internal/60022@my.server.ip.address Standard REPORTING, cause: INCOMPATIBLE_DESTINATION<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/60022@my.server.ip.address) State REPORTING going to sleep<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/60022@my.server.ip.address) State Change CS_REPORTING -> CS_DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1499 Session 26 (sofia/internal/60022@my.server.ip.address) Locked, Waiting on external entities<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [NOTICE] switch_core_session.c:1517 Session 26 (sofia/internal/60022@my.server.ip.address) Ended<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [NOTICE] switch_core_session.c:1521 Close Channel sofia/internal/60022@my.server.ip.address [CS_DESTROY]<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/60022@my.server.ip.address) Callstate Change HANGUP -> DOWN<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:559 (sofia/internal/60022@my.server.ip.address) Running State Change CS_DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/60022@my.server.ip.address) State DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] mod_sofia.c:396 sofia/internal/60022@my.server.ip.address SOFIA DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:99 sofia/internal/60022@my.server.ip.address Standard DESTROY<o:p></o:p></p></div><div><p class=MsoNormal>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/60022@my.server.ip.address) State DESTROY going to sleep<o:p></o:p></p></div></div><div><p class=MsoNormal style='margin-bottom:12.0pt'><o:p> </o:p></p><div><p class=MsoNormal>2013/5/10 Steven Ayre <<a href="mailto:steveayre@gmail.com" target="_blank">steveayre@gmail.com</a>><o:p></o:p></p><p class=MsoNormal>Are you using the default dialplan?<o:p></o:p></p><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>Chances are your destination_number condition's regex for the extension that calls users is limited to 4 digits.<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>Eg<o:p></o:p></p></div><div><p class=MsoNormal>\d\d\d\d<o:p></o:p></p></div><div><p class=MsoNormal>\d{4}<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>-Steve<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal style='margin-bottom:12.0pt'><o:p> </o:p></p><div><div><div><p class=MsoNormal>On 10 May 2013 12:30, Burak BorYazýlým <<a href="mailto:mburakbor@gmail.com" target="_blank">mburakbor@gmail.com</a>> wrote:<o:p></o:p></p></div></div><blockquote style='border:none;border-left:solid #CCCCCC 1.0pt;padding:0in 0in 0in 6.0pt;margin-left:4.8pt;margin-right:0in'><div><div><div><p class=MsoNormal>Hello,<o:p></o:p></p><div><p class=MsoNormal><o:p> </o:p></p><div><p class=MsoNormal>I have problems with adding new user to freeswitch. When trying to add user with user id has different number of digits than 4, it can register but it cant call or cant be called. I changed local extension regular expression in dialpan default.xml but the changes only work with four digits users(user ids or dial number) .So I want dial a number that has five or more digits. What other configurations I must change.<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>Thank you...<o:p></o:p></p></div><div><p class=MsoNormal><span style='color:#888888'>Burak,<o:p></o:p></span></p></div></div></div><p class=MsoNormal><o:p> </o:p></p></div></div><div><p class=MsoNormal style='margin-bottom:12.0pt'>_________________________________________________________________________<br>Professional FreeSWITCH Consulting Services:<br><a href="mailto:consulting@freeswitch.org" target="_blank">consulting@freeswitch.org</a><br><a href="http://www.freeswitchsolutions.com" target="_blank">http://www.freeswitchsolutions.com</a><br><br>FreeSWITCH-powered IP PBX: The CudaTel Communication Server<br><a href="http://www.cudatel.com" target="_blank">http://www.cudatel.com</a><br><br>Official FreeSWITCH Sites<br><a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br><a href="http://wiki.freeswitch.org" target="_blank">http://wiki.freeswitch.org</a><br><a href="http://www.cluecon.com" target="_blank">http://www.cluecon.com</a><br><br>FreeSWITCH-users mailing list<br><a href="mailto:FreeSWITCH-users@lists.freeswitch.org" target="_blank">FreeSWITCH-users@lists.freeswitch.org</a><br><a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br><a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><o:p></o:p></p></div></blockquote></div><p class=MsoNormal><o:p> </o:p></p></div><p class=MsoNormal style='margin-bottom:12.0pt'><br>_________________________________________________________________________<br>Professional FreeSWITCH Consulting Services:<br><a href="mailto:consulting@freeswitch.org">consulting@freeswitch.org</a><br><a href="http://www.freeswitchsolutions.com" target="_blank">http://www.freeswitchsolutions.com</a><br><br>FreeSWITCH-powered IP PBX: The CudaTel Communication Server<br><a href="http://www.cudatel.com" target="_blank">http://www.cudatel.com</a><br><br>Official FreeSWITCH Sites<br><a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br><a href="http://wiki.freeswitch.org" target="_blank">http://wiki.freeswitch.org</a><br><a href="http://www.cluecon.com" target="_blank">http://www.cluecon.com</a><br><br>FreeSWITCH-users mailing list<br><a href="mailto:FreeSWITCH-users@lists.freeswitch.org">FreeSWITCH-users@lists.freeswitch.org</a><br><a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br><a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><o:p></o:p></p></div><p class=MsoNormal><o:p> </o:p></p></div></div></body></html>