<div dir="ltr"><div>First off all thanks for your kind helps.</div><div><br></div><div>Let me tell you the situation more clearly.</div><div><br></div><div>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.</div>
<div>freeswitch version output: FreeSWITCH Version 1.3.13b+git~20130205T003128Z~70a9560306 (git 70a9560 2013-02-05 00:31:28Z)</div><div>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.</div>
<div><br></div><div>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. </div><div>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. </div>
<div><br></div><div>show registretions output: </div><div>reg_user,realm,token,url,expires,network_ip,network_port,network_proto,hostname,metadata</div><div>60022,my.server.ip.address,oOnxiVJFAgQWLABrD01UsYTVOY3TVSlx,sofia/internal/sip:60022@141.196.174.60:52245;ob,1368271435,141.196.174.60,52245,udp,server,</div>
<div>60021,my.server.ip.address,5r4MTDiZPhs5qzdin9A3hEUh1zZsdqqk,sofia/internal/sip:60021@141.196.174.60:57938;ob,1368271446,141.196.174.60,57938,udp,server,</div><div><br></div><div><br></div><div>@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.</div>
<div><br></div><div>If I could not be clear please let me know. Thanks...</div><div><br></div><div>user xml example: This was exact same with the default users in freeswitch. Only the number changed.</div><div><include></div>
<div> <user id="60022"></div><div> <params></div><div> <param name="password" value="$${default_password}"/></div><div> <param name="vm-password" value="60022"/></div>
<div> </params></div><div> <variables></div><div> <variable name="toll_allow" value="domestic,international,local"/></div><div> <variable name="accountcode" value="60022"/></div>
<div> <variable name="user_context" value="default"/></div><div> <variable name="effective_caller_id_name" value="Extension 60022"/></div><div> <variable name="effective_caller_id_number" value="60022"/></div>
<div> <variable name="outbound_caller_id_name" value="$${outbound_caller_name}"/></div><div> <variable name="outbound_caller_id_number" value="$${outbound_caller_id}"/></div>
<div> <variable name="callgroup" value="techsupport"/></div><div> </variables></div><div> </user></div><div></include></div><div><br></div><div><br></div><div><br></div>
<div>The second test error:</div><div><br></div><div>2013-05-11 12:13:56.800443 [DEBUG] sofia_reg.c:1511 Send challenge for [60021@my.server.ip.address]</div><div>2013-05-11 12:13:56.900444 [DEBUG] sofia_reg.c:1511 Send challenge for [60021@my.server.ip.address] </div>
<div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' </div><div>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>' </div>
<div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' </div><div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' </div>
<div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' </div><div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60021' </div>
<div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' </div><div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60021' </div>
<div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60021' </div><div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' </div>
<div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' </div><div>2013-05-11 12:13:56.920444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' </div>
<div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' </div><div>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>' </div>
<div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' </div><div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' </div>
<div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' </div><div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60021' </div>
<div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' </div><div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60021' </div>
<div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60021' </div><div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' </div>
<div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' </div><div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' </div>
<div>2013-05-11 12:13:56.980451 [DEBUG] sofia_reg.c:1683 Register: </div><div>From: [60021@my.server.ip.address] </div>
<div>Contact: ["user" <sip:60021@141.196.174.60:54008;ob>] </div><div>Expires: [900] </div>
<div>2013-05-11 12:14:06.820443 [DEBUG] sofia_reg.c:1511 Send challenge for [60022@my.server.ip.address] </div><div>2013-05-11 12:14:06.900444 [DEBUG] sofia_reg.c:1511 Send challenge for [60022@my.server.ip.address] </div>
<div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' </div><div>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>' </div>
<div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' </div><div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' </div>
<div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' </div><div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60022' </div>
<div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' </div><div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60022' </div>
<div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60022' </div><div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' </div>
<div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' </div><div>2013-05-11 12:14:06.980444 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' </div>
<div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' </div><div>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>' </div>
<div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' </div><div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' </div>
<div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' </div><div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60022' </div>
<div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' </div><div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60022' </div>
<div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60022' </div><div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' </div>
<div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' </div><div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' </div>
<div>2013-05-11 12:14:07.020442 [DEBUG] sofia_reg.c:1683 Register: </div><div>From: [60022@my.server.ip.address] </div>
<div>Contact: ["user" <sip:60022@141.196.174.60:33822;ob>] </div><div>Expires: [900] </div>
<div>2013-05-11 12:14:14.640437 [NOTICE] switch_channel.c:968 New Channel sofia/internal/60021@my.server.ip.address [26730cd2-ba1b-11e2-acc5-bda7cbfd9554]</div><div>2013-05-11 12:14:14.640437 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60021@my.server.ip.address [BREAK]</div>
<div>2013-05-11 12:14:14.640437 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60021@my.server.ip.address [BREAK] </div><div>2013-05-11 12:14:14.640437 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60021@my.server.ip.address) Running State Change CS_NEW </div>
<div>2013-05-11 12:14:14.640437 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/60021@my.server.ip.address) State NEW </div><div>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. </div>
<div>2013-05-11 12:14:14.660438 [DEBUG] sofia_reg.c:1511 Send challenge for [60022@my.server.ip.address] </div><div>2013-05-11 12:14:14.660438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60021@my.server.ip.address [BREAK] </div>
<div>2013-05-11 12:14:14.660438 [DEBUG] sofia.c:1719 detaching session 26730cd2-ba1b-11e2-acc5-bda7cbfd9554 </div><div>2013-05-11 12:14:14.780439 [DEBUG] sofia.c:1811 Re-attaching to session 26730cd2-ba1b-11e2-acc5-bda7cbfd9554 </div>
<div>2013-05-11 12:14:14.780439 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60021@my.server.ip.address [BREAK] </div><div>2013-05-11 12:14:14.780439 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60021@my.server.ip.address [BREAK] </div>
<div>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. </div><div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' </div>
<div>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>' </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' </div><div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' </div><div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60021' </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' </div><div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60021' </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60021' </div><div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' </div><div>2013-05-11 12:14:14.800439 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] sofia.c:5578 Channel sofia/internal/60021@my.server.ip.address entering state [received][100] </div><div>2013-05-11 12:14:14.800439 [DEBUG] sofia.c:5589 Remote SDP: </div>
<div>v=0 </div><div>o=- 3577252345 3577252345 IN IP4 141.196.174.60 </div>
<div>s=pjmedia </div><div>c=IN IP4 141.196.174.60 </div>
<div>t=0 0 </div><div>m=audio 4010 RTP/AVP 8 0 3 101 </div>
<div>c=IN IP4 141.196.174.60 </div><div>a=rtpmap:8 PCMA/8000 </div>
<div>a=rtpmap:0 PCMU/8000 </div><div>a=rtpmap:3 GSM/8000 </div>
<div>a=rtpmap:101 telephone-event/8000 </div><div>a=fmtp:101 0-15 </div>
<div>a=rtcp:4011 IN IP4 192.168.43.10 </div><div>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fig56WojEoKmN07gnvdJZ9Mk6lznskMJszpBOqik </div>
<div>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:HUiy486/260zwSkQ0Z771fKC+g48P9cYEXNqlEYO </div><div><br></div><div>2013-05-11 12:14:14.800439 [DEBUG] sofia.c:5802 (sofia/internal/60021@my.server.ip.address) State Change CS_NEW -> CS_INIT </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60021@my.server.ip.address [BREAK] </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60021@my.server.ip.address) Running State Change CS_INIT </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/60021@my.server.ip.address) State INIT </div><div>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:86 sofia/internal/60021@my.server.ip.address SOFIA INIT </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:126 (sofia/internal/60021@my.server.ip.address) State Change CS_INIT -> CS_ROUTING </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60021@my.server.ip.address [BREAK] </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/60021@my.server.ip.address) State INIT going to sleep </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60021@my.server.ip.address) Running State Change CS_ROUTING </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:2003 (sofia/internal/60021@my.server.ip.address) Callstate Change DOWN -> RINGING </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/60021@my.server.ip.address) State ROUTING </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:149 sofia/internal/60021@my.server.ip.address SOFIA ROUTING </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:117 sofia/internal/60021@my.server.ip.address Standard ROUTING </div>
<div>2013-05-11 12:14:14.800439 [INFO] mod_dialplan_xml.c:557 Processing 60021 <60021>->60022 in context default</div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->unloop] continue=false</div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->tod_example] continue=true </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Date/TimeMatch (FAIL) [tod_example] break=on-false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->holiday_example] continue=true </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Date/TimeMatch (FAIL) [holiday_example] break=on-false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->global-intercept] continue=false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [global-intercept] destination_number(60022) =~ /^886$/ break=on-false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->group-intercept] continue=false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [group-intercept] destination_number(60022) =~ /^\*8$/ break=on-false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->intercept-ext] continue=false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [intercept-ext] destination_number(60022) =~ /^\*\*(\d+)$/ break=on-false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->redial] continue=false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [redial] destination_number(60022) =~ /^(redial|870)$/ break=on-false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->global] continue=true </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (PASS) [global] ${switch_r_sdp}(v=0 </div><div>o=- 3577252345 3577252345 IN IP4 141.196.174.60 </div>
<div>s=pjmedia </div><div>c=IN IP4 141.196.174.60 </div>
<div>t=0 0 </div><div>m=audio 4010 RTP/AVP 8 0 3 101 </div>
<div>c=IN IP4 141.196.174.60 </div><div>a=rtpmap:8 PCMA/8000 </div>
<div>a=rtpmap:0 PCMU/8000 </div><div>a=rtpmap:3 GSM/8000 </div>
<div>a=rtpmap:101 telephone-event/8000 </div><div>a=fmtp:101 0-15 </div>
<div>a=rtcp:4011 IN IP4 192.168.43.10 </div><div>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fig56WojEoKmN07gnvdJZ9Mk6lznskMJszpBOqik </div>
<div>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:HUiy486/260zwSkQ0Z771fKC+g48P9cYEXNqlEYO </div><div>) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action set(sip_secure_media=true) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action export(sip_secure_media=true) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Absolute Condition [global] </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action hash(insert/${domain_name}-last_dial/global/${uuid}) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->snom-demo-2] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [snom-demo-2] destination_number(60022) =~ /^9001$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->snom-demo-1] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [snom-demo-1] destination_number(60022) =~ /^9000$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->eavesdrop] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [eavesdrop] destination_number(60022) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->eavesdrop] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [eavesdrop] destination_number(60022) =~ /^779$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->call_return] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [call_return] destination_number(60022) =~ /^\*69$|^869$|^lcr$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->del-group] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [del-group] destination_number(60022) =~ /^80(\d{2})$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->add-group] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [add-group] destination_number(60022) =~ /^81(\d{2})$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->call-group-simo] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [call-group-simo] destination_number(60022) =~ /^82(\d{2})$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->call-group-order] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [call-group-order] destination_number(60022) =~ /^83(\d{2})$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->extension-intercom] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Regex (FAIL) [extension-intercom] destination_number(60022) =~ /^8(10[01][0-9])$/ break=on-false </div><div>Dialplan: sofia/internal/60021@my.server.ip.address parsing [default->Local_Extension] continue=false </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address 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 </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action export(dialed_extension=60022) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action bind_meta_app(1 b s execute_extension::dx XML features) </div>
<div>Dialplan: sofia/internal/60021@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) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action bind_meta_app(3 b s execute_extension::cf XML features) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action bind_meta_app(4 b s execute_extension::att_xfer XML features) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action set(ringback=${us-ring}) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action set(transfer_ringback=local_stream://moh) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action set(call_timeout=30) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action set(hangup_after_bridge=true) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action set(continue_on_fail=true) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid}) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action hash(insert/${domain_name}-last_dial_ext/global/${uuid}) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action bridge(user/${dialed_extension}@${domain_name}) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action answer() </div><div>Dialplan: sofia/internal/60021@my.server.ip.address Action sleep(1000) </div>
<div>Dialplan: sofia/internal/60021@my.server.ip.address Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension}) </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/60021@my.server.ip.address) State Change CS_ROUTING -> CS_EXECUTE </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60021@my.server.ip.address [BREAK] </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/60021@my.server.ip.address) State ROUTING going to sleep </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/60021@my.server.ip.address) Running State Change CS_EXECUTE </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/60021@my.server.ip.address) State EXECUTE </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:242 sofia/internal/60021@my.server.ip.address SOFIA EXECUTE </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_core_state_machine.c:209 sofia/internal/60021@my.server.ip.address Standard EXECUTE </div>
<div>EXECUTE sofia/internal/60021@my.server.ip.address set(sip_secure_media=true) </div><div>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 sofia/internal/60021@my.server.ip.address SET [sip_secure_media]=[true] </div>
<div>EXECUTE sofia/internal/60021@my.server.ip.address export(sip_secure_media=true) </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [sip_secure_media]=[true] </div>
<div>EXECUTE sofia/internal/60021@my.server.ip.address hash(insert/my.server.ip.address-spymap/60021/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) </div><div>EXECUTE sofia/internal/60021@my.server.ip.address hash(insert/my.server.ip.address-last_dial/60021/60022) </div>
<div>EXECUTE sofia/internal/60021@my.server.ip.address hash(insert/my.server.ip.address-last_dial/global/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) </div><div>EXECUTE sofia/internal/60021@my.server.ip.address export(RFC2822_DATE=Sat, 11 May 2013 12:14:14 +0300) </div>
<div>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] </div><div>EXECUTE sofia/internal/60021@my.server.ip.address export(dialed_extension=60022) </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [dialed_extension]=[60022] </div><div>EXECUTE sofia/internal/60021@my.server.ip.address bind_meta_app(1 b s execute_extension::dx XML features) </div>
<div>2013-05-11 12:14:14.800439 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *1 execute_extension::dx XML features</div><div>EXECUTE sofia/internal/60021@my.server.ip.address bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/60021.2013-05-11-12-14-14.wav)</div>
<div>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</div><div>EXECUTE sofia/internal/60021@my.server.ip.address bind_meta_app(3 b s execute_extension::cf XML features)</div>
<div>2013-05-11 12:14:14.800439 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *3 execute_extension::cf XML features</div><div>EXECUTE sofia/internal/60021@my.server.ip.address bind_meta_app(4 b s execute_extension::att_xfer XML features)</div>
<div>2013-05-11 12:14:14.800439 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *4 execute_extension::att_xfer XML features</div><div>EXECUTE sofia/internal/60021@my.server.ip.address set(ringback=%(2000,4000,440,480))</div><div>
2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 sofia/internal/60021@my.server.ip.address SET [ringback]=[%(2000,4000,440,480)] </div><div>EXECUTE sofia/internal/60021@my.server.ip.address set(transfer_ringback=local_stream://moh) </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 sofia/internal/60021@my.server.ip.address SET [transfer_ringback]=[local_stream://moh] </div><div>EXECUTE sofia/internal/60021@my.server.ip.address set(call_timeout=30) </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 sofia/internal/60021@my.server.ip.address SET [call_timeout]=[30] </div><div>EXECUTE sofia/internal/60021@my.server.ip.address set(hangup_after_bridge=true) </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 sofia/internal/60021@my.server.ip.address SET [hangup_after_bridge]=[true] </div><div>EXECUTE sofia/internal/60021@my.server.ip.address set(continue_on_fail=true) </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 sofia/internal/60021@my.server.ip.address SET [continue_on_fail]=[true] </div><div>EXECUTE sofia/internal/60021@my.server.ip.address hash(insert/my.server.ip.address-call_return/60022/60021) </div>
<div>EXECUTE sofia/internal/60021@my.server.ip.address hash(insert/my.server.ip.address-last_dial_ext/60022/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) </div><div>EXECUTE sofia/internal/60021@my.server.ip.address set(called_party_callgroup=techsupport) </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] mod_dptools.c:1349 sofia/internal/60021@my.server.ip.address SET [called_party_callgroup]=[techsupport] </div><div>EXECUTE sofia/internal/60021@my.server.ip.address hash(insert/my.server.ip.address-last_dial_ext/techsupport/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) </div>
<div>EXECUTE sofia/internal/60021@my.server.ip.address hash(insert/my.server.ip.address-last_dial_ext/global/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) </div><div>EXECUTE sofia/internal/60021@my.server.ip.address hash(insert/my.server.ip.address-last_dial/techsupport/26730cd2-ba1b-11e2-acc5-bda7cbfd9554) </div>
<div>EXECUTE sofia/internal/60021@my.server.ip.address bridge(user/60022@my.server.ip.address) </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 sofia/internal/60021@my.server.ip.address EXPORTING[export_vars] [sip_secure_media]=[true] to event </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 sofia/internal/60021@my.server.ip.address EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 11 May 2013 12:14:14 +0300] to event </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 sofia/internal/60021@my.server.ip.address EXPORTING[export_vars] [dialed_extension]=[60022] to event </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 sofia/internal/60021@my.server.ip.address EXPORTING[export_vars] [sip_secure_media]=[true] to event </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 sofia/internal/60021@my.server.ip.address EXPORTING[export_vars] [RFC2822_DATE]=[Sat, 11 May 2013 12:14:14 +0300] to event </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_channel.c:1089 sofia/internal/60021@my.server.ip.address EXPORTING[export_vars] [dialed_extension]=[60022] to event </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] switch_event.c:1608 Parsing variable [sip_invite_domain]=[my.server.ip.address] </div><div>2013-05-11 12:14:14.800439 [DEBUG] switch_event.c:1608 Parsing variable [presence_id]=[60022@my.server.ip.address] </div>
<div>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]</div><div>
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</div><div>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] </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] mod_sofia.c:5031 [zrtp_passthru] Setting a-leg inherit_codec=true </div><div>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' </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>2013-05-11 12:14:14.800439 [DEBUG] sofia_glue.c:3157 Set Local Key [1 AES_CM_128_HMAC_SHA1_32 inline:jy7Mnu44PUrnS4nFSUGkaIsFNftmZRTnE61m4sui] </div><div>2013-05-11 12:14:14.800439 [DEBUG] sofia_glue.c:2649 Local SDP: </div>
<div>v=0 </div><div>o=FreeSWITCH 1368242802 1368242803 IN IP4 my.server.ip.address </div>
<div>s=FreeSWITCH </div><div>c=IN IP4 my.server.ip.address </div>
<div>t=0 0 </div><div>m=audio 20852 RTP/SAVP 8 0 3 101 13 </div>
<div>a=rtpmap:101 telephone-event/8000 </div><div>a=fmtp:101 0-16 </div>
<div>a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:jy7Mnu44PUrnS4nFSUGkaIsFNftmZRTnE61m4sui </div><div>a=ptime:20 </div>
<div>a=sendrecv </div><div>m=audio 20852 RTP/AVP 8 0 3 101 13 </div>
<div>a=rtpmap:101 telephone-event/8000 </div><div>a=fmtp:101 0-16 </div>
<div>a=ptime:20 </div><div>a=sendrecv </div>
<div><br></div><div>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 </div>
<div>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] </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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] </div>
<div>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 </div>
<div>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] </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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] </div>
<div>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] </div>
<div>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] </div>
<div>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] </div>
<div>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>!</div><div>2013-05-11 12:14:14.980444 [INFO] switch_ivr_originate.c:1185 Sending early media</div>
<div>2013-05-11 12:14:14.980444 [ERR] sofia_glue.c:4927 a=crypto in RTP/AVP, refer to rfc3711</div><div>2013-05-11 12:14:14.980444 [ERR] mod_sofia.c:2789 CODEC NEGOTIATION ERROR. SDP: </div>
<div>v=0 </div><div>o=- 3577252345 3577252345 IN IP4 141.196.174.60 </div>
<div>s=pjmedia </div><div>c=IN IP4 141.196.174.60 </div>
<div>t=0 0 </div><div>m=audio 4010 RTP/AVP 8 0 3 101 </div>
<div>c=IN IP4 141.196.174.60 </div><div>a=rtpmap:8 PCMA/8000 </div>
<div>a=rtpmap:0 PCMU/8000 </div><div>a=rtpmap:3 GSM/8000 </div>
<div>a=rtpmap:101 telephone-event/8000 </div><div>a=fmtp:101 0-15 </div>
<div>a=rtcp:4011 IN IP4 192.168.43.10 </div><div>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fig56WojEoKmN07gnvdJZ9Mk6lznskMJszpBOqik </div>
<div>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:HUiy486/260zwSkQ0Z771fKC+g48P9cYEXNqlEYO </div><div><br></div><div>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:830 Send signal sofia/internal/60021@my.server.ip.address [BREAK]</div>
<div>2013-05-11 12:14:14.980444 [DEBUG] switch_channel.c:2994 (sofia/internal/60021@my.server.ip.address) Callstate Change RINGING -> HANGUP </div><div>2013-05-11 12:14:14.980444 [NOTICE] switch_channel.c:3216 Hangup sofia/internal/60021@my.server.ip.address [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]</div>
<div>2013-05-11 12:14:14.980444 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/60021@my.server.ip.address [KILL]</div><div>2013-05-11 12:14:14.980444 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60021@my.server.ip.address [BREAK] </div>
<div>2013-05-11 12:14:14.980444 [DEBUG] switch_ivr_originate.c:1186 sofia/internal/60021@my.server.ip.address Media Establishment Failed. </div><div>2013-05-11 12:14:14.980444 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL] </div>
<div>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 </div>
<div>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]</div><div>
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</div><div>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] </div>
<div>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] </div>
<div>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 </div>
<div>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 </div>
<div>2013-05-11 12:14:14.980444 [NOTICE] switch_ivr_originate.c:2608 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL]</div><div>2013-05-11 12:14:14.980444 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]</div>
<div>2013-05-11 12:14:14.980444 [INFO] mod_dptools.c:3060 Originate Failed. Cause: ORIGINATOR_CANCEL</div><div>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></div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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] </div>
<div>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) </div>
<div>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 </div><div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>2013-05-11 12:14:14.980444 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/60021@my.server.ip.address) State HANGUP </div><div>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 </div>
<div>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 </div>
<div>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] </div>
<div>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 </div>
<div>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</div><div>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] </div>
<div>2013-05-11 12:14:15.000444 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 488</div><div>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 </div>
<div>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 </div><div>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 </div>
<div>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 </div><div>2013-05-11 12:14:15.000444 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60021@my.server.ip.address [BREAK]</div>
<div>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</div><div>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</div>
<div>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/60021@my.server.ip.address) State REPORTING</div><div>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</div>
<div>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</div><div>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</div>
<div>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</div><div>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</div>
<div>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</div><div>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</div>
<div>2013-05-11 12:14:15.000444 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60021@my.server.ip.address [BREAK]</div><div>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</div>
<div>2013-05-11 12:14:15.000444 [NOTICE] switch_core_session.c:1517 Session 22 (sofia/internal/60021@my.server.ip.address) Ended</div><div>2013-05-11 12:14:15.000444 [NOTICE] switch_core_session.c:1521 Close Channel sofia/internal/60021@my.server.ip.address [CS_DESTROY]</div>
<div>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</div><div>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</div>
<div>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/60021@my.server.ip.address) State DESTROY</div><div>2013-05-11 12:14:15.000444 [DEBUG] mod_sofia.c:396 sofia/internal/60021@my.server.ip.address SOFIA DESTROY</div>
<div>2013-05-11 12:14:15.000444 [DEBUG] switch_core_state_machine.c:99 sofia/internal/60021@my.server.ip.address Standard DESTROY</div><div>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</div>
<div>2013-05-11 12:29:03.100449 [DEBUG] sofia_reg.c:1511 Send challenge for [60022@my.server.ip.address]</div><div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true'</div>
<div>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>'</div><div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918'</div>
<div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator'</div><div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local'</div>
<div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60022'</div><div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default'</div>
<div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60022'</div><div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60022'</div>
<div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH'</div><div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000'</div>
<div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport'</div><div>2013-05-11 12:29:05.900434 [DEBUG] sofia_reg.c:1683 Register:</div><div>From: [60022@my.server.ip.address]</div>
<div>Contact: ["user" <sip:60022@141.196.174.60:33822;ob>]</div><div>Expires: [900]</div><div><br></div><div><br></div><div><br></div><div>third test</div><div><br></div><div>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]</div>
<div>2013-05-11 14:08:17.980434 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK]</div><div>2013-05-11 14:08:17.980434 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK] </div>
<div>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 </div><div>2013-05-11 14:08:17.980434 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/60022@my.server.ip.address) State NEW </div>
<div>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. </div><div>2013-05-11 14:08:18.000438 [DEBUG] sofia_reg.c:1511 Send challenge for [60021@my.server.ip.address] </div>
<div>2013-05-11 14:08:18.000438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK] </div><div>2013-05-11 14:08:18.000438 [DEBUG] sofia.c:1719 detaching session 156488c0-ba2b-11e2-ad18-bda7cbfd9554 </div>
<div>2013-05-11 14:08:18.120438 [DEBUG] sofia.c:1811 Re-attaching to session 156488c0-ba2b-11e2-ad18-bda7cbfd9554 </div><div>2013-05-11 14:08:18.120438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK] </div>
<div>2013-05-11 14:08:18.120438 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/60022@my.server.ip.address [BREAK] </div><div>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. </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'record_stereo' = 'true' </div><div>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>' </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'default_areacode' = '918' </div><div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'transfer_fallback_extension' = 'operator' </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'toll_allow' = 'domestic,international,local' </div><div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'accountcode' = '60022' </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'user_context' = 'default' </div><div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_name' = 'Extension 60022' </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'effective_caller_id_number' = '60022' </div><div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_name' = 'FreeSWITCH' </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'outbound_caller_id_number' = '0000000000' </div><div>2013-05-11 14:08:18.140456 [DEBUG] sofia_reg.c:2767 event_add_header -> 'callgroup' = 'techsupport' </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] sofia.c:5578 Channel sofia/internal/60022@my.server.ip.address entering state [received][100] </div><div>2013-05-11 14:08:18.140456 [DEBUG] sofia.c:5589 Remote SDP: </div>
<div>v=0 </div><div>o=- 3577259178 3577259178 IN IP4 141.196.174.60 </div>
<div>s=pjmedia </div><div>c=IN IP4 141.196.174.60 </div>
<div>t=0 0 </div><div>m=audio 4000 RTP/AVP 97 101 </div>
<div>c=IN IP4 141.196.174.60 </div><div>a=rtpmap:97 SILK/16000 </div>
<div>a=fmtp:97 useinbandfec=0 </div><div>a=rtpmap:101 telephone-event/8000 </div>
<div>a=fmtp:101 0-15 </div><div>a=rtcp:4001 IN IP4 192.168.43.193 </div>
<div>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:+8RV0fxAu+3s1Fc8BQxiMa9ras5u/JDmQ9uCVagu </div><div>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:CQ25iu0Z418+mKdV+nRcwXqkd5z+gUxuXsqQt40P </div>
<div><br></div><div>2013-05-11 14:08:18.140456 [DEBUG] sofia.c:5802 (sofia/internal/60022@my.server.ip.address) State Change CS_NEW -> CS_INIT </div><div>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK] </div>
<div>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 </div><div>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/60022@my.server.ip.address) State INIT </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:86 sofia/internal/60022@my.server.ip.address SOFIA INIT </div><div>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 </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK] </div><div>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 </div>
<div>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 </div><div>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:2003 (sofia/internal/60022@my.server.ip.address) Callstate Change DOWN -> RINGING </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/60022@my.server.ip.address) State ROUTING </div><div>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:149 sofia/internal/60022@my.server.ip.address SOFIA ROUTING </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:117 sofia/internal/60022@my.server.ip.address Standard ROUTING </div><div>2013-05-11 14:08:18.140456 [INFO] mod_dialplan_xml.c:557 Processing 60022 <60022>->60021 in context default</div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->unloop] continue=false</div><div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->tod_example] continue=true </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Date/TimeMatch (FAIL) [tod_example] break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->holiday_example] continue=true </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Date/TimeMatch (FAIL) [holiday_example] break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->global-intercept] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [global-intercept] destination_number(60021) =~ /^886$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->group-intercept] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [group-intercept] destination_number(60021) =~ /^\*8$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->intercept-ext] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [intercept-ext] destination_number(60021) =~ /^\*\*(\d+)$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->redial] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [redial] destination_number(60021) =~ /^(redial|870)$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->global] continue=true </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never </div><div>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 </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (PASS) [global] ${switch_r_sdp}(v=0 </div>
<div>o=- 3577259178 3577259178 IN IP4 141.196.174.60 </div><div>s=pjmedia </div>
<div>c=IN IP4 141.196.174.60 </div><div>t=0 0 </div>
<div>m=audio 4000 RTP/AVP 97 101 </div><div>c=IN IP4 141.196.174.60 </div>
<div>a=rtpmap:97 SILK/16000 </div><div>a=fmtp:97 useinbandfec=0 </div>
<div>a=rtpmap:101 telephone-event/8000 </div><div>a=fmtp:101 0-15 </div>
<div>a=rtcp:4001 IN IP4 192.168.43.193 </div><div>a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:+8RV0fxAu+3s1Fc8BQxiMa9ras5u/JDmQ9uCVagu </div>
<div>a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:CQ25iu0Z418+mKdV+nRcwXqkd5z+gUxuXsqQt40P </div><div>) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action set(sip_secure_media=true) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action export(sip_secure_media=true) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Absolute Condition [global] </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial/global/${uuid}) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->snom-demo-2] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [snom-demo-2] destination_number(60021) =~ /^9001$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->snom-demo-1] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [snom-demo-1] destination_number(60021) =~ /^9000$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->eavesdrop] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [eavesdrop] destination_number(60021) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->eavesdrop] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [eavesdrop] destination_number(60021) =~ /^779$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->call_return] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [call_return] destination_number(60021) =~ /^\*69$|^869$|^lcr$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->del-group] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [del-group] destination_number(60021) =~ /^80(\d{2})$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->add-group] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [add-group] destination_number(60021) =~ /^81(\d{2})$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->call-group-simo] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [call-group-simo] destination_number(60021) =~ /^82(\d{2})$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->call-group-order] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [call-group-order] destination_number(60021) =~ /^83(\d{2})$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->extension-intercom] continue=false </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Regex (FAIL) [extension-intercom] destination_number(60021) =~ /^8(10[01][0-9])$/ break=on-false </div><div>Dialplan: sofia/internal/60022@my.server.ip.address parsing [default->Local_Extension] continue=false </div>
<div>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 </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action export(dialed_extension=60021) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action bind_meta_app(1 b s execute_extension::dx XML features) </div>
<div>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) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action bind_meta_app(3 b s execute_extension::cf XML features) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action bind_meta_app(4 b s execute_extension::att_xfer XML features) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action set(ringback=${us-ring}) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action set(transfer_ringback=local_stream://moh) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action set(call_timeout=30) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action set(hangup_after_bridge=true) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action set(continue_on_fail=true) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid}) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial_ext/global/${uuid}) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action bridge(user/${dialed_extension}@${domain_name}) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action answer() </div><div>Dialplan: sofia/internal/60022@my.server.ip.address Action sleep(1000) </div>
<div>Dialplan: sofia/internal/60022@my.server.ip.address Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension}) </div><div>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 </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK] </div><div>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 </div>
<div>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 </div><div>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/60022@my.server.ip.address) State EXECUTE </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:242 sofia/internal/60022@my.server.ip.address SOFIA EXECUTE </div><div>2013-05-11 14:08:18.140456 [DEBUG] switch_core_state_machine.c:209 sofia/internal/60022@my.server.ip.address Standard EXECUTE </div>
<div>EXECUTE sofia/internal/60022@my.server.ip.address set(sip_secure_media=true) </div><div>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [sip_secure_media]=[true] </div>
<div>EXECUTE sofia/internal/60022@my.server.ip.address export(sip_secure_media=true) </div><div>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [sip_secure_media]=[true] </div>
<div>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-spymap/60022/156488c0-ba2b-11e2-ad18-bda7cbfd9554) </div><div>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial/60022/60021) </div>
<div>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial/global/156488c0-ba2b-11e2-ad18-bda7cbfd9554) </div><div>EXECUTE sofia/internal/60022@my.server.ip.address export(RFC2822_DATE=Sat, 11 May 2013 14:08:18 +0300) </div>
<div>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] </div><div>EXECUTE sofia/internal/60022@my.server.ip.address export(dialed_extension=60021) </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [dialed_extension]=[60021] </div><div>EXECUTE sofia/internal/60022@my.server.ip.address bind_meta_app(1 b s execute_extension::dx XML features) </div>
<div>2013-05-11 14:08:18.140456 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *1 execute_extension::dx XML features</div><div>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)</div>
<div>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</div><div>EXECUTE sofia/internal/60022@my.server.ip.address bind_meta_app(3 b s execute_extension::cf XML features)</div>
<div>2013-05-11 14:08:18.140456 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *3 execute_extension::cf XML features</div><div>EXECUTE sofia/internal/60022@my.server.ip.address bind_meta_app(4 b s execute_extension::att_xfer XML features)</div>
<div>2013-05-11 14:08:18.140456 [INFO] switch_ivr_async.c:3409 Bound B-Leg: *4 execute_extension::att_xfer XML features</div><div>EXECUTE sofia/internal/60022@my.server.ip.address set(ringback=%(2000,4000,440,480))</div><div>
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)] </div><div>EXECUTE sofia/internal/60022@my.server.ip.address set(transfer_ringback=local_stream://moh) </div>
<div>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] </div><div>EXECUTE sofia/internal/60022@my.server.ip.address set(call_timeout=30) </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [call_timeout]=[30] </div><div>EXECUTE sofia/internal/60022@my.server.ip.address set(hangup_after_bridge=true) </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [hangup_after_bridge]=[true] </div><div>EXECUTE sofia/internal/60022@my.server.ip.address set(continue_on_fail=true) </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [continue_on_fail]=[true] </div><div>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-call_return/60021/60022) </div>
<div>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial_ext/60021/156488c0-ba2b-11e2-ad18-bda7cbfd9554) </div><div>EXECUTE sofia/internal/60022@my.server.ip.address set(called_party_callgroup=techsupport) </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] mod_dptools.c:1349 sofia/internal/60022@my.server.ip.address SET [called_party_callgroup]=[techsupport] </div><div>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial_ext/techsupport/156488c0-ba2b-11e2-ad18-bda7cbfd9554) </div>
<div>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial_ext/global/156488c0-ba2b-11e2-ad18-bda7cbfd9554) </div><div>EXECUTE sofia/internal/60022@my.server.ip.address hash(insert/my.server.ip.address-last_dial/techsupport/156488c0-ba2b-11e2-ad18-bda7cbfd9554) </div>
<div>EXECUTE sofia/internal/60022@my.server.ip.address bridge(user/60021@my.server.ip.address) </div><div>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 </div>
<div>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 </div>
<div>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 </div><div>2013-05-11 14:08:18.140456 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables </div>
<div>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 </div><div>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 </div>
<div>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 </div><div>2013-05-11 14:08:18.140456 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] switch_event.c:1608 Parsing variable [sip_invite_domain]=[my.server.ip.address] </div><div>2013-05-11 14:08:18.140456 [DEBUG] switch_event.c:1608 Parsing variable [presence_id]=[60021@my.server.ip.address] </div>
<div>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]</div><div>
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</div><div>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] </div>
<div>2013-05-11 14:08:18.140456 [DEBUG] mod_sofia.c:5031 [zrtp_passthru] Setting a-leg inherit_codec=true </div><div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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] </div><div>2013-05-11 14:08:18.140456 [DEBUG] sofia_glue.c:2649 Local SDP: </div>
<div>v=0 </div><div>o=FreeSWITCH 1368243506 1368243507 IN IP4 my.server.ip.address </div>
<div>s=FreeSWITCH </div><div>c=IN IP4 my.server.ip.address </div>
<div>t=0 0 </div><div>m=audio 26992 RTP/SAVP 9 0 8 3 101 13 </div>
<div>a=rtpmap:101 telephone-event/8000 </div><div>a=fmtp:101 0-16 </div>
<div>a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:FGwgO9qNK7dbHa/ZYQcA2fWT17ktjsvjEt5fYXf4 </div><div>a=ptime:20 </div>
<div>a=sendrecv </div><div>m=audio 26992 RTP/AVP 9 0 8 3 101 13 </div>
<div>a=rtpmap:101 telephone-event/8000 </div><div>a=fmtp:101 0-16 </div>
<div>a=ptime:20 </div><div>a=sendrecv </div>
<div><br></div><div>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 </div>
<div>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] </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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] </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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] </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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] </div>
<div>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] </div>
<div>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] </div>
<div>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] </div>
<div>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] </div>
<div>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 </div>
<div>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]</div><div>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]</div>
<div>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] </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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] </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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 </div>
<div>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] </div>
<div>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 </div>
<div>2013-05-11 14:08:18.340438 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED] </div><div>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</div>
<div>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] </div>
<div>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</div><div>2013-05-11 14:08:18.340438 [NOTICE] switch_ivr_originate.c:2608 Cannot create outgoing channel of type [user] cause: [SERVICE_NOT_IMPLEMENTED]</div>
<div>2013-05-11 14:08:18.340438 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED]</div><div>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 </div>
<div>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 </div>
<div>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</div><div>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</div>
<div>2013-05-11 14:08:18.340438 [INFO] mod_dptools.c:3060 Originate Failed. Cause: SERVICE_NOT_IMPLEMENTED</div><div>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</div>
<div>EXECUTE sofia/internal/60022@my.server.ip.address answer()</div><div>2013-05-11 14:08:18.340438 [ERR] sofia_glue.c:4927 a=crypto in RTP/AVP, refer to rfc3711</div><div>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:830 Send signal sofia/internal/60022@my.server.ip.address [BREAK]</div>
<div>2013-05-11 14:08:18.340438 [DEBUG] switch_channel.c:2994 (sofia/internal/60022@my.server.ip.address) Callstate Change RINGING -> HANGUP</div><div>2013-05-11 14:08:18.340438 [NOTICE] switch_channel.c:3484 Hangup sofia/internal/60022@my.server.ip.address [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]</div>
<div>2013-05-11 14:08:18.340438 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/60022@my.server.ip.address [KILL]</div><div>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK]</div>
<div>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)</div><div>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</div>
<div>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</div><div>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/60022@my.server.ip.address) State HANGUP</div>
<div>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</div><div>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</div>
<div>2013-05-11 14:08:18.340438 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 406</div><div>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</div>
<div>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</div><div>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</div>
<div>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK]</div><div>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</div>
<div>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/60022@my.server.ip.address) State REPORTING</div><div>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</div>
<div>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</div><div>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</div>
<div>2013-05-11 14:08:18.340438 [DEBUG] switch_core_session.c:1291 Send signal sofia/internal/60022@my.server.ip.address [BREAK]</div><div>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</div>
<div>2013-05-11 14:08:18.340438 [NOTICE] switch_core_session.c:1517 Session 26 (sofia/internal/60022@my.server.ip.address) Ended</div><div>2013-05-11 14:08:18.340438 [NOTICE] switch_core_session.c:1521 Close Channel sofia/internal/60022@my.server.ip.address [CS_DESTROY]</div>
<div>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</div><div>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</div>
<div>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/60022@my.server.ip.address) State DESTROY</div><div>2013-05-11 14:08:18.340438 [DEBUG] mod_sofia.c:396 sofia/internal/60022@my.server.ip.address SOFIA DESTROY</div>
<div>2013-05-11 14:08:18.340438 [DEBUG] switch_core_state_machine.c:99 sofia/internal/60022@my.server.ip.address Standard DESTROY</div><div>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</div>
</div><div class="gmail_extra"><br><br><div class="gmail_quote">2013/5/10 Steven Ayre <span dir="ltr"><<a href="mailto:steveayre@gmail.com" target="_blank">steveayre@gmail.com</a>></span><br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
Are you using the default dialplan?<div><br></div><div>Chances are your destination_number condition's regex for the extension that calls users is limited to 4 digits.</div><div><br></div><div>Eg</div><div>\d\d\d\d</div>
<div>\d{4}</div><div><br></div><div>-Steve</div><div><br></div><div><br><br><div class="gmail_quote"><div><div class="h5">On 10 May 2013 12:30, Burak BorYazılım <span dir="ltr"><<a href="mailto:mburakbor@gmail.com" target="_blank">mburakbor@gmail.com</a>></span> wrote:<br>
</div></div><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div><div class="h5"><div dir="ltr">Hello,<div><br><div>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.</div>
<div><br></div><div>Thank you...</div><span><font color="#888888"><div>Burak,</div></font></span></div></div>
<br></div></div><div class="im">_________________________________________________________________________<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><br>
<br></div></blockquote></div><br></div>
<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><br>
<br></blockquote></div><br></div>