cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1733
Views
7
Helpful
10
Replies

CVP Subdialog Return takes 1 second

burkr
Level 4
Level 4

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


1 Accepted Solution

Accepted Solutions

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

View solution in original post

10 Replies 10

janinegraves
Spotlight
Spotlight

Is your vxml server program playing any audio at all?

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.

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

Janine

"unfortunatly" the Gateway(s) is/are local

Roland

Is it consistently the same duration delay, or just intermittent?

it is consistently the same delay +- some milliseconds

Roland,

Suggest you start with a Wireshark trace on CVP to see exactly where the delay happens in the message flow.

I opened a SR with this... will let you know the result of it.

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

janinegraves
Spotlight
Spotlight

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