CVP Handoff Failed

cdnadmin · ‎01-25-2014

This document was generated from CDN thread

Created by: Raun hr on 27-02-2012 08:24:46 AM
At the VXML script after number of tries exaust rather than ending the call it gives the message "I'm sorry we are unable to process your call".IOS is :c2900-universalk9-mz.SSA.bin

From: <sip:88886@141.94.130.91>;tag=ds41a66623

To: <sip:12272288@10.226.60.253>;tag=3659775C-1601

Date: Sat, 25 Feb 2012 15:06:23 GMT

Call-ID: 131B8C0C-5EF711E1-9A94E97F-44552613@10.226.60.253

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 3 BYE

Reason: Q.850;cause=16

P-RTP-Stat: PS=43773,OS=7000092,PR=40795,OR=6527200,PL=136,JI=0,LA=15,DU=876

Content-Length: 0

Feb 25 15:06:24.205: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:24.205: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:24.205: read data from the socket 0 : first 400 bytes of data:
HTTP/1.1 200 OK

Server: Apache-Coyote/1.1

Content-Type: text/xml;charset=ISO-8859-1

Transfer-Encoding: chunked

Date: Sat, 25 Feb 2012 14:59:28 GMT

Connection: close

1803

<?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'30F218955EF711E19ADCE97F44552613'" />

    <var nam
Feb 25 15:06:24.205: //192004//HTTPC:/httpc_msg_read: Start processing 1072 bytes data
Feb 25 15:06:24.205: //192004//HTTPC:/httpc_msg_read: Ignored - failed to decode message header (Server: Apache-Coyote/1.1) for (msg(2BBBFD3C)
Feb 25 15:06:24.205: //192004//HTTPC:/httpc_msg_read: Stored header at 0x2C138658, len=173 stored
Feb 25 15:06:24.205: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=893
Feb 25 15:06:24.225: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:24.225: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:24.225: read data from the socket 0 : first 400 bytes of data:
<audio src="http://media/en-us/BAJ/Sales/ar/MainQueue.wav" fetchtimeout="10s" />

       </prompt>

         <filled>

          <goto next="#TrySubmit"/>

        </filled>

</object>

   

     <catch event="telephone.disconnect.hangup">

<assign name="ERROR_CODE" expr="1"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.badfetch">

<ass
Feb 25 15:06:24.225: //192004//HTTPC:/httpc_msg_read: Start processing 1608 bytes data
Feb 25 15:06:24.225: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=1608
Feb 25 15:06:24.245: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:24.245: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:24.245: read data from the socket 0 : first 400 bytes of data:
"ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="telephone.disconnect.hangup.forced">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "com.cisco.isn.session.disconnect.error">

<assign name="ERROR_CODE" expr="28"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.unsupport
Feb 25 15:06:24.249: //192004//HTTPC:/httpc_msg_read: Start processing 2048 bytes data
Feb 25 15:06:24.249: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=2048
Feb 25 15:06:24.249: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:24.249: read data from the socket 0 : first 400 bytes of data:
var name="MaxTries" expr="1" />



      <catch event="error.badfetch">

<if cond="TryNum == MaxTries">

   <if cond="DEBUG == '1'">

    <log> Error: Exceeded Retries to Application Server. Going to Recovery VXML: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

    </log>

   </if>

   <goto next="flash:recovery.vxml"/>

</if>

Feb 25 15:06:24.253: //192004//HTTPC:/httpc_msg_read: Start processing 632 bytes data
Feb 25 15:06:24.253: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=632
Feb 25 15:06:24.269: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:24.269: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:24.269: read data from the socket 0 : first 400 bytes of data:
ttp://141.94.130.90:8000/cvp/VBServlet"

         namelist="MSG_TYPE CALL_ID CALL_SEQ_NUM ERROR_CODE" fetchtimeout="7s"/>

</catch>

      <catch event = "error">

<if cond="DEBUG == '1'">

   <log> General Error encountered when trying to contact Application Server. Going to Recovery VXML: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

Feb 25 15:06:24.269: //192004//HTTPC:/httpc_msg_read: Start processing 973 bytes data
Feb 25 15:06:24.269: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=966
Feb 25 15:06:24.269: //192004//HTTPC:/httpc_msg_read: Start processing 5 bytes data
Feb 25 15:06:24.269: //192004//HTTPC:/httpc_socket_read: MSG_COMPLETE: rsp_code(200),msg(2BBBFD3C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0, fd(0)
Feb 25 15:06:24.269: processing server rsp msg: msg(2BBBFD3C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0, fd(0):
Feb 25 15:06:24.269: Request msg: GET /cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0 HTTP/1.1
Feb 25 15:06:24.269: Message Response Code: 200
Feb 25 15:06:24.269: Message Rsp Decoded Headers:
Feb 25 15:06:24.269: Date:Sat, 25 Feb 2012 14:59:28 GMT
Feb 25 15:06:24.269: Transfer-Encoding:chunked
Feb 25 15:06:24.269: Content-Type:text/xml;charset=ISO-8859-1
Feb 25 15:06:24.269: Connection:close
Feb 25 15:06:24.269: headers:
Feb 25 15:06:24.269: HTTP/1.1 200 OK

Server: Apache-Coyote/1.1

Content-Type: text/xml;charset=ISO-8859-1

Transfer-Encoding: chunked

Date: Sat, 25 Feb 2012 14:59:28 GMT

Connection: close

Feb 25 15:06:24.273: body:
Feb 25 15:06:24.273: ... Buffer too large - truncated to (4096) len.
Feb 25 15:06:24.273: <?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'30F218955EF711E19ADCE97F44552613'" />

    <var name="CALL_SEQ_NUM" expr="58" />

    <var name="ERROR_CODE" expr="0" />

    <var name="DEBUG" expr="1" />

    <property name="com.cisco.media-logging-id" value="'30F218955EF711E19ADCE97F44552613'" />

    <form id="ExternalVXML" scope="dialog">







   <catch event="error.com.cisco.callhandoff.failure">

         <goto next="#TrySubmit"/>

     </catch>

     <object name="dummyobj" classid="builtin://com.cisco.callhandoff">

   <param name="return" expr="true" valuetype="data"/>

         <param name="app-uri" expr="'builtin://dummyobj'" valuetype="data"/>

         <prompt bargein="false" cisco-maxtime="0s" cisco-typeaheadflush="false" >

     <audio src="http://media/en-us/BAJ/Sales/ar/MainQueue.wav" fetchtimeout="10s" />

       </prompt>

         <filled>

          <goto next="#TrySubmit"/>

        </filled>

</object>

   

     <catch event="telephone.disconnect.hangup">

<assign name="ERROR_CODE" expr="1"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.badfetch">

<assign name="ERROR_CODE" expr="9"/>

<if cond="DEBUG == '1'">

   <log> Media Fetch Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.semantic">

<assign name="ERROR_CODE" expr="10"/>

<if cond="DEBUG == '1'">

   <log> Semantic Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "error.unsupported.format">

<assign name="ERROR_CODE" expr="11"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Format Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "error.unsupported.element">

<assign name="ERROR_CODE" expr="12"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Element Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="com.cisco.isn.session.disconnect.abnormal">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="telephone.disconnect.hangup.forced">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "com.cisco.isn.session.disconnect.error">

<assign name="ERROR_CODE" expr="28"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.unsupported.language">

<assign name="ERROR_CODE" expr="30"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Language Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>



<catch event = "error.com.cisco.media.resource.unavailable.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Unavailable Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource.failure.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Failure Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource">

<assign name="ERROR_CODE" expr="33"/>

<if cond="DEBUG == '1'">

   <log> General Media Resource Unavailable Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <va
Feb 25 15:06:24.297:
Feb 25 15:06:24.297: //192004//HIFS:/hifs_http_cb: hifs http read succeeded. size=6147, url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
Feb 25 15:06:24.297: //192004//AFW_:/vapp_bgload_done: status=No Error
Feb 25 15:06:24.297: //192004//HIFS:/hifs_http_cb: hifs ifs file read succeeded. size=6147, url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
Feb 25 15:06:24.297: //192004//HIFS:/hifs_free_idata: hifs_free_idata: 0x2C76DDB8
Feb 25 15:06:24.297: //192004//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x2C76DDB8
Feb 25 15:06:24.297: //192004//HTTPC:/httpc_socket_cleanup: fd=0, bytes_sent=460
Feb 25 15:06:24.297: //192004//AFW_:/vapp_driver: evtID: 253 vapp record state: 0
Feb 25 15:06:24.297: //192004//AFW_:/vapp_bgload_done_event:
Feb 25 15:06:24.297: //192004//AFW_:/vapp_bgload_done_event: length=6147, page loaded=
<?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'30F218955EF711E19ADCE97F4455261
Feb 25 15:06:24.297: //192004/30F218959ADC/VXML:/vxml_is_doc:
   vxml_is_doc

Feb 25 15:06:24.297: //192004//HTTPC:/httpc_get_tag: No cache entry found for body(2BFC7B3C)
Feb 25 15:06:24.297: //-1//VXML:/vxml_tree_lock:
   vxmlp=326273F0 usage_cnt=0
Feb 25 15:06:24.297: //0/30F218959ADC/VXML:/vxml_parse:

Feb 25 15:06:24.301: vxml_parse: XML_Parse success err=0
Feb 25 15:06:24.301: //0/30F218959ADC/VXML:/vxml_session_delete:

Feb 25 15:06:24.301: vxml_session_delete:mem_mgr_mempool_free: mempool=NULL
Feb 25 15:06:24.301: vxml_session_delete:mem_mgr_mempool_free: mempool=NULL
Feb 25 15:06:24.301: //-1//VXML:/vxml_crc_generate_element_tree: 142 crc generated
Feb 25 15:06:24.301: //192004//HTTPC:/httpc_set_tag: No cache entry found for body(2BFC7B3C)
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_bgload_post_done:
   vxmlhandle=2C3FAE38 status=0 async_status=400000000
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_bgload_post_done:
   Loading file with url (http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0)
Feb 25 15:06:24.301: //-1//VXML:/vxml_is_cisco_data: Enter
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_leave_scope:
   scope=application
Feb 25 15:06:24.301: //-1//VXML:/vxml_crc_verify_element_tree: 142 crc verified
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_tree_delete:
   vxmlp=2B4A674C, usage_cnt=1 url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=56&ERROR_CODE=0
Feb 25 15:06:24.301: vxml_tree_delete:mem_mgr_mempool_free: mem_refcnt(2BBCA55C)=0 - mempool cleanup
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_stop_fetchaudio:

Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_start:
   vxmlhandle=2C3FAE38 vapphandle=2C4FC93C status=0 async_status=80000
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_vxml_proc:
<vxml>
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
   base=
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0 lang=none version=2.0
         <var>: namep=MSG_TYPE expr='CALL_RESULT'
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var MSG_TYPE='CALL_RESULT')
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_jse_global_switch:
   switch to scope(application)
         <var>: namep=CALL_ID expr='30F218955EF711E19ADCE97F44552613'
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var CALL_ID='30F218955EF711E19ADCE97F44552613')
         <var>: namep=CALL_SEQ_NUM expr=58
