07-16-2014 07:21 AM
Hi
we are doing a DBLookup with a CVP App developed in CVP Studio. Now, I'm facing an issue, that the app takes one second to pass back the controll to CCE.
the activity log shows:
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,newcall,
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,ani,006252674315
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,areacode,NA
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,exchange,NA
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,dnis,86891
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,uui,NA
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,iidigits,NA
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,parameter,id=532400
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,parameter,p3=A3001#A3002#A3003#S1004#A4001#A4002#A4003#
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,parameter,p2=S1002#CT1002#A2000#S1003#A3000#
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,parameter,callid=6BEDD2AB05E511E492A43C08F63C1268
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,parameter,p1=A1002#A1003#A1004#A1005#S1001#NS1000# 10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/201416:46:07.576,,start,parameter,p0=Z1000#CT1001#S1000#A1000#A1001#Z1001#
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,parameter,_dnis=86891
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,parameter,table=routing_msd
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,,start,parameter,_ani=006252674315
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,enter,
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,Start SQL preprossing,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,jndiName,RoutingDB
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,P0,Z1000#CT1001#S1000#A1000#A1001#Z1001#
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,P1,A1002#A1003#A1004#A1005#S1001#NS1000#
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,P2,S1002#CT1002#A2000#S1003#A3000#
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,P3,A3001#A3002#A3003#S1004#A4001#A4002#A4003#
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,END SQL preprossing,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,Begin SQL Connection,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,SQL Connection OK,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,Statement Ready,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,Start Execute Query,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,Start RS Parsing,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,END RS Parsing,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,Connectoins Closed,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,custom,Go back,2014-07-08 16:46:07.576
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,BS_DB_Routing,exit,Done
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:07.576,SQL Lookup OK,enter,
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:08.621,SQL Lookup OK,exit,
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:08.621,,end,how,app_session_complete
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:08.621,,end,result,normal
10.201.34.9.1404830767576.3346.BSRoutingDB,07/08/2014 16:46:08.621,,end,duration,1
We added some additional log output for all the steps we do in the custom element and we see, that the connect to the DB, runing the SQL command, parsing the result takes just "no time" (marked in yellow)
the red marked part is just a simple "CVP Subdialog Return" passing some data back to CCE.
Any hint why we face this issue and where to start with troubleshooting?
CVP Version: 10.0
Regards
Roland
Solved! Go to Solution.
07-28-2014 12:29 AM
the problem is solved (or probably workarounded)
setting the MSS on the GW to 1500 Bytes did it (with the following configuration on the GW: ip tcp mss 1500).
-roland
07-16-2014 09:09 AM
Is your vxml server program playing any audio at all?
07-17-2014 12:19 AM
Hi Janine
In the CCE script, we are 1st using a PM microapp to play a prompt.
after that, we set the required ECC variables (call.user.media_server, call.user.ToExtVXML[0-4]...) to the necessary values and run the ExtScript GS,Server to call the VXML app.
within the VXML App we do NOT play any audiofiles at all.
07-17-2014 10:08 AM
You might want to try minimizing the size of the VXML Server's root
document.
It's large and could 'possibly' be causing latency if your VXML Gateway
is far from your VXML Server.
To do this you must modify these 2 files:
C:\Cisco\CVP\VXMLServer\gateways\*cisco_dtmf_01\6.0.1\plugin.xml * and
also cisco_dtmf_02*\6.0.1\plugin.xml
*
Change this: default</setting>
To this: <setting name="vxml_error_handling">minimal</setting>
Then restart the VXML Server service
07-18-2014 12:41 AM
Janine
"unfortunatly" the Gateway(s) is/are local
Roland
07-18-2014 05:32 AM
Is it consistently the same duration delay, or just intermittent?
07-21-2014 01:18 AM
it is consistently the same delay +- some milliseconds
07-21-2014 02:10 AM
Roland,
Suggest you start with a Wireshark trace on CVP to see exactly where the delay happens in the message flow.
07-23-2014 08:03 AM
I opened a SR with this... will let you know the result of it.
07-28-2014 12:29 AM
the problem is solved (or probably workarounded)
setting the MSS on the GW to 1500 Bytes did it (with the following configuration on the GW: ip tcp mss 1500).
-roland
07-16-2014 03:03 PM
I just tried this and it ran very quickly.
The CVP_Subdialog_Return element causes 2 back-and-forth requests to the vxml gateway.
So, if it's slow, then perhaps you have a networking latency or a load issue on the gateway.
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,,start,parameter,name=Janine
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,,start,parameter,_dnis=1011
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,,start,parameter,RCD=151041
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,,start,parameter,_ani=3421
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,dbLookup,enter,
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,dbLookup,exit,done
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,checkResult,enter,
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,checkResult,exit,InDb
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,cvpReturnToIcm,enter,
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.777,cvpReturnToIcm,exit,
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.777,,end,how,app_session_complete
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.777,,end,result,normal
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.777,,end,duration,0
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,,start,parameter,name=Janine
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,,start,parameter,_dnis=1011
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,,start,parameter,RCD=151041
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,,start,parameter,_ani=3421
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,dbLookup,enter,
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,dbLookup,exit,done
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,checkResult,enter,
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,checkResult,exit,InDb
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.731,cvpReturnToIcm,enter,
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.777,cvpReturnToIcm,exit,
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.777,,end,how,app_session_complete
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.777,,end,result,normal
10.10.10.12.1405547736731.10.MyApp,07/16/2014 14:55:36.777,,end,duration,0
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide