Re: VXML gateway doesn't load the WAV file from Nuance Speech Server (NSS)

suri7 · ‎06-14-2019

Hi,

We are trying to get the the URL for recorded user utterance wav file on Nuance Speech Server and download the recorded audio from the NSS to our web app server (Cisco VXML server) by setting recordutterance value to true in Cisco Call Studio IVR application. But VXML gateway fails with following error message in the debug.

VXML Gateway Logs:

Jun 14 17:34:26.303: //433762//AFW_:/vapp_driver: evtID: 201 vapp record state: 0
Jun 14 17:34:26.303: //433762//AFW_:/vapp_asr_done:
Jun 14 17:34:26.303: //433762//AFW_:/vapp_asr_done: status [0]
Jun 14 17:34:26.303: //433762//AFW_:/vapp_bgload: url=http://WVNWN0027976:90/Nuance/callLogs/MyApp/2019/06June/14/13/NUAN-33-30-WVNWN0027976-FNADNKGKAAAEGBNIAAAAAAAB-utt001-SAVEWAVEFORM.wav
Jun 14 17:34:26.303: //433762//AFW_:/vxml_update_cleanup_timer: cleaning timer running 0 fetchtimeout 0
Jun 14 17:34:26.303: //433762//AFW_:/vapp_checksessionstate:
Jun 14 17:34:26.303: //433762//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
Jun 14 17:34:26.303: %HTTPC-6-CONNECT_FAILED: The connection to server 0.0.0.0 failed
Jun 14 17:34:26.303: //433762//HIFS:/hifs_http_cb: hifs http callback: load of http://WVNWN0027976:90/Nuance/callLogs/MyApp/2019/06June/14/13/NUAN-33-30-WVNWN0027976-FNADNKGKAAAEGBNIAAAAAAAB-utt001-SAVEWAVEFORM.wav failed. Status=httpc error -7=internal client connection error
Jun 14 17:34:26.303: //433762//AFW_:/vapp_bgload_done: status=httpc error -7=internal client connection error
Jun 14 17:34:26.303: //433762//HIFS:/hifs_free_idata: hifs_free_idata: 0x30CED03C
Jun 14 17:34:26.303: //433762//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x30CED03C
Jun 14 17:34:26.303: %HTTPC-6-REQUEST_FAILED: request URI http://WVNWN0027976:90/Nuance/callLogs/MyApp/2019/06June/14/13/NUAN-33-30-WVNWN0027976-FNADNKGKAAAEGBNIAAAAAAAB-utt001-SAVEWAVEFORM.wav failed
Jun 14 17:34:26.303: //433762//AFW_:/vapp_driver: evtID: 301 vapp record state: 0
Jun 14 17:34:26.303: //433762//AFW_:/vapp_bgload_done_event:
Jun 14 17:34:26.303: //433762/5805D59C8605/VXML:/vxml_asr_done:
Jun 14 17:34:26.303: vxmlp 2E862844 status 0 async_status 20000000 waveform_url NULL duration 0 size 0
Jun 14 17:34:26.303: //433762/5805D59C8605/VXML:/vxml_vapp_typeahead_flush:

nss_logs:

Seeing "Save-Waveform: true" in nss logs.

Seeing "Waveform-URL: http://WVNWN0027976:90/Nuance/callLogs/MyApp/2019/06June/14/13/NUAN-33-30-WVNWN0027976-FNADNKGKAAAEGBNIAAAAAAAB-utt001-SAVEWAVEFORM.wav" in nss logs.

The files are available under the following location %NUANCE_DATA_DIR%\Nuance\callLogs ..." and I am able to load the Wave Form URLs in Local System and in NSS browsers successfully.

So I am thinking that NSS did its job and the problem is not in Nuance, it looks like a problem inside the Gateway fetch ("httpc error -7=internal client connection error").

Could you please help me to fix this issue.

Regards,

Suri.

ptindall · ‎06-15-2019