Feb 25 15:06:24.301: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var CALL_SEQ_NUM=58)
         <var>: namep=ERROR_CODE expr=0
Feb 25 15:06:24.305: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var ERROR_CODE=0)
         <var>: namep=DEBUG expr=1
Feb 25 15:06:24.305: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var DEBUG=1)
Feb 25 15:06:24.305: //192004/30F218959ADC/VXML:/vxml_form_proc:
Feb 25 15:06:24.305: <form>: id=ExternalVXML   scope=dialog
Feb 25 15:06:24.305: //192004/30F218959ADC/VXML:/vxml_form_init:
   current scope: dialog
   vxml_counter_reset:
Feb 25 15:06:24.305: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var dummyobj)
Feb 25 15:06:24.305: //192004/30F218959ADC/VXML:/vxml_jse_global_switch:
   switch to scope(dialog)
Feb 25 15:06:24.305: //192004/30F218959ADC/VXML:/vxml_formitem_select:
   Status=VXML_STATUS_OK,
Feb 25 15:06:24.305: //192004/30F218959ADC/VXML:/vxml_formitem_select:
    AsyncStatus=VXML_STATUS_OK

Feb 25 15:06:24.305: vxml_object_proc:
Feb 25 15:06:24.305: //192004/30F218959ADC/VXML:/vxml_item_attrs_proc:
   name=dummyobj

Feb 25 15:06:24.305: vxml_param_proc: name=return expr=true valuetype=123

Feb 25 15:06:24.305: vxml_param_proc: name=app-uri expr='builtin://dummyobj' valuetype=123
Feb 25 15:06:24.305: //-1//VXML:/vxml_play_prompts: Enter
Feb 25 15:06:24.305: //-1//VXML:/vxml_prompt_proc: Entercache(2BFC7A94)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
Feb 25 15:06:25.205: //192063//HTTPC:/httpc_socket_read: socket readable fd: 1
Feb 25 15:06:25.205: //192063//HTTPC:/httpc_socket_read:
Feb 25 15:06:25.205: read data from the socket 1 : first 400 bytes of data:
HTTP/1.1 200 OK

Server: Apache-Coyote/1.1

Content-Type: text/xml;charset=ISO-8859-1

Transfer-Encoding: chunked

Date: Sat, 25 Feb 2012 14:59:28 GMT

Connection: close

1803

<?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'F171E0BB5EF711E19B3AE97F44552613'" />

    <var nam
Feb 25 15:06:25.209: //192063//HTTPC:/httpc_msg_read: Start processing 1072 bytes data
Feb 25 15:06:25.209: //192063//HTTPC:/httpc_msg_read: Ignored - failed to decode message header (Server: Apache-Coyote/1.1) for (msg(2BBB9A6C)
Feb 25 15:06:25.209: //192063//HTTPC:/httpc_msg_read: Stored header at 0x2C059E90, len=173 stored
Feb 25 15:06:25.209: //192063//HTTPC:/httpc_msg_read: Stored msg. body: len=893
Feb 25 15:06:25.225: //192063//HTTPC:/httpc_socket_read: socket readable fd: 1
Feb 25 15:06:25.225: //192063//HTTPC:/httpc_socket_read:
Feb 25 15:06:25.225: read data from the socket 1 : first 400 bytes of data:
<audio src="http://media/en-us/BAJ/Sales/ar/MainQueue.wav" fetchtimeout="10s" />

       </prompt>

         <filled>

          <goto next="#TrySubmit"/>

        </filled>

</object>

   

     <catch event="telephone.disconnect.hangup">

<assign name="ERROR_CODE" expr="1"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.badfetch">

<ass
Feb 25 15:06:25.229: //192063//HTTPC:/httpc_msg_read: Start processing 1608 bytes data
Feb 25 15:06:25.229: //192063//HTTPC:/httpc_msg_read: Stored msg. body: len=1608
Feb 25 15:06:25.245: //192063//HTTPC:/httpc_socket_read: socket readable fd: 1
Feb 25 15:06:25.245: //192063//HTTPC:/httpc_socket_read:
Feb 25 15:06:25.245: read data from the socket 1 : first 400 bytes of data:
"ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="telephone.disconnect.hangup.forced">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "com.cisco.isn.session.disconnect.error">

<assign name="ERROR_CODE" expr="28"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.unsupport
Feb 25 15:06:25.249: //192063//HTTPC:/httpc_msg_read: Start processing 1072 bytes data
Feb 25 15:06:25.249: //192063//HTTPC:/httpc_msg_read: Stored msg. body: len=1072
Feb 25 15:06:25.249: //192063//HTTPC:/httpc_socket_read: socket readable fd: 1
Feb 25 15:06:25.249: //192063//HTTPC:/httpc_socket_read:
Feb 25 15:06:25.249: read data from the socket 1 : first 400 bytes of data:
e.failure.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Failure Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource">

<assign name="ERROR_CODE" expr="33"/>

<if cond="D
Feb 25 15:06:25.253: //192063//HTTPC:/httpc_msg_read: Start processing 1608 bytes data
Feb 25 15:06:25.253: //192063//HTTPC:/httpc_msg_read: Stored msg. body: len=1608
Feb 25 15:06:25.269: //192063//HTTPC:/httpc_socket_read: socket readable fd: 1
Feb 25 15:06:25.269: //192063//HTTPC:/httpc_socket_read:
Feb 25 15:06:25.269: read data from the socket 1 : first 400 bytes of data:
ttp://141.94.130.90:8000/cvp/VBServlet"

         namelist="MSG_TYPE CALL_ID CALL_SEQ_NUM ERROR_CODE" fetchtimeout="7s"/>

</catch>

      <catch event = "error">

<if cond="DEBUG == '1'">

   <log> General Error encountered when trying to contact Application Server. Going to Recovery VXML: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

