Hi Bob,

Sorry I don't have access to AMS log. What are the possible reasons could be? Is it related to the URI I set for this voiceXMLDialogItem?

Thank you,
Qian

Without access to the Media Server logs it will be difficult to figure out the problem - in the past I have received this message when the VoiceXML itself had syntax or semantic errors.

Does anyone else on the Forum have any other suggestions?

Thanks,
Bob

Qian, are you using the Collaboratory or are you testing in your own lab?

Hi Joel,

All the snap-ins are in our EDP lab server.

OK. In that case, you should be able to access the logs on your AMS. As Bob said, it's going to be difficult to debug VoiceXML issues without accessing those logs.

So for AMS log, is it the one use command "ce dlogon" and "ce dlogv" (or "ce dlogt") on our EDP lab server?

No, you need to actually log into the AMS server. Whoever set up the system should be able to tell you how to do that.

Hi,

There are some SSL connection warning in the AMS log, saying" self signed certificate in certificate chain".
The EDP API voiceXML sample which I followed use HTTP address as URI, while mine is HTTPS. Does the setVoiceXMLScript method support HTTPS uri?

Here is part of my code:
final URI documentUrl = new URI(https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml);
voiceXMLDialogItem.setVoiceXMLScript(documentUrl);
final MyVoiceXMLDialogListener voiceXMLDialogListener = new MyVoiceXMLDialogListener(
myCall.getCallingParty());
final UUID vxmlrequestId = SpeechFactory.createSpeechService()
.startVoiceXMLDialog(myCall.getCallingParty(),
voiceXMLDialogItem, voiceXMLDialogListener);
logger.info("After Digit collect, VoiceXML dialog started with requestId "
+ vxmlrequestId.toString());



Also, which log file I should investigate for vxml trace? There are lots of logs in AMS like vxmliDebug.txt, vxmliAppDebug.txt, vxmli1Debug.txt. None of them has the log information related to my test.

Thanks,
Qian

https should work, but AMS uses different default certs, and so you will need to import these into the System Manager. This would explain both the SSL issue and the problem of the VoiceXML script not being read on the AMS.

Bob

Hi bob,

I report this issue to the server team and they said this SSL warning should not cause the problem since the URI I used has the System Manager management certificate.

Besides this warning message, there is no other warning or error log related to voiceXML in AMS.
Is is possible that we get this "A severe error has occurred" announcement while no error log in AMS?

Thanks,
Qian

Hi Qian,

Did you look in the /opt/avaya/app/amsinst/ma/MAS/common/log directory on AMS?

Thanks,
Bob

Yes I did.
And for all the log files which related to vxml, there is no log recorded since April 21st.
I also check other files under this folder, but most of them haven't log anything since April, except the javaDebug.txt.

Hi Qian,

It sounds like debug logging isn't enabled on the AMS. Can you have your administrator enable it? This is done in the AMS Element Manager by navigating to Home » System Configuration » Debug Tracing » General Settings and setting the drop-down to Enabled.

Thanks,
Bob

Thanks, now I have the right log...
Here is the vxml log:


