Caller put into mod_fifo queue. Caller listens tp the ring tone. Mod_fifo connects the caller tp the agent. The caller and the agent are conferencing. The agent presses the "first" TRANSFER (telephone icon) button in Bria. Caller listens to moh. The agent keys in the number to transfer to and then the TRANSFER button. The agent receives "Transfer succeeded» The caller hears the ring tone (not the transfer ring tone) because the caller is back into the control of the fifo. It seems like the original call comes back in to the dial plan via the sofia profile.. A bit lost here. Any ideas ? Thoreif From the freeswitch log. 2014-03-26 09:36:20.508640 [DEBUG] sofia.c:6889 Process REFER to [73951111@133582] 2014-03-26 09:36:20.508640 [DEBUG] switch_ivr.c:1831 (sofia/external/73951110@sip1.vmlab.iplink.no) State Change CS_EXCHANGE_MEDIA -> CS_ROUTING 2014-03-26 09:36:20.508640 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/73951110@sip1.vmlab.iplink.no [BREAK] 2014-03-26 09:36:20.508640 [DEBUG] switch_core_session.c:871 Send signal sofia/external/73951110@sip1.vmlab.iplink.no [BREAK] 2014-03-26 09:36:20.508640 [NOTICE] switch_ivr.c:1838 Transfer sofia/external/73951110@sip1.vmlab.iplink.no to XML[73951111@133582] 2014-03-26 09:36:20.528557 [DEBUG] switch_ivr_play_say.c:1717 done playing file local_stream://moh 2014-03-26 09:36:20.528557 [DEBUG] switch_core_session.c:871 Send signal sofia/external/73951110@sip1.vmlab.iplink.no [BREAK] 2014-03-26 09:36:20.528557 [DEBUG] switch_ivr_bridge.c:386 Send signal sofia/internal/sip:1002@10.211.55.253:64608 [BREAK] 2014-03-26 09:36:20.528557 [DEBUG] switch_ivr_bridge.c:645 BRIDGE THREAD DONE [sofia/internal/sip:1002@10.211.55.253:64608] 2014-03-26 09:36:20.528557 [DEBUG] switch_channel.c:1952 (sofia/internal/sip:1002@10.211.55.253:64608) Callstate Change HELD -> UNHOLD 2014-03-26 09:36:20.528557 [DEBUG] switch_channel.c:1963 (sofia/internal/sip:1002@10.211.55.253:64608) Callstate Change UNHOLD -> ACTIVE 2014-03-26 09:36:20.528557 [DEBUG] switch_ivr_bridge.c:675 Send signal sofia/external/73951110@sip1.vmlab.iplink.no [BREAK] 2014-03-26 09:36:20.528557 [DEBUG] switch_core_session.c:871 Send signal sofia/external/73951110@sip1.vmlab.iplink.no [BREAK] 2014-03-26 09:36:20.528557 [DEBUG] switch_core_session.c:871 Send signal sofia/internal/sip:1002@10.211.55.253:64608 [BREAK] 2014-03-26 09:36:20.528557 [DEBUG] mod_fifo.c:3506 sofia/internal/sip:1002@10.211.55.253:64608 is still alive, tracking call. 2014-03-26 09:36:20.528557 [DEBUG] mod_fifo.c:2354 sofia/internal/sip:1002@10.211.55.253:64608 tracking call on uuid 096198194e8c47a66fd1477ceb17c16b! 2014-03-26 09:36:20.528557 [NOTICE] switch_core_state_machine.c:262 sofia/internal/sip:1002@10.211.55.253:64608 has executed the last dialplan instruction, hanging up. 2014-03-26 09:36:20.528557 [NOTICE] switch_core_state_machine.c:264 Hangup sofia/internal/sip:1002@10.211.55.253:64608 [CS_EXECUTE] [NORMAL_CLEARING] 2014-03-26 09:36:20.528557 [DEBUG] switch_channel.c:3187 Send signal sofia/internal/sip:1002@10.211.55.253:64608 [KILL] 2014-03-26 09:36:20.528557 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/sip:1002@10.211.55.253:64608 [BREAK] 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:478 (sofia/internal/sip:1002@10.211.55.253:64608) State EXECUTE going to sleep 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:1002@10.211.55.253:64608) Running State Change CS_HANGUP 2014-03-26 09:36:20.528557 [DEBUG] mod_fifo.c:2267 sofia/internal/sip:1002@10.211.55.253:64608 untracking call on uuid 096198194e8c47a66fd1477ceb17c16b! 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:678 (sofia/internal/sip:1002@10.211.55.253:64608) Callstate Change ACTIVE -> HANGUP 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:680 (sofia/internal/sip:1002@10.211.55.253:64608) State HANGUP 2014-03-26 09:36:20.528557 [DEBUG] mod_sofia.c:506 Channel sofia/internal/sip:1002@10.211.55.253:64608 hanging up, cause: NORMAL_CLEARING 2014-03-26 09:36:20.528557 [DEBUG] mod_sofia.c:558 Sending BYE to sofia/internal/sip:1002@10.211.55.253:64608 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:48 sofia/internal/sip:1002@10.211.55.253:64608 Standard HANGUP, cause: NORMAL_CLEARING 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:680 (sofia/internal/sip:1002@10.211.55.253:64608) State HANGUP going to sleep 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:447 (sofia/internal/sip:1002@10.211.55.253:64608) State Change CS_HANGUP -> CS_REPORTING 2014-03-26 09:36:20.528557 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/sip:1002@10.211.55.253:64608 [BREAK] 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:1002@10.211.55.253:64608) Running State Change CS_REPORTING 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:764 (sofia/internal/sip:1002@10.211.55.253:64608) State REPORTING 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:92 sofia/internal/sip:1002@10.211.55.253:64608 Standard REPORTING, cause: NORMAL_CLEARING 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:764 (sofia/internal/sip:1002@10.211.55.253:64608) State REPORTING going to sleep 2014-03-26 09:36:20.528557 [DEBUG] switch_core_state_machine.c:441 (sofia/internal/sip:1002@10.211.55.253:64608) State Change CS_REPORTING -> CS_DESTROY 2014-03-26 09:36:20.528557 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/sip:1002@10.211.55.253:64608 [BREAK] 2014-03-26 09:36:20.528557 [DEBUG] switch_core_session.c:1559 Session 11 (sofia/internal/sip:1002@10.211.55.253:64608) Locked, Waiting on external entities 2014-03-26 09:36:20.588437 [DEBUG] switch_ivr_bridge.c:569 sofia/internal/sip:1002@10.211.55.253:64608 ending bridge by request from write function 2014-03-26 09:36:20.588437 [DEBUG] switch_ivr_bridge.c:645 BRIDGE THREAD DONE [sofia/external/73951110@sip1.vmlab.iplink.no] 2014-03-26 09:36:20.588437 [DEBUG] switch_ivr_bridge.c:675 Send signal sofia/internal/sip:1002@10.211.55.253:64608 [BREAK] 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:481 (sofia/external/73951110@sip1.vmlab.iplink.no) State EXCHANGE_MEDIA going to sleep 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:415 (sofia/external/73951110@sip1.vmlab.iplink.no) Running State Change CS_ROUTING 2014-03-26 09:36:20.588437 [DEBUG] switch_channel.c:2163 (sofia/external/73951110@sip1.vmlab.iplink.no) Callstate Change ACTIVE -> RINGING 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:471 (sofia/external/73951110@sip1.vmlab.iplink.no) State ROUTING 2014-03-26 09:36:20.588437 [DEBUG] mod_sofia.c:150 sofia/external/73951110@sip1.vmlab.iplink.no SOFIA ROUTING 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:117 sofia/external/73951110@sip1.vmlab.iplink.no Standard ROUTING 2014-03-26 09:36:20.588437 [INFO] mod_dialplan_xml.c:558 Processing Outbound Call <1002>->73951111 in context 133582 2014-03-26 09:36:20.588437 [NOTICE] switch_core_session.c:1577 Session 11 (sofia/internal/sip:1002@10.211.55.253:64608) Ended 2014-03-26 09:36:20.588437 [NOTICE] switch_core_session.c:1581 Close Channel sofia/internal/sip:1002@10.211.55.253:64608 [CS_DESTROY] Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no parsing [133582->main] continue=true Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [main] destination_number(73951111) =~ /^in|^out|^custom/ break=on-false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action set(return_to_main=true) INLINE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no set(return_to_main=true) 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:566 (sofia/internal/sip:1002@10.211.55.253:64608) Callstate Change HANGUP -> DOWN 2014-03-26 09:36:20.588437 [DEBUG] mod_dptools.c:1402 sofia/external/73951110@sip1.vmlab.iplink.no SET [return_to_main]=[true] 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/sip:1002@10.211.55.253:64608) Running State Change CS_DESTROY Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action set(domain_name=${context}) INLINE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no set(domain_name=133582) 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:579 (sofia/internal/sip:1002@10.211.55.253:64608) State DESTROY 2014-03-26 09:36:20.588437 [DEBUG] mod_sofia.c:399 sofia/internal/sip:1002@10.211.55.253:64608 SOFIA DESTROY 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:99 sofia/internal/sip:1002@10.211.55.253:64608 Standard DESTROY 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:579 (sofia/internal/sip:1002@10.211.55.253:64608) State DESTROY going to sleep 2014-03-26 09:36:20.588437 [DEBUG] mod_dptools.c:1402 sofia/external/73951110@sip1.vmlab.iplink.no SET [domain_name]=[133582] Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action set(domain=${context}) INLINE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no set(domain=133582) 2014-03-26 09:36:20.588437 [DEBUG] mod_dptools.c:1402 sofia/external/73951110@sip1.vmlab.iplink.no SET [domain]=[133582] Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action set(return_to_main=true) INLINE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no set(return_to_main=true) 2014-03-26 09:36:20.588437 [DEBUG] mod_dptools.c:1402 sofia/external/73951110@sip1.vmlab.iplink.no SET [return_to_main]=[true] Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action set(gateway_to_context_75400400=${domain_name}) INLINE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no set(gateway_to_context_75400400=133582) 2014-03-26 09:36:20.588437 [DEBUG] mod_dptools.c:1402 sofia/external/73951110@sip1.vmlab.iplink.no SET [gateway_to_context_75400400]=[133582] Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action set(gateway_to_context_31003145=${domain_name}) INLINE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no set(gateway_to_context_31003145=133582) 2014-03-26 09:36:20.588437 [DEBUG] mod_dptools.c:1402 sofia/external/73951110@sip1.vmlab.iplink.no SET [gateway_to_context_31003145]=[133582] Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action set(allGateways=75400400|33503300|69698800|21050869|31003145) INLINE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no set(allGateways=75400400|33503300|69698800|21050869|31003145) 2014-03-26 09:36:20.588437 [DEBUG] mod_dptools.c:1402 sofia/external/73951110@sip1.vmlab.iplink.no SET [allGateways]=[75400400|33503300|69698800|21050869|31003145] Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action set(defaultGateway=75400400) INLINE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no set(defaultGateway=75400400) 2014-03-26 09:36:20.588437 [DEBUG] mod_dptools.c:1402 sofia/external/73951110@sip1.vmlab.iplink.no SET [defaultGateway]=[75400400] Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action set(faxGateway_1=) INLINE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no set(faxGateway_1=) 2014-03-26 09:36:20.588437 [DEBUG] mod_dptools.c:1402 sofia/external/73951110@sip1.vmlab.iplink.no SET [faxGateway_1]=[UNDEF] Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no ANTI-Action transfer(${destination_number} XML CallHandler) Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no parsing [133582->customCheckMainTimeTable] continue=false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [customCheckMainTimeTable] destination_number(73951111) =~ /^customCheckMainTimeTable_(\d+)$/ break=on-false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no parsing [133582->custom_voicemail_1] continue=false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [custom_voicemail_1] destination_number(73951111) =~ /^custom_voicemail_1$/ break=on-true Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no parsing [133582->inbound] continue=false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [inbound] destination_number(73951111) =~ /^inbound_to_(75400400|31003145)$/ break=on-true Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [inbound] destination_number(73951111) =~ /^inbound_to_(\d{8})$/ break=on-true Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no parsing [133582->custom_menu_1] continue=false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [custom_menu_1] destination_number(73951111) =~ /^custom_menu_1$/ break=on-true Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no parsing [133582->custom_callout_1] continue=false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [custom_callout_1] destination_number(73951111) =~ /^custom_callout_1_(\d+)$/ break=on-false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no parsing [133582->custom_queue_1] continue=false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [custom_queue_1] destination_number(73951111) =~ /^custom_queue_1$/ break=on-true Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no parsing [133582->custom_queue_2] continue=false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [custom_queue_2] destination_number(73951111) =~ /^custom_queue_2$/ break=on-true Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no parsing [133582->custom_queue_3] continue=false Dialplan: sofia/external/73951110@sip1.vmlab.iplink.no Regex (FAIL) [custom_queue_3] destination_number(73951111) =~ /^custom_queue_3$/ break=on-true 2014-03-26 09:36:20.588437 [INFO] switch_channel.c:3027 sofia/external/73951110@sip1.vmlab.iplink.no Flipping CID from "Outbound Call" <1002> to "73951110" <73951110> ?????????????? What happens here !!!!!!! 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:167 (sofia/external/73951110@sip1.vmlab.iplink.no) State Change CS_ROUTING -> CS_EXECUTE 2014-03-26 09:36:20.588437 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/73951110@sip1.vmlab.iplink.no [BREAK] 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:471 (sofia/external/73951110@sip1.vmlab.iplink.no) State ROUTING going to sleep 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:415 (sofia/external/73951110@sip1.vmlab.iplink.no) Running State Change CS_EXECUTE 2014-03-26 09:36:20.588437 [DEBUG] switch_channel.c:2165 (sofia/external/73951110@sip1.vmlab.iplink.no) Callstate Change RINGING -> ACTIVE 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:478 (sofia/external/73951110@sip1.vmlab.iplink.no) State EXECUTE 2014-03-26 09:36:20.588437 [DEBUG] mod_sofia.c:243 sofia/external/73951110@sip1.vmlab.iplink.no SOFIA EXECUTE 2014-03-26 09:36:20.588437 [DEBUG] switch_core_state_machine.c:209 sofia/external/73951110@sip1.vmlab.iplink.no Standard EXECUTE EXECUTE sofia/external/73951110@sip1.vmlab.iplink.no transfer(75400400 XML CallHandler) 2014-03-26 09:36:20.588437 [DEBUG] switch_ivr.c:1831 (sofia/external/73951110@sip1.vmlab.iplink.no) State Change CS_EXECUTE -> CS_ROUTING 2014-03-26 09:36:20.588437 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/73951110@sip1.vmlab.iplink.no [BREAK] 2014-03-26 09:36:20.588437 [DEBUG] switch_core_session.c:871 Send signal sofia/external/73951110@sip1.vmlab.iplink.no [BREAK] 2014