Feb 25 15:06:25.273: //192063//HTTPC:/httpc_msg_read: Start processing 973 bytes data
Feb 25 15:06:25.273: //192063//HTTPC:/httpc_msg_read: Stored msg. body: len=966
Feb 25 15:06:25.273: //192063//HTTPC:/httpc_msg_read: Start processing 5 bytes data
Feb 25 15:06:25.273: //192063//HTTPC:/httpc_socket_read: MSG_COMPLETE: rsp_code(200),msg(2BBB9A6C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=35&ERROR_CODE=0, fd(1)
Feb 25 15:06:25.273: processing server rsp msg: msg(2BBB9A6C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=35&ERROR_CODE=0, fd(1):
Feb 25 15:06:25.273: Request msg: GET /cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=35&ERROR_CODE=0 HTTP/1.1
Feb 25 15:06:25.273: Message Response Code: 200
Feb 25 15:06:25.273: Message Rsp Decoded Headers:
Feb 25 15:06:25.273: Date:Sat, 25 Feb 2012 14:59:28 GMT
Feb 25 15:06:25.273: Transfer-Encoding:chunked
Feb 25 15:06:25.273: Content-Type:text/xml;charset=ISO-8859-1
Feb 25 15:06:25.273: Connection:close
Feb 25 15:06:25.273: headers:
Feb 25 15:06:25.273: HTTP/1.1 200 OK

Server: Apache-Coyote/1.1

Content-Type: text/xml;charset=ISO-8859-1

Transfer-Encoding: chunked

Date: Sat, 25 Feb 2012 14:59:28 GMT

Connection: close

Feb 25 15:06:25.273: body:
Feb 25 15:06:25.273: ... Buffer too large - truncated to (4096) len.
Feb 25 15:06:25.273: <?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'F171E0BB5EF711E19B3AE97F44552613'" />

    <var name="CALL_SEQ_NUM" expr="36" />

    <var name="ERROR_CODE" expr="0" />

    <var name="DEBUG" expr="1" />

    <property name="com.cisco.media-logging-id" value="'F171E0BB5EF711E19B3AE97F44552613'" />

    <form id="ExternalVXML" scope="dialog">







   <catch event="error.com.cisco.callhandoff.failure">

         <goto next="#TrySubmit"/>

     </catch>

     <object name="dummyobj" classid="builtin://com.cisco.callhandoff">

   <param name="return" expr="true" valuetype="data"/>

         <param name="app-uri" expr="'builtin://dummyobj'" valuetype="data"/>

         <prompt bargein="false" cisco-maxtime="0s" cisco-typeaheadflush="false" >

     <audio src="http://media/en-us/BAJ/Sales/ar/MainQueue.wav" fetchtimeout="10s" />

       </prompt>

         <filled>

          <goto next="#TrySubmit"/>

        </filled>

</object>

   

     <catch event="telephone.disconnect.hangup">

<assign name="ERROR_CODE" expr="1"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.badfetch">

<assign name="ERROR_CODE" expr="9"/>

<if cond="DEBUG == '1'">

   <log> Media Fetch Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.semantic">

<assign name="ERROR_CODE" expr="10"/>

<if cond="DEBUG == '1'">

   <log> Semantic Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "error.unsupported.format">

<assign name="ERROR_CODE" expr="11"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Format Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "error.unsupported.element">

<assign name="ERROR_CODE" expr="12"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Element Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="com.cisco.isn.session.disconnect.abnormal">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="telephone.disconnect.hangup.forced">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "com.cisco.isn.session.disconnect.error">

<assign name="ERROR_CODE" expr="28"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.unsupported.language">

<assign name="ERROR_CODE" expr="30"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Language Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>



<catch event = "error.com.cisco.media.resource.unavailable.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Unavailable Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource.failure.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Failure Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource">

<assign name="ERROR_CODE" expr="33"/>

<if cond="DEBUG == '1'">

   <log> General Media Resource Unavailable Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <va
Feb 25 15:06:25.297:
Feb 25 15:06:25.297: //192063//HIFS:/hifs_http_cb: hifs http read succeeded. size=6147, url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=35&ERROR_CODE=0
Feb 25 15:06:25.297: //192063//AFW_:/vapp_bgload_done: status=No Error
Feb 25 15:06:25.297: //192063//HIFS:/hifs_http_cb: hifs ifs file read succeeded. size=6147, url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=35&ERROR_CODE=0
Feb 25 15:06:25.297: //192063//HIFS:/hifs_free_idata: hifs_free_idata: 0x2C77E714
Feb 25 15:06:25.297: //192063//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x2C77E714
Feb 25 15:06:25.297: //192063//HTTPC:/httpc_socket_cleanup: fd=1, bytes_sent=460
Feb 25 15:06:25.297: //192063//AFW_:/vapp_driver: evtID: 253 vapp record state: 0
Feb 25 15:06:25.297: //192063//AFW_:/vapp_bgload_done_event:
Feb 25 15:06:25.297: //192063//AFW_:/vapp_bgload_done_event: length=6147, page loaded=
<?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'F171E0BB5EF711E19B3AE97F4455261
Feb 25 15:06:25.297: //192063/F171E0BB9B3A/VXML:/vxml_is_doc:
   vxml_is_doc

Feb 25 15:06:25.297: //192063//HTTPC:/httpc_get_tag: No cache entry found for body(32B0A8C4)
Feb 25 15:06:25.297: //-1//VXML:/vxml_tree_lock:
   vxmlp=2B4A674C usage_cnt=0
Feb 25 15:06:25.297: //0/F171E0BB9B3A/VXML:/vxml_parse:

Feb 25 15:06:25.297: vxml_parse: XML_Parse success err=0
Feb 25 15:06:25.297: //0/F171E0BB9B3A/VXML:/vxml_session_delete:

Feb 25 15:06:25.993: about to send data to the socket 0 : first 400 bytes of data:
GET /cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F201CF445EF811E19BEFE97F44552613&CALL_SEQ_NUM=4&ERROR_CODE=0 HTTP/1.1

Host: 141.94.130.90:8000

Content-Type: application/x-www-form-urlencoded

Connection: close

Accept: 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, app
Feb 25 15:06:25.993: //192177//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:26.073: //192177//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:26.073: //192177//HTTPC:/httpc_socket_read:
Feb 25 15:06:26.073: read data from the socket 0 : first 400 bytes of data:
HTTP/1.1 200 OK

Server: Apache-Coyote/1.1

Content-Type: text/xml;charset=ISO-8859-1

Transfer-Encoding: chunked

Date: Sat, 25 Feb 2012 14:59:30 GMT

Connection: close

17ff

<?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'F201CF445EF811E19BEFE97F44552613'" />

    <var nam
Feb 25 15:06:26.073: //192177//HTTPC:/httpc_msg_read: Start processing 1072 bytes data
Feb 25 15:06:26.073: //192177//HTTPC:/httpc_msg_read: Ignored - failed to decode message header (Server: Apache-Coyote/1.1) for (msg(2BBB832C)
Feb 25 15:06:26.073: //192177//HTTPC:/httpc_msg_read: Stored header at 0x2C138658, len=173 stored
Feb 25 15:06:26.073: //192177//HTTPC:/httpc_msg_read: Stored msg. body: len=893
Feb 25 15:06:26.093: //192177//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:26.093: //192177//HTTPC:/httpc_socket_read:
Feb 25 15:06:26.093: read data from the socket 0 : first 400 bytes of data:
<audio src="http://media/en-us/BAJ/Sales/ar/Queue1.wav" fetchtimeout="10s" />

       </prompt>

         <filled>

          <goto next="#TrySubmit"/>

        </filled>

</object>

   

     <catch event="telephone.disconnect.hangup">

<assign name="ERROR_CODE" expr="1"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.badfetch">

<assign
Feb 25 15:06:26.093: //192177//HTTPC:/httpc_msg_read: Start processing 536 bytes data
Feb 25 15:06:26.093: //192177//HTTPC:/httpc_msg_read: Stored msg. body: len=536
Feb 25 15:06:26.093: //192177//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:26.093: //192177//HTTPC:/httpc_socket_read:
Feb 25 15:06:26.093: read data from the socket 0 : first 400 bytes of data:
um: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.semantic">

<assign name="ERROR_CODE" expr="10"/>

<if cond="DEBUG == '1'">

   <log> Semantic Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event =
Feb 25 15:06:26.097: //192177//HTTPC:/httpc_msg_read: Start processing 1072 bytes data
Feb 25 15:06:26.097: //192177//HTTPC:/httpc_msg_read: Stored msg. body: len=1072
Feb 25 15:06:26.113: //192177//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:26.113: //192177//HTTPC:/httpc_socket_read:
Feb 25 15:06:26.113: read data from the socket 0 : first 400 bytes of data:
OR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="telephone.disconnect.hangup.forced">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "com.cisco.isn.session.disconnect.error">

<assign name="ERROR_CODE" expr="28"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.unsupported.l
Feb 25 15:06:26.117: //192177//HTTPC:/httpc_msg_read: Start processing 1072 bytes data
Feb 25 15:06:26.117: //192177//HTTPC:/httpc_msg_read: Stored msg. body: len=1072
Feb 25 15:06:26.117: //192177//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:26.117: //192177//HTTPC:/httpc_socket_read:
Feb 25 15:06:26.117: read data from the socket 0 : first 400 bytes of data:
ilure.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Failure Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource">

<assign name="ERROR_CODE" expr="33"/>

<if cond="DEBUG
Feb 25 15:06:26.121: //192177//HTTPC:/httpc_msg_read: Start processing 1608 bytes data
Feb 25 15:06:26.121: //192177//HTTPC:/httpc_msg_read: Stored msg. body: len=1608
Feb 25 15:06:26.137: //192177//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:26.137: //192177//HTTPC:/httpc_socket_read:
Feb 25 15:06:26.137: read data from the socket 0 : first 400 bytes of data:
//141.94.130.90:8000/cvp/VBServlet"

         namelist="MSG_TYPE CALL_ID CALL_SEQ_NUM ERROR_CODE" fetchtimeout="7s"/>

</catch>

      <catch event = "error">

<if cond="DEBUG == '1'">

   <log> General Error encountered when trying to contact Application Server. Going to Recovery VXML: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if
Feb 25 15:06:26.137: //192177//HTTPC:/httpc_msg_read: Start processing 969 bytes data
Feb 25 15:06:26.137: //192177//HTTPC:/httpc_msg_read: Stored msg. body: len=962
Feb 25 15:06:26.137: //192177//HTTPC:/httpc_msg_read: Start processing 5 bytes data
Feb 25 15:06:26.137: //192177//HTTPC:/httpc_socket_read: MSG_COMPLETE: rsp_code(200),msg(2BBB832C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F201CF445EF811E19BEFE97F44552613&CALL_SEQ_NUM=4&ERROR_CODE=0, fd(0)
Feb 25 15:06:26.137: processing server rsp msg: msg(2BBB832C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F201CF445EF811E19BEFE97F44552613&CALL_SEQ_NUM=4&ERROR_CODE=0, fd(0):
Feb 25 15:06:26.137: Request msg: GET /cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F201CF445EF811E19BEFE97F44552613&CALL_SEQ_NUM=4&ERROR_CODE=0 HTTP/1.1
Feb 25 15:06:26.137: Message Response Code: 200
Feb 25 15:06:26.137: Message Rsp Decoded Headers:
Feb 25 15:06:26.137: Date:Sat, 25 Feb 2012 14:59:30 GMT
Feb 25 15:06:26.137: Transfer-Encoding:chunked
Feb 25 15:06:26.137: Content-Type:text/xml;charset=ISO-8859-1
Feb 25 15:06:26.137: Connection:close
Feb 25 15:06:26.137: headers:
Feb 25 15:06:26.137: HTTP/1.1 200 OK

Server: Apache-Coyote/1.1

Content-Type: text/xml;charset=ISO-8859-1

Transfer-Encoding: chunked

Date: Sat, 25 Feb 2012 14:59:30 GMT

Connection: close

Feb 25 15:06:26.141: body:
Feb 25 15:06:26.141: ... Buffer too large - truncated to (4096) len.
Feb 25 15:06:26.141: <?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'F201CF445EF811E19BEFE97F44552613'" />

    <var name="CALL_SEQ_NUM" expr="5" />

    <var name="ERROR_CODE" expr="0" />

    <var name="DEBUG" expr="1" />

    <property name="com.cisco.media-logging-id" value="'F201CF445EF811E19BEFE97F44552613'" />

    <form id="ExternalVXML" scope="dialog">







   <catch event="error.com.cisco.callhandoff.failure">

         <goto next="#TrySubmit"/>

     </catch>

     <object name="dummyobj" classid="builtin://com.cisco.callhandoff">

   <param name="return" expr="true" valuetype="data"/>

         <param name="app-uri" expr="'builtin://dummyobj'" valuetype="data"/>

         <prompt bargein="false" cisco-maxtime="0s" cisco-typeaheadflush="false" >

     <audio src="http://media/en-us/BAJ/Sales/ar/Queue1.wav" fetchtimeout="10s" />

       </prompt>

         <filled>

          <goto next="#TrySubmit"/>

        </filled>

</object>

   

     <catch event="telephone.disconnect.hangup">

<assign name="ERROR_CODE" expr="1"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.badfetch">

<assign name="ERROR_CODE" expr="9"/>

<if cond="DEBUG == '1'">

   <log> Media Fetch Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.semantic">

<assign name="ERROR_CODE" expr="10"/>

<if cond="DEBUG == '1'">

   <log> Semantic Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "error.unsupported.format">

<assign name="ERROR_CODE" expr="11"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Format Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "error.unsupported.element">

<assign name="ERROR_CODE" expr="12"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Element Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="com.cisco.isn.session.disconnect.abnormal">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="telephone.disconnect.hangup.forced">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "com.cisco.isn.session.disconnect.error">

<assign name="ERROR_CODE" expr="28"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.unsupported.language">

<assign name="ERROR_CODE" expr="30"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Language Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>



<catch event = "error.com.cisco.media.resource.unavailable.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Unavailable Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource.failure.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Failure Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource">

<assign name="ERROR_CODE" expr="33"/>

<if cond="DEBUG == '1'">

   <log> General Media Resource Unavailable Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value
Feb 25 15:06:26.165:
Feb 25 15:06:26.165: //192177//HIFS:/hifs_http_cb: hifs http read succeeded. size=6143, url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F201CF445EF811E19BEFE97F44552613&CALL_SEQ_NUM=4&ERROR_CODE=0
Feb 25 15:06:26.165: //192177//AFW_:/vapp_bgload_done: status=No Error
Feb 25 15:06:26.165: //192177//HIFS:/hifs_http_cb: hifs ifs file read succeeded. size=6143, url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F201CF445EF811E19BEFE97F44552613&CALL_SEQ_NUM=4&ERROR_CODE=0
Feb 25 15:06:26.165: //192177//HIFS:/hifs_free_idata: hifs_free_idata: 0x2C784144
Feb 25 15:06:26.165: //192177//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x2C784144
Feb 25 15:06:26.165: //192177//HTTPC:/httpc_socket_cleanup: fd=0, bytes_sent=459
Feb 25 15:06:26.165: //192177//AFW_:/vapp_driver: evtID: 253 vapp record state: 0
Feb 25 15:06:26.165: //192177//AFW_:/vapp_bgload_done_event:
Feb 25 15:06:26.165: //192177//AFW_:/vapp_bgload_done_event:
Feb 25 15:06:26.173: //-1//HTTPC:/httpc_process: received event, sid: 0, method: 262144
Feb 25 15:06:26.173: //192177//HTTPC:/httpc_process_free_rsp: User returns noncache response (2C4D7954)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F201CF445EF811E19BEFE97F44552613&CALL_SEQ_NUM=4&ERROR_CODE=0
Feb 25 15:06:26.173: //192177//HTTPC:/httpc_cache_entry_free: cache(2C4D7954)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F201CF445EF811E19BEFE97F44552613&CALL_SEQ_NUM=4&ERROR_CODE=0
Feb 25 15:06:27.153: //192191//AFW_:/vapp_driver: evtID: 173 vapp record state: 0
Feb 25 15:06:27.153: //192191//AFW_:/vapp_play_done: evID=173 reason=17, protocol=2, status_code=0, dur=5992, rate=0
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_media_done:
   status 0 async_status 104000000 duration=5992 rate=0
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_save_lastprompt_info:

Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_save_lastprompt_info:

Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_vapp_typeahead_flush:

Feb 25 15:06:27.153: //192191//AFW_:/vapp_typeahead_flush:
Feb 25 15:06:27.153: //192191//AFW_:/vapp_notify_play_done:
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_vapp_vcr_control_disable:

Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_vapp_object_execute:
   classid builtin://com.cisco.callhandoff
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_vapp_object_callhandoff:
Feb 25 15:06:27.153: //192191//AFW_:/vapp_callhandoff: app_uri=builtin://dummyobj, wait_for_return=true, arg_string=
Feb 25 15:06:27.153: //192191//AFW_:/vapp_callhandoff: ccGetApp dummyobj failed
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_vapp_object_callhandoff:
   Handoff failed
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_start:
   vxmlhandle=329270A8 vapphandle=2C4FC434 status=0 async_status=80
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_throw_error_session_event:
   async_status=80
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_event_proc:
         <event>: event=error.com.cisco.callhandoff.failure status=0
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_expr_eval:
   expr=(var _event='error.com.cisco.callhandoff.failure')
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_expr_eval:
   expr=(var _message=undefined)
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_catch_proc:
         <catch>:
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_nmtokens_proc:
   name=error.com.cisco.callhandoff.failure count=1
   script_loading=0
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_asynceventparams_get:

Feb 25 15:06:27.153: vxml_asynceventparams_get:
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_goto_proc:
            <goto>: caching=fast fetchhint=invalid fetchtimeout=0 maxage=-1 maxstale=-1
   URI

TrySubmit
   fragmentp=TrySubmit
Feb 25 15:06:27.153: //-1//VXML:/vxml_next_dialog: Enter
Feb 25 15:06:27.153: //-1//VXML:/vxml_next_dialog: Got dialog, Exit@1264
Feb 25 15:06:27.153: //-1//VXML:/vxml_goto_proc:
(400) Status not OK, exit@5603
Feb 25 15:06:27.153: //-1//VXML:/vxml_elem_proc:
(400) Status not OK, exit@9954
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_leave_scope:
   scope=anonymous
Feb 25 15:06:27.153: //-1//VXML:/vxml_catch_proc: Exit (400)
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_load_immediate_done:
   sidp->status=400
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_start:
   vxmlhandle=329270A8 vapphandle=2C4FC434 status=400 async_status=400
            vxml_dialog_reset:
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_leave_scope:
   scope=dialog
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_vxml_proc:
<vxml>
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=1&ERROR_CODE=0&CALLCED=Success&FROM_EXTVXML0=CreditCard&FROM_EXTVXML1=ar&FROM_EXTVXML2=FollowUp
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=1&ERROR_CODE=0&CALLCED=Success&FROM_EXTVXML0=CreditCard&FROM_EXTVXML1=ar&FROM_EXTVXML2=FollowUp
   base=
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=1&ERROR_CODE=0&CALLCED=Success&FROM_EXTVXML0=CreditCard&FROM_EXTVXML1=ar&FROM_EXTVXML2=FollowUp
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=1&ERROR_CODE=0&CALLCED=Success&FROM_EXTVXML0=CreditCard&FROM_EXTVXML1=ar&FROM_EXTVXML2=FollowUp lang=none version=2.0
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_form_proc:
Feb 25 15:06:27.153: <form>: id=TrySubmit   scope=dialog
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_form_init:
   current scope: dialog
   <var>: namep=TryNum expr=1
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_expr_eval:
   expr=(var TryNum=1)
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_jse_global_switch:
   switch to scope(dialog)
   <var>: namep=MaxTries expr=1
Feb 25 15:06:27.153: //192191/068C98819C08/VXML:/vxml_expr_eval:
   expr=(var MaxTries=1)
   vxml_counter_reset:
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_expr_eval:
   expr=(var _in430638)
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_formitem_select:
   Status=VXML_STATUS_OK,
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_formitem_select:
    AsyncStatus=VXML_STATUS_OK
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_block_proc:
Feb 25 15:06:27.157:   <block>:
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_item_attrs_proc:
   name=_in430638
Feb 25 15:06:27.157: //-1//VXML:/vxml_submit_proc: Enter
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_submit_proc:
         <submit>: caching=fast fetchhint=invalid fetchtimeout=7 maxage=-1 maxstale=-1
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_sub_attrs_proc:
   method=get
   enctype=application/x-www-form-urlencoded
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_nmtokens_proc:
   name=MSG_TYPE
   name=CALL_ID
   name=CALL_SEQ_NUM
   name=ERROR_CODE
Feb 25 15:06:27.157: //-1//VXML:/vxml_sub_attrs_proc: Enter
Feb 25 15:06:27.157: //-1//VXML:/vxml_sub_attrs_proc: Exit
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_vapp_bgload_from_proc:
   urlp=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=2&ERROR_CODE=0 fetchaudio=NULL delay=0 minimum=0
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_get_doc: url: http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=2&ERROR_CODE=0
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_vapp_bgload:
   url http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=2&ERROR_CODE=0 cachable 1 fetchtimeout 7 maxage=-1 maxstale=-1
Feb 25 15:06:27.157: //192191//AFW_:/vapp_bgload: url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=2&ERROR_CODE=0
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_get: url length=129
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_get: url: http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=2&ERROR_CODE=0
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_send_ev: event sent to HTTP Client:
Feb 25 15:06:27.157:     method (GET), url (http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=068C98815EF911E19C08E97F44552613&CALL_SEQ_NUM=2&ERROR_CODE=0)
Feb 25 15:06:27.157:     callback (251B9078), argp (2C772FD0), sid (0), timeout (7), maxage (-1), maxstale(-1) retries (0)
Feb 25 15:06:27.157: //192191//AFW_:/vxml_update_cleanup_timer: cleaning timer running 0 fetchtimeout 7
Feb 25 15:06:27.157: //-1//VXML:/vxml_vapp_bgload: Exit
Feb 25 15:06:27.157: //-1//VXML:/vxml_vapp_bgload_from_proc: normal_async_load, exit@8299
Feb 25 15:06:27.157: //-1//VXML:/vxml_submit_proc: Exit
Feb 25 15:06:27.157: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9954
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_leave_scope:
   scope=anonymous
Feb 25 15:06:27.157: //-1//VXML:/vxml_block_proc: Exit (400000000)
Feb 25 15:06:27.157: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9954
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_formitem_select:
   loop cnt=0,
Feb 25 15:06:27.157: //-1//VXML:/vxml_formitem_select:
(400000000) Status not OK, exit@5409
Feb 25 15:06:27.157: //-1//VXML:/vxml_form_proc:
(400000000) Status not OK, exit@5541
Feb 25 15:06:27.157: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9954
Feb 25 15:06:27.157: //-1//VXML:/vxml_vxml_proc:
(400000000) Status not OK, exit@10160
Feb 25 15:06:27.157: //192191/068C98819C08/VXML:/vxml_load_immediate_done:
   sidp->status=400000000
Feb 25 15:06:27.157: //-1//VXML:/vxml_start: Exit
Feb 25 15:06:27.157: //192191//AFW_:/vapp_checksessionstate:
Feb 25 15:06:27.157: //192191//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
Feb 25 15:06:27.157: //-1//HTTPC:/httpc_process: received event, sid: 0, method: 2
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_msg_send: sid: 0, method: 2
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_connect: No Connection found, opening socket...
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_connect: socket_open() OK fd=0
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_connect: calling socket_connect() for fd 0
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_connect: socket_connect() return errno=EWOULDBLOCK fd(0) state ---> HTTPC_SOCK_PENDING
Feb 25 15:06:27.157: //192191//HTTPC:/httpc_enqueue_wmsg: sid: 0, method: 2
Feb 25 15:06:27.177: //192191//HTTPC:/httpc_process_read_ev: SOCK_PENDING --> SOCK_CONNECTED fd(0) port(8000)SIP/2.0 200 OK

Via: SIP/2.0/UDP 141.94.130.90:5060;branch=z9hG4bKFFcTOLKZ9BQL0eCvLVdZhw~~1089

From: <sip:88885@141.94.130.90>;tag=ds37f48e12

To: <sip:2342@10.226.60.253>;tag=36659FC8-BA8

Date: Sat, 25 Feb 2012 15:06:27 GMT

uCall-ID: EE068568-5EF811E1-9BE8E97F-44552613@10.226.60.253

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 3 BYE

Reason: Q.850;cause=16

P-RTP-Stat: PS=4180,OS=667708,PR=3230,OR=516800,PL=0,JI=0,LA=0,DU=84

Content-Length: 0

Feb 25 15:06:30.309: //192004//AFW_:/vapp_driver: evtID: 173 vapp record state: 0
Feb 25 15:06:30.309: //192004//AFW_:/vapp_play_done: evID=173 reason=17, protocol=2, status_code=0, dur=5992, rate=0
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_media_done:
   status 0 async_status 104000000 duration=5992 rate=0
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_save_lastprompt_info:

Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_save_lastprompt_info:

Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_vapp_typeahead_flush:

Feb 25 15:06:30.309: //192004//AFW_:/vapp_typeahead_flush:
Feb 25 15:06:30.309: //192004//AFW_:/vapp_notify_play_done:
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_vapp_vcr_control_disable:

Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_vapp_object_execute:
   classid builtin://com.cisco.callhandoff
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_vapp_object_callhandoff:
Feb 25 15:06:30.309: //192004//AFW_:/vapp_callhandoff: app_uri=builtin://dummyobj, wait_for_return=true, arg_string=
Feb 25 15:06:30.309: //192004//AFW_:/vapp_callhandoff: ccGetApp dummyobj failed
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_vapp_object_callhandoff:
   Handoff failed
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_start:
   vxmlhandle=2C3FAE38 vapphandle=2C4FC93C status=0 async_status=80
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_throw_error_session_event:
   async_status=80
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_event_proc:
         <event>: event=error.com.cisco.callhandoff.failure status=0
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var _event='error.com.cisco.callhandoff.failure')
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var _message=undefined)
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_catch_proc:
         <catch>:
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_nmtokens_proc:
   name=error.com.cisco.callhandoff.failure count=1
   script_loading=0
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_asynceventparams_get:

