blind transfer from SIP phone
19 views
Skip to first unread message
John Burke
Jul 13, 2017, 6:02:08 PM7/13/17
to Adhearsion
Hello!
I am running into an issue while trying to perform a blind transfer with my Adhearsion application. Here is the scenario:
(1) Caller A dials Caller B ----> dialplan routes to <action application="rayo"> where I have a controller bridge the two extensions.
(2) From Caller A's phone, a blind transfer for Caller B to Caller C is initiated (A hangs up, outbound call originated to C).
(3) The dialplan routes the initiated call to <action application="rayo">. However, freeswitch fails to connect to Adhearsion via mod_rayo and therefore nothing is executing in the controllers. You can see in this step that the mod_rayo never "has control of call" as is normally logged by FS.
I have successfully been able to make attended transfers work using the same application that fails in the above scenario. My attended transfer is: Caller A has an active call with Caller B and Caller C at the same time... and when Caller A hangs up B/C are connected. Again, the transfer is initiated from Caller A's SIP phone and the SIP refer is handled just fine.
Any thoughts on why I can't get mod_rayo to take control of the call in step 3???
Thanks for the help!
John
I attached full logs if interested... but the portion of interest in the FS logs I pasted below.
Successful connection to mod_rayo (step 1):
2017-07-13 16:47:01.575482 [INFO] mod_dialplan_xml.c:637 Processing test-freeswitchPBX <1015>->1002 in context default
Dialplan: sofia/external/10...@192.168.20.32 parsing [john-test->john-test-extension] continue=false
Dialplan: sofia/external/10...@192.168.20.32 Absolute Condition [john-test-extension]
Dialplan: sofia/external/10...@192.168.20.32 Action rayo()
2017-07-13 16:47:01.575482 [DEBUG] switch_core_state_machine.c:286 (sofia/external/10...@192.168.20.32) State Change CS_ROUTING -> CS_EXECUTE
2017-07-13 16:47:01.575482 [DEBUG] switch_core_state_machine.c:643 (sofia/external/10...@192.168.20.32) State ROUTING going to sleep
2017-07-13 16:47:01.575482 [DEBUG] switch_core_state_machine.c:584 (sofia/external/10...@192.168.20.32) Running State Change CS_EXECUTE (Cur 1 Tot 17)
2017-07-13 16:47:01.575482 [DEBUG] switch_core_state_machine.c:650 (sofia/external/10...@192.168.20.32) State EXECUTE
2017-07-13 16:47:01.575482 [DEBUG] mod_sofia.c:198 sofia/external/10...@192.168.20.32 SOFIA EXECUTE
2017-07-13 16:47:01.575482 [DEBUG] switch_core_state_machine.c:328 sofia/external/10...@192.168.20.32 Standard EXECUTE
EXECUTE sofia/external/10...@192.168.20.32 rayo()
2017-07-13 16:47:01.575482 [DEBUG] mod_rayo.c:4088 Init cd961030-6814-11e7...@192.168.20.32
2017-07-13 16:47:01.575482 [DEBUG] mod_rayo.c:3696 got event CHANNEL_EXECUTE
2017-07-13 16:47:01.575482 [DEBUG] mod_rayo.c:4098 Offering call for Rayo 3PCC
2017-07-13 16:47:01.575482 [DEBUG] mod_rayo.c:842 Locate (jid) us...@192.168.20.32/wahoo-john-19152: ref count = 2
2017-07-13 16:47:01.575482 [DEBUG] mod_rayo.c:3907 Deliver cd961030-6814-11e7...@192.168.20.32 => us...@192.168.20.32/wahoo-john-19152 <presence from='cd961030-6814-11e7...@192.168.20.32' to='us...@192.168.20.32/wahoo-john-19152'><c xmlns='http://jabber.org/protocol/caps' hash='sha-1' node='urn:xmpp:rayo:call:1' ver='L9rOKMEJl9rssEggArljiapRd9k='/><offer xmlns='urn:xmpp:rayo:1' from='sip:10...@192.168.20.32' to='sip:10...@192.168.20.32'><header name='from' value='"test-freeswitchPBX" <sip:10...@192.168.20.32>;tag=e6814c01'/><header name='to' value='<sip:10...@192.168.20.32>'/><header name='via' value='SIP/2.0/UDP 192.168.51.2:58901;branch=z9hG4bK-524287-1---e59dda1644c15b03;rport=58901'/></offer><delay xmlns='urn:xmpp:delay' stamp='2017-07-13T21:47:01Z'/></presence>
2017-07-13 16:47:01.575482 [DEBUG] mod_rayo.c:849 Release us...@192.168.20.32/wahoo-john-19152: ref count = 1
2017-07-13 16:47:01.575482 [DEBUG] mod_rayo.c:3696 got event CHANNEL_PARK
2017-07-13 16:47:01.575482 [DEBUG] xmpp_streams.c:192 us...@192.168.20.32/wahoo-john-19152, 192.168.20.137:44768, c2s_in SEND <presence from='cd961030-6814-11e7...@192.168.20.32' to='us...@192.168.20.32/wahoo-john-19152'><c xmlns='http://jabber.org/protocol/caps' hash='sha-1' node='urn:xmpp:rayo:call:1' ver='L9rOKMEJl9rssEggArljiapRd9k='/><offer xmlns='urn:xmpp:rayo:1' from='sip:10...@192.168.20.32' to='sip:10...@192.168.20.32'><header name='from' value='"test-freeswitchPBX" <sip:10...@192.168.20.32>;tag=e6814c01'/><header name='to' value='<sip:10...@192.168.20.32>'/><header name='via' value='SIP/2.0/UDP 192.168.51.2:58901;branch=z9hG4bK-524287-1---e59dda1644c15b03;rport=58901'/></offer><delay xmlns='urn:xmpp:delay' stamp='2017-07-13T21:47:01Z'/></presence>
2017-07-13 16:47:01.615456 [DEBUG] xmpp_streams.c:192 us...@192.168.20.32/wahoo-john-19152, 192.168.20.137:44768, c2s_in RECV <iq type="set" to="cd961030-6814-11e7...@192.168.20.32" id="f14cb3d7-e165-4a3b-9567-bb7468516f5a"><accept xmlns="urn:xmpp:rayo:1"/></iq>
2017-07-13 16:47:01.615456 [DEBUG] xmpp_streams.c:1073 us...@192.168.20.32/wahoo-john-19152, 192.168.20.137:44768, state = READY, node type = NODE_NORMAL
2017-07-13 16:47:01.615456 [DEBUG] mod_rayo.c:4199 Lock us...@192.168.20.32/wahoo-john-19152: ref count = 2
2017-07-13 16:47:01.615456 [DEBUG] mod_rayo.c:3145 us...@192.168.20.32/wahoo-john-19152, recv iq, availability = ONLINE
2017-07-13 16:47:01.615456 [DEBUG] mod_rayo.c:4283 Release us...@192.168.20.32/wahoo-john-19152: ref count = 1
2017-07-13 16:47:01.615456 [DEBUG] mod_rayo.c:842 Locate (jid) cd961030-6814-11e7...@192.168.20.32: ref count = 2
2017-07-13 16:47:01.615456 [DEBUG] mod_rayo.c:3148 Deliver us...@192.168.20.32/wahoo-john-19152 => cd961030-6814-11e7...@192.168.20.32 <iq type='set' to='cd961030-6814-11e7...@192.168.20.32' id='f14cb3d7-e165-4a3b-9567-bb7468516f5a' from='us...@192.168.20.32/wahoo-john-19152'><accept xmlns='urn:xmpp:rayo:1'/></iq>
2017-07-13 16:47:01.615456 [DEBUG] mod_rayo.c:723 cd961030-6814-11e7...@192.168.20.32, looking for CALL::set:urn:xmpp:rayo:1:accept command
2017-07-13 16:47:01.615456 [INFO] mod_rayo.c:1713 us...@192.168.20.32/wahoo-john-19152 has control of call
Failed connection to mod_rayo (step 3):
2017-07-13 16:47:09.435451 [INFO] mod_dialplan_xml.c:637 Processing Outbound Call <1002>->1011 in context default
Dialplan: sofia/external/10...@192.168.20.112:5060 parsing [default->eaves] continue=false
Dialplan: sofia/external/10...@192.168.20.112:5060 Regex (PASS) [eaves] destination_number(1011) =~ /^.*$/ break=on-false
Dialplan: sofia/external/10...@192.168.20.112:5060 Action sleep(5000)
Dialplan: sofia/external/10...@192.168.20.112:5060 Action rayo()
2017-07-13 16:47:09.435451 [DEBUG] switch_core_state_machine.c:286 (sofia/external/10...@192.168.20.112:5060) State Change CS_ROUTING -> CS_EXECUTE
2017-07-13 16:47:09.435451 [DEBUG] switch_core_state_machine.c:643 (sofia/external/10...@192.168.20.112:5060) State ROUTING going to sleep
2017-07-13 16:47:09.435451 [DEBUG] switch_core_state_machine.c:584 (sofia/external/10...@192.168.20.112:5060) Running State Change CS_EXECUTE (Cur 2 Tot 18)
2017-07-13 16:47:09.435451 [DEBUG] switch_core_state_machine.c:650 (sofia/external/10...@192.168.20.112:5060) State EXECUTE
2017-07-13 16:47:09.435451 [DEBUG] mod_sofia.c:198 sofia/external/10...@192.168.20.112:5060 SOFIA EXECUTE
2017-07-13 16:47:09.435451 [DEBUG] switch_core_state_machine.c:328 sofia/external/10...@192.168.20.112:5060 Standard EXECUTE
EXECUTE sofia/external/10...@192.168.20.112:5060 sleep(5000)
2017-07-13 16:47:09.435451 [DEBUG] mod_rayo.c:3696 got event CHANNEL_EXECUTE
2017-07-13 16:47:09.435451 [DEBUG] switch_ivr.c:195 Codec Activated L16@8000hz 1 channels 20ms
2017-07-13 16:47:09.435451 [DEBUG] mod_rayo.c:3701 us...@192.168.20.32/wahoo-john-19152 rayo event CHANNEL_EXECUTE
2017-07-13 16:47:09.435451 [DEBUG] mod_rayo.c:3703 Locate (jid) us...@192.168.20.32/wahoo-john-19152: ref count = 2
2017-07-13 16:47:09.435451 [DEBUG] mod_rayo.c:3624 Locate (id) 0bf19fba-a590-4de7...@192.168.20.32: ref count = 2
2017-07-13 16:47:09.435451 [DEBUG] mod_rayo.c:3626 Application sleep execute
2017-07-13 16:47:09.435451 [DEBUG] mod_rayo.c:3627 Release 0bf19fba-a590-4de7...@192.168.20.32: ref count = 1
2017-07-13 16:47:09.435451 [DEBUG] mod_rayo.c:3711 Release us...@192.168.20.32/wahoo-john-19152: ref count = 1
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3696 got event CHANNEL_EXECUTE_COMPLETE
EXECUTE sofia/external/10...@192.168.20.112:5060 rayo()
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3701 us...@192.168.20.32/wahoo-john-19152 rayo event CHANNEL_EXECUTE_COMPLETE
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3703 Locate (jid) us...@192.168.20.32/wahoo-john-19152: ref count = 2
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3638 Locate (id) 0bf19fba-a590-4de7...@192.168.20.32: ref count = 2
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3641 Application sleep execute complete: _none_
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3644 Release 0bf19fba-a590-4de7...@192.168.20.32: ref count = 1
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3711 Release us...@192.168.20.32/wahoo-john-19152: ref count = 1
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:4044 Locate (id) 0bf19fba-a590-4de7...@192.168.20.32: ref count = 2
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:4050 Release 0bf19fba-a590-4de7...@192.168.20.32: ref count = 1
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3696 got event CHANNEL_EXECUTE
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3701 us...@192.168.20.32/wahoo-john-19152 rayo event CHANNEL_EXECUTE
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3703 Locate (jid) us...@192.168.20.32/wahoo-john-19152: ref count = 2
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3624 Locate (id) 0bf19fba-a590-4de7...@192.168.20.32: ref count = 2
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3626 Application rayo execute
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3627 Release 0bf19fba-a590-4de7...@192.168.20.32: ref count = 1
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3711 Release us...@192.168.20.32/wahoo-john-19152: ref count = 1
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3696 got event CHANNEL_PARK
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3701 us...@192.168.20.32/wahoo-john-19152 rayo event CHANNEL_PARK
2017-07-13 16:47:14.435479 [DEBUG] switch_ivr.c:980 Codec Activated L16@8000hz 1 channels 20ms
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3703 Locate (jid) us...@192.168.20.32/wahoo-john-19152: ref count = 2
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3585 Locate (id) 0bf19fba-a590-4de7...@192.168.20.32: ref count = 2
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3613 Release 0bf19fba-a590-4de7...@192.168.20.32: ref count = 1
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3711 Release us...@192.168.20.32/wahoo-john-19152: ref count = 1
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:842 Locate (jid) us...@192.168.20.32/wahoo-john-19152: ref count = 2
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:3611 Deliver 0bf19fba-a590-4de7...@192.168.20.32 => us...@192.168.20.32/wahoo-john-19152 <presence from='0bf19fba-a590-4de7...@192.168.20.32' to='us...@192.168.20.32/wahoo-john-19152'><unjoined xmlns='urn:xmpp:rayo:1' call-uri='xmpp:cd961030-6814-11e7...@192.168.20.32'/><delay xmlns='urn:xmpp:delay' stamp='2017-07-13T21:47:14Z'/></presence>
2017-07-13 16:47:14.435479 [DEBUG] mod_rayo.c:849 Release us...@192.168.20.32/wahoo-john-19152: ref count = 1
2017-07-13 16:47:14.435479 [DEBUG] xmpp_streams.c:192 us...@192.168.20.32/wahoo-john-19152, 192.168.20.137:44768, c2s_in SEND <presence from='0bf19fba-a590-4de7...@192.168.20.32' to='us...@192.168.20.32/wahoo-john-19152'><unjoined xmlns='urn:xmpp:rayo:1' call-uri='xmpp:cd961030-6814-11e7...@192.168.20.32'/><delay xmlns='urn:xmpp:delay' stamp='2017-07-13T21:47:14Z'/></presence>
Reply all
Reply to author
Forward
0 new messages