Hi FreeSWITCH,<br><br>I'm working on routing calls from external profile to a FIFO ( RAFQ1 ). Although I've set the origination_caller_id_name & origination_caller_id_number variables in my dialplan, unfortunately the agent receives calls with Queue , fifo+RAFQ1 Ids. My fifo status and logs as follow.<br>
<br>BEST,<br>-- afshin<br><br><br><br> freeswitch@internal> fifo list_verbose<br><fifo_report><br> <fifo name="<a href="mailto:RAFQ1@192.168.128.36">RAFQ1@192.168.128.36</a>" consumer_count="0" caller_count="0" waiting_count="0" importance="1"><br>
<outbound><br> <member timeout="60" simo="1" lag="5" outbound-call-count="5" outbound-fail-count="1" next-available="2010-06-24 13:49:41">{execute_on_answer='unset fifo_hangup_check',fifo_hangup_check='<a href="mailto:RAFQ1@192.168.128.36">RAFQ1@192.168.128.36</a>',origination_caller_id_name=Queue,origination_caller_id_number='fifo+RAFQ1'}{fifo_member_wait=nowait}user/1001</member><br>
</outbound><br> <callers></callers><br> <consumers></consumers><br> </fifo><br> <fifo name="<a href="mailto:cool_fifo@192.168.128.36">cool_fifo@192.168.128.36</a>" consumer_count="0" caller_count="0" waiting_count="0" importance="0"><br>
<callers></callers><br> <consumers></consumers><br> </fifo><br></fifo_report><br><br><br><br><br><br><br>freeswitch@internal> 2010-06-24 13:42:14.673471 [NOTICE] switch_channel.c:776 New Channel sofia/external/22808182@noProvider [4c530c08-7f96-11df-9204-6904a4602528]<br>
2010-06-24 13:42:14.673471 [DEBUG] sofia.c:4293 Channel sofia/external/22808182@noProvider entering state [received][100]<br>2010-06-24 13:42:14.673471 [DEBUG] sofia.c:4304 Remote SDP:<br>v=0<br>o=- 6 2 IN IP4 192.168.128.31<br>
s=CounterPath eyeBeam 1.5<br>c=IN IP4 192.168.128.31<br>t=0 0<br>m=audio 37558 RTP/AVP 107 0 8 18 101<br>a=rtpmap:107 BV32/16000<br>a=rtpmap:18 G729/8000<br>a=fmtp:18 annexb=yes<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-15<br>
<br>2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [BV32:107:16000:20]/[G7221:115:32000:20]<br>2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [BV32:107:16000:20]/[G7221:107:16000:20]<br>
2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [BV32:107:16000:20]/[G722:9:8000:20]<br>2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [BV32:107:16000:20]/[PCMU:0:8000:20]<br>
2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [BV32:107:16000:20]/[PCMA:8:8000:20]<br>2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [BV32:107:16000:20]/[GSM:3:8000:20]<br>
2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [PCMU:0:8000:20]/[G7221:115:32000:20]<br>2010-06-24 13:42:14.673471 [DEBUG] switch_core_state_machine.c:314 (sofia/external/22808182@noProvider) Running State Change CS_NEW<br>
2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [PCMU:0:8000:20]/[G7221:107:16000:20]<br>2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [PCMU:0:8000:20]/[G722:9:8000:20]<br>
2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [PCMU:0:8000:20]/[PCMU:0:8000:20]<br>2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:2462 Set Codec sofia/external/22808182@noProvider PCMU/8000 20 ms 160 samples<br>
2010-06-24 13:42:14.673471 [DEBUG] switch_core_state_machine.c:320 (sofia/external/22808182@noProvider) State NEW<br>2010-06-24 13:42:14.673471 [DEBUG] sofia_glue.c:3816 Set 2833 dtmf send/recv payload to 101<br>2010-06-24 13:42:14.673471 [DEBUG] sofia.c:4451 (sofia/external/22808182@noProvider) State Change CS_NEW -> CS_INIT<br>
2010-06-24 13:42:14.673471 [DEBUG] switch_core_session.c:1027 Send signal sofia/external/22808182@noProvider [BREAK]<br>2010-06-24 13:42:14.674505 [DEBUG] switch_core_state_machine.c:314 (sofia/external/22808182@noProvider) Running State Change CS_INIT<br>
2010-06-24 13:42:14.674505 [DEBUG] switch_core_state_machine.c:338 (sofia/external/22808182@noProvider) State INIT<br>2010-06-24 13:42:14.674505 [DEBUG] mod_sofia.c:83 sofia/external/22808182@noProvider SOFIA INIT<br>2010-06-24 13:42:14.674505 [DEBUG] mod_sofia.c:117 (sofia/external/22808182@noProvider) State Change CS_INIT -> CS_ROUTING<br>
2010-06-24 13:42:14.674505 [DEBUG] switch_core_session.c:1027 Send signal sofia/external/22808182@noProvider [BREAK]<br>2010-06-24 13:42:14.674505 [DEBUG] switch_core_state_machine.c:338 (sofia/external/22808182@noProvider) State INIT going to sleep<br>
2010-06-24 13:42:14.674505 [DEBUG] switch_core_state_machine.c:314 (sofia/external/22808182@noProvider) Running State Change CS_ROUTING<br>2010-06-24 13:42:14.674505 [DEBUG] switch_channel.c:1474 (sofia/external/22808182@noProvider) Callstate Change DOWN -> RINGING<br>
2010-06-24 13:42:14.674505 [DEBUG] switch_core_state_machine.c:341 (sofia/external/22808182@noProvider) State ROUTING<br>2010-06-24 13:42:14.674505 [DEBUG] switch_channel.c:1333 (sofia/external/22808182@noProvider) Callstate Change RINGING -> ACTIVE<br>
2010-06-24 13:42:14.674505 [DEBUG] mod_sofia.c:140 sofia/external/22808182@noProvider SOFIA ROUTING<br>2010-06-24 13:42:14.674505 [DEBUG] switch_core_state_machine.c:77 sofia/external/22808182@noProvider Standard ROUTING<br>
2010-06-24 13:42:14.674505 [INFO] mod_dialplan_xml.c:331 Processing Afshin Afzali->1880 in context public<br>Dialplan: sofia/external/22808182@noProvider parsing [public->unloop] continue=false<br>Dialplan: sofia/external/22808182@noProvider Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<br>
Dialplan: sofia/external/22808182@noProvider Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false<br>Dialplan: sofia/external/22808182@noProvider parsing [public->outside_call] continue=true<br>Dialplan: sofia/external/22808182@noProvider Absolute Condition [outside_call]<br>
Dialplan: sofia/external/22808182@noProvider Action set(outside_call=true) <br>Dialplan: sofia/external/22808182@noProvider parsing [public->call_debug] continue=true<br>Dialplan: sofia/external/22808182@noProvider Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never<br>
Dialplan: sofia/external/22808182@noProvider parsing [public->public_extensions] continue=false<br>Dialplan: sofia/external/22808182@noProvider Regex (FAIL) [public_extensions] destination_number(1880) =~ /^(10[01][0-9])$/ break=on-false<br>
Dialplan: sofia/external/22808182@noProvider parsing [public->public_did] continue=false<br>Dialplan: sofia/external/22808182@noProvider Regex (FAIL) [public_did] destination_number(1880) =~ /^(5551212)$/ break=on-false<br>
Dialplan: sofia/external/22808182@noProvider parsing [public->pub1880_did] continue=false<br>Dialplan: sofia/external/22808182@noProvider Regex (PASS) [pub1880_did] destination_number(1880) =~ /^(1880)$/ break=on-false<br>
Dialplan: sofia/external/22808182@noProvider Action set(domain_name=192.168.128.36) <br>Dialplan: sofia/external/22808182@noProvider Action set(sound_prefix=/usr/local/freeswitch/sounds/en/us/callie) <br>Dialplan: sofia/external/22808182@noProvider Action set(fifo_music=local_stream://moh) <br>
Dialplan: sofia/external/22808182@noProvider Action set(origination_caller_id_name=AFSHIN) <br>Dialplan: sofia/external/22808182@noProvider Action set(origination_caller_id_number=22808182) <br>Dialplan: sofia/external/22808182@noProvider Action answer() <br>
Dialplan: sofia/external/22808182@noProvider Action sleep(500) <br>Dialplan: sofia/external/22808182@noProvider Action playback(ivr/ivr-generic_greeting.wav) <br>Dialplan: sofia/external/22808182@noProvider Action fifo(<a href="mailto:RAFQ1@192.168.128.36">RAFQ1@192.168.128.36</a> in) <br>
2010-06-24 13:42:14.675445 [DEBUG] switch_core_state_machine.c:119 (sofia/external/22808182@noProvider) State Change CS_ROUTING -> CS_EXECUTE<br>2010-06-24 13:42:14.675445 [DEBUG] switch_core_session.c:1027 Send signal sofia/external/22808182@noProvider [BREAK]<br>
2010-06-24 13:42:14.675445 [DEBUG] switch_core_state_machine.c:341 (sofia/external/22808182@noProvider) State ROUTING going to sleep<br>2010-06-24 13:42:14.675445 [DEBUG] switch_core_state_machine.c:314 (sofia/external/22808182@noProvider) Running State Change CS_EXECUTE<br>
2010-06-24 13:42:14.675445 [DEBUG] switch_core_state_machine.c:348 (sofia/external/22808182@noProvider) State EXECUTE<br>2010-06-24 13:42:14.675445 [DEBUG] mod_sofia.c:233 sofia/external/22808182@noProvider SOFIA EXECUTE<br>
2010-06-24 13:42:14.675445 [DEBUG] switch_core_state_machine.c:157 sofia/external/22808182@noProvider Standard EXECUTE<br>EXECUTE sofia/external/22808182@noProvider set(outside_call=true)<br>2010-06-24 13:42:14.675445 [DEBUG] mod_dptools.c:843 sofia/external/22808182@noProvider SET [outside_call]=[true]<br>
EXECUTE sofia/external/22808182@noProvider set(domain_name=192.168.128.36)<br>2010-06-24 13:42:14.675445 [DEBUG] mod_dptools.c:843 sofia/external/22808182@noProvider SET [domain_name]=[192.168.128.36]<br>EXECUTE sofia/external/22808182@noProvider set(sound_prefix=/usr/local/freeswitch/sounds/en/us/callie)<br>
2010-06-24 13:42:14.676396 [DEBUG] mod_dptools.c:843 sofia/external/22808182@noProvider SET [sound_prefix]=[/usr/local/freeswitch/sounds/en/us/callie]<br>EXECUTE sofia/external/22808182@noProvider set(fifo_music=local_stream://moh)<br>
2010-06-24 13:42:14.676396 [DEBUG] mod_dptools.c:843 sofia/external/22808182@noProvider SET [fifo_music]=[local_stream://moh]<br>EXECUTE sofia/external/22808182@noProvider set(origination_caller_id_name=AFSHIN)<br>2010-06-24 13:42:14.676396 [DEBUG] mod_dptools.c:843 sofia/external/22808182@noProvider SET [origination_caller_id_name]=[AFSHIN]<br>
EXECUTE sofia/external/22808182@noProvider set(origination_caller_id_number=22808182)<br>2010-06-24 13:42:14.676396 [DEBUG] mod_dptools.c:843 sofia/external/22808182@noProvider SET [origination_caller_id_number]=[22808182]<br>
EXECUTE sofia/external/22808182@noProvider answer()<br>2010-06-24 13:42:14.676396 [DEBUG] sofia_glue.c:2702 AUDIO RTP [sofia/external/22808182@noProvider] 192.168.128.36 port 30436 -> 192.168.128.31 port 37558 codec: 0 ms: 20<br>
2010-06-24 13:42:14.676396 [DEBUG] switch_rtp.c:1408 Starting timer [soft] 160 bytes per 20ms<br>2010-06-24 13:42:14.678369 [DEBUG] sofia_glue.c:2912 Set 2833 dtmf send payload to 101<br>2010-06-24 13:42:14.678369 [DEBUG] sofia_glue.c:2917 Set 2833 dtmf receive payload to 101<br>
2010-06-24 13:42:14.678369 [DEBUG] mod_sofia.c:667 Local SDP sofia/external/22808182@noProvider:<br>v=0<br>o=FreeSWITCH 1277356498 1277356499 IN IP4 192.168.128.36<br>s=FreeSWITCH<br>c=IN IP4 192.168.128.36<br>t=0 0<br>m=audio 30436 RTP/AVP 0 101<br>
a=rtpmap:0 PCMU/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>2010-06-24 13:42:14.678369 [DEBUG] switch_core_session.c:647 Send signal sofia/external/22808182@noProvider [BREAK]<br>
2010-06-24 13:42:14.678369 [NOTICE] mod_dptools.c:746 Channel [sofia/external/22808182@noProvider] has been answered<br>EXECUTE sofia/external/22808182@noProvider sleep(500)<br>2010-06-24 13:42:14.678369 [DEBUG] sofia.c:4293 Channel sofia/external/22808182@noProvider entering state [completed][200]<br>
2010-06-24 13:42:14.718466 [DEBUG] switch_rtp.c:2512 Correct ip/port confirmed.<br>2010-06-24 13:42:14.782161 [DEBUG] sofia.c:4293 Channel sofia/external/22808182@noProvider entering state [ready][200]<br>EXECUTE sofia/external/22808182@noProvider playback(ivr/ivr-generic_greeting.wav)<br>
2010-06-24 13:42:15.178406 [DEBUG] switch_ivr_play_say.c:1161 Codec Activated L16@8000hz 1 channels 20ms<br>2010-06-24 13:42:21.018797 [DEBUG] switch_ivr_play_say.c:1468 done playing file<br>EXECUTE sofia/external/22808182@noProvider fifo(<a href="mailto:RAFQ1@192.168.128.36">RAFQ1@192.168.128.36</a> in)<br>
2010-06-24 13:42:21.018797 [DEBUG] mod_local_stream.c:421 Opening Stream [moh/8000] 8000hz<br>2010-06-24 13:42:21.018797 [DEBUG] switch_ivr_play_say.c:1161 Codec Activated L16@8000hz 1 channels 20ms<br>2010-06-24 13:42:21.564836 [DEBUG] switch_ivr_originate.c:1954 variable string 0 = [execute_on_answer=unset fifo_hangup_check]<br>
2010-06-24 13:42:21.564836 [DEBUG] switch_ivr_originate.c:1954 variable string 1 = [fifo_hangup_check=<a href="mailto:RAFQ1@192.168.128.36">RAFQ1@192.168.128.36</a>]<br>2010-06-24 13:42:21.564836 [DEBUG] switch_ivr_originate.c:1954 variable string 2 = [origination_caller_id_name=Queue]<br>
2010-06-24 13:42:21.564836 [DEBUG] switch_ivr_originate.c:1954 variable string 3 = [origination_caller_id_number=fifo+RAFQ1]<br>2010-06-24 13:42:21.564836 [DEBUG] switch_ivr_originate.c:1954 variable string 4 = [fifo_member_wait=nowait]<br>
2010-06-24 13:42:21.564836 [DEBUG] switch_ivr_originate.c:1954 variable string 0 = [presence_id=<a href="mailto:1001@192.168.128.36">1001@192.168.128.36</a>]<br>2010-06-24 13:42:21.564836 [NOTICE] switch_channel.c:776 New Channel sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> [506eaaa4-7f96-11df-9205-6904a4602528]<br>
2010-06-24 13:42:21.566617 [DEBUG] mod_sofia.c:3883 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State Change CS_NEW -> CS_INIT<br>2010-06-24 13:42:21.566617 [DEBUG] switch_core_session.c:1027 Send signal sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> [BREAK]<br>
2010-06-24 13:42:21.566617 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) Running State Change CS_INIT<br>2010-06-24 13:42:21.566617 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State INIT<br>
2010-06-24 13:42:21.566617 [DEBUG] mod_sofia.c:83 sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> SOFIA INIT<br>2010-06-24 13:42:21.567514 [DEBUG] mod_sofia.c:117 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State Change CS_INIT -> CS_ROUTING<br>
2010-06-24 13:42:21.567514 [DEBUG] switch_core_session.c:1027 Send signal sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> [BREAK]<br>2010-06-24 13:42:21.567514 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State INIT going to sleep<br>
2010-06-24 13:42:21.567514 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) Running State Change CS_ROUTING<br>2010-06-24 13:42:21.567514 [DEBUG] sofia.c:4293 Channel sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> entering state [calling][0]<br>
2010-06-24 13:42:21.567514 [DEBUG] switch_channel.c:1474 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) Callstate Change DOWN -> RINGING<br>2010-06-24 13:42:21.567514 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State ROUTING<br>
2010-06-24 13:42:21.567514 [DEBUG] switch_channel.c:1333 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) Callstate Change RINGING -> ACTIVE<br>2010-06-24 13:42:21.567514 [DEBUG] mod_sofia.c:140 sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> SOFIA ROUTING<br>
2010-06-24 13:42:21.567514 [DEBUG] switch_ivr_originate.c:64 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2010-06-24 13:42:21.567514 [DEBUG] switch_core_session.c:1027 Send signal sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> [BREAK]<br>
2010-06-24 13:42:21.567514 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State ROUTING going to sleep<br>2010-06-24 13:42:21.567514 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) Running State Change CS_CONSUME_MEDIA<br>
2010-06-24 13:42:21.567514 [DEBUG] switch_core_state_machine.c:360 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State CONSUME_MEDIA<br>2010-06-24 13:42:21.567514 [DEBUG] switch_core_state_machine.c:360 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State CONSUME_MEDIA going to sleep<br>
2010-06-24 13:42:21.673796 [INFO] sofia.c:662 sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> Update Callee ID to "1001" <1001><br>2010-06-24 13:42:21.675668 [DEBUG] sofia.c:4293 Channel sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> entering state [proceeding][180]<br>
2010-06-24 13:42:21.675668 [NOTICE] sofia.c:4365 Ring-Ready sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>!<br>2010-06-24 13:42:22.082684 [DEBUG] sofia.c:4293 Channel sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> entering state [completing][200]<br>
2010-06-24 13:42:22.082684 [DEBUG] sofia.c:4304 Remote SDP:<br>v=0<br>o=- 6 2 IN IP4 192.168.128.31<br>s=CounterPath X-Lite 3.0<br>c=IN IP4 192.168.128.31<br>t=0 0<br>m=audio 63470 RTP/AVP 0 8 101<br>a=rtpmap:101 telephone-event/8000<br>
a=fmtp:101 0-15<br><br>2010-06-24 13:42:22.082684 [DEBUG] sofia.c:4293 Channel sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> entering state [ready][200]<br>2010-06-24 13:42:22.082684 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [PCMU:0:8000:20]/[G7221:115:32000:20]<br>
2010-06-24 13:42:22.082684 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [PCMU:0:8000:20]/[G7221:107:16000:20]<br>2010-06-24 13:42:22.082684 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [PCMU:0:8000:20]/[G722:9:8000:20]<br>
2010-06-24 13:42:22.082684 [DEBUG] sofia_glue.c:3877 Audio Codec Compare [PCMU:0:8000:20]/[PCMU:0:8000:20]<br>2010-06-24 13:42:22.082684 [DEBUG] sofia_glue.c:2462 Set Codec sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> PCMU/8000 20 ms 160 samples<br>
2010-06-24 13:42:22.082684 [DEBUG] sofia_glue.c:3810 Set 2833 dtmf send payload to 101<br>2010-06-24 13:42:22.082684 [DEBUG] sofia_glue.c:2702 AUDIO RTP [sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>] 192.168.128.36 port 30704 -> 192.168.128.31 port 63470 codec: 0 ms: 20<br>
2010-06-24 13:42:22.083576 [DEBUG] switch_rtp.c:1408 Starting timer [soft] 160 bytes per 20ms<br>2010-06-24 13:42:22.084524 [DEBUG] sofia_glue.c:2912 Set 2833 dtmf send payload to 101<br>2010-06-24 13:42:22.084524 [DEBUG] sofia_glue.c:2917 Set 2833 dtmf receive payload to 101<br>
2010-06-24 13:42:22.084524 [NOTICE] sofia.c:4851 Channel [sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>] has been answered<br>2010-06-24 13:42:22.084524 [DEBUG] switch_channel.c:2549 sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> execute on answer: unset(fifo_hangup_check)<br>
EXECUTE sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> unset(fifo_hangup_check)<br>2010-06-24 13:42:22.084524 [DEBUG] mod_dptools.c:951 UNSET [fifo_hangup_check]<br>2010-06-24 13:42:22.084524 [DEBUG] switch_ivr_originate.c:3271 Originate Resulted in Success: [sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>]<br>
2010-06-24 13:42:22.085421 [DEBUG] switch_ivr_originate.c:3271 Originate Resulted in Success: [sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>]<br>2010-06-24 13:42:22.085421 [DEBUG] mod_fifo.c:530 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State Change CS_CONSUME_MEDIA -> CS_EXECUTE<br>
2010-06-24 13:42:22.085421 [DEBUG] switch_core_session.c:1027 Send signal sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> [BREAK]<br>2010-06-24 13:42:22.085421 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) Running State Change CS_EXECUTE<br>
2010-06-24 13:42:22.085421 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a>) State EXECUTE<br>2010-06-24 13:42:22.085421 [DEBUG] mod_sofia.c:233 sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> SOFIA EXECUTE<br>
2010-06-24 13:42:22.085421 [DEBUG] switch_core_state_machine.c:157 sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> Standard EXECUTE<br>EXECUTE sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> fifo(<a href="mailto:RAFQ1@192.168.128.36">RAFQ1@192.168.128.36</a> out nowait)<br>
2010-06-24 13:42:22.138560 [DEBUG] switch_rtp.c:2512 Correct ip/port confirmed.<br>2010-06-24 13:42:22.619153 [DEBUG] switch_ivr_play_say.c:1468 done playing file<br>2010-06-24 13:42:22.619153 [DEBUG] mod_fifo.c:1097 (sofia/external/22808182@noProvider) State Change CS_EXECUTE -> CS_HIBERNATE<br>
2010-06-24 13:42:22.619153 [DEBUG] switch_core_session.c:1027 Send signal sofia/external/22808182@noProvider [BREAK]<br>2010-06-24 13:42:22.619153 [DEBUG] switch_core_state_machine.c:348 (sofia/external/22808182@noProvider) State EXECUTE going to sleep<br>
2010-06-24 13:42:22.619153 [DEBUG] switch_core_state_machine.c:314 (sofia/external/22808182@noProvider) Running State Change CS_HIBERNATE<br>2010-06-24 13:42:22.619153 [DEBUG] switch_core_state_machine.c:363 (sofia/external/22808182@noProvider) State HIBERNATE<br>
2010-06-24 13:42:22.619153 [DEBUG] mod_sofia.c:214 sofia/external/22808182@noProvider SOFIA HIBERNATE<br>2010-06-24 13:42:22.619153 [DEBUG] switch_core_state_machine.c:220 sofia/external/22808182@noProvider Standard HIBERNATE<br>
2010-06-24 13:42:22.619153 [DEBUG] switch_core_state_machine.c:363 (sofia/external/22808182@noProvider) State HIBERNATE going to sleep<br>2010-06-24 13:42:22.638406 [DEBUG] switch_ivr_bridge.c:1088 (sofia/external/22808182@noProvider) State Change CS_HIBERNATE -> CS_CONSUME_MEDIA<br>
2010-06-24 13:42:22.638406 [DEBUG] switch_core_session.c:1027 Send signal sofia/external/22808182@noProvider [BREAK]<br>2010-06-24 13:42:22.638406 [DEBUG] switch_core_state_machine.c:314 (sofia/external/22808182@noProvider) Running State Change CS_CONSUME_MEDIA<br>
2010-06-24 13:42:22.638406 [DEBUG] switch_core_state_machine.c:360 (sofia/external/22808182@noProvider) State CONSUME_MEDIA<br>2010-06-24 13:42:22.638406 [DEBUG] switch_core_session.c:647 Send signal sofia/external/22808182@noProvider [BREAK]<br>
2010-06-24 13:42:22.638406 [DEBUG] switch_ivr_bridge.c:665 sofia/external/22808182@noProvider CUSTOM HOLD<br>2010-06-24 13:42:22.638406 [DEBUG] switch_core_session.c:647 Send signal sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> [BREAK]<br>
2010-06-24 13:42:22.638406 [DEBUG] switch_core_state_machine.c:360 (sofia/external/22808182@noProvider) State CONSUME_MEDIA going to sleep<br>2010-06-24 13:42:22.638406 [DEBUG] switch_ivr_bridge.c:1183 (sofia/external/22808182@noProvider) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA<br>
2010-06-24 13:42:22.638406 [DEBUG] switch_core_session.c:1027 Send signal sofia/external/22808182@noProvider [BREAK]<br>2010-06-24 13:42:22.638406 [DEBUG] switch_core_state_machine.c:314 (sofia/external/22808182@noProvider) Running State Change CS_EXCHANGE_MEDIA<br>
2010-06-24 13:42:22.638406 [DEBUG] switch_core_state_machine.c:351 (sofia/external/22808182@noProvider) State EXCHANGE_MEDIA<br>2010-06-24 13:42:22.638406 [DEBUG] mod_sofia.c:538 SOFIA EXCHANGE_MEDIA<br>2010-06-24 13:42:22.639349 [DEBUG] switch_core_session.c:708 Send signal sofia/external/22808182@noProvider [BREAK]<br>
2010-06-24 13:42:22.639349 [DEBUG] switch_core_session.c:708 Send signal sofia/internal/<a href="http://sip:1001@192.168.128.31:63820">sip:1001@192.168.128.31:63820</a> [BREAK]<br><br><br><br>