Feb 25 15:06:30.309: vxml_asynceventparams_get:
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_goto_proc:
            <goto>: caching=fast fetchhint=invalid fetchtimeout=0 maxage=-1 maxstale=-1
   URI

TrySubmit
   fragmentp=TrySubmit
Feb 25 15:06:30.309: //-1//VXML:/vxml_next_dialog: Enter
Feb 25 15:06:30.309: //-1//VXML:/vxml_next_dialog: Got dialog, Exit@1264
Feb 25 15:06:30.309: //-1//VXML:/vxml_goto_proc:
(400) Status not OK, exit@5603
Feb 25 15:06:30.309: //-1//VXML:/vxml_elem_proc:
(400) Status not OK, exit@9954
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_leave_scope:
   scope=anonymous
Feb 25 15:06:30.309: //-1//VXML:/vxml_catch_proc: Exit (400)
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_load_immediate_done:
   sidp->status=400
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_start:
   vxmlhandle=2C3FAE38 vapphandle=2C4FC93C status=400 async_status=400
            vxml_dialog_reset:
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_leave_scope:
   scope=dialog
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_vxml_proc:
<vxml>
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
   base=
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0 lang=none version=2.0
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_form_proc:
Feb 25 15:06:30.309: <form>: id=TrySubmit   scope=dialog
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_form_init:
   current scope: dialog
   <var>: namep=TryNum expr=1
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var TryNum=1)
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_jse_global_switch:
   switch to scope(dialog)
   <var>: namep=MaxTries expr=1
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var MaxTries=1)
   vxml_counter_reset:
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var _in430644)
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_formitem_select:
   Status=VXML_STATUS_OK,
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_formitem_select:
    AsyncStatus=VXML_STATUS_OK
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_block_proc:
Feb 25 15:06:30.309:   <block>:
Feb 25 15:06:30.309: //192004/30F218959ADC/VXML:/vxml_item_attrs_proc:
   name=_in430644
