<HTML>
<HEAD>
<TITLE>Re: [Freeswitch-dev] :how to let the version 2.1.3 as well as the latest (from git) work for the 0911 and 5001 dialplan? there is no problem with version 1.06</TITLE>
</HEAD>
<BODY>
<FONT FACE="Monaco, Courier New"><SPAN STYLE='font-size:11pt'>Did you update your configs? 1.0.6 is so old and has more issues then I can count... And configs have been update to resolve problems...<BR>
<BR>
<BR>
On 9/27/12 2:02 AM, "zhi sun" <<a href="sunzhimailbox@gmail.com">sunzhimailbox@gmail.com</a>> wrote:<BR>
<BR>
</SPAN></FONT><BLOCKQUOTE><FONT FACE="Monaco, Courier New"><SPAN STYLE='font-size:11pt'>further testing results:<BR>
<BR>
- for version 1.0.6, the 0911 works, but 5001 doesn't.<BR>
<BR>
- for version 1.2.3 and 1.3, both 0911 and 5001 doesn't work.<BR>
<BR>
the following are logs for 0911, version 1.2.3<BR>
<BR>
the 1000,1001,1002,1003 should be called!!!!<BR>
<BR>
==============================================<BR>
2012-09-27 14:58:47.233466 [NOTICE] switch_channel.c:951 New Channel <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> [c8c8f364-0870-11e2-bd7f-df6c04573b9b]<BR>
2012-09-27 14:58:47.273467 [INFO] mod_dialplan_xml.c:485 Processing 1001 <1001>->0911 in context default<BR>
2012-09-27 14:58:47.293472 [INFO] switch_core_session.c:2392 Sending early media<BR>
2012-09-27 14:58:47.293472 [NOTICE] sofia_glue.c:4226 Pre-Answer <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a>!<BR>
2012-09-27 14:58:47.293472 [NOTICE] mod_conference.c:7211 Channel [<a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a>] has been answered<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8315 using channel sound prefix: /home/sunzhi/sunzhi/freeswitch/freeswitch-1.2.3/rel/sounds/en/us/callie<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '0' to 'mute'<BR>
2012-09-27 14:58:47.293472 [INFO] switch_ivr_async.c:194 Digit parser mod_conference: Setting realm to 'conf'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '*' to 'deaf mute'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '9' to 'energy up'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '8' to 'energy equ'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '7' to 'energy dn'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '3' to 'vol talk up'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '2' to 'vol talk zero'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '1' to 'vol talk dn'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '6' to 'vol listen up'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '5' to 'vol listen zero'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '4' to 'vol listen dn'<BR>
2012-09-27 14:58:47.293472 [INFO] mod_conference.c:8869 <a href="sofia/internal/1001@192.168.0.100">sofia/internal/1001@192.168.0.100</a> binding '#' to 'hangup'<BR>
2012-09-27 14:58:47.293472 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 14:58:47.293472 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1001@192.168.0.100]error">presence_id=1001@192.168.0.100]error</a><BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
2012-09-27 14:58:47.293472 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 14:58:47.293472 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1000@192.168.0.100]sofia">presence_id=1000@192.168.0.100]sofia</a><BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
2012-09-27 14:58:47.293472 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1002@192.168.0.100]sofia">presence_id=1002@192.168.0.100]sofia</a><BR>
2012-09-27 14:58:47.293472 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1002@192.168.0.100]sofia">presence_id=1002@192.168.0.100]sofia</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 14:58:47.293472 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 14:58:47.293472 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1001@192.168.0.100]error">presence_id=1001@192.168.0.100]error</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 14:58:47.293472 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1003@192.168.0.100]error">presence_id=1003@192.168.0.100]error</a><BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 14:58:47.293472 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1003@192.168.0.100]error">presence_id=1003@192.168.0.100]error</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
2012-09-27 14:58:47.293472 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1000@192.168.0.100]sofia">presence_id=1000@192.168.0.100]sofia</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 14:58:47.293472 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
2012-09-27 14:58:47.293472 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1004@192.168.0.100]error">presence_id=1004@192.168.0.100]error</a><BR>
2012-09-27 14:58:47.293472 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1004@192.168.0.100]error">presence_id=1004@192.168.0.100]error</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 14:58:47.293472 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 14:58:47.293472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
========================================================<BR>
<BR>
<BR>
2012/9/27 zhi sun <<a href="sunzhimailbox@gmail.com">sunzhimailbox@gmail.com</a>><BR>
</SPAN></FONT><BLOCKQUOTE><FONT FACE="Monaco, Courier New"><SPAN STYLE='font-size:11pt'>in addition to previous email, i also try the following:<BR>
<BR>
- add a new diaplan (5002) similar to 5001 in default.xml<BR>
<BR>
<extension name="dynamic_conference"><BR>
<condition field="destination_number" expression="^5002$"><BR>
<action application="conference" data="bridge:mydynaconf:<a href="sofia/${use_profile}/1002@192.168.0.100"/">sofia/${use_profile}/1002@192.168.0.100"/</a>><BR>
</condition><BR>
</extension><BR>
<BR>
- reloadxml<BR>
<BR>
- then call 5002 from 1010 sip client, <BR>
<BR>
- the same problem happens<BR>
<BR>
PS: it works fine on version 1.06<BR>
<BR>
==========================================================<BR>
<a href="freeswitch@mydev.mydomain.com">freeswitch@mydev.mydomain.com</a>> reloadxml<BR>
<BR>
+OK [Success]<BR>
<BR>
2012-09-27 09:46:46.613468 [INFO] mod_enum.c:871 ENUM Reloaded<BR>
2012-09-27 09:46:46.613468 [INFO] switch_time.c:1163 Timezone reloaded 530 definitions<BR>
<a href="freeswitch@mydev.mydomain.com">freeswitch@mydev.mydomain.com</a>> 2012-09-27 09:47:19.413468 [NOTICE] switch_channel.c:951 New Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [45f8ccbe-0845-11e2-b58c-8d63071eb0f5]<BR>
2012-09-27 09:47:19.413468 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.413468 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_NEW<BR>
2012-09-27 09:47:19.413468 [DEBUG] switch_core_state_machine.c:416 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State NEW<BR>
2012-09-27 09:47:19.413468 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.433469 [DEBUG] sofia.c:8412 IP 192.168.0.10 Rejected by acl "domains". Falling back to Digest auth.<BR>
2012-09-27 09:47:19.433469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.433469 [DEBUG] sofia.c:1728 detaching session 45f8ccbe-0845-11e2-b58c-8d63071eb0f5<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia.c:1820 Re-attaching to session 45f8ccbe-0845-11e2-b58c-8d63071eb0f5<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia.c:8412 IP 192.168.0.10 Rejected by acl "domains". Falling back to Digest auth.<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia.c:6282 Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> entering state [received][100]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia.c:6293 Remote SDP:<BR>
v=0<BR>
o=1010 3519 3519 IN IP4 192.168.0.10<BR>
s=Talk<BR>
c=IN IP4 192.168.0.10<BR>
t=0 0<BR>
m=audio 7078 RTP/AVP 112 111 110 3 0 8 101<BR>
a=rtpmap:112 speex/32000<BR>
a=fmtp:112 vbr=on<BR>
a=rtpmap:111 speex/16000<BR>
a=fmtp:111 vbr=on<BR>
a=rtpmap:110 speex/8000<BR>
a=fmtp:110 vbr=on<BR>
a=rtpmap:101 telephone-event/8000<BR>
a=fmtp:101 0-11<BR>
<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia.c:6506 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_NEW -> CS_INIT<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_INIT<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:437 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State INIT<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:86 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SOFIA INIT<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:126 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_INIT -> CS_ROUTING<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:437 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State INIT going to sleep<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_ROUTING<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_channel.c:1964 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change DOWN -> RINGING<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:446 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State ROUTING<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:149 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SOFIA ROUTING<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:117 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard ROUTING<BR>
2012-09-27 09:47:19.453469 [INFO] mod_dialplan_xml.c:485 Processing 1010 <1010>->5002 in context default<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->unloop] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->tod_example] continue=true<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Date/Time Match (PASS) [tod_example] break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action set(open=true) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->holiday_example] continue=true<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Date/TimeMatch (FAIL) [holiday_example] break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->global-intercept] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [global-intercept] destination_number(5002) =~ /^886$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->group-intercept] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [group-intercept] destination_number(5002) =~ /^\*8$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->intercept-ext] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [intercept-ext] destination_number(5002) =~ /^\*\*(\d+)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->redial] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [redial] destination_number(5002) =~ /^(redial|870)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->global] continue=true<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Absolute Condition [global]<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action hash(insert/${domain_name}-last_dial/global/${uuid}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->snom-demo-2] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [snom-demo-2] destination_number(5002) =~ /^9001$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->snom-demo-1] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [snom-demo-1] destination_number(5002) =~ /^9000$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->eavesdrop] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [eavesdrop] destination_number(5002) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->eavesdrop] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [eavesdrop] destination_number(5002) =~ /^779$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->call_return] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [call_return] destination_number(5002) =~ /^\*69$|^869$|^lcr$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->del-group] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [del-group] destination_number(5002) =~ /^80(\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->add-group] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [add-group] destination_number(5002) =~ /^81(\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->call-group-simo] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [call-group-simo] destination_number(5002) =~ /^82(\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->call-group-order] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [call-group-order] destination_number(5002) =~ /^83(\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->extension-intercom] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [extension-intercom] destination_number(5002) =~ /^8(10[01][0-9])$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->Local_Extension] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [Local_Extension] destination_number(5002) =~ /^(10[01][0-9])$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->Local_Extension_Skinny] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [Local_Extension_Skinny] destination_number(5002) =~ /^(11[01][0-9])$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->group_dial_sales] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [group_dial_sales] destination_number(5002) =~ /^2000$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->group_dial_support] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [group_dial_support] destination_number(5002) =~ /^2001$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->group_dial_billing] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [group_dial_billing] destination_number(5002) =~ /^2002$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->operator] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [operator] destination_number(5002) =~ /^(operator|0)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->vmain] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [vmain] destination_number(5002) =~ /^vmain$|^4000$|^\*98$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->sip_uri] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [sip_uri] destination_number(5002) =~ /^sip:(.*)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->nb_conferences] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [nb_conferences] destination_number(5002) =~ /^(30\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->wb_conferences] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [wb_conferences] destination_number(5002) =~ /^(31\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->uwb_conferences] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [uwb_conferences] destination_number(5002) =~ /^(32\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->cdquality_conferences] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [cdquality_conferences] destination_number(5002) =~ /^(33\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->freeswitch_public_conf_via_sip] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(5002) =~ /^9(888|8888|1616|3232)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->mad_boss_intercom] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [mad_boss_intercom] destination_number(5002) =~ /^0911$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->mad_boss_intercom] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [mad_boss_intercom] destination_number(5002) =~ /^0912$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->mad_boss] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [mad_boss] destination_number(5002) =~ /^0913$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->ivr_demo] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [ivr_demo] destination_number(5002) =~ /^5000$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->dynamic_conference] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [dynamic_conference] destination_number(5002) =~ /^5001$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->dynamic_conference] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (PASS) [dynamic_conference] destination_number(5002) =~ /^5002$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action conference(bridge:mydynaconf:<a href="sofia/${use_profile}/1002@192.168.0.100">sofia/${use_profile}/1002@192.168.0.100</a>) <BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:167 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_ROUTING -> CS_EXECUTE<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:446 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State ROUTING going to sleep<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_EXECUTE<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:453 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State EXECUTE<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:242 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SOFIA EXECUTE<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:209 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard EXECUTE<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> set(open=true)<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_dptools.c:1319 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SET [open]=[true]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> hash(insert/192.168.0.100-spymap/1010/45f8ccbe-0845-11e2-b58c-8d63071eb0f5)<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> hash(insert/192.168.0.100-last_dial/1010/5002)<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> hash(insert/192.168.0.100-last_dial/global/45f8ccbe-0845-11e2-b58c-8d63071eb0f5)<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> export(RFC2822_DATE=Thu, 27 Sep 2012 09:47:19 +0800)<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_channel.c:1118 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 27 Sep 2012 09:47:19 +0800]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:2390 Application conference Requires media! pre_answering channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a><BR>
2012-09-27 09:47:19.453469 [INFO] switch_core_session.c:2392 Sending early media<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:112:32000:20:0]/[G722:9:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:112:32000:20:0]/[PCMU:0:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:112:32000:20:0]/[PCMA:8:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:112:32000:20:0]/[GSM:3:8000:20:13200]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:111:16000:20:0]/[G722:9:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:111:16000:20:0]/[PCMU:0:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:111:16000:20:0]/[PCMA:8:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:111:16000:20:0]/[GSM:3:8000:20:13200]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:110:8000:20:0]/[G722:9:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:110:8000:20:0]/[PCMU:0:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:110:8000:20:0]/[PCMA:8:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:110:8000:20:0]/[GSM:3:8000:20:13200]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [GSM:3:8000:20:13200]/[G722:9:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [GSM:3:8000:20:13200]/[PCMU:0:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [GSM:3:8000:20:13200]/[PCMA:8:8000:20:64000]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [GSM:3:8000:20:13200]/[GSM:3:8000:20:13200]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:3077 Set Codec <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> GSM/8000 20 ms 160 samples 13200 bits<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_codec.c:111 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Original read codec set to GSM:3<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:5219 Set 2833 dtmf send/recv payload to 101<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:3327 AUDIO RTP [<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>] 192.168.0.100 port 31092 -> 192.168.0.10 port 7078 codec: 3 ms: 20<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_rtp.c:1927 Starting timer [soft] 160 bytes per 20ms<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:3591 Set 2833 dtmf send payload to 101<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:3597 Set 2833 dtmf receive payload to 101<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:3624 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Set rtp dtmf delay to 40<BR>
2012-09-27 09:47:19.453469 [NOTICE] sofia_glue.c:4226 Pre-Answer <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>!<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_channel.c:3092 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change RINGING -> EARLY<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:2730 Ring SDP:<BR>
v=0<BR>
o=FreeSWITCH 1348679347 1348679348 IN IP4 192.168.0.100<BR>
s=FreeSWITCH<BR>
c=IN IP4 192.168.0.100<BR>
t=0 0<BR>
m=audio 31092 RTP/AVP 3 101<BR>
a=rtpmap:3 GSM/8000<BR>
a=rtpmap:101 telephone-event/8000<BR>
a=fmtp:101 0-16<BR>
a=silenceSupp:off - - - -<BR>
a=ptime:20<BR>
a=sendrecv<BR>
<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:759 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> conference(bridge:mydynaconf:<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>)<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia.c:6282 Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> entering state [early][183]<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:836 Local SDP <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>:<BR>
v=0<BR>
o=FreeSWITCH 1348679347 1348679349 IN IP4 192.168.0.100<BR>
s=FreeSWITCH<BR>
c=IN IP4 192.168.0.100<BR>
t=0 0<BR>
m=audio 31092 RTP/AVP 3 101<BR>
a=rtpmap:3 GSM/8000<BR>
a=rtpmap:101 telephone-event/8000<BR>
a=fmtp:101 0-16<BR>
a=silenceSupp:off - - - -<BR>
a=ptime:20<BR>
a=sendrecv<BR>
<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:759 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_channel.c:3351 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change EARLY -> ACTIVE<BR>
2012-09-27 09:47:19.453469 [NOTICE] mod_conference.c:7211 Channel [<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>] has been answered<BR>
2012-09-27 09:47:19.453469 [INFO] mod_conference.c:8315 using channel sound prefix: /home/sunzhi/sunzhi/freeswitch/freeswitch-1.2.3/rel/sounds/en/us/callie<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_conference.c:1922 Setup timer success interval: 20 samples: 160<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_channel.c:1072 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 27 Sep 2012 09:47:19 +0800] to event<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:47:19.453469 [NOTICE] switch_channel.c:951 New Channel <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [46000434-0845-11e2-b598-8d63071eb0f5]<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:4879 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State Change CS_NEW -> CS_INIT<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:4954 [zrtp_passthru] Setting a-leg inherit_codec=true<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:4957 [zrtp_passthru] Setting b-leg absolute_codec_string='GSM@8000h@20i@13200b,PCMU@8000h@20i@64000b,PCMA@8000h@20i@64000b'<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia.c:6282 Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> entering state [completed][200]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Running State Change CS_INIT<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:437 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State INIT<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:86 <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> SOFIA INIT<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia_glue.c:2637 Local SDP:<BR>
v=0<BR>
o=FreeSWITCH 1348687005 1348687006 IN IP4 192.168.0.100<BR>
s=FreeSWITCH<BR>
c=IN IP4 192.168.0.100<BR>
t=0 0<BR>
m=audio 23434 RTP/AVP 3 0 8 101 13<BR>
a=rtpmap:101 telephone-event/8000<BR>
a=fmtp:101 0-16<BR>
a=ptime:20<BR>
a=sendrecv<BR>
<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:126 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State Change CS_INIT -> CS_ROUTING<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:437 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State INIT going to sleep<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Running State Change CS_ROUTING<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_channel.c:1964 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Callstate Change DOWN -> RINGING<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:446 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State ROUTING<BR>
2012-09-27 09:47:19.453469 [DEBUG] mod_sofia.c:149 <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> SOFIA ROUTING<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_ivr_originate.c:67 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State Change CS_ROUTING -> CS_CONSUME_MEDIA<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:446 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State ROUTING going to sleep<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Running State Change CS_CONSUME_MEDIA<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:465 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State CONSUME_MEDIA<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:465 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State CONSUME_MEDIA going to sleep<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] sofia.c:6282 Channel <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> entering state [calling][0]<BR>
2012-09-27 09:47:19.453469 [NOTICE] switch_channel.c:951 New Channel <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [46006712-0845-11e2-b59c-8d63071eb0f5]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Running State Change CS_NEW<BR>
2012-09-27 09:47:19.453469 [DEBUG] switch_core_state_machine.c:416 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State NEW<BR>
2012-09-27 09:47:19.473478 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [DEBUG] sofia.c:8412 IP 192.168.0.100 Rejected by acl "domains". Falling back to Digest auth.<BR>
2012-09-27 09:47:19.493470 [DEBUG] sofia.c:6282 Channel <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> entering state [received][100]<BR>
2012-09-27 09:47:19.493470 [DEBUG] sofia.c:6293 Remote SDP:<BR>
v=0<BR>
o=FreeSWITCH 1348687005 1348687006 IN IP4 192.168.0.100<BR>
s=FreeSWITCH<BR>
c=IN IP4 192.168.0.100<BR>
t=0 0<BR>
m=audio 23434 RTP/AVP 3 0 8 101 13<BR>
a=rtpmap:101 telephone-event/8000<BR>
a=fmtp:101 0-16<BR>
a=ptime:20<BR>
<BR>
2012-09-27 09:47:19.493470 [DEBUG] sofia.c:6506 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State Change CS_NEW -> CS_INIT<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Running State Change CS_INIT<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:437 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State INIT<BR>
2012-09-27 09:47:19.493470 [DEBUG] mod_sofia.c:86 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> SOFIA INIT<BR>
2012-09-27 09:47:19.493470 [DEBUG] mod_sofia.c:126 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State Change CS_INIT -> CS_ROUTING<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:437 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State INIT going to sleep<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Running State Change CS_ROUTING<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_channel.c:1964 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Callstate Change DOWN -> RINGING<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:446 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State ROUTING<BR>
2012-09-27 09:47:19.493470 [DEBUG] mod_sofia.c:149 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> SOFIA ROUTING<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:117 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> Standard ROUTING<BR>
2012-09-27 09:47:19.493470 [INFO] mod_dialplan_xml.c:485 Processing FreeSWITCH <0000000000>->1002 in context public<BR>
Dialplan: <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> parsing [public->unloop] continue=false<BR>
Dialplan: <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> Regex (PASS) [unloop] ${sip_looped_call}(true) =~ /^true$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> Action deflect(${destination_number}) <BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:167 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State Change CS_ROUTING -> CS_EXECUTE<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:446 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State ROUTING going to sleep<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Running State Change CS_EXECUTE<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:453 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State EXECUTE<BR>
2012-09-27 09:47:19.493470 [DEBUG] mod_sofia.c:242 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> SOFIA EXECUTE<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_state_machine.c:209 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> Standard EXECUTE<BR>
EXECUTE <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> deflect(1002)<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [DEBUG] sofia.c:7308 Process REFER to [<a href="1002@192.168.0.100">1002@192.168.0.100</a>]<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_ivr.c:1742 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_EXECUTE -> CS_ROUTING<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:759 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [NOTICE] switch_ivr.c:1748 Transfer <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> to XML[1002@default]<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.493470 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_channel.c:2950 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Callstate Change RINGING -> HANGUP<BR>
2012-09-27 09:47:19.513472 [NOTICE] switch_ivr_originate.c:3326 Hangup <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_channel.c:2973 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [KILL]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Running State Change CS_HANGUP<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_ivr_originate.c:3502 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]<BR>
2012-09-27 09:47:19.513472 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: ORIGINATOR_CANCEL<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_channel.c:2950 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change ACTIVE -> HANGUP<BR>
2012-09-27 09:47:19.513472 [NOTICE] mod_conference.c:6617 Hangup <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [CS_ROUTING] [ORIGINATOR_CANCEL]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:638 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State HANGUP<BR>
2012-09-27 09:47:19.513472 [DEBUG] mod_sofia.c:483 Channel <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> hanging up, cause: ORIGINATOR_CANCEL<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_channel.c:2973 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [KILL]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:2553 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:453 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State EXECUTE going to sleep<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_HANGUP<BR>
2012-09-27 09:47:19.513472 [DEBUG] mod_sofia.c:542 Sending CANCEL to <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a><BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:48 <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> Standard HANGUP, cause: ORIGINATOR_CANCEL<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:638 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State HANGUP going to sleep<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:429 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State Change CS_HANGUP -> CS_REPORTING<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Running State Change CS_REPORTING<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:703 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State REPORTING<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:92 <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> Standard REPORTING, cause: ORIGINATOR_CANCEL<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:703 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State REPORTING going to sleep<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:423 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State Change CS_REPORTING -> CS_DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:638 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State HANGUP<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:1415 Session 5 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Locked, Waiting on external entities<BR>
2012-09-27 09:47:19.513472 [DEBUG] mod_sofia.c:483 Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> hanging up, cause: ORIGINATOR_CANCEL<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.513472 [DEBUG] mod_sofia.c:532 Sending BYE to <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a><BR>
2012-09-27 09:47:19.513472 [NOTICE] switch_core_session.c:1433 Session 5 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Ended<BR>
2012-09-27 09:47:19.513472 [NOTICE] switch_core_session.c:1437 Close Channel <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> [CS_DESTROY]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:48 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard HANGUP, cause: ORIGINATOR_CANCEL<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:638 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State HANGUP going to sleep<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:429 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_HANGUP -> CS_REPORTING<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_REPORTING<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:703 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State REPORTING<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:527 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Callstate Change HANGUP -> DOWN<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:92 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard REPORTING, cause: ORIGINATOR_CANCEL<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:703 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State REPORTING going to sleep<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:530 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) Running State Change CS_DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:540 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] mod_sofia.c:376 <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> SOFIA DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:99 <a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a> Standard DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:540 (<a href="sofia/internal/1002@192.168.0.100">sofia/internal/1002@192.168.0.100</a>) State DESTROY going to sleep<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:423 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_REPORTING -> CS_DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:1415 Session 4 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Locked, Waiting on external entities<BR>
2012-09-27 09:47:19.513472 [NOTICE] switch_core_session.c:1433 Session 4 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Ended<BR>
2012-09-27 09:47:19.513472 [NOTICE] switch_core_session.c:1437 Close Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [CS_DESTROY]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:527 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change HANGUP -> DOWN<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:530 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:540 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] mod_sofia.c:376 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SOFIA DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:99 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard DESTROY<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_state_machine.c:540 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State DESTROY going to sleep<BR>
2012-09-27 09:47:19.513472 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.533467 [DEBUG] mod_conference.c:2461 Write Lock ON<BR>
2012-09-27 09:47:19.533467 [DEBUG] mod_conference.c:2464 Write Lock OFF<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_channel.c:2950 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Callstate Change RINGING -> HANGUP<BR>
2012-09-27 09:47:19.593467 [NOTICE] sofia.c:420 Hangup <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [CS_EXECUTE] [BLIND_TRANSFER]<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_channel.c:2973 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [KILL]<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_session.c:2553 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:453 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State EXECUTE going to sleep<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Running State Change CS_HANGUP<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:638 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State HANGUP<BR>
2012-09-27 09:47:19.593467 [DEBUG] mod_sofia.c:483 Channel <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> hanging up, cause: BLIND_TRANSFER<BR>
2012-09-27 09:47:19.593467 [DEBUG] mod_sofia.c:613 Responding to INVITE with: 480<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:48 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> Standard HANGUP, cause: BLIND_TRANSFER<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:638 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State HANGUP going to sleep<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:429 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State Change CS_HANGUP -> CS_REPORTING<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Running State Change CS_REPORTING<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:703 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State REPORTING<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:92 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> Standard REPORTING, cause: BLIND_TRANSFER<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:703 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State REPORTING going to sleep<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:423 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State Change CS_REPORTING -> CS_DESTROY<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_session.c:1415 Session 6 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Locked, Waiting on external entities<BR>
2012-09-27 09:47:19.593467 [NOTICE] switch_core_session.c:1433 Session 6 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Ended<BR>
2012-09-27 09:47:19.593467 [NOTICE] switch_core_session.c:1437 Close Channel <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [CS_DESTROY]<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:527 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Callstate Change HANGUP -> DOWN<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:530 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) Running State Change CS_DESTROY<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:540 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State DESTROY<BR>
2012-09-27 09:47:19.593467 [DEBUG] mod_sofia.c:376 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> SOFIA DESTROY<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:99 <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> Standard DESTROY<BR>
2012-09-27 09:47:19.593467 [DEBUG] switch_core_state_machine.c:540 (<a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a>) State DESTROY going to sleep<BR>
<BR>
============================================================================ <BR>
<BR>
<BR>
<BR>
2012/9/27 zhi sun <<a href="sunzhimailbox@gmail.com">sunzhimailbox@gmail.com</a>><BR>
</SPAN></FONT><BLOCKQUOTE><FONT FACE="Monaco, Courier New"><SPAN STYLE='font-size:11pt'>thanks for response, the detailed debug log as below:<BR>
<BR>
version 2.1.3<BR>
<BR>
there are two sip client: 1002, 1010<BR>
<BR>
i can call 1002 from 1010 successfully.<BR>
<BR>
then i try to call 0911 from 1010, as you know the 1002 is a member of sales group, the 0911 will try to out call 1002 according the rule in default.xml<BR>
<BR>
=============================================<BR>
2012-09-27 09:34:07.533467 [NOTICE] switch_channel.c:951 New Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [6dfa8876-0843-11e2-b574-8d63071eb0f5]<BR>
2012-09-27 09:34:07.533467 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.533467 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_NEW<BR>
2012-09-27 09:34:07.533467 [DEBUG] switch_core_state_machine.c:416 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State NEW<BR>
2012-09-27 09:34:07.533467 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.553467 [DEBUG] sofia.c:8412 IP 192.168.0.10 Rejected by acl "domains". Falling back to Digest auth.<BR>
2012-09-27 09:34:07.553467 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.553467 [DEBUG] sofia.c:1728 detaching session 6dfa8876-0843-11e2-b574-8d63071eb0f5<BR>
2012-09-27 09:34:07.573471 [DEBUG] sofia.c:1820 Re-attaching to session 6dfa8876-0843-11e2-b574-8d63071eb0f5<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.573471 [DEBUG] sofia.c:8412 IP 192.168.0.10 Rejected by acl "domains". Falling back to Digest auth.<BR>
2012-09-27 09:34:07.573471 [DEBUG] sofia.c:6282 Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> entering state [received][100]<BR>
2012-09-27 09:34:07.573471 [DEBUG] sofia.c:6293 Remote SDP:<BR>
v=0<BR>
o=1010 3118 3118 IN IP4 192.168.0.10<BR>
s=Talk<BR>
c=IN IP4 192.168.0.10<BR>
t=0 0<BR>
m=audio 7078 RTP/AVP 112 111 110 3 0 8 101<BR>
a=rtpmap:112 speex/32000<BR>
a=fmtp:112 vbr=on<BR>
a=rtpmap:111 speex/16000<BR>
a=fmtp:111 vbr=on<BR>
a=rtpmap:110 speex/8000<BR>
a=fmtp:110 vbr=on<BR>
a=rtpmap:101 telephone-event/8000<BR>
a=fmtp:101 0-11<BR>
<BR>
2012-09-27 09:34:07.573471 [DEBUG] sofia.c:6506 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_NEW -> CS_INIT<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_INIT<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_state_machine.c:437 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State INIT<BR>
2012-09-27 09:34:07.573471 [DEBUG] mod_sofia.c:86 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SOFIA INIT<BR>
2012-09-27 09:34:07.573471 [DEBUG] mod_sofia.c:126 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_INIT -> CS_ROUTING<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_state_machine.c:437 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State INIT going to sleep<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_ROUTING<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_channel.c:1964 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change DOWN -> RINGING<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_state_machine.c:446 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State ROUTING<BR>
2012-09-27 09:34:07.573471 [DEBUG] mod_sofia.c:149 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SOFIA ROUTING<BR>
2012-09-27 09:34:07.573471 [DEBUG] switch_core_state_machine.c:117 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard ROUTING<BR>
2012-09-27 09:34:07.573471 [INFO] mod_dialplan_xml.c:485 Processing 1010 <1010>->0911 in context default<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->unloop] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->tod_example] continue=true<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Date/Time Match (PASS) [tod_example] break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action set(open=true) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->holiday_example] continue=true<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Date/TimeMatch (FAIL) [holiday_example] break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->global-intercept] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [global-intercept] destination_number(0911) =~ /^886$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->group-intercept] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [group-intercept] destination_number(0911) =~ /^\*8$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->intercept-ext] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [intercept-ext] destination_number(0911) =~ /^\*\*(\d+)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->redial] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [redial] destination_number(0911) =~ /^(redial|870)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->global] continue=true<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Absolute Condition [global]<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action hash(insert/${domain_name}-last_dial/global/${uuid}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->snom-demo-2] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [snom-demo-2] destination_number(0911) =~ /^9001$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->snom-demo-1] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [snom-demo-1] destination_number(0911) =~ /^9000$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->eavesdrop] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [eavesdrop] destination_number(0911) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->eavesdrop] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [eavesdrop] destination_number(0911) =~ /^779$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->call_return] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [call_return] destination_number(0911) =~ /^\*69$|^869$|^lcr$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->del-group] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [del-group] destination_number(0911) =~ /^80(\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->add-group] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [add-group] destination_number(0911) =~ /^81(\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->call-group-simo] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [call-group-simo] destination_number(0911) =~ /^82(\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->call-group-order] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [call-group-order] destination_number(0911) =~ /^83(\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->extension-intercom] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [extension-intercom] destination_number(0911) =~ /^8(10[01][0-9])$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->Local_Extension] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [Local_Extension] destination_number(0911) =~ /^(10[01][0-9])$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->Local_Extension_Skinny] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [Local_Extension_Skinny] destination_number(0911) =~ /^(11[01][0-9])$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->group_dial_sales] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [group_dial_sales] destination_number(0911) =~ /^2000$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->group_dial_support] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [group_dial_support] destination_number(0911) =~ /^2001$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->group_dial_billing] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [group_dial_billing] destination_number(0911) =~ /^2002$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->operator] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [operator] destination_number(0911) =~ /^(operator|0)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->vmain] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [vmain] destination_number(0911) =~ /^vmain$|^4000$|^\*98$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->sip_uri] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [sip_uri] destination_number(0911) =~ /^sip:(.*)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->nb_conferences] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [nb_conferences] destination_number(0911) =~ /^(30\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->wb_conferences] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [wb_conferences] destination_number(0911) =~ /^(31\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->uwb_conferences] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [uwb_conferences] destination_number(0911) =~ /^(32\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->cdquality_conferences] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [cdquality_conferences] destination_number(0911) =~ /^(33\d{2})$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->freeswitch_public_conf_via_sip] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(0911) =~ /^9(888|8888|1616|3232)$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> parsing [default->mad_boss_intercom] continue=false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Regex (PASS) [mad_boss_intercom] destination_number(0911) =~ /^0911$/ break=on-false<BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action set(conference_auto_outcall_caller_id_name=Mad Boss1) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action set(conference_auto_outcall_caller_id_number=0911) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action set(conference_auto_outcall_timeout=60) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action set(conference_auto_outcall_flags=mute) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action set(conference_auto_outcall_prefix={sip_auto_answer=true,execute_on_answer='bind_meta_app 2 a s1 transfer::intercept:${uuid} inline'}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action set(sip_exclude_contact=${network_addr}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action conference_set_auto_outcall(${group_call(sales)}) <BR>
Dialplan: <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Action conference(madboss_intercom1@default+flags{endconf|deaf}) <BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_state_machine.c:167 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_ROUTING -> CS_EXECUTE<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_state_machine.c:446 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State ROUTING going to sleep<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_EXECUTE<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_state_machine.c:453 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State EXECUTE<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_sofia.c:242 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SOFIA EXECUTE<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_state_machine.c:209 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard EXECUTE<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> set(open=true)<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_dptools.c:1319 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SET [open]=[true]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> hash(insert/192.168.0.100-spymap/1010/6dfa8876-0843-11e2-b574-8d63071eb0f5)<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> hash(insert/192.168.0.100-last_dial/1010/0911)<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> hash(insert/192.168.0.100-last_dial/global/6dfa8876-0843-11e2-b574-8d63071eb0f5)<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> export(RFC2822_DATE=Thu, 27 Sep 2012 09:34:07 +0800)<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_channel.c:1118 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 27 Sep 2012 09:34:07 +0800]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> set(conference_auto_outcall_caller_id_name=Mad Boss1)<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_dptools.c:1319 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SET [conference_auto_outcall_caller_id_name]=[Mad Boss1]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> set(conference_auto_outcall_caller_id_number=0911)<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_dptools.c:1319 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SET [conference_auto_outcall_caller_id_number]=[0911]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> set(conference_auto_outcall_timeout=60)<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_dptools.c:1319 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SET [conference_auto_outcall_timeout]=[60]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> set(conference_auto_outcall_flags=mute)<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_dptools.c:1319 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SET [conference_auto_outcall_flags]=[mute]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> set(conference_auto_outcall_prefix={sip_auto_answer=true,execute_on_answer='bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline'})<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_dptools.c:1319 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SET [conference_auto_outcall_prefix]=[{sip_auto_answer=true,execute_on_answer='bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline'}]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> set(sip_exclude_contact=192.168.0.10)<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_dptools.c:1319 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SET [sip_exclude_contact]=[192.168.0.10]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_session.c:2390 Application conference_set_auto_outcall Requires media! pre_answering channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a><BR>
2012-09-27 09:34:07.593468 [INFO] switch_core_session.c:2392 Sending early media<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:112:32000:20:0]/[G722:9:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:112:32000:20:0]/[PCMU:0:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:112:32000:20:0]/[PCMA:8:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:112:32000:20:0]/[GSM:3:8000:20:13200]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:111:16000:20:0]/[G722:9:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:111:16000:20:0]/[PCMU:0:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:111:16000:20:0]/[PCMA:8:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:111:16000:20:0]/[GSM:3:8000:20:13200]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:110:8000:20:0]/[G722:9:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:110:8000:20:0]/[PCMU:0:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:110:8000:20:0]/[PCMA:8:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [speex:110:8000:20:0]/[GSM:3:8000:20:13200]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [GSM:3:8000:20:13200]/[G722:9:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [GSM:3:8000:20:13200]/[PCMU:0:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [GSM:3:8000:20:13200]/[PCMA:8:8000:20:64000]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [GSM:3:8000:20:13200]/[GSM:3:8000:20:13200]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:3077 Set Codec <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> GSM/8000 20 ms 160 samples 13200 bits<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_codec.c:111 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Original read codec set to GSM:3<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:5219 Set 2833 dtmf send/recv payload to 101<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:3327 AUDIO RTP [<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>] 192.168.0.100 port 19426 -> 192.168.0.10 port 7078 codec: 3 ms: 20<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_rtp.c:1927 Starting timer [soft] 160 bytes per 20ms<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:3591 Set 2833 dtmf send payload to 101<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:3597 Set 2833 dtmf receive payload to 101<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia_glue.c:3624 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Set rtp dtmf delay to 40<BR>
2012-09-27 09:34:07.593468 [NOTICE] sofia_glue.c:4226 Pre-Answer <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>!<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_channel.c:3092 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change RINGING -> EARLY<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_sofia.c:2730 Ring SDP:<BR>
v=0<BR>
o=FreeSWITCH 1348690221 1348690222 IN IP4 192.168.0.100<BR>
s=FreeSWITCH<BR>
c=IN IP4 192.168.0.100<BR>
t=0 0<BR>
m=audio 19426 RTP/AVP 3 101<BR>
a=rtpmap:3 GSM/8000<BR>
a=rtpmap:101 telephone-event/8000<BR>
a=fmtp:101 0-16<BR>
a=silenceSupp:off - - - -<BR>
a=ptime:20<BR>
a=sendrecv<BR>
<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_session.c:759 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> conference_set_auto_outcall([sip_invite_domain=192.168.0.100,<a href="presence_id=1000@192.168.0.100]sofia/internal/sip:1000">presence_id=1000@192.168.0.100]sofia/internal/sip:1000</a>@192.168.0.20:5060 <<a href="http://sip:1000@192.168.0.20:5060">http://sip:1000@192.168.0.20:5060</a>> ,[sip_invite_domain=192.168.0.100,<a href="presence_id=1001@192.168.0.100]error/user_not_registered">presence_id=1001@192.168.0.100]error/user_not_registered</a>,[sip_invite_domain=192.168.0.100,<a href="presence_id=1002@192.168.0.100]error/user_not_registered">presence_id=1002@192.168.0.100]error/user_not_registered</a>,[sip_invite_domain=192.168.0.100,<a href="presence_id=1003@192.168.0.100]error/user_not_registered">presence_id=1003@192.168.0.100]error/user_not_registered</a>,[sip_invite_domain=192.168.0.100,<a href="presence_id=1004@192.168.0.100]error/user_not_registered">presence_id=1004@192.168.0.100]error/user_not_registered</a>)<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia.c:6282 Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> entering state [early][183]<BR>
EXECUTE <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> conference(madboss_intercom1@default+flags{endconf|deaf})<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_sofia.c:836 Local SDP <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>:<BR>
v=0<BR>
o=FreeSWITCH 1348690221 1348690223 IN IP4 192.168.0.100<BR>
s=FreeSWITCH<BR>
c=IN IP4 192.168.0.100<BR>
t=0 0<BR>
m=audio 19426 RTP/AVP 3 101<BR>
a=rtpmap:3 GSM/8000<BR>
a=rtpmap:101 telephone-event/8000<BR>
a=fmtp:101 0-16<BR>
a=silenceSupp:off - - - -<BR>
a=ptime:20<BR>
a=sendrecv<BR>
<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_session.c:759 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_channel.c:3351 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change EARLY -> ACTIVE<BR>
2012-09-27 09:34:07.593468 [NOTICE] mod_conference.c:7211 Channel [<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>] has been answered<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.593468 [DEBUG] sofia.c:6282 Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> entering state [completed][200]<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8315 using channel sound prefix: /home/sunzhi/sunzhi/freeswitch/freeswitch-1.2.3/rel/sounds/en/us/callie<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:7092 Raw Codec Activation Success L16@8000hz 1 channel 20ms<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:7137 Raw Codec Activation Success L16@8000hz 1 channel 20ms<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:1922 Setup timer success interval: 20 samples: 160<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_codec.c:219 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Push codec L16:70<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '0' to 'mute'<BR>
2012-09-27 09:34:07.593468 [INFO] switch_ivr_async.c:194 Digit parser mod_conference: Setting realm to 'conf'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bab8<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '*' to 'deaf mute'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding */conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bae8<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '9' to 'energy up'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bb18<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '8' to 'energy equ'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bb48<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '7' to 'energy dn'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bb78<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '3' to 'vol talk up'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bba8<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '2' to 'vol talk zero'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bbd8<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '1' to 'vol talk dn'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bc08<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '6' to 'vol listen up'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bc38<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '5' to 'vol listen zero'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bc68<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '4' to 'vol listen dn'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bc98<BR>
2012-09-27 09:34:07.593468 [INFO] mod_conference.c:8869 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> binding '#' to 'hangup'<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_async.c:288 Digit parser mod_conference: binding #/conf/0 callback: 0x7f7bcbde4640 data: 0x7f7b8002bcc8<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_core_session.c:759 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:3474 Setup timer soft success interval: 20 samples: 160<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2425 Parsing session specific variables<BR>
2012-09-27 09:34:07.593468 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 27 [DESTINATION_OUT_OF_ORDER]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1000@192.168.0.100]sofia">presence_id=1000@192.168.0.100]sofia</a><BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2425 Parsing session specific variables<BR>
2012-09-27 09:34:07.593468 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1000@192.168.0.100]sofia">presence_id=1000@192.168.0.100]sofia</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 66 [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 27 [DESTINATION_OUT_OF_ORDER]<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
2012-09-27 09:34:07.593468 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1001@192.168.0.100]error">presence_id=1001@192.168.0.100]error</a><BR>
2012-09-27 09:34:07.593468 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1001@192.168.0.100]error">presence_id=1001@192.168.0.100]error</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 66 [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2425 Parsing session specific variables<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 27 [DESTINATION_OUT_OF_ORDER]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
2012-09-27 09:34:07.593468 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1002@192.168.0.100]error">presence_id=1002@192.168.0.100]error</a><BR>
2012-09-27 09:34:07.593468 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1002@192.168.0.100]error">presence_id=1002@192.168.0.100]error</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2425 Parsing session specific variables<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 66 [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [DEBUG] mod_conference.c:6815 Launching BG Thread for outcall<BR>
2012-09-27 09:34:07.593468 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 27 [DESTINATION_OUT_OF_ORDER]<BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables<BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
2012-09-27 09:34:07.593468 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1003@192.168.0.100]error">presence_id=1003@192.168.0.100]error</a><BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1003@192.168.0.100]error">presence_id=1003@192.168.0.100]error</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [sip_auto_answer]=[true]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_event.c:1569 Parsing variable [execute_on_answer]=[bind_meta_app 2 a s1 transfer::intercept:6dfa8876-0843-11e2-b574-8d63071eb0f5 inline]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 66 [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:2425 Parsing session specific variables<BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 09:34:07.593468 [ERR] switch_ivr_originate.c:2440 Parse Error!<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 27 [DESTINATION_OUT_OF_ORDER]<BR>
2012-09-27 09:34:07.593468 [ERR] switch_core_session.c:408 Could not locate channel type <a href="presence_id=1004@192.168.0.100]error">presence_id=1004@192.168.0.100]error</a><BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: DESTINATION_OUT_OF_ORDER<BR>
2012-09-27 09:34:07.593468 [NOTICE] switch_ivr_originate.c:2591 Cannot create outgoing channel of type [<a href="presence_id=1004@192.168.0.100]error">presence_id=1004@192.168.0.100]error</a>] cause: [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [DEBUG] switch_ivr_originate.c:3508 Originate Resulted in Error Cause: 66 [CHAN_NOT_IMPLEMENTED]<BR>
2012-09-27 09:34:07.593468 [ERR] mod_conference.c:6614 Cannot create outgoing channel, cause: CHAN_NOT_IMPLEMENTED<BR>
2012-09-27 09:34:07.613471 [DEBUG] mod_local_stream.c:417 Opening Stream [moh/8000] 8000hz<BR>
2012-09-27 09:34:07.613471 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.613471 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.613471 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:07.633467 [DEBUG] sofia.c:6282 Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> entering state [ready][200]<BR>
2012-09-27 09:34:07.693467 [DEBUG] switch_rtp.c:3596 Correct ip/port confirmed.<BR>
2012-09-27 09:34:07.713468 [DEBUG] mod_conference.c:4288 Queueing file 'tone_stream://%(500,0,640)' for play<BR>
2012-09-27 09:34:10.733467 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_channel.c:2950 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change ACTIVE -> HANGUP<BR>
2012-09-27 09:34:10.753468 [NOTICE] sofia.c:711 Hangup <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [CS_EXECUTE] [NORMAL_CLEARING]<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_channel.c:2973 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [KILL]<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:10.753468 [DEBUG] mod_conference.c:3777 Channel leaving conference, cause: NORMAL_CLEARING<BR>
2012-09-27 09:34:10.753468 [DEBUG] mod_conference.c:7645 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> skip receive message [UNBRIDGE] (channel is hungup already)<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_codec.c:244 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Restore previous codec GSM:3.<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_session.c:2553 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:453 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State EXECUTE going to sleep<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_HANGUP<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:638 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State HANGUP<BR>
2012-09-27 09:34:10.753468 [DEBUG] mod_sofia.c:483 Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> hanging up, cause: NORMAL_CLEARING<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:48 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard HANGUP, cause: NORMAL_CLEARING<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:638 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State HANGUP going to sleep<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:429 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_HANGUP -> CS_REPORTING<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_REPORTING<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:703 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State REPORTING<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:92 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard REPORTING, cause: NORMAL_CLEARING<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:703 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State REPORTING going to sleep<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:423 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State Change CS_REPORTING -> CS_DESTROY<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_session.c:1415 Session 3 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Locked, Waiting on external entities<BR>
2012-09-27 09:34:10.753468 [NOTICE] switch_core_session.c:1433 Session 3 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Ended<BR>
2012-09-27 09:34:10.753468 [NOTICE] switch_core_session.c:1437 Close Channel <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [CS_DESTROY]<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:527 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change HANGUP -> DOWN<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:530 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_DESTROY<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:540 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State DESTROY<BR>
2012-09-27 09:34:10.753468 [DEBUG] mod_sofia.c:376 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> SOFIA DESTROY<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:99 <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> Standard DESTROY<BR>
2012-09-27 09:34:10.753468 [DEBUG] switch_core_state_machine.c:540 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State DESTROY going to sleep<BR>
2012-09-27 09:34:10.773471 [NOTICE] mod_conference.c:2369 Ending pending outcall channels for Conference: 'madboss_intercom1'<BR>
2012-09-27 09:34:10.773471 [DEBUG] mod_conference.c:2461 Write Lock ON<BR>
2012-09-27 09:34:10.773471 [DEBUG] mod_conference.c:2464 Write Lock OFF<BR>
<BR>
======================================================================<BR>
<BR>
<BR>
2012/9/27 Anthony Minessale <<a href="anthony.minessale@gmail.com">anthony.minessale@gmail.com</a>><BR>
</SPAN></FONT><BLOCKQUOTE><FONT FACE="Monaco, Courier New"><SPAN STYLE='font-size:11pt'>That is not nearly enough log file, you need to supply the entire log of the call in full debug mode<BR>
<BR>
On Wed, Sep 26, 2012 at 8:13 AM, zhi sun <<a href="sunzhimailbox@gmail.com">sunzhimailbox@gmail.com</a>> wrote:<BR>
</SPAN></FONT><BLOCKQUOTE><FONT FACE="Monaco, Courier New"><SPAN STYLE='font-size:11pt'>in the default diaplan, the 0911 conference call that make a out call to a group doesn't work for the latest and version 2.1.3. the out call always failed because of CHAN_NOT_IMPLEMENTED,<BR>
<BR>
but it works fine for version 1.06<BR>
<BR>
the same problem happens with the 5001 in dialplan (default.xml)<BR>
<BR>
it is very easy to reproduce this issue: i just get the correct version, make, make install, make cd-sounds-install.<BR>
<BR>
the log with problem looks like below:<BR>
<BR>
===========================================<BR>
<BR>
2012-09-26 15:37:10.973469 [NOTICE] switch_ivr.c:1748 Transfer <a href="sofia/internal/1000@192.168.0.100">sofia/internal/1000@192.168.0.100</a> to XML[1010@default]<BR>
2012-09-26 15:37:10.973469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-26 15:37:10.973469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/0000000000@192.168.0.100">sofia/internal/0000000000@192.168.0.100</a> [BREAK]<BR>
2012-09-26 15:37:10.973469 [DEBUG] switch_core_session.c:905 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-26 15:37:10.993469 [DEBUG] switch_channel.c:2950 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Callstate Change RINGING -> HANGUP<BR>
2012-09-26 15:37:10.993469 [NOTICE] switch_ivr_originate.c:3330 Hangup <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]<BR>
2012-09-26 15:37:10.993469 [DEBUG] switch_channel.c:2973 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [KILL]<BR>
2012-09-26 15:37:10.993469 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a> [BREAK]<BR>
2012-09-26 15:37:10.993469 [DEBUG] switch_ivr_originate.c:3506 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]<BR>
2012-09-26 15:37:10.993469 [DEBUG] switch_core_state_machine.c:398 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) Running State Change CS_HANGUP<BR>
2012-09-26 15:37:10.993469 [ERR] mod_conference.c:6626 Cannot create outgoing channel, cause: ORIGINATOR_CANCEL<BR>
2012-09-26 15:37:10.993469 [DEBUG] switch_channel.c:2950 (<a href="sofia/internal/1000@192.168.0.100">sofia/internal/1000@192.168.0.100</a>) Callstate Change ACTIVE -> HANGUP<BR>
2012-09-26 15:37:10.993469 [NOTICE] mod_conference.c:6629 Hangup <a href="sofia/internal/1000@192.168.0.100">sofia/internal/1000@192.168.0.100</a> [CS_ROUTING] [ORIGINATOR_CANCEL]<BR>
2012-09-26 15:37:10.993469 [DEBUG] switch_channel.c:2973 Send signal <a href="sofia/internal/1000@192.168.0.100">sofia/internal/1000@192.168.0.100</a> [KILL]<BR>
2012-09-26 15:37:10.993469 [DEBUG] switch_core_state_machine.c:638 (<a href="sofia/internal/1010@192.168.0.100">sofia/internal/1010@192.168.0.100</a>) State HANGUP<BR>
2012-09-26 15:37:10.993469 [DEBUG] switch_core_session.c:1210 Send signal <a href="sofia/internal/1000@192.168.0.100">sofia/internal/1000@192.168.0.100</a> [BREAK]<BR>
<BR>
============================================<BR>
<BR>
i am new to freeswitch, Is there anything i missed to let the version 2.1.3 as well as the latest (from git) work for the 0911 and 5001 dialplan? both of them make an out call from a conference.<BR>
<BR>
thanks<BR>
-zhisun<BR>
<BR>
<BR>
_________________________________________________________________________<BR>
Professional FreeSWITCH Consulting Services:<BR>
<a href="consulting@freeswitch.org">consulting@freeswitch.org</a><BR>
<a href="http://www.freeswitchsolutions.com">http://www.freeswitchsolutions.com</a><BR>
<BR>
FreeSWITCH-powered IP PBX: The CudaTel Communication Server<BR>
<a href="http://www.cudatel.com">http://www.cudatel.com</a><BR>
<BR>
Official FreeSWITCH Sites<BR>
<a href="http://www.freeswitch.org">http://www.freeswitch.org</a><BR>
<a href="http://wiki.freeswitch.org">http://wiki.freeswitch.org</a><BR>
<a href="http://www.cluecon.com">http://www.cluecon.com</a><BR>
<BR>
FreeSWITCH-dev mailing list<BR>
<a href="FreeSWITCH-dev@lists.freeswitch.org">FreeSWITCH-dev@lists.freeswitch.org</a><BR>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev">http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev</a><BR>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-dev">http://lists.freeswitch.org/mailman/options/freeswitch-dev</a><BR>
<a href="http://www.freeswitch.org">http://www.freeswitch.org</a><BR>
<BR>
</SPAN></FONT></BLOCKQUOTE><FONT FACE="Monaco, Courier New"><SPAN STYLE='font-size:11pt'><BR>
<BR>
</SPAN></FONT></BLOCKQUOTE></BLOCKQUOTE></BLOCKQUOTE></BLOCKQUOTE><FONT FACE="Monaco, Courier New"><SPAN STYLE='font-size:11pt'><BR>
-- <BR>
Ken<BR>
<FONT COLOR="#0000FF"><U><a href="http://www.FreeSWITCH.org">http://www.FreeSWITCH.org</a><BR>
<a href="http://www.ClueCon.com">http://www.ClueCon.com</a><BR>
<a href="http://www.OSTAG.org">http://www.OSTAG.org</a><BR>
</U></FONT>irc.freenode.net #freeswitch<BR>
</SPAN></FONT>
</BODY>
</HTML>