Developer Network

« Back to CVP - All Versions

Delay while transfer to ICM | Subdialog Return execution Delay

Combination View

Feroz Syed Posts: 33 Join Date: 10/15/09 Recent Posts	Delay while transfer to ICM \| Subdialog Return execution Delay Answer 4/23/12 8:52 PM Mark as an Answer Submit Reply with Quote Quick Reply 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>
	Sign in to vote. Flag Please sign in to flag this as inappropriate. Top

GEOFFREY THOMPSON Posts: 289 Join Date: 7/10/08 Recent Posts	RE: New Message from Feroz Syed in Customer Voice Portal (CVP) - CVP - All Answer 4/23/12 9:48 PM as a reply to Feroz Syed. Mark as an Answer Submit Reply with Quote Quick Reply >>>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
	Sign in to vote. Flag Please sign in to flag this as inappropriate. Top

Janine Graves Posts: 1320 Join Date: 5/2/08 Recent Posts	RE: New Message from Feroz Syed in Customer Voice Portal (CVP) - CVP - All Answer 4/23/12 10:24 PM as a reply to GEOFFREY THOMPSON. Mark as an Answer Submit Reply with Quote Quick Reply 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.
	Sign in to vote. Flag Please sign in to flag this as inappropriate. Top

Janine Graves Posts: 1320 Join Date: 5/2/08 Recent Posts	RE: Delay while transfer to ICM \| Subdialog Return execution Delay Answer 4/23/12 10:31 PM as a reply to Janine Graves. Mark as an Answer Submit Reply with Quote Quick Reply Also please ensure that your Studio application is ending with a CVP_Subdialog_Return node, and not a Subdialog_Return node.
	Sign in to vote. Flag Please sign in to flag this as inappropriate. Top

Feroz Syed Posts: 33 Join Date: 10/15/09 Recent Posts	RE: Delay while transfer to ICM \| Subdialog Return execution Delay Answer 4/23/12 11:13 PM as a reply to Janine Graves. Mark as an Answer Submit Reply with Quote Quick Reply 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.
	Sign in to vote. Flag Please sign in to flag this as inappropriate. Top