Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for
- Cisco Community
- Technology and Support
- DevNet
- DevNet Collaboration
- Collaboration Knowledge Base
- Delay while transfer to ICM | Subdialog Return execution Delay
Options
- Subscribe to RSS Feed
- Mark as New
- Mark as Read
- Bookmark
- Subscribe
- Printer Friendly Page
- Report Inappropriate Content
127
Views
0
Helpful
0
Comments
Options
- Subscribe to RSS Feed
- Mark as New
- Mark as Read
- Bookmark
- Subscribe
- Printer Friendly Page
- Report Inappropriate Content
on 01-24-2014 03:53 PM
This document was generated from CDN thread
Created by: Feroz Syed on 23-04-2012 03:52:08 PM
Hi,
I have a CVP Application which has an Audio and then few backend hits and then transfers to ICM with data in Caller Input and ExternalVXML 2 sections. The call is getting trabsferred to ICM and then to Agent VDN without any issues,
But I see a significant delay while transferring to Agent after the last audio played. I found from the logs that Subdialog Return element execution is taking that 8-12 sec delay. Also i Have replaced the audio with silence and
audios of different duration and the delay is increased with increased audio. I have verified and there are no issues with the data being passed to ICM as well.
Below are the activity logs and VXML logs for a call with hilighted observations.
Please do the needful and appriciate your quick response.
Activity Logs:
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,InteractionsTransfer,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,InteractionsTransfer,interaction,audio_group,initial_audio_group
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,InteractionsTransfer,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,custom,MT Interface custom action element version 1.4,May 25 2010
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,custom,The absolute URL is
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,custom,fetchtimeout not specified in root document ,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,custom,Setting to default timeout,14000
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,Log_6214,custom,XML Response is :,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,Log_6214,custom,Data.Element : ,Result - True
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,Log_6214,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetPilotTransfer,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetPilotTransfer,custom,Action,INT_5001
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetPilotTransfer,custom,Counter Progress, INITIAL: 0 STEP: 1 TYPE: increment COUNT: 0
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetPilotTransfer,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetSaveCallData,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetSaveCallData,custom,Counter Progress, INITIAL: 0 STEP: 1 TYPE: increment COUNT: 0
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetSaveCallData,custom,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetSaveCallData,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,custom,MT Interface custom action element version 1.4,May 25 2010
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,custom,The absolute URL is
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,custom,fetchtimeout not specified in root document ,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,custom,Setting to default timeout,14000
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,SaveCallData,custom,XML Response is :,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,SaveCallData,custom,Data.Element : ,Result - True
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,SaveCallData,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,custom,MT Interface custom action element version 1.4,May 25 2010
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,custom,The absolute URL is
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,custom,fetchtimeout not specified in root document ,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,custom,Setting to default timeout,14000
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.612,Log_EndCT,custom,XML Response is :,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.612,Log_EndCT,custom,Data.Element : ,Result - True
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.612,Log_EndCT,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.612,Subdialog_Return,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,Subdialog_Return,exit,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : X ,XXX_XXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XX ,XXXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : X,XXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XX,XXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXX,XXXXXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXX,X
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXX,XXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXX,XXXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXXXXXXXXXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXXXX,XXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXXXX,XXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXXXXXXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,end,how,app_session_complete
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,end,result,normal
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,end,duration,114
VXML Logs:
------------------------------------
--------- New Page Request ---------
------------------------------------
Request Received on: 04/23/2012 13:25:11.003
------- HTTP Request Headers -------
Header Name = "content-type" Header Value = "application/x-www-form-urlencoded"
Header Name = "cookie" Header Value = "$Version=0; JSESSIONID=1B5DB5BCFE1E7AAB0DFA52BBD81CB122; $Path=/CVP"
Header Name = "connection" Header Value = "close"
Header Name = "host" Header Value = "XX.X.XX.XXX:8080"
Header Name = "content-length" Header Value = "307"
Header Name = "accept" Header Value = "text/vxml, text/x-vxml, application/vxml, application/x-vxml, application/voicexml, application/x-voicexml, text/plain, text/html, audio/basic, audio/wav, multipart/form-data, application/octet-stream"
Header Name = "user-agent" Header Value = "Cisco-IOS-C5350/15.1"
------ Request HTTP Arguments ------
Parameter Name = "completeNBestStr" Parameter Value #0 = "|||1^^^1^^^dtmf^^^1"
Parameter Name = "confidence" Parameter Value #0 = "1"
Parameter Name = "audium_vxmlLog" Parameter Value #0 = "|||audio_group$$$initial_audio_group^^^0|||utterance$$$1^^^25289|||inputmode$$$dtmf^^^25289|||interpretation$$$1^^^25289|||confidence$$$1^^^25293"
Parameter Name = "choice_fld" Parameter Value #0 = "1"
--------- VoiceXML Response --------
Response Returned on: 04/23/2012 13:25:13.300
<?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.0" application="/CVP/Server?audium_root=true&calling_into=XXXXXXXXXXXXXXXXX&session_id=XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX" xml:lang="en-US">
<meta name="maintainer" content="Feroz Syed" />
<form id="audium_start_form">
<block>
<assign name="audium_vxmlLog" expr="''" />
<assign name="audium_element_start_time_millisecs" expr="new Date().getTime()" />
<goto next="#start" />
</block>
</form>
<form id="start">
<block>
<prompt bargein="true">
<audio src="[url=http://XX.X.XX.XXX.252/Media/Dev_/en-us/app/FE/Interactions_Transfer.wav">XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX</audio]http://XX.X.XX.XXX.252/Media/Dev_/en-us/app/FE/Interactions_Transfer.wav">XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX</audio>
</prompt>
<assign name="audium_vxmlLog" expr="audium_vxmlLog + '|||audio_group$$$' + 'initial_audio_group' + '^^^' + application.getElapsedTime(audium_element_start_time_millisecs)" />
<submit next="/CVP/Server" method="post" namelist="audium_vxmlLog" />
</block>
</form>
</vxml>
------------------------------------
--------- New Page Request ---------
------------------------------------
Request Received on: 04/23/2012 13:25:13.300
------- HTTP Request Headers -------
Header Name = "content-type" Header Value = "application/x-www-form-urlencoded"
Header Name = "cookie" Header Value = "$Version=0; JSESSIONID=1B5DB5BCFE1E7AAB0DFA52BBD81CB122; $Path=/CVP"
Header Name = "connection" Header Value = "close"
Header Name = "host" Header Value = "XX.X.XX.XXX:8080"
Header Name = "content-length" Header Value = "67"
Header Name = "accept" Header Value = "text/vxml, text/x-vxml, application/vxml, application/x-vxml, application/voicexml, application/x-voicexml, text/plain, text/html, audio/basic, audio/wav, multipart/form-data, application/octet-stream"
Header Name = "user-agent" Header Value = "Cisco-IOS-C5350/15.1"
------ Request HTTP Arguments ------
Parameter Name = "audium_vxmlLog" Parameter Value #0 = "|||audio_group$$$initial_audio_group^^^0"
--------- VoiceXML Response --------
Response Returned on: 04/23/2012 13:25:14.612
<?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.0" application="/CVP/Server?audium_root=true&calling_into=XXXXXXXXXXXXXXXXX&session_id=XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX" xml:lang="en-US">
<meta name="maintainer" content="Feroz Syed" />
<catch event="error.com.cisco.callhandoff.failure">
<cisco-typeaheadflush />
<submit next="/CVP/Server" method="post" namelist="audium_vxmlLog" />
</catch>
<form id="audium_start_form">
<block>
<assign name="audium_vxmlLog" expr="''" />
<assign name="audium_element_start_time_millisecs" expr="new Date().getTime()" />
<goto next="#start" />
</block>
</form>
<form id="start">
<object name="dummyobj" classid="builtin://com.cisco.callhandoff">
<param name="return" expr="true" valuetype="data" />
<param name="app-uri" expr="'builtin://dummyobj'" valuetype="data" />
<filled>
<submit next="/CVP/Server" method="post" namelist="audium_vxmlLog" />
</filled>
</object>
</form>
</vxml>
------------------------------------
--------- New Page Request ---------
------------------------------------
Request Received on: 04/23/2012 13:26:29.819
------- HTTP Request Headers -------
Header Name = "content-type" Header Value = "application/x-www-form-urlencoded"
Header Name = "cookie" Header Value = "$Version=0; JSESSIONID=1B5DB5BCFE1E7AAB0DFA52BBD81CB122; $Path=/CVP"
Header Name = "connection" Header Value = "close"
Header Name = "host" Header Value = "XX.X.XX.XXX:8080"
Header Name = "content-length" Header Value = "15"
Header Name = "accept" Header Value = "text/vxml, text/x-vxml, application/vxml, application/x-vxml, application/voicexml, application/x-voicexml, text/plain, text/html, audio/basic, audio/wav, multipart/form-data, application/octet-stream"
Header Name = "user-agent" Header Value = "Cisco-IOS-C5350/15.1"
------ Request HTTP Arguments ------
Parameter Name = "audium_vxmlLog" Parameter Value #0 = ""
--------- VoiceXML Response --------
Response Returned on: 04/23/2012 13:26:29.819
<?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.0" xml:lang="en-US">
<catch event="vxml.session.error">
<return event="vxml.session.error" />
</catch>
<catch event="telephone.disconnect.hangup">
<return event="telephone.disconnect.hangup" />
</catch>
<catch event="telephone.disconnect">
<return event="telephone.disconnect" />
</catch>
<catch event="error.unsupported.object">
<return event="error.unsupported.object" />
</catch>
<catch event="error.unsupported.language">
<return event="error.unsupported.language" />
</catch>
<catch event="error.unsupported.format">
<return event="error.unsupported.format" />
</catch>
<catch event="error.unsupported.element">
<return event="error.unsupported.element" />
</catch>
<catch event="error.unsupported.builtin">
<return event="error.unsupported.builtin" />
</catch>
<catch event="error.unsupported">
<return event="error.unsupported" />
</catch>
<catch event="error.semantic">
<return event="error.semantic" />
</catch>
<catch event="error.noauthorization">
<return event="error.noauthorization" />
</catch>
<catch event="error.eventhandler.notfound">
<return event="error.eventhandler.notfound" />
</catch>
<catch event="error.connection.noroute">
<return event="error.connection.noroute" />
</catch>
<catch event="error.connection.noresource">
<return event="error.connection.noresource" />
</catch>
<catch event="error.connection.nolicense">
<return event="error.connection.nolicense" />
</catch>
<catch event="error.connection.noauthorization">
<return event="error.connection.noauthorization" />
</catch>
<catch event="error.connection.baddestination">
<return event="error.connection.baddestination" />
</catch>
<catch event="error.condition.baddestination">
<return event="error.condition.baddestination" />
</catch>
<catch event="error.com.cisco.resource.failure.tts">
<return event="error.com.cisco.resource.failure.tts" />
</catch>
<catch event="error.com.cisco.media.resource.unavailable">
<return event="error.com.cisco.media.resource.unavailable" />
</catch>
<catch event="error.com.cisco.handoff.failure">
<return event="error.com.cisco.handoff.failure" />
</catch>
<catch event="error.com.cisco.callhandoff.failure">
<return event="error.com.cisco.callhandoff.failure" />
</catch>
<catch event="error.com.cisco.aaa.authorize.failure">
<return event="error.com.cisco.aaa.authorize.failure" />
</catch>
<catch event="error.com.cisco.aaa.authenticate.failure">
<return event="error.com.cisco.aaa.authenticate.failure" />
</catch>
<catch event="error.badfetch.https">
<return event="com.audium.error.badfetch.https" />
</catch>
<catch event="error.badfetch.http">
<return event="com.audium.error.badfetch.http" />
</catch>
<catch event="error.badfetch">
<return event="com.audium.error.badfetch" />
</catch>
<catch event="error">
<return event="error" />
</catch>
<catch event="disconnect.com.cisco.handoff">
<return event="disconnect.com.cisco.handoff" />
</catch>
<catch event="connection.disconnect.hangup">
<return event="connection.disconnect.hangup" />
</catch>
<catch event="connection.disconnect">
<return event="connection.disconnect" />
</catch>
<form>
<block>
<var name="caller_input" expr="'A=INT_5001;AT=ICMRoute;L=en-us;EID=XXXXXXXX;XX=XXXX;CID=XXXXXXX'" />
<var name="FromExtVXML2" expr="'XXX=XXXXXXXXXX;XXXX=X'" />
<return namelist="caller_input FromExtVXML2" />
</block>
</form>
</vxml>
Subject: RE: New Message from Feroz Syed in Customer Voice Portal (CVP) - CVP - All
Replied by: GEOFFREY THOMPSON on 23-04-2012 04:48:13 PM
>>>04/23/2012 13:25:14.612,Log_EndCT,custom,Data.Element : ,Result - True
>>>04/23/2012 13:25:14.612,Log_EndCT,exit,done
>>>04/23/2012 13:25:14.612,Subdialog_Return,enter,
>>>04/23/2012 13:26:29.819,Subdialog_Return,exit,
I’ve never seen anything like this, but I certainly have seen “issues” with the CVP logging facility.
Is it possible that it hasn’t actually returned from the element above “MT Interface custom action element version 1.4,May 25 2010”.
Since this is your custom element you could write to a low-level log file of your own, or the Tomcat screen, when you exit, just to be really sure of the timing.
Regards,
Geoff
Subject: RE: Delay while transfer to ICM | Subdialog Return execution Delay
Replied by: Feroz Syed on 23-04-2012 06:13:00 PM
After analysis, I suspect the delay could be because of the Audio. I see CVP sent com.cisco.callhandoff object to Gateway at 04/23/2012 13:25:14.612 and Gateway waited until the Audio finishes (The audio was about 1 min 16 sec) though CVP executed Non VXML content while playing the audio but it took in Milli Seconds. And when I replaced with 0.5 sec silence auido, I dont see any delay in SubDialog Return execution.
BTW, the node used is CVP_Subdialog_Return.
So the problem could be something else as the time frame of SubDialog Return exit could be same as Call transfered to ICM. And the actual delay is after playing the audio. Actual environmnet is once the call transferred to ICM, ICM transfers the call to Third party CVP App IVR application. So the delay was between last audio played in CVP and the first audio played in Third party application which is in different network. Is that could be because of Intra Network delays??
Any thoughts?
Thanks for your quick responses.
Subject: RE: New Message from Feroz Syed in Customer Voice Portal (CVP) - CVP - All
Replied by: Janine Graves on 23-04-2012 05:24:53 PM
Someone else had this problem in the Forum a few months ago.
http://developer.cisco.com/web/cvp/forums/-/message_boards/view_message/4822682
But, in their case it was due to the gateway misconfiguration. I don't think that's your problem. But look at the other post and let us know.
Subject: RE: Delay while transfer to ICM | Subdialog Return execution Delay
Replied by: Janine Graves on 23-04-2012 05:31:13 PM
Also please ensure that your Studio application is ending with a CVP_Subdialog_Return node, and not a Subdialog_Return node.
Created by: Feroz Syed on 23-04-2012 03:52:08 PM
Hi,
I have a CVP Application which has an Audio and then few backend hits and then transfers to ICM with data in Caller Input and ExternalVXML 2 sections. The call is getting trabsferred to ICM and then to Agent VDN without any issues,
But I see a significant delay while transferring to Agent after the last audio played. I found from the logs that Subdialog Return element execution is taking that 8-12 sec delay. Also i Have replaced the audio with silence and
audios of different duration and the delay is increased with increased audio. I have verified and there are no issues with the data being passed to ICM as well.
Below are the activity logs and VXML logs for a call with hilighted observations.
Please do the needful and appriciate your quick response.
Activity Logs:
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,InteractionsTransfer,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,InteractionsTransfer,interaction,audio_group,initial_audio_group
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,InteractionsTransfer,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,custom,MT Interface custom action element version 1.4,May 25 2010
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,custom,The absolute URL is
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,custom,fetchtimeout not specified in root document ,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.300,Log_6214,custom,Setting to default timeout,14000
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,Log_6214,custom,XML Response is :,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,Log_6214,custom,Data.Element : ,Result - True
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,Log_6214,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetPilotTransfer,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetPilotTransfer,custom,Action,INT_5001
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetPilotTransfer,custom,Counter Progress, INITIAL: 0 STEP: 1 TYPE: increment COUNT: 0
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetPilotTransfer,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetSaveCallData,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetSaveCallData,custom,Counter Progress, INITIAL: 0 STEP: 1 TYPE: increment COUNT: 0
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetSaveCallData,custom,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SetSaveCallData,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,custom,MT Interface custom action element version 1.4,May 25 2010
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,custom,The absolute URL is
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,custom,fetchtimeout not specified in root document ,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:13.784,SaveCallData,custom,Setting to default timeout,14000
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,SaveCallData,custom,XML Response is :,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,SaveCallData,custom,Data.Element : ,Result - True
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,SaveCallData,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,custom,MT Interface custom action element version 1.4,May 25 2010
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,custom,The absolute URL is
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,custom,fetchtimeout not specified in root document ,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.175,Log_EndCT,custom,Setting to default timeout,14000
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.612,Log_EndCT,custom,XML Response is :,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.612,Log_EndCT,custom,Data.Element : ,Result - True
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.612,Log_EndCT,exit,done
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:25:14.612,Subdialog_Return,enter,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,Subdialog_Return,exit,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : X ,XXX_XXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XX ,XXXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : X,XXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XX,XXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXX,XXXXXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXX,X
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXX,XXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXX,XXXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXXXXXXXXXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXXXX,XXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXXXX,XXXXXXX
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,custom,LoggingReturnVars sessionVar : XXXXXXXXXXXXX,
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,end,how,app_session_complete
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,end,result,normal
XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX,04/23/2012 13:26:29.819,,end,duration,114
VXML Logs:
------------------------------------
--------- New Page Request ---------
------------------------------------
Request Received on: 04/23/2012 13:25:11.003
------- HTTP Request Headers -------
Header Name = "content-type" Header Value = "application/x-www-form-urlencoded"
Header Name = "cookie" Header Value = "$Version=0; JSESSIONID=1B5DB5BCFE1E7AAB0DFA52BBD81CB122; $Path=/CVP"
Header Name = "connection" Header Value = "close"
Header Name = "host" Header Value = "XX.X.XX.XXX:8080"
Header Name = "content-length" Header Value = "307"
Header Name = "accept" Header Value = "text/vxml, text/x-vxml, application/vxml, application/x-vxml, application/voicexml, application/x-voicexml, text/plain, text/html, audio/basic, audio/wav, multipart/form-data, application/octet-stream"
Header Name = "user-agent" Header Value = "Cisco-IOS-C5350/15.1"
------ Request HTTP Arguments ------
Parameter Name = "completeNBestStr" Parameter Value #0 = "|||1^^^1^^^dtmf^^^1"
Parameter Name = "confidence" Parameter Value #0 = "1"
Parameter Name = "audium_vxmlLog" Parameter Value #0 = "|||audio_group$$$initial_audio_group^^^0|||utterance$$$1^^^25289|||inputmode$$$dtmf^^^25289|||interpretation$$$1^^^25289|||confidence$$$1^^^25293"
Parameter Name = "choice_fld" Parameter Value #0 = "1"
--------- VoiceXML Response --------
Response Returned on: 04/23/2012 13:25:13.300
<?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.0" application="/CVP/Server?audium_root=true&calling_into=XXXXXXXXXXXXXXXXX&session_id=XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX" xml:lang="en-US">
<meta name="maintainer" content="Feroz Syed" />
<form id="audium_start_form">
<block>
<assign name="audium_vxmlLog" expr="''" />
<assign name="audium_element_start_time_millisecs" expr="new Date().getTime()" />
<goto next="#start" />
</block>
</form>
<form id="start">
<block>
<prompt bargein="true">
<audio src="[url=http://XX.X.XX.XXX.252/Media/Dev_/en-us/app/FE/Interactions_Transfer.wav">XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX</audio]http://XX.X.XX.XXX.252/Media/Dev_/en-us/app/FE/Interactions_Transfer.wav">XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX</audio>
</prompt>
<assign name="audium_vxmlLog" expr="audium_vxmlLog + '|||audio_group$$$' + 'initial_audio_group' + '^^^' + application.getElapsedTime(audium_element_start_time_millisecs)" />
<submit next="/CVP/Server" method="post" namelist="audium_vxmlLog" />
</block>
</form>
</vxml>
------------------------------------
--------- New Page Request ---------
------------------------------------
Request Received on: 04/23/2012 13:25:13.300
------- HTTP Request Headers -------
Header Name = "content-type" Header Value = "application/x-www-form-urlencoded"
Header Name = "cookie" Header Value = "$Version=0; JSESSIONID=1B5DB5BCFE1E7AAB0DFA52BBD81CB122; $Path=/CVP"
Header Name = "connection" Header Value = "close"
Header Name = "host" Header Value = "XX.X.XX.XXX:8080"
Header Name = "content-length" Header Value = "67"
Header Name = "accept" Header Value = "text/vxml, text/x-vxml, application/vxml, application/x-vxml, application/voicexml, application/x-voicexml, text/plain, text/html, audio/basic, audio/wav, multipart/form-data, application/octet-stream"
Header Name = "user-agent" Header Value = "Cisco-IOS-C5350/15.1"
------ Request HTTP Arguments ------
Parameter Name = "audium_vxmlLog" Parameter Value #0 = "|||audio_group$$$initial_audio_group^^^0"
--------- VoiceXML Response --------
Response Returned on: 04/23/2012 13:25:14.612
<?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.0" application="/CVP/Server?audium_root=true&calling_into=XXXXXXXXXXXXXXXXX&session_id=XX.X.XX.XXX.1335201875673.422.XXXXXXXXXXXXXXXXX" xml:lang="en-US">
<meta name="maintainer" content="Feroz Syed" />
<catch event="error.com.cisco.callhandoff.failure">
<cisco-typeaheadflush />
<submit next="/CVP/Server" method="post" namelist="audium_vxmlLog" />
</catch>
<form id="audium_start_form">
<block>
<assign name="audium_vxmlLog" expr="''" />
<assign name="audium_element_start_time_millisecs" expr="new Date().getTime()" />
<goto next="#start" />
</block>
</form>
<form id="start">
<object name="dummyobj" classid="builtin://com.cisco.callhandoff">
<param name="return" expr="true" valuetype="data" />
<param name="app-uri" expr="'builtin://dummyobj'" valuetype="data" />
<filled>
<submit next="/CVP/Server" method="post" namelist="audium_vxmlLog" />
</filled>
</object>
</form>
</vxml>
------------------------------------
--------- New Page Request ---------
------------------------------------
Request Received on: 04/23/2012 13:26:29.819
------- HTTP Request Headers -------
Header Name = "content-type" Header Value = "application/x-www-form-urlencoded"
Header Name = "cookie" Header Value = "$Version=0; JSESSIONID=1B5DB5BCFE1E7AAB0DFA52BBD81CB122; $Path=/CVP"
Header Name = "connection" Header Value = "close"
Header Name = "host" Header Value = "XX.X.XX.XXX:8080"
Header Name = "content-length" Header Value = "15"
Header Name = "accept" Header Value = "text/vxml, text/x-vxml, application/vxml, application/x-vxml, application/voicexml, application/x-voicexml, text/plain, text/html, audio/basic, audio/wav, multipart/form-data, application/octet-stream"
Header Name = "user-agent" Header Value = "Cisco-IOS-C5350/15.1"
------ Request HTTP Arguments ------
Parameter Name = "audium_vxmlLog" Parameter Value #0 = ""
--------- VoiceXML Response --------
Response Returned on: 04/23/2012 13:26:29.819
<?xml version="1.0" encoding="UTF-8"?>
<vxml version="2.0" xml:lang="en-US">
<catch event="vxml.session.error">
<return event="vxml.session.error" />
</catch>
<catch event="telephone.disconnect.hangup">
<return event="telephone.disconnect.hangup" />
</catch>
<catch event="telephone.disconnect">
<return event="telephone.disconnect" />
</catch>
<catch event="error.unsupported.object">
<return event="error.unsupported.object" />
</catch>
<catch event="error.unsupported.language">
<return event="error.unsupported.language" />
</catch>
<catch event="error.unsupported.format">
<return event="error.unsupported.format" />
</catch>
<catch event="error.unsupported.element">
<return event="error.unsupported.element" />
</catch>
<catch event="error.unsupported.builtin">
<return event="error.unsupported.builtin" />
</catch>
<catch event="error.unsupported">
<return event="error.unsupported" />
</catch>
<catch event="error.semantic">
<return event="error.semantic" />
</catch>
<catch event="error.noauthorization">
<return event="error.noauthorization" />
</catch>
<catch event="error.eventhandler.notfound">
<return event="error.eventhandler.notfound" />
</catch>
<catch event="error.connection.noroute">
<return event="error.connection.noroute" />
</catch>
<catch event="error.connection.noresource">
<return event="error.connection.noresource" />
</catch>
<catch event="error.connection.nolicense">
<return event="error.connection.nolicense" />
</catch>
<catch event="error.connection.noauthorization">
<return event="error.connection.noauthorization" />
</catch>
<catch event="error.connection.baddestination">
<return event="error.connection.baddestination" />
</catch>
<catch event="error.condition.baddestination">
<return event="error.condition.baddestination" />
</catch>
<catch event="error.com.cisco.resource.failure.tts">
<return event="error.com.cisco.resource.failure.tts" />
</catch>
<catch event="error.com.cisco.media.resource.unavailable">
<return event="error.com.cisco.media.resource.unavailable" />
</catch>
<catch event="error.com.cisco.handoff.failure">
<return event="error.com.cisco.handoff.failure" />
</catch>
<catch event="error.com.cisco.callhandoff.failure">
<return event="error.com.cisco.callhandoff.failure" />
</catch>
<catch event="error.com.cisco.aaa.authorize.failure">
<return event="error.com.cisco.aaa.authorize.failure" />
</catch>
<catch event="error.com.cisco.aaa.authenticate.failure">
<return event="error.com.cisco.aaa.authenticate.failure" />
</catch>
<catch event="error.badfetch.https">
<return event="com.audium.error.badfetch.https" />
</catch>
<catch event="error.badfetch.http">
<return event="com.audium.error.badfetch.http" />
</catch>
<catch event="error.badfetch">
<return event="com.audium.error.badfetch" />
</catch>
<catch event="error">
<return event="error" />
</catch>
<catch event="disconnect.com.cisco.handoff">
<return event="disconnect.com.cisco.handoff" />
</catch>
<catch event="connection.disconnect.hangup">
<return event="connection.disconnect.hangup" />
</catch>
<catch event="connection.disconnect">
<return event="connection.disconnect" />
</catch>
<form>
<block>
<var name="caller_input" expr="'A=INT_5001;AT=ICMRoute;L=en-us;EID=XXXXXXXX;XX=XXXX;CID=XXXXXXX'" />
<var name="FromExtVXML2" expr="'XXX=XXXXXXXXXX;XXXX=X'" />
<return namelist="caller_input FromExtVXML2" />
</block>
</form>
</vxml>
Subject: RE: New Message from Feroz Syed in Customer Voice Portal (CVP) - CVP - All
Replied by: GEOFFREY THOMPSON on 23-04-2012 04:48:13 PM
>>>04/23/2012 13:25:14.612,Log_EndCT,custom,Data.Element : ,Result - True
>>>04/23/2012 13:25:14.612,Log_EndCT,exit,done
>>>04/23/2012 13:25:14.612,Subdialog_Return,enter,
>>>04/23/2012 13:26:29.819,Subdialog_Return,exit,
I’ve never seen anything like this, but I certainly have seen “issues” with the CVP logging facility.
Is it possible that it hasn’t actually returned from the element above “MT Interface custom action element version 1.4,May 25 2010”.
Since this is your custom element you could write to a low-level log file of your own, or the Tomcat screen, when you exit, just to be really sure of the timing.
Regards,
Geoff
Subject: RE: Delay while transfer to ICM | Subdialog Return execution Delay
Replied by: Feroz Syed on 23-04-2012 06:13:00 PM
After analysis, I suspect the delay could be because of the Audio. I see CVP sent com.cisco.callhandoff object to Gateway at 04/23/2012 13:25:14.612 and Gateway waited until the Audio finishes (The audio was about 1 min 16 sec) though CVP executed Non VXML content while playing the audio but it took in Milli Seconds. And when I replaced with 0.5 sec silence auido, I dont see any delay in SubDialog Return execution.
BTW, the node used is CVP_Subdialog_Return.
So the problem could be something else as the time frame of SubDialog Return exit could be same as Call transfered to ICM. And the actual delay is after playing the audio. Actual environmnet is once the call transferred to ICM, ICM transfers the call to Third party CVP App IVR application. So the delay was between last audio played in CVP and the first audio played in Third party application which is in different network. Is that could be because of Intra Network delays??
Any thoughts?
Thanks for your quick responses.
Subject: RE: New Message from Feroz Syed in Customer Voice Portal (CVP) - CVP - All
Replied by: Janine Graves on 23-04-2012 05:24:53 PM
Someone else had this problem in the Forum a few months ago.
http://developer.cisco.com/web/cvp/forums/-/message_boards/view_message/4822682
But, in their case it was due to the gateway misconfiguration. I don't think that's your problem. But look at the other post and let us know.
Subject: RE: Delay while transfer to ICM | Subdialog Return execution Delay
Replied by: Janine Graves on 23-04-2012 05:31:13 PM
Also please ensure that your Studio application is ending with a CVP_Subdialog_Return node, and not a Subdialog_Return node.
Labels:
Getting Started
Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community:
Customers Also Viewed These Support Documents