Feb 25 15:06:30.309: //-1//VXML:/vxml_submit_proc: Enter
Feb 25 15:06:30.313: //192004/30F218959ADC/VXML:/vxml_submit_proc:
         <submit>: caching=fast fetchhint=invalid fetchtimeout=7 maxage=-1 maxstale=-1
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
Feb 25 15:06:30.313: //192004/30F218959ADC/VXML:/vxml_sub_attrs_proc:
   method=get
   enctype=application/x-www-form-urlencoded
Feb 25 15:06:30.313: //192004/30F218959ADC/VXML:/vxml_nmtokens_proc:
   name=MSG_TYPE
   name=CALL_ID
   name=CALL_SEQ_NUM
   name=ERROR_CODE
Feb 25 15:06:30.313: //-1//VXML:/vxml_sub_attrs_proc: Enter
Feb 25 15:06:30.313: //-1//VXML:/vxml_sub_attrs_proc: Exit
Feb 25 15:06:30.313: //192004/30F218959ADC/VXML:/vxml_vapp_bgload_from_proc:
   urlp=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0 fetchaudio=NULL delay=0 minimum=0
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_get_doc: url: http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
Feb 25 15:06:30.313: //192004/30F218959ADC/VXML:/vxml_vapp_bgload:
   url http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0 cachable 1 fetchtimeout 7 maxage=-1 maxstale=-1
Feb 25 15:06:30.313: //192004//AFW_:/vapp_bgload: url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_get: url length=130
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_get: url: http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_send_ev: event sent to HTTP Client:
Feb 25 15:06:30.313:     method (GET), url (http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0)
Feb 25 15:06:30.313:     callback (251B9078), argp (2C77034C), sid (0), timeout (7), maxage (-1), maxstale(-1) retries (0)
Feb 25 15:06:30.313: //192004//AFW_:/vxml_update_cleanup_timer: cleaning timer running 0 fetchtimeout 7
Feb 25 15:06:30.313: //-1//VXML:/vxml_vapp_bgload: Exit
Feb 25 15:06:30.313: //-1//VXML:/vxml_vapp_bgload_from_proc: normal_async_load, exit@8299
Feb 25 15:06:30.313: //-1//VXML:/vxml_submit_proc: Exit
Feb 25 15:06:30.313: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9954
Feb 25 15:06:30.313: //192004/30F218959ADC/VXML:/vxml_leave_scope:
   scope=anonymous
Feb 25 15:06:30.313: //-1//VXML:/vxml_block_proc: Exit (400000000)
Feb 25 15:06:30.313: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9954
Feb 25 15:06:30.313: //192004/30F218959ADC/VXML:/vxml_formitem_select:
   loop cnt=0,
Feb 25 15:06:30.313: //-1//VXML:/vxml_formitem_select:
(400000000) Status not OK, exit@5409
Feb 25 15:06:30.313: //-1//VXML:/vxml_form_proc:
(400000000) Status not OK, exit@5541
Feb 25 15:06:30.313: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9954
Feb 25 15:06:30.313: //-1//VXML:/vxml_vxml_proc:
(400000000) Status not OK, exit@10160
Feb 25 15:06:30.313: //192004/30F218959ADC/VXML:/vxml_load_immediate_done:
   sidp->status=400000000
Feb 25 15:06:30.313: //-1//VXML:/vxml_start: Exit
Feb 25 15:06:30.313: //192004//AFW_:/vapp_checksessionstate:
Feb 25 15:06:30.313: //192004//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
Feb 25 15:06:30.313: //-1//HTTPC:/httpc_process: received event, sid: 0, method: 2
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_msg_send: sid: 0, method: 2
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_connect: No Connection found, opening socket...
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_connect: socket_open() OK fd=0
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_connect: calling socket_connect() for fd 0
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_connect: socket_connect() return errno=EWOULDBLOCK fd(0) state ---> HTTPC_SOCK_PENDING
Feb 25 15:06:30.313: //192004//HTTPC:/httpc_enqueue_wmsg: sid: 0, method: 2
Feb 25 15:06:30.333: //192004//HTTPC:/httpc_process_read_ev: SOCK_PENDING --> SOCK_CONNECTED fd(0) port(8000)
Feb 25 15:06:30.333: //192004//HTTPC:/httpc_process_read_ev: httpc_check_connect_secure() return HTTPC_ERR_SSLHS_NOT_NEEDED for fd 0
Feb 25 15:06:30.333: //192004//HTTPC:/httpc_process_write_queue: socket writeble fd: 0, process enqueued msg,
                            sid: 0, method: 2
Feb 25 15:06:30.333: //192004//HTTPC:/httpc_msg_write: sid: 0, method: 2
Feb 25 15:06:30.333: //192004//HTTPC:/httpc_write_stream: use temp_buf
Feb 25 15:06:30.333: //192004//HTTPC:/httpc_write_stream: Client write buffer fd(0):
GET /cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0 HTTP/1.1

Host: 141.94.130.90:8000

Content-Type: application/x-www-form-urlencoded

Connection: close

Accept: text/vxml, text/x-vxml, application/vxml, application/x-vxml, application/voicexml all
All possible debugging has been turned off
CALL-CENTER-RTR-253#, application/x-voicexml, text/plain, text/html, audio/basic, audio/wav, multipart/form-data, application/octet-stream

User-Agent: Cisco-IOS-C2900/15.1

Feb 25 15:06:30.333: //192004//HTTPC:/httpc_socket_send: fd: 0
Feb 25 15:06:30.333: //192004//HTTPC:/httpc_socket_send:
Feb 25 15:06:30.333: about to send data to the socket 0 : first 400 bytes of data:
GET /cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0 HTTP/1.1

Host: 141.94.130.90:8000

Content-Type: application/x-www-form-urlencoded

Connection: close

Accept: 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, ap
Feb 25 15:06:30.337: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:30.365: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:30.365: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:30.365: read data from the socket 0 : first 400 bytes of data:
HTTP/1.1 200 OK

Server: Apache-Coyote/1.1

Content-Type: text/xml;charset=ISO-8859-1

Transfer-Encoding: chunked

Date: Sat, 25 Feb 2012 14:59:34 GMT

Connection: close

1800

<?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'30F218955EF711E19ADCE97F44552613'" />

    <var nam
Feb 25 15:06:30.369: //192004//HTTPC:/httpc_msg_read: Start processing 1072 bytes data
Feb 25 15:06:30.369: //192004//HTTPC:/httpc_msg_read: Ignored - failed to decode message header (Server: Apache-Coyote/1.1) for (msg(2BBBA41C)
Feb 25 15:06:30.369: //192004//HTTPC:/httpc_msg_read: Stored header at 0x32E22A54, len=173 stored
Feb 25 15:06:30.369: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=893
Feb 25 15:06:30.389: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:30.389: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:30.389: read data from the socket 0 : first 400 bytes of data:
<audio src="http://media/en-us/BAJ/Sales/ar/Queue2.wav" fetchtimeout="10s" />

       </prompt>

         <filled>

          <goto next="#TrySubmit"/>

        </filled>

</object>

   

     <catch event="telephone.disconnect.hangup">

<assign name="ERROR_CODE" expr="1"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.badfetch">

<assign
Feb 25 15:06:30.389: //192004//HTTPC:/httpc_msg_read: Start processing 1608 bytes data
Feb 25 15:06:30.389: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=1608
Feb 25 15:06:30.409: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:30.409: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:30.409: read data from the socket 0 : first 400 bytes of data:
ROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="telephone.disconnect.hangup.forced">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "com.cisco.isn.session.disconnect.error">

<assign name="ERROR_CODE" expr="28"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.unsupported.
Feb 25 15:06:30.409: //192004//HTTPC:/httpc_msg_read: Start processing 1072 bytes data
Feb 25 15:06:30.409: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=1072
Feb 25 15:06:30.413: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:30.413: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:30.413: read data from the socket 0 : first 400 bytes of data:
ailure.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Failure Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource">

<assign name="ERROR_CODE" expr="33"/>

<if cond="DEBU
Feb 25 15:06:30.413: //192004//HTTPC:/httpc_msg_read: Start processing 1608 bytes data
Feb 25 15:06:30.413: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=1608
Feb 25 15:06:30.429: //192004//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:30.429: //192004//HTTPC:/httpc_socket_read:
Feb 25 15:06:30.429: read data from the socket 0 : first 400 bytes of data:
://141.94.130.90:8000/cvp/VBServlet"

         namelist="MSG_TYPE CALL_ID CALL_SEQ_NUM ERROR_CODE" fetchtimeout="7s"/>

</catch>

      <catch event = "error">

<if cond="DEBUG == '1'">

   <log> General Error encountered when trying to contact Application Server. Going to Recovery VXML: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</i
Feb 25 15:06:30.429: //192004//HTTPC:/httpc_msg_read: Start processing 970 bytes data
Feb 25 15:06:30.429: //192004//HTTPC:/httpc_msg_read: Stored msg. body: len=963
Feb 25 15:06:30.433: //192004//HTTPC:/httpc_msg_read: Start processing 5 bytes data
Feb 25 15:06:30.433: //192004//HTTPC:/httpc_socket_read: MSG_COMPLETE: rsp_code(200),msg(2BBBA41C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0, fd(0)
Feb 25 15:06:30.433: processing server rsp msg: msg(2BBBA41C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0, fd(0):
Feb 25 15:06:30.433: Request msg: GET /cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0 HTTP/1.1
Feb 25 15:06:30.433: Message Response Code: 200
Feb 25 15:06:30.433: Message Rsp Decoded Headers:
Feb 25 15:06:30.433: Date:Sat, 25 Feb 2012 14:59:34 GMT
Feb 25 15:06:30.433: Transfer-Encoding:chunked
Feb 25 15:06:30.433: Content-Type:text/xml;charset=ISO-8859-1
Feb 25 15:06:30.433: Connection:close
Feb 25 15:06:30.433: headers:
Feb 25 15:06:30.433: HTTP/1.1 200 OK

Server: Apache-Coyote/1.1

Content-Type: text/xml;charset=ISO-8859-1

Transfer-Encoding: chunked

Date: Sat, 25 Feb 2012 14:59:34 GMT

Connection: close

Feb 25 15:06:30.433: body:
Feb 25 15:06:30.433: ... Buffer too large - truncated to (4096) len.
Feb 25 15:06:30.433: <?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'30F218955EF711E19ADCE97F44552613'" />

    <var name="CALL_SEQ_NUM" expr="59" />

    <var name="ERROR_CODE" expr="0" />

    <var name="DEBUG" expr="1" />

    <property name="com.cisco.media-logging-id" value="'30F218955EF711E19ADCE97F44552613'" />

    <form id="ExternalVXML" scope="dialog">







   <catch event="error.com.cisco.callhandoff.failure">

         <goto next="#TrySubmit"/>

     </catch>

     <object name="dummyobj" classid="builtin://com.cisco.callhandoff">

   <param name="return" expr="true" valuetype="data"/>

         <param name="app-uri" expr="'builtin://dummyobj'" valuetype="data"/>

         <prompt bargein="false" cisco-maxtime="0s" cisco-typeaheadflush="false" >

     <audio src="http://media/en-us/BAJ/Sales/ar/Queue2.wav" fetchtimeout="10s" />

       </prompt>

         <filled>

          <goto next="#TrySubmit"/>

        </filled>

</object>

   

     <catch event="telephone.disconnect.hangup">

<assign name="ERROR_CODE" expr="1"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.badfetch">

<assign name="ERROR_CODE" expr="9"/>

<if cond="DEBUG == '1'">

   <log> Media Fetch Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.semantic">

<assign name="ERROR_CODE" expr="10"/>

<if cond="DEBUG == '1'">

   <log> Semantic Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "error.unsupported.format">

<assign name="ERROR_CODE" expr="11"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Format Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "error.unsupported.element">

<assign name="ERROR_CODE" expr="12"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Element Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="com.cisco.isn.session.disconnect.abnormal">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="telephone.disconnect.hangup.forced">

<assign name="ERROR_CODE" expr="27"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event = "com.cisco.isn.session.disconnect.error">

<assign name="ERROR_CODE" expr="28"/>

<goto next="#TrySubmit"/>

     </catch>

     <catch event="error.unsupported.language">

<assign name="ERROR_CODE" expr="30"/>

<if cond="DEBUG == '1'">

   <log> Unsupported Language Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>



<catch event = "error.com.cisco.media.resource.unavailable.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Unavailable Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource.failure.tts">

<assign name="ERROR_CODE" expr="32"/>

<if cond="DEBUG == '1'">

   <log> TTS or Streaming Media Resource Failure Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value expr="CALL_SEQ_NUM"/>

   </log>

</if>

<goto next="#TrySubmit"/>

     </catch>

<catch event = "error.com.cisco.media.resource">

<assign name="ERROR_CODE" expr="33"/>

<if cond="DEBUG == '1'">

   <log> General Media Resource Unavailable Error: CallID: <value expr="CALL_ID"/>

      : CallSeqNum: <value
Feb 25 15:06:30.457:
Feb 25 15:06:30.457: //192004//HIFS:/hifs_http_cb: hifs http read succeeded. size=6144, url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
Feb 25 15:06:30.457: //192004//AFW_:/vapp_bgload_done: status=No Error
Feb 25 15:06:30.457: //192004//HIFS:/hifs_http_cb: hifs ifs file read succeeded. size=6144, url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
Feb 25 15:06:30.457: //192004//HIFS:/hifs_free_idata: hifs_free_idata: 0x2C77034C
Feb 25 15:06:30.457: //192004//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x2C77034C
Feb 25 15:06:30.457: //192004//HTTPC:/httpc_socket_cleanup: fd=0, bytes_sent=460
Feb 25 15:06:30.457: //192004//AFW_:/vapp_driver: evtID: 253 vapp record state: 0
Feb 25 15:06:30.457: //192004//AFW_:/vapp_bgload_done_event:
Feb 25 15:06:30.457: //192004//AFW_:/vapp_bgload_done_event: length=6144, page loaded=
<?xml version="1.0" encoding="iso-8859-1"?>

<vxml version="2.0" xml:lang="en-us">

    <var name="MSG_TYPE" expr="'CALL_RESULT'" />

    <var name="CALL_ID" expr="'30F218955EF711E19ADCE97F4455261
Feb 25 15:06:30.457: //192004/30F218959ADC/VXML:/vxml_is_doc:
   vxml_is_doc

Feb 25 15:06:30.457: //192004//HTTPC:/httpc_get_tag: No cache entry found for body(32D5A6D4)
Feb 25 15:06:30.457: //-1//VXML:/vxml_tree_lock:
   vxmlp=2C61E510 usage_cnt=0
Feb 25 15:06:30.461: //0/30F218959ADC/VXML:/vxml_parse:

Feb 25 15:06:30.461: vxml_parse: XML_Parse success err=0
Feb 25 15:06:30.461: //0/30F218959ADC/VXML:/vxml_session_delete:

Feb 25 15:06:30.461: vxml_session_delete:mem_mgr_mempool_free: mempool=NULL
Feb 25 15:06:30.461: vxml_session_delete:mem_mgr_mempool_free: mempool=NULL
Feb 25 15:06:30.461: //-1//VXML:/vxml_crc_generate_element_tree: 142 crc generated
Feb 25 15:06:30.461: //192004//HTTPC:/httpc_set_tag: No cache entry found for body(32D5A6D4)
Feb 25 15:06:30.461: //192004/30F218959ADC/VXML:/vxml_bgload_post_done:
   vxmlhandle=2C3FAE38 status=0 async_status=400000000
Feb 25 15:06:30.461: //192004/30F218959ADC/VXML:/vxml_bgload_post_done:
   Loading file with url (http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0)
Feb 25 15:06:30.461: //-1//VXML:/vxml_is_cisco_data: Enter
Feb 25 15:06:30.461: //192004/30F218959ADC/VXML:/vxml_leave_scope:
   scope=application
Feb 25 15:06:30.461: //-1//VXML:/vxml_crc_verify_element_tree: 142 crc verified
Feb 25 15:06:30.461: //192004/30F218959ADC/VXML:/vxml_tree_delete:
   vxmlp=326273F0, usage_cnt=1 url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=57&ERROR_CODE=0
Feb 25 15:06:30.461: vxml_tree_delete:mem_mgr_mempool_free: mem_refcnt(326E6EDC)=0 - mempool cleanup
Feb 25 15:06:30.461: //192004/30F218959ADC/VXML:/vxml_stop_fetchaudio:

Feb 25 15:06:30.461: //192004/30F218959ADC/VXML:/vxml_start:
   vxmlhandle=2C3FAE38 vapphandle=2C4FC93C status=0 async_status=80000
Feb 25 15:06:30.461: //192004/30F218959ADC/VXML:/vxml_vxml_proc:
<vxml>
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
   base=
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0 lang=none version=2.0
         <var>: namep=MSG_TYPE expr='CALL_RESULT'
Feb 25 15:06:30.461: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var MSG_TYPE='CALL_RESULT')
Feb 25 15:06:30.461: //192004/30F218959ADC/VXML:/vxml_jse_global_switch:
   switch to scope(application)
         <var>: namep=CALL_ID expr='30F218955EF711E19ADCE97F44552613'
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var CALL_ID='30F218955EF711E19ADCE97F44552613')
         <var>: namep=CALL_SEQ_NUM expr=59
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var CALL_SEQ_NUM=59)
         <var>: namep=ERROR_CODE expr=0
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var ERROR_CODE=0)
         <var>: namep=DEBUG expr=1
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var DEBUG=1)
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_form_proc:
Feb 25 15:06:30.465: <form>: id=ExternalVXML   scope=dialog
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_form_init:
   current scope: dialog
   vxml_counter_reset:
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_expr_eval:
   expr=(var dummyobj)
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_jse_global_switch:
   switch to scope(dialog)
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_formitem_select:
   Status=VXML_STATUS_OK,
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_formitem_select:
    AsyncStatus=VXML_STATUS_OK