https://bst.cloudapps.cisco.com/bugsearch/bug/CSCue99125

suri7 · ‎06-26-2019

Thank you so much Paul.

We have fixed the "ip host" issue in VXML gateway and it is able to load the Wav file URL successfully.

Jun 25 15:52:05.282: //397525//HIFS:/hifs_http_cb: hifs http read succeeded. size=59404, url=http://WV00277:90/Nuance/callLogs/MyApp/2019/06June/25/11/NUAN-51-04-WV00277-FNBCECOIAAAFHJOOAAAAAAAA-utt006-SAVEWAVEFORM.wav

Jun 25 15:52:05.282: //397525//AFW_:/vapp_bgload_done: status=No Error

Jun 25 15:52:05.282: //397525//HIFS:/hifs_http_cb: hifs ifs file read succeeded. size=59404, url=http://WV00277:90/Nuance/callLogs/MyApp/2019/06June/25/11/NUAN-51-04-WV00277-FNBCECOIAAAFHJOOAAAAAAAA-utt006-SAVEWAVEFORM.wav

Jun 25 15:52:05.282: vxmlp 2FAEC024 status 0 async_status 20000000 waveform_url http://WV00277:90/Nuance/callLogs/MyApp/2019/06June/25/11/NUAN-51-04-WV00277-FNBCECOIAAAFHJOOAAAAAAAA-utt006-SAVEWAVEFORM.wav duration 7425 size 59428

But the value of VXML shadow variable($.recording) which holds the URL of Wav file is not populated into VXML user variable u_recording_2 by VXML Gateway.

VXML Gateway Log:

---------

<var>: namep=form_confidence expr=foundation_fld$.confidence

Jun 25 15:52:05.368: //397525/D8E00838BABA/VXML:/vxml_expr_eval:

Jun 25 15:52:05.368: expr=var form_confidence=foundation_fld$.confidence

<var>: namep=form_inputmode expr=foundation_fld$.inputmode

Jun 25 15:52:05.368: //397525/D8E00838BABA/VXML:/vxml_expr_eval:

Jun 25 15:52:05.368: expr=var form_inputmode=foundation_fld$.inputmode

<var>: namep=u_recording_2 expr=foundation_fld$.recording

Jun 25 15:52:05.368: //397525/D8E00838BABA/VXML:/vxml_expr_eval:

Jun 25 15:52:05.368: expr=var u_recording_2=foundation_fld$.recording

Jun 25 15:52:05.368: //-1//VXML:/vxml_jse_increment_record_refcount:

recp(225E549C), url(http://WV00277:90/Nuance/callLogs/MyApp/2019/06June/25/11/NUAN-51-04-WV00277-FNBCECOIAAAFHJOOAAAAAAAA-utt006-SAVEWAVEFORM.wav), refcount(4)

Jun 25 15:52:05.368: //-1//VXML:/vxml_submit_proc: Enter

Jun 25 15:52:05.368: //397525/D8E00838BABA/VXML:/vxml_submit_proc:

<submit>: caching=fast fetchhint=invalid fetchtimeout=30 maxage=-1 maxstale=-1

URI(abs):/CVP/Server

scheme=http

host=CVP2

port=7000

path=/CVP/Server

Jun 25 15:52:05.368: //397525/D8E00838BABA/VXML:/vxml_sub_attrs_proc:

method=post

enctype=application/x-www-form-urlencoded

Jun 25 15:52:05.368: //397525/D8E00838BABA/VXML:/vxml_nmtokens_proc:

name=completeNBestStr

name=dm_confirm_string

name=form_confidence

name=foundation_fld

name=confirmation_mode

name=form_inputmode

name=meaning

name=collection_type

name=audium_vxmlLog

Jun 26 14:03:11.093: //-1//VXML:/vxml_sub_attrs_proc: Enter
Jun 26 14:03:11.093: //-1//VXML:/vxml_submit_compose:
escape count=37, new value len=415
Jun 26 14:03:11.093: //-1//VXML:/vxml_submit_compose:
escape count=1, new value len=50
Jun 26 14:03:11.093: //-1//VXML:/vxml_submit_compose:
escape count=1, new value len=50
Jun 26 14:03:11.093: //-1//VXML:/vxml_submit_compose:
escape count=8, new value len=56
Jun 26 14:03:11.093: //-1//VXML:/vxml_submit_compose:
escape count=35, new value len=232
Jun 26 14:03:11.093: //-1//VXML:/vxml_sub_attrs_proc: Exit
Jun 26 14:03:11.093: //448547/D0E9F5FAAECB/VXML:/vxml_vapp_bgpost:
url http://CVP2:7000/CVP/Server cachable 1 timeout 30 body completeNBestStr=%7C%7C%7C0.59%5E%5E%5Enine%20one%20two%20five%20six%20one%20one%20zero%20three%5E%5E%5Evoice%5E%5E%5E%2Bdm_confirmation_mode:ALWAYS%2Bgrammar_version:20150904%2Bdm_confirm_string:1411_cnf_ini_03~911111103%5Edigits~1411_cnf_ini_07%2Bdm_root:911111103%2BMEANING:911111103%2BSWI_literal:nine%20one%20two%20five%20six%20one%20one%20zero%20three%2BSWI_meaning:911111103%2BSWI_grammarName:session:field67288@field.grammar&dm_confirm_string=1411_cnf_ini_03~911111103%5Edigits~1411_cnf_ini_07&form_confidence=0.59&foundation_fld.dm_confirmation_mode=ALWAYS&foundation_fld.grammar_version=201**MSG 24800 TRUNCATED**
**MSG 24800 CONTINUATION #01**50904&foundation_fld.dm_confirm_string=1411_cnf_ini_03~911111103%5Edigits~1411_cnf_ini_07&foundation_fld.dm_root=911111103&foundation_fld.MEANING=911111103&foundation_fld.SWI_literal=nine%20one%20two%20five%20six%20one%20one%20zero%20three&foundation_fld.SWI_meaning=911111103&foundation_fld.SWI_grammarName=session:field67288@field.grammar&confirmation_mode=ALWAYS&form_inputmode=voice&meaning=911111103&collection_type=meaning&audium_vxmlLog=%7C%7C%7Cutterance$$$nine%20one%20two%20five%20six%20one%20one%20zero%20three%5E%5E%5E16861%7C%7C%7Cinputmode$$$voice%5E%5E%5E16861%7C%7C%7Cinterpretation$$$%5Bobject%20Object%5D%5E%5E**MSG 24800 TRUNCATED**
**MSG 24800 CONTINUATION #02**%5E16861%7C%7C%7Cconfidence$$$0.59%5E%5E%5E16861 len 1276maxage -1 maxstale -1
Jun 26 14:03:11.237: //448547//AFW_:/vapp_bgpost: url=http://CVP2:7000/CVP/Server mime_type=application/x-www-form-urlencoded len=1276 completeNBestStr=%7C%7C%7C0.59%5E%5E%5Enine%20one%20two%20five%20six%20one%20one%20zero%20three%5E%5E%5Evoice%5E%5E%5E%2Bdm_confirmation_mode:ALWAYS%2Bgrammar_version:20150904%2Bdm_confirm_string:1411_cnf_ini_03~911111103%5Edigits~1411_cnf_ini_07%2Bdm_root:911111103%2BMEANING:911111103%2BSWI_literal:nine%20one%20two%20five%20six%20one%20one%20zero%20three%2BSWI_meaning:911111103%2BSWI_grammarName:session:field67288@field.grammar&dm_confirm_string=1411_cnf_ini_03~911111103%5Edigits~1411_cnf_ini_07&form_conf
Jun 26 14:03:11.237: //448547//AFW_:/vxml_update_cleanup_timer: cleaning timer running 0 fetchtimeout 30
Jun 26 14:03:11.237: //-1//VXML:/vxml_vapp_bgpost: Exit
Jun 26 14:03:11.237: //-1//VXML:/vxml_submit_proc: Exit
Jun 26 14:03:11.237: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9983
Jun 26 14:03:11.237: //448547/D0E9F5FAAECB/VXML:/vxml_leave_scope:
scope=anonymous
Jun 26 14:03:11.237: //-1//VXML:/vxml_jse_decrement_record_refcount:
recp(2E8CC0B4), url(http://WV00276:90/Nuance/callLogs/MyApp/2019/06June/26/10/NUAN-02-17-WV00276-FNBDHKOJAAANOPJPAAAAAAAB-utt005-SAVEWAVEFORM.wav), refcount(3), force_delete(0)
Jun 26 14:03:11.237: //-1//VXML:/vxml_jse_decrement_record_refcount:
recp(2E8CC0B4), url(http://WV00276:90/Nuance/callLogs/MyApp/2019/06June/26/10/NUAN-02-17-WV00276-FNBDHKOJAAANOPJPAAAAAAAB-utt005-SAVEWAVEFORM.wav), refcount(2), force_delete(0)
Jun 26 14:03:11.237: //-1//VXML:/vxml_jse_decrement_record_refcount:
recp(2E8CC0B4), url(http://WV00276:90/Nuance/callLogs/MyApp/2019/06June/26/10/NUAN-02-17-WV00276-FNBDHKOJAAANOPJPAAAAAAAB-utt005-SAVEWAVEFORM.wav), refcount(1), force_delete(0)
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5182
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5186
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5192
Jun 26 14:03:11.237: //-1//VXML:/vxml_form_filled:
(400000000) Status not OK, exit@5186
Jun 26 14:03:11.237: //-1//VXML:/vxml_field_asr_done: Exit
Jun 26 14:03:11.237: //-1//VXML:/vxml_field_proc: Input done, Exit@4772
Jun 26 14:03:11.237: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9983
Jun 26 14:03:11.237: //-1//VXML:/vxml_vxml_proc:
(400000000) Status not OK, exit@10189
Jun 26 14:03:11.237: //448547/D0E9F5FAAECB/VXML:/vxml_load_immediate_done:
sidp->status=400000000
Jun 26 14:03:11.237: //-1//VXML:/vxml_asr_done: Exit
Jun 26 14:03:11.237: //448547//AFW_:/vapp_checksessionstate:
Jun 26 14:03:11.237: //448547//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
Jun 26 14:03:11.309: //448547//AFW_:/vapp_bgpost_done: status=http OK
Jun 26 14:03:11.309: //448547//AFW_:/vapp_driver: evtID: 301 vapp record state: 0
Jun 26 14:03:11.309: //448547//AFW_:/vapp_bgpost_done_event:
Jun 26 14:03:11.309: //448547/D0E9F5FAAECB/VXML:/vxml_bgload_post_done:
vxmlhandle=23D1DE98 status=0 async_status=400000000

---------

Could you please help me to fix it ?

Regards,

Suri.

ptindall · ‎07-01-2019

To narrow it down, personally, I'd start with a Wireshark trace to check the exact VoiceXML and response content.

amir.ayub · ‎01-21-2020

How did you resolve the ip host issue on VXML gateway? we are facing the same issue and getting the following error.

Jan 21 12:32:21: %HTTPC-6-REQUEST_FAILED: request URI http://cvp-vxml2:7000/CVP/Server?dnis=xxxx&application=xxxx&ani=xxxxxxxxxx&callid=AFF4DB1C3B5611EAB8490013803E8F48&_dnis=xxxx&_ani=xxxxxxxxx failed

There is a bug https://bst.cloudapps.cisco.com/bugsearch/bug/CSCue99125/?rfs=iqvred but we are using IOS version Version 15.7(3)M4b.

ptindall · ‎01-23-2020

Does the HTTP request get sent and fails or is never sent? Try turning on some "deb http client" logging to capture some more detail.