(05-11 14:57:04.679)<I,Plat,NWLoResTimer,00000000-0000-0000-0000-000000000000> NWIniConfig: Handle notification: ANY_CHANGED_CONFIG 0
(05-11 14:57:14.223)<I,Plat,main,00000000-0000-0000-0000-000000000000> (31) Recv Evt:[MX_EVENT_GET_LINE_COMPLETE] MxReqID:[31] LnState:[call_state_connected] lnHnd:[53477377] CallDir:[1] ANI:[<sip:6898391f-980f-4f38-b83d-64fb46155fce@mediaserver>;tag=d94d4d62-6bae-3c80-8a04-5c1d0205c90d] DNIS:[<sip:dialog@135.9.39.73:5060;voicexml=https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml>] sa:[:-3] VarDataLen:[2235]
(05-11 14:57:14.224)<I,Plat,main,00000000-0000-0000-0000-000000000000> (31) Dump AppData AppDataLen:[265]
(05-11 14:57:14.224)<I,Plat,main,00000000-0000-0000-0000-000000000000> (31) ==========Data Start==========
(05-11 14:57:14.224)<I,Plat,main,00000000-0000-0000-0000-000000000000> Dialog.Start:( private:( callid:"629da459-f213-3107-b7fb-28a79cbc4965" url:(0:"https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml" ) defpath:"file:///opt/avaya/app/amsinst/ma/MAS/platdata/CStore/StorageRoot/n-1408721378-4/defaults-gen-1/"))
(05-11 14:57:14.224)<I,Plat,main,00000000-0000-0000-0000-000000000000> (31) ==========Data End ==========
(05-11 14:57:14.224)<I,Plat,main,00000000-0000-0000-0000-000000000000> (31) AppId=c8dcbefd-66f9-1029-a199-9e50b495d05
(05-11 14:57:14.224)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) CallID Session Gslid - [629da459-f213-3107-b7fb-28a79cbc4965]
(05-11 14:57:14.224)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) DialogID Gslid - [629da459-f213-3107-b7fb-28a79cbc4965]
(05-11 14:57:14.224)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) UCID - []
(05-11 14:57:14.224)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) Trace Diagnostics Disabled
(05-11 14:57:14.224)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) Change Line State [Wait for Dialog]===>[Idle]
(05-11 14:57:14.224)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) Change Call Status [INACTIVE]===>[ACTIVE]
(05-11 14:57:14.224)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) Issue mx_SetDTMFSequence() termchar:[#] Enable:[Yes]
(05-11 14:57:14.224)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) Send Msg:[Dialog Prep Wait Rsp] TranID:[3] Result:[0]
(05-11 14:57:14.224)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) Send Msg:[Dialog Start] TranID:[0] Result:[0]
(05-11 14:57:14.224)<I,Integ,82258800,00000000-0000-0000-0000-000000000000> (31) Recv Msg:[Dialog Prep Wait Rsp] TranID:[3]
(05-11 14:57:14.224)<I,Integ,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) Preparing dialog
(05-11 14:57:14.224)<I,Integ,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) Trying URL[https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml]
(05-11 14:57:14.224)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|VXI::Prepare - loading defaultDoc.
(05-11 14:57:14.224)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::FetchDocument()
(05-11 14:57:14.224)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::FetchBufferFromDB(, NULL)
(05-11 14:57:14.224)<I,Plat,82258800,00000000-0000-0000-0000-000000000000> getAppDefaultsDoc() : Success!
(05-11 14:57:14.224)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::FetchBufferFromDB - success
(05-11 14:57:14.224)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::FetchDocument - latency in microseconds min 39, max 39, avg 39, count 1
(05-11 14:57:14.224)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::Memory Cache Statistics: Hits=0, Misses=0, TotalBytes=5323, MaxBytes=1048576
(05-11 14:57:14.224)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::Memory Cache: Hit
(05-11 14:57:14.224)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::FetchDocument(): Default document - success
(05-11 14:57:14.224)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8005||URL: , contentFile: /opt/avaya/app/amsinst/ma/MAS/common/log/vxidump/mps1_vxmli/00000031/com.avaya.vxi.00000000
(05-11 14:57:14.225)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|VXI::Prepare(https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml)
(05-11 14:57:14.225)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::FetchDocument(https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml)
(05-11 14:57:14.226)<I,Plat,main,629da459-f213-3107-b7fb-28a79cbc4965> (31) Recv Evt:[MX_EVENT_SET_DTMF_SEQ_COMPLETE] MxReqID:[342]
(05-11 14:57:14.245)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::FetchBuffer(https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml, { (inet.timeoutDownload, 15000), (inet.urlEncoding, UTF-8) })
(05-11 14:57:14.254)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31)|com.avaya.SBinet|219||URL=https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml|Method=GET|HTTPStatus=404|HTTPStatusDescription=Not found
(05-11 14:57:14.254)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8000|com.avaya.vxi|DocumentParser::FetchBuffer - could not retrieve absolute path of document at URL: https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml
(05-11 14:57:14.254)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8002|com.avaya.vxi|DocumentParser::FetchDocument - latency in microseconds min 39, max 8275, avg 4157, count 2
(05-11 14:57:14.254)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) 8000|com.avaya.vxi|DocumentParser::FetchDocument - exiting with error result 2
(05-11 14:57:14.254)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31)|com.avaya.vxi|203||URL=https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml
(05-11 14:57:14.254)<I,Interp,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31)|com.avaya.vxi|999||message=fail to prepare document|URL=https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml
(05-11 14:57:14.254)<I,Integ,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) Prepare failure rc=-100
(05-11 14:57:14.254)<I,Integ,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) Wait for dialog start
(05-11 14:57:14.254)<I,Integ,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) Waiting for message ReqId:[0]
(05-11 14:57:14.254)<I,Integ,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) Recv Msg:[Dialog Start] TranID:[0]
(05-11 14:57:14.254)<I,Integ,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) uuiSharedMode = false
(05-11 14:57:14.254)<I,Integ,82258800,629da459-f213-3107-b7fb-28a79cbc4965> (31) Session ECMAScript:
var connection = {local:{uri:'<sip:dialog@135.9.39.73:5060;voicexml=https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml>'},remote:{uri:'<sip:6898391f-980f-4f38-b83d-64fb46155fce@mediaserver>;tag=d94d4d62-6bae-3c80-8a04-5c1d0205c90d'},protocol:{name:'sip',version:'2.0',sip:{headers:{'to':'<sip:dialog@135.9.39.73:5060;voicexml=https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml>','from':'<sip:6898391f-980f-4f38-b83d-64fb46155fce@mediaserver>;tag=d94d4d62-6bae-3c80-8a04-5c1d0205c90d','call-id':'ea6da8c8-49270987-13c4-6006-7eb521-6157363d-7eb521','cseq':'1 INVITE','content-length':'407','content-type':'application/sdp','via':'SIP/2.0/UDP 135.9.39.73:5060;branch=z9hG4bK-7eb521-eef38ad9-1856aaab-eb0ee5a8-0xea6da8c8','accept':'application/sdp, text/*, application/msml+xml, application/moml+xml','user-agent':'Avaya Media Server 7.6.0.959','max-forwards':'70','supported':'100rel,replaces,sdp-anat','x-nt-gslid':'d94d4d62-6bae-3c80-8a04-5c1d0205c90d','allow':'INVITE,ACK,CANCEL,BYE,OPTIONS,INFO,REFER,NOTIFY,PRACK,UPDATE,MESSAGE','contact':'<sip:6898391f-980f-4f38-b83d-64fb46155fce@135.9.39.73;nt_service=6898391f-980f-4f38-b83d-64fb46155fce>','user-to-user':''},requesturi:{'transport':'udp','voicexml':'https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml'}}},aai:'',uui:'',ccxml:'',originator:undefined};
session.connection.uui=session.connection.protocol.sip.headers["user-to-user"];
session.connection.ccxml=session.connection.protocol.sip.requesturi["ccxml"];
session.connection.protocol.sip.requesturi.toString = function() { return "sip:dialog@135.9.39.73:5060;transport=udp;voicexml=https://135.9.39.61/services/NotifyMeRecordService/resources/wav/voice.vxml"; };

session.connection.redirect = new Array();

session.connection.originator=session.connection.remote;

var com = {avaya:{navigator:{userAgent:'MAS/7.6.0.959'},ivr:{call:{id:'629da459-f213-3107-b7fb-28a79cbc4965'},dialog:{id:'629da459-f213-3107-b7fb-28a79cbc4965'}}}};

(05-11 14:57:14.258)<I,Plat,82258800,00000000-0000-0000-0000-000000000000> omIndexFromVxiStr() - The event string is error.badfetch.http.404


All the error log is focus on the 404 not found for the URI I set for voiceXMLScript.
This URI pattern is supposed to be: https://135.9.39.61/services/NotifyMeRecordService/resources/wav/<resourceID>.vxml
The resourceID is generated in the code for each unique call, and then use this ID for locating voiceXML script.
For sure it will give the 404 not found error on this URI.
So did I misunderstand the logic of creating an voiceXMLDialogItem and startVoiceXMLDialog ?

Most VoiceXML is dynamically generated. So, once you generate the page and save it somewhere you then pass the URL to that saved location to the API, which will send it to the media server. Does this answer your question?

Bob