Feb 25 15:06:30.465: vxml_object_proc:
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_item_attrs_proc:
   name=dummyobj

Feb 25 15:06:30.465: vxml_param_proc: name=return expr=true valuetype=123

Feb 25 15:06:30.465: vxml_param_proc: name=app-uri expr='builtin://dummyobj' valuetype=123
Feb 25 15:06:30.465: //-1//VXML:/vxml_play_prompts: Enter
Feb 25 15:06:30.465: //-1//VXML:/vxml_prompt_proc: Enter
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_prompt_proc:
         <prompt>: typeaheadflush=0 alternative=0
Feb 25 15:06:30.465: //-1//VXML:/vxml_process_prompt: Enter
Feb 25 15:06:30.465: //-1//VXML:/vxml_audio_proc: Enter
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_audio_proc:
         <audio>:
   URI(abs):http://media/en-us/BAJ/Sales/ar/Queue2.wav
   scheme=http
   host=media
   path=/en-us/BAJ/Sales/ar/Queue2.wav caching=fast fetchhint=invalid fetchtimeout=10 maxage=-1 maxstale=-1
Feb 25 15:06:30.465: //-1//VXML:/vxml_audio_proc: Exit
Feb 25 15:06:30.465: //-1//VXML:/vxml_finish_generate_prompt_list: Enter
Feb 25 15:06:30.465: //-1//VXML:/vxml_finish_generate_prompt_list: Exit
Feb 25 15:06:30.465: //-1//VXML:/vxml_process_prompt: Exit
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_vapp_media_play:
   bargein=0 timeout=0 typeaheadflush=0 vcr=0 rate=0 alternative=0 hotword=0 maxtime=0
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_vapp_media_play:
   str=http://media/en-us/BAJ/Sales/ar/Queue2.wav cachable=1 timeout10 maxage=-1 maxstale=-1 codec=-1
Feb 25 15:06:30.465: //192004//AFW_:/vapp_media_play:
Feb 25 15:06:30.465: //192004//AFW_:/vapp_media_play: prompt=http://media/en-us/BAJ/Sales/ar/Queue2.wav:
Feb 25 15:06:30.465: //956//HTTPC:/httpc_valid_cache: pcontext(2BD828F0) maxage(-1) maxstale(-1)
Feb 25 15:06:30.465: //956//HTTPC:/httpc_cache_isfresh: cachep(2BD828F0) fresh(Yes) lifetime(1164107) age(897374)
Feb 25 15:06:30.465: //956//HTTPC:/httpc_cache_is_acceptable: cachep(2BD828F0) maxage(-1) maxstale(-1) status(TRUE)
Feb 25 15:06:30.465: //-1//VXML:/vxml_vapp_media_play: Exit
Feb 25 15:06:30.465: //-1//VXML:/vxml_prompt_proc: Exit
Feb 25 15:06:30.465: //-1//VXML:/vxml_play_prompts: Exit
Feb 25 15:06:30.465: //-1//VXML:/vxml_elem_proc:
(104000000) Status not OK, exit@9954
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_formitem_select:
   loop cnt=0,
Feb 25 15:06:30.465: //-1//VXML:/vxml_formitem_select:
(104000000) Status not OK, exit@5409
Feb 25 15:06:30.465: //-1//VXML:/vxml_form_proc:
(104000000) Status not OK, exit@5541
Feb 25 15:06:30.465: //-1//VXML:/vxml_elem_proc:
(104000000) Status not OK, exit@9954
Feb 25 15:06:30.465: //-1//VXML:/vxml_vxml_proc:
(104000000) Status not OK, exit@10151
Feb 25 15:06:30.465: //192004/30F218959ADC/VXML:/vxml_load_immediate_done:
   sidp->status=104000000
Feb 25 15:06:30.465: //-1//VXML:/vxml_bgload_post_done: Async load, exit@6812
Feb 25 15:06:30.465: //-1//VXML:/vxml_bgload_post_done: return
Feb 25 15:06:30.465: //192004//AFW_:/vapp_checksessionstate:
Feb 25 15:06:30.465: //192004//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
Feb 25 15:06:30.465: //192004//HTTPC:/httpc_free: app freeing response data(32D5A6D4)
Feb 25 15:06:30.469: //-1//HTTPC:/httpc_process: received event, sid: 0, method: 262144
Feb 25 15:06:30.469: //192004//HTTPC:/httpc_process_free_rsp: User returns noncache response (32D5A62C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
Feb 25 15:06:30.469: //192004//HTTPC:/httpc_cache_entry_free: cache(32D5A62C)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=30F218955EF711E19ADCE97F44552613&CALL_SEQ_NUM=58&ERROR_CODE=0
Feb 25 15:06:30.477: //192184//AFW_:/vapp_driver: evtID: 173 vapp record state: 0
Feb 25 15:06:30.477: //192184//AFW_:/vapp_play_done: evID=173 reason=17, protocol=2, status_code=0, dur=15312, rate=0
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_media_done:
   status 0 async_status 104000000 duration=15312 rate=0
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_save_lastprompt_info:

Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_save_lastprompt_info:

Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_vapp_typeahead_flush:

Feb 25 15:06:30.477: //192184//AFW_:/vapp_typeahead_flush:
Feb 25 15:06:30.477: //192184//AFW_:/vapp_notify_play_done:
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_vapp_vcr_control_disable:

Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_vapp_object_execute:
   classid builtin://com.cisco.callhandoff
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_vapp_object_callhandoff:
Feb 25 15:06:30.477: //192184//AFW_:/vapp_callhandoff: app_uri=builtin://dummyobj, wait_for_return=true, arg_string=
Feb 25 15:06:30.477: //192184//AFW_:/vapp_callhandoff: ccGetApp dummyobj failed
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_vapp_object_callhandoff:
   Handoff failed
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_start:
   vxmlhandle=32F9ABFC vapphandle=2C4FA604 status=0 async_status=80
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_throw_error_session_event:
   async_status=80
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_event_proc:
         <event>: event=error.com.cisco.callhandoff.failure status=0
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_expr_eval:
   expr=(var _event='error.com.cisco.callhandoff.failure')
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_expr_eval:
   expr=(var _message=undefined)
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_catch_proc:
         <catch>:
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_nmtokens_proc:
   name=error.com.cisco.callhandoff.failure count=1
   script_loading=0
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_asynceventparams_get:

Feb 25 15:06:30.477: vu all
All possible debugging has been turned off
CALL-CENTER-RTR-253#xml_asynceventparams_get:
Feb 25 15:06:30.477: //192184/0054CD1C9BFD/VXML:/vxml_goto_proc:
            <goto>: caching=fast fetchhint=invalid fetchtimeout=0 maxage=-1 maxstale=-1
   URI

TrySubmit
   fragmentp=TrySubmit
Feb 25 15:06:30.477: //-1//VXML:/vxml_next_dialog: Enter
Feb 25 15:06:30.477: //-1//VXML:/vxml_next_dialog: Got dialog, Exit@1264
Feb 25 15:06:30.477: //-1//VXML:/vxml_goto_proc:
(400) Status not OK, exit
Feb 25 15:06:30.553: //192184//HTTPC:/httpc_socket_send:
Feb 25 15:06:30.553: about to send data to the socket 0 : first 400 bytes of data:
GET /cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=0054CD1C5EF911E19BFDE97F44552613&CALL_SEQ_NUM=3&ERROR_CODE=0 HTTP/1.1

Host: 141.94.130.91:8000

Content-Type: application/x-www-form-urlencoded

Connection: close

Accept: 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, app
Feb 25 15:06:30.557: //192184//HTTPC:/httpc_socket_read: socket readable fd: 0
Feb 25 15:06:31.309: //466//HTTPC:/httpc_is_cached: HTTPC_FILE_IS_CACHED
Feb 25 15:06:31.309: //-1//HTTPC:/httpc_set_cache_revoke_cb: Registering revoke_callback(0x2533B6B4)+pcontext(0x2BD0E030) for cachep(0x326B4CF0)
Feb 25 15:06:31.309: //192063//AFW_:/vapp_driver: evtID: 173 vapp record state: 0
Feb 25 15:06:31.309: //192063//AFW_:/vapp_play_done: evID=173 reason=17, protocol=2, status_code=0, dur=5992, rate=0
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_media_done:
   status 0 async_status 104000000 duration=5992 rate=0
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_save_lastprompt_info:

Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_save_lastprompt_info:

Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_vapp_typeahead_flush:

Feb 25 15:06:31.309: //192063//AFW_:/vapp_typeahead_flush:
Feb 25 15:06:31.309: //192063//AFW_:/vapp_notify_play_done:
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_vapp_vcr_control_disable:

Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_vapp_object_execute:
   classid builtin://com.cisco.callhandoff
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_vapp_object_callhandoff:
Feb 25 15:06:31.309: //192063//AFW_:/vapp_callhandoff: app_uri=builtin://dummyobj, wait_for_return=true, arg_string=
Feb 25 15:06:31.309: //192063//AFW_:/vapp_callhandoff: ccGetApp dummyobj failed
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_vapp_object_callhandoff:
   Handoff failed
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_start:
   vxmlhandle=2C201DE8 vapphandle=2BBA3880 status=0 async_status=80
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_throw_error_session_event:
   async_status=80
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_event_proc:
         <event>: event=error.com.cisco.callhandoff.failure status=0
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_expr_eval:
   expr=(var _event='error.com.cisco.callhandoff.failure')
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_expr_eval:
   expr=(var _message=undefined)
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_catch_proc:
         <catch>:
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_nmtokens_proc:
   name=error.com.cisco.callhandoff.failure count=1
   script_loading=0
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_asynceventparams_get:

Feb 25 15:06:31.309: vxml_asynceventparams_get:
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_goto_proc:
            <goto>: caching=fast fetchhint=invalid fetchtimeout=0 maxage=-1 maxstale=-1
   URI

TrySubmit
   fragmentp=TrySubmit
Feb 25 15:06:31.309: //-1//VXML:/vxml_next_dialog: Enter
Feb 25 15:06:31.309: //-1//VXML:/vxml_next_dialog: Got dialog, Exit@1264
Feb 25 15:06:31.309: //-1//VXML:/vxml_goto_proc:
(400) Status not OK, exit@5603
Feb 25 15:06:31.309: //-1//VXML:/vxml_elem_proc:
(400) Status not OK, exit@9954
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_leave_scope:
   scope=anonymous
Feb 25 15:06:31.309: //-1//VXML:/vxml_catch_proc: Exit (400)
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_load_immediate_done:
   sidp->status=400
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_start:
   vxmlhandle=2C201DE8 vapphandle=2BBA3880 status=400 async_status=400
            vxml_dialog_reset:
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_leave_scope:
   scope=dialog
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_vxml_proc:
<vxml>
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=35&ERROR_CODE=0
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=35&ERROR_CODE=0
   base=
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=35&ERROR_CODE=0
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
   query=MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=35&ERROR_CODE=0 lang=none version=2.0
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_form_proc:
Feb 25 15:06:31.309: <form>: id=TrySubmit   scope=dialog
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_form_init:
   current scope: dialog
   <var>: namep=TryNum expr=1
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_expr_eval:
   expr=(var TryNum=1)
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_jse_global_switch:
   switch to scope(dialog)
   <var>: namep=MaxTries expr=1
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_expr_eval:
   expr=(var MaxTries=1)
   vxml_counter_reset:
Feb 25 15:06:31.309: //192063/F171E0BB9B3A/VXML:/vxml_expr_eval:
   expr=(var _in430645)
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_formitem_select:
   Status=VXML_STATUS_OK,
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_formitem_select:
    AsyncStatus=VXML_STATUS_OK
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_block_proc:
Feb 25 15:06:31.313:   <block>:
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_item_attrs_proc:
   name=_in430645
Feb 25 15:06:31.313: //-1//VXML:/vxml_submit_proc: Enter
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_submit_proc:
         <submit>: caching=fast fetchhint=invalid fetchtimeout=7 maxage=-1 maxstale=-1
   URI(abs):http://141.94.130.90:8000/cvp/VBServlet
   scheme=http
   host=141.94.130.90
   port=8000
   path=/cvp/VBServlet
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_sub_attrs_proc:
   method=get
   enctype=application/x-www-form-urlencoded
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_nmtokens_proc:
   name=MSG_TYPE
   name=CALL_ID
   name=CALL_SEQ_NUM
   name=ERROR_CODE
Feb 25 15:06:31.313: //-1//VXML:/vxml_sub_attrs_proc: Enter
Feb 25 15:06:31.313: //-1//VXML:/vxml_sub_attrs_proc: Exit
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_vapp_bgload_from_proc:
   urlp=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=36&ERROR_CODE=0 fetchaudio=NULL delay=0 minimum=0
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_get_doc: url: http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=36&ERROR_CODE=0
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_vapp_bgload:
   url http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=36&ERROR_CODE=0 cachable 1 fetchtimeout 7 maxage=-1 maxstale=-1
Feb 25 15:06:31.313: //192063//AFW_:/vapp_bgload: url=http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=36&ERROR_CODE=0
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_get: url length=130
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_get: url: http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=36&ERROR_CODE=0
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_send_ev: event sent to HTTP Client:
Feb 25 15:06:31.313:     method (GET), url (http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=36&ERROR_CODE=0)
Feb 25 15:06:31.313:     callback (251B9078), argp (2C772C58), sid (0), timeout (7), maxage (-1), maxstale(-1) retries (0)
Feb 25 15:06:31.313: //192063//AFW_:/vxml_update_cleanup_timer: cleaning timer running 0 fetchtimeout 7
Feb 25 15:06:31.313: //-1//VXML:/vxml_vapp_bgload: Exit
Feb 25 15:06:31.313: //-1//VXML:/vxml_vapp_bgload_from_proc: normal_async_load, exit@8299
Feb 25 15:06:31.313: //-1//VXML:/vxml_submit_proc: Exit
Feb 25 15:06:31.313: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9954
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_leave_scope:
   scope=anonymous
Feb 25 15:06:31.313: //-1//VXML:/vxml_block_proc: Exit (400000000)
Feb 25 15:06:31.313: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9954
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_formitem_select:
   loop cnt=0,
Feb 25 15:06:31.313: //-1//VXML:/vxml_formitem_select:
(400000000) Status not OK, exit@5409
Feb 25 15:06:31.313: //-1//VXML:/vxml_form_proc:
(400000000) Status not OK, exit@5541
Feb 25 15:06:31.313: //-1//VXML:/vxml_elem_proc:
(400000000) Status not OK, exit@9954
Feb 25 15:06:31.313: //-1//VXML:/vxml_vxml_proc:
(400000000) Status not OK, exit@10160
Feb 25 15:06:31.313: //192063/F171E0BB9B3A/VXML:/vxml_load_immediate_done:
   sidp->status=400000000
Feb 25 15:06:31.313: //-1//VXML:/vxml_start: Exit
Feb 25 15:06:31.313: //192063//AFW_:/vapp_checksessionstate:
Feb 25 15:06:31.313: //192063//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
Feb 25 15:06:31.313: //-1//HTTPC:/httpc_process: received event, sid: 0, method: 2
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_msg_send: sid: 0, method: 2
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_connect: No Connection found, opening socket...
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_connect: socket_open() OK fd=1
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_connect: calling socket_connect() for fd 1
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_connect: socket_connect() return errno=EWOULDBLOCK fd(1) state ---> HTTPC_SOCK_PENDING
Feb 25 15:06:31.313: //192063//HTTPC:/httpc_enqueue_wmsg: sid: 0, method: 2
Feb 25 15:06:31.333: //192063//HTTPC:/httpc_process_read_ev: SOCK_PENDING --> SOCK_CONNECTED fd(1) port(8000)
Feb 25 15:06:31.333: //192063//HTTPC:/httpc_process_read_ev: httpc_check_connect_secure() return HTTPC_ERR_SSLHS_NOT_NEEDED for fd 1
Feb 25 15:06:31.333: //192063//HTTPC:/httpc_process_write_queue: Object: 1, Leg: 1
Feb 25 15:06:31.513: //192063//HTTPC:/httpc_free: app freeing response data(2BFC7B3C)
Feb 25 15:06:31.513: //-1//HTTPC:/yhttpc_process: received event, sid: 0, method: 262144
Feb 25 15:06:31.513: //192063//HTTPC:/httpc_process_free_rsp: User returns noncache response (2BFC7A94)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=36&ERROR_CODE=0
Feb 25 15:06:31.513: //192063//HTTPC:/httpc_cache_entry_free: cache(2BFC7A94)URL:http://141.94.130.90:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=F171E0BB5EF711E19B3AE97F44552613&CALL_SEQ_NUM=36&ERROR_CODE=0
Feb 25 15:06:33.265: //192184//HIFS:/hifs_http_cb: hifs http read succeeded. size=6146, url=http://141.94.130.91:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=0054CD1C5EF911E19BFDE97F44552613&CALL_SEQ_NUM=3&ERROR_CODE=0
Feb 25 15:06:33.265: //192184//HIFS:/hifs_http_cb: hifs ifs file read succeeded. size=6146, url=http://141.94.130.91:8000/cvp/VBServlet?MSG_TYPE=CALL_RESULT&CALL_ID=0054CD1C5EF911E19BFDE97F44552613&CALL_SEQ_NUM=3&ERROR_CODE=0
Feb 25 15:06:33.265: //192184//HIFS:/hifs_free_idata: hifs_free_idata: 0x2C776E40
Feb 25 15:06:33.265: //192184//HIFS:/hifs_hold_idata: hifs_hold_idata: 0x2C776E40   u all

Subject: RE: New Message from Raun hr in Customer Voice Portal (CVP) - CVP - All Ver
Replied by: GEOFFREY THOMPSON on 27-02-2012 11:27:18 AM
Do you have the handoff service in your “application” section on the voice gateway?

service handoff flash:handoff.tcl
paramspace english language en
paramspace english index 0
paramspace english location flash
paramspace english prefix en

Is the .tcl in flash?

Have you loaded the application (or reloaded the gwy) since adding this section?

Regards,
Geoff

Subject: RE: CVP Handoff Failed
Replied by: Raun hr on 27-02-2012 11:35:54 PM
Thank you very much for your quick response yes the handoff file is there in the gateway and the VG is reloaded afterwards, i dont really get what exactly is the isssue with the handoff.tcl in this case.... the call goes through each node successfully but at the last step if the user does not enter any value and exausts all options i can hear the lady the lasy telling me "I'm sorry we are unable to process your call".

Now how does the handoff comes into picture and whats the purpose of it and any suggestions why am i facing this issue.

Subject: Re: New Message from Raun hr in Customer Voice Portal (CVP) - CVP - All Ver
Replied by: Janine Graves on 28-02-2012 08:34:06 AM
One of the functions of the call handoff is to send a call from a VxmlServer application back to ICM (it's part of the CVPSubdialog Return functionality). You haven't specified what you had running when you received the call handoff error. Janine On 2/27/2012 11:35 PM, Cisco Developer Community Forums wrote: Raun hr has created a new message in the forum "CVP - All Versions": -------------------------------------------------------------- Thank you very much for your quick response yes the handoff file is there in the gateway and the VG is reloaded afterwards, i dont really get what exactly is the isssue with the handoff.tcl in this case.... the call goes through each node successfully but at the last step if the user does not enter any value and exausts all options i can hear the lady the lasy telling me "I'm sorry we are unable to process your call". Now how does the handoff comes into picture and whats the purpose of it and any suggestions why am i facing this issue. -- To respond to this post, please click the following link: <http://developer.cisco.com/web/cvp/forums/-/message_boards/view_message/5213704> or simply reply to this email. -- Janine Graves

Subject: RE: CVP Handoff Failed
Replied by: Raun hr on 07-03-2012 11:49:48 PM
Still stuck with the same error message...right at the end of the script i get this prompt "I'm sorry we are unable to process your call"
Logs are already provided any suggestions would be appreciated