VXML Record issue

Version 1
    This document was generated from CDN thread

    Created by: Wes Schochet on 15-09-2009 06:48:05 AM
    I have a simple recprding app running on a n 12.4(24)T gateway. No matter what I do, the caller gets cut off after 30 seconds of recording.   Any advise would be appreciated.  Sript follows:
     
     <record name="myrec" beep="true" maxtime="400s" finalsilence="3s" type="audio/basic;codec=g711ulaw" dtmfterm="false">
           <prompt>
             <audio src="/vxml/prompts/2136.au" />
             <audio src="/vxml/prompts/SndDoubleBeep.au" />
           </prompt>
           <noinput>
             <audio src="/vxml/audio/E.au" />
           </noinput>
           <filled>
             <log> Duration: <value expr="myrec$.duration / 1000"/> </log>
             <assign name="dur" expr="myrec$.duration / 1000" />
             <submit next="saveRec.vxml.jsp" namelist="myrec dur" method="post" enctype="multipart/form-data"/>
           </filled>
           <catch event="connection.disconnect.hangup">
             <log> Duration: <value expr="myrec$.duration / 1000"/> </log>
             <assign name="dur" expr="myrec$.duration / 1000" />
             <submit next="saveRec.vxml.jsp" namelist="myrec dur" method="post" enctype="multipart/form-data" />
           </catch>
         </record>
     
     
    Tace is here  (sorry it's big)
    *Sep 15 06:48:31.716: //1336//AFW_:/vapp_media_play:
    *Sep 15 06:48:31.716: //1336//AFW_:/vapp_media_play: prompt=http://10.35.241.6:8080/vxml/prompts/2136.au:
    *Sep 15 06:48:31.716: //1336//AFW_:/vapp_media_play: prompt=http://10.35.241.6:8080/vxml/prompts/SndDoubleBeep.au:
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_vapp_media_play: Exit
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_prompt_proc: Exit
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_play_prompts: Exit
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_generic_asr: Enter
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_record_proc: Exit
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_elem_proc:
     (140000000) Status not OK, <a>mailto:exit@9899</a>]
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_formitem_select:
     (140000000) Status not OK, <a>mailto:exit@5384</a>]
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_form_proc:
     (140000000) Status not OK, <a>mailto:exit@5536</a>]
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_elem_proc:
     (140000000) Status not OK, <a>mailto:exit@9899</a>]
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_vxml_proc:
     (140000000) Status not OK, <a>mailto:exit@10105</a>]
    *Sep 15 06:48:31.716: //1336/802474020901/VXML:/vxml_load_immediate_done:
       sidp->status=140000000
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_bgload_post_done: Async load, <a>mailto:exit@6757</a>]
    *Sep 15 06:48:31.716: //-1//VXML:/vxml_bgload_post_done: return
    *Sep 15 06:48:31.716: //1336//AFW_:/vapp_checksessionstate:
    *Sep 15 06:48:31.716: //1336//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
    *Sep 15 06:48:37.504: //1336//AFW_:/vapp_driver: evtID: 161 vapp record state: 0
    *Sep 15 06:48:37.504: //1336//AFW_:/vapp_play_done: evID=161 reason=17, protocol=2, status_code=0, dur=5740, rate=0
    *Sep 15 06:48:37.504: //1336/802474020901/VXML:/vxml_media_done:
       status 0 async_status 140000000 duration=5740 rate=0
    *Sep 15 06:48:37.504: //1336/802474020901/VXML:/vxml_save_lastprompt_info:
    *Sep 15 06:48:37.504: //1336/802474020901/VXML:/vxml_save_lastprompt_info:
    *Sep 15 06:48:37.504: //1336/802474020901/VXML:/vxml_vapp_vcr_control_disable:
    *Sep 15 06:48:37.504: //1336/802474020901/VXML:/vxml_vapp_media_record:  name=myrec type=audio/basic;codec=g711ulaw beep=1 initial_timeout=10000 maxtime=400000 silence=3000 dtmfterm=0 recordbeep=0 recordbeepinterval=15000 hotword=0
    *Sep 15 06:48:37.504: //1336//AFW_:/vapp_media_record:
    *Sep 15 06:48:37.504: //1336//AFW_:/vapp_media_record:  ram_recurl=ram:myrec_127_0_1336 codec=5 format=1 memory_req=256000(byte) maxtime=400000(ms) init_timeout=10000(ms) silence=3000(ms)
    *Sep 15 06:48:37.504: //-1//VXML:/vxml_vapp_media_record: Exit
    *Sep 15 06:48:37.504: //-1//VXML:/vxml_media_done: Exit
    *Sep 15 06:48:37.504: //1336//AFW_:/vapp_checksessionstate:
    *Sep 15 06:48:37.504: //1336//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
    *Sep 15 06:48:41.668: //1336//AFW_:/vapp_driver: evtID: 25 vapp record state: 1
    *Sep 15 06:48:41.668: //1336//AFW_:/vapp_checksessionstate:
    *Sep 15 06:48:41.668: //1336//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
    *Sep 15 06:49:09.544: //1336//AFW_:/vapp_driver: evtID: 160 vapp record state: 1
    *Sep 15 06:49:09.544: //1336//AFW_:/vapp_record_done: evID=160 reason=16, protocol=2, status_code=0
    *Sep 15 06:49:09.548: //1336//AFW_:/vapp_record_done: Interp Url = ram:myrec_127_0_1336
    *Sep 15 06:49:09.548: //1336//AFW_:/vapp_record_done: MC Url = ram:myrec_127_0_1336
    *Sep 15 06:49:09.548: //1336//AFW_:/vapp_record_done: Length = 256000
    *Sep 15 06:49:09.548: //1336//AFW_:/vapp_record_done: Duration = 32000
    *Sep 15 06:49:09.548: //1336//AFW_:/vapp_record_memory: current recording mem_limit=0.
    *Sep 15 06:49:09.548: //1336//AFW_:/vapp_record_done:  Calling vxml_media_record_done,
       RecUrl ram:myrec_127_0_1336
    *Sep 15 06:49:09.548: //1336/802474020901/VXML:/vxml_media_record_done:
       status=0;async_status=40000000;record url=ram:myrec_127_0_1336duration=32000 size=256000 maxtime=0
    *Sep 15 06:49:09.548: //1336/802474020901/VXML:/vxml_bind_lastprompt:
    *Sep 15 06:49:09.548: //1336/802474020901/VXML:/vxml_cancel_hotword:
    *Sep 15 06:49:09.548: //-1//VXML:/vxml_jse_increment_record_refcount:
       recp(69F7DDB8), url(ram:myrec_127_0_1336), refcount(1)
    *Sep 15 06:49:09.548: //1336/802474020901/VXML:/vxml_start:
       vxmlhandle=698475E4 vapphandle=6864D770 status=0 async_status=2000
    *Sep 15 06:49:09.548: //1336/802474020901/VXML:/vxml_vxml_proc:
    <vxml>
       URI(abs):http://10.35.241.6:8080/vxmlRec/rec.vxml.jsp?ani=2303
       scheme=http
       host=10.35.241.6
       port=8080
       path=/vxmlRec/rec.vxml.jsp
       query=ani=2303
       base=
       URI(abs):http://10.35.241.6:8080/vxmlRec/rec.vxml.jsp?ani=2303
       scheme=http
       host=10.35.241.6
       port=8080
       path=/vxmlRec/rec.vxml.jsp
       query=ani=2303 lang=none version=2.0
    *Sep 15 06:49:09.552: //1336/802474020901/VXML:/vxml_record_proc:
    *Sep 15 06:49:09.552: vxml_record_proc:
    *Sep 15 06:49:09.552: //1336/802474020901/VXML:/vxml_item_attrs_proc:
       name=myrec type=audio/basic;codec=g711ulaw beep=1 maxtime=400000 silence=3000 dtmfterm=0
    *Sep 15 06:49:09.552: //1336/802474020901/VXML:/vxml_record_proc:
    *Sep 15 06:49:09.552: vxml_record_proc: its after sync reentry
    *Sep 15 06:49:09.552: //1336/802474020901/VXML:/vxml_form_proc:
    *Sep 15 06:49:09.552:  <form>: id=answer-and-record   scope=dialog
    *Sep 15 06:49:09.552: //1336/802474020901/VXML:/vxml_filled_proc:
    *Sep 15 06:49:09.552: <filled>: mode=all
          <log>:
    *Sep 15 06:49:09.552: //1336/802474020901/VXML:/vxml_value_proc:
             <value>: expr=myrec$.duration / 1000
    *Sep 15 06:49:09.552: //1336/802474020901/VXML:/vxml_jse_global_switch:
       switch to scope(anonymous)
    *Sep 15 06:49:09.556:  Duration: 32
    *Sep 15 06:49:09.556: //1336/802474020901/VXML:/vxml_assign_proc:
          <assign>: name=dur expr=myrec$.duration / 1000
    *Sep 15 06:49:09.556: //1336/802474020901/VXML:/vxml_expr_eval:
       expr=(dur=myrec$.duration / 1000)
    *Sep 15 06:49:09.556: //-1//VXML:/vxml_submit_proc: Enter
    *Sep 15 06:49:09.556: //1336/802474020901/VXML:/vxml_submit_proc:
          <submit>: caching=fast fetchhint=invalid fetchtimeout=0 maxage=-1 maxstale=-1
       URI(abs):saveRec.vxml.jsp
       scheme=http
       host=10.35.241.6
       port=8080
       path=/vxmlRec/saveRec.vxml.jsp
    *Sep 15 06:49:09.556: //1336/802474020901/VXML:/vxml_sub_attrs_proc:
       method=post
       enctype=multipart/form-data
    *Sep 15 06:49:09.556: //1336/802474020901/VXML:/vxml_nmtokens_proc:
       name=myrec
       name=dur
    *Sep 15 06:49:09.556: //-1//VXML:/vxml_sub_attrs_proc: Enter
    *Sep 15 06:49:09.556: //-1//VXML:/vxml_sub_attrs_proc: <a>mailto:Exit@2052</a>]
    *Sep 15 06:49:09.556: //1336/802474020901/VXML:/vxml_vapp_post:
       url <a>http://10.35.241.6:8080/vxmlRec/saveRec.vxml.jsp</a>] cachable 1 timeout 0 enctype multipart/form-data
    *Sep 15 06:49:09.556: //1336//AFW_:/vapp_post: url=http://10.35.241.6:8080/vxmlRec/saveRec.vxml.jsp; mime_type=multipart/form-data;
    *Sep 15 06:49:09.560: //1336//AFW_:/vapp_post: multiform post with mime=15, name=myrec,
    *Sep 15 06:49:09.560: //1336//AFW_:/vapp_post: multiform post with mime=10, name=dur, buf=32, size=2
    *Sep 15 06:49:09.560: //1336//AFW_:/vxml_update_cleanup_timer: cleaning timer running 0 fetchtimeout 0
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_submit_proc: <a>mailto:Exit@8051</a>]
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_elem_proc:
     (400000000) Status not OK, <a>mailto:exit@9899</a>]
    *Sep 15 06:49:09.560: //1336/802474020901/VXML:/vxml_leave_scope:
       scope=anonymous
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_form_filled:
     (400000000) Status not OK, <a>mailto:exit@5172</a>]
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_form_filled:
     (400000000) Status not OK, <a>mailto:exit@5182</a>]
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_form_filled:
     (400000000) Status not OK, <a>mailto:exit@5182</a>]
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_form_filled:
     (400000000) Status not OK, <a>mailto:exit@5176</a>]
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_form_filled:
     (400000000) Status not OK, <a>mailto:exit@5182</a>]
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_form_filled:
     (400000000) Status not OK, <a>mailto:exit@5176</a>]
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_record_proc: Record done, <a>mailto:Exit@7009</a>]
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_elem_proc:
     (400000000) Status not OK, <a>mailto:exit@9899</a>]
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_vxml_proc:
     (400000000) Status not OK, <a>mailto:exit@10105</a>]
    *Sep 15 06:49:09.560: //1336/802474020901/VXML:/vxml_load_immediate_done:
       sidp->status=400000000
    *Sep 15 06:49:09.560: //-1//VXML:/vxml_media_record_done: Exit
    *Sep 15 06:49:09.560: //1336//AFW_:/vapp_checksessionstate:
    *Sep 15 06:49:09.560: //1336//AFW_:/vapp_checkifdone: Object: 1, Leg: 1
    *Sep 15 06:49:09.660: //1336//AFW_:/vapp_post_done: status=http OK
    *Sep 15 06:49:09.660: //1336//AFW_:/vapp_driver: evtID: 237 vapp record state: 0
    *Sep 15 06:49:09.660: //1336//AFW_:/vapp_bgpost_done_event:
    *Sep 15 06:49:09.660: //1336/802474020901/VXML:/vxml_bgload_post_done:
       vxmlhandle=698475E4 status=0 async_status=400000000
    *Sep 15 06:49:09.660: //1336/802474020901/VXML:/vxml_bgload_post_done:
       Loading file with url (<a>http://10.35.241.6:8080/vxmlRec/saveRec.vxml.jsp</a>])
    *Sep 15 06:49:09.660: //-1//VXML:/vxml_is_cisco_data: Enter
    *Sep 15 06:49:09.660: //-1//VXML:/vxml_tree_lock:
       vxmlp=69FB4344 usage_cnt=0
    *Sep 15 06:49:09.664: //0/802474020901/VXML:/vxml_parse:
    *Sep 15 06:49:09.664: vxml_parse: XML_Parse success err=0
    *Sep 15 06:49:09.664: //0/802474020901/VXML:/vxml_session_delete:
    *Sep 15 06:49:09.664: vxml_session_delete:mem_mgr_mempool_free: mempool=NULL
    *Sep 15 06:49:09.664: vxml_session_delete:mem_mgr_mempool_free: mempool=NULL
    *Sep 15 06:49:09.664: //-1//VXML:/vxml_crc_generate_element_tree: 13 crc generated
    *Sep 15 06:49:09.664: //1336/802474020901/VXML:/vxml_bgload_post_done:
       internal parse tree created. sidp=698475E4, new_vxmlp=69FB4344
    *Sep 15 06:49:09.664: //1336/802474020901/VXML:/vxml_leave_scope:
       scope=application
    *Sep 15 06:49:09.664: //-1//VXML:/vxml_jse_decrement_record_refcount:
       recp(69F7DDB8), url(ram:myrec_127_0_1336), refcount(0), force_delete(0)
    *Sep 15 06:49:09.664: //1336/802474020901/VXML:/vxml_vapp_media_delete:
       recp=69F7DDB8 url=ram:myrec_127_0_1336
    *Sep 15 06:49:09.664: //1336//AFW_:/vapp_media_delete:
    *Sep 15 06:49:09.664: delete url=ram:myrec_127_0_1336
    *Sep 15 06:49:09.672: //-1//VXML:/vxml_vapp_media_delete: Exit
    *Sep 15 06:49:09.672: //-1//VXML:/vxml_crc_verify_element_tree: 33 crc verified
    *Sep 15 06:49:09.672: //1336/802474020901/VXML:/vxml_tree_delete:
       vxmlp=69F74F3C, usage_cnt=1 url=http://10.35.241.6:8080/vxmlRec/rec.vxml.jsp?ani=2303
    *Sep 15 06:49:09.672: vxml_tree_delete:mem_mgr_mempool_free: mem_refcnt(6975F728)=0 - mempool cleanup
    *Sep 15 06:49:09.672: //-1//VXML:/vxml_tree_lock:
       vxmlp=697B0F80 usage_cnt=1 url=http://10.35.241.6:8080/vxmlRec/rec.vxml.jsp
    *Sep 15 06:49:09.672: //-1//AFW_:/vapp_get_http_doc: get tree direct vxmlp=697B0F80, url=http://10.35.241.6:8080/vxmlRec/rec.vxml.jsp
    *Sep 15 06:49:09.672: //1336/802474020901/VXML:/vxml_stop_fetchaudio:
    *Sep 15 06:49:09.672: //1336/802474020901/VXML:/vxml_start:
       vxmlhandle=698475E4 vapphandle=6864D770 status=0 async_status=0
    *Sep 15 06:49:09.672: //1336/802474020901/VXML:/vxml_vxml_proc:
    <vxml> applicationp=697B0F80
       URI(abs):http://10.35.241.6:8080/vxmlRec/saveRec.vxml.jsp
       scheme=http
       host=10.35.241.6
       port=8080
       path=/vxmlRec/saveRec.vxml.jsp
       base=
       URI(abs):http://10.35.241.6:8080/vxmlRec/saveRec.vxml.jsp
       scheme=http
       host=10.35.241.6
       port=8080
       path=/vxmlRec/saveRec.vxml.jsp lang=none version=2.0
    *Sep 15 06:49:09.676: //1336/802474020901/VXML:/vxml_form_proc:
    *Sep 15 06:49:09.676:  <form>: id=uploadAudio   scope=dialog
    *Sep 15 06:49:09.676: //1336/802474020901/VXML:/vxml_form_init:
       current scope: dialog
       vxml_counter_reset:
    *Sep 15 06:49:09.676: //1336/802474020901/VXML:/vxml_expr_eval:
       expr=(var sendOK)
    *Sep 15 06:49:09.676: //1336/802474020901/VXML:/vxml_jse_global_switch:
       switch to scope(dialog)
    *Sep 15 06:49:09.680: //1336/802474020901/VXML:/vxml_formitem_select:
       Status=VXML_STATUS_OK,
    *Sep 15 06:49:09.680: //1336/802474020901/VXML:/vxml_formitem_select:
        AsyncStatus=VXML_STATUS_OK
    *Sep 15 06:49:09.680: //1336/802474020901/VXML:/vxml_block_proc:
    *Sep 15 06:49:09.680:   <block>:
    *Sep 15 06:49:09.680: //1336/802474020901/VXML:/vxml_item_attrs_proc:
       name=sendOK
             <log>:
    *Sep 15 06:49:09.680:  File name vmail_2009-09-15_01-43-39.wav
             <log>:
    *Sep 15 06:49:09.680:  File wrtten to C:\Program Files\Apache Software Foundation\Tomcat 6.0\webapps\ROOT\vxml\prompts\vmail_2009-09-15_01-43-39.wav
    *Sep 15 06:49:09.680: //-1//VXML:/vxml_prompt_proc: Enter
    *Sep 15 06:49:09.680: //1336/802474020901/VXML:/vxml_prompt_proc:
             <prompt>default_prompt) typeaheadflush=0 alternative=0
    *Sep 15 06:49:09.680: //-1//VXML:/vxml_process_prompt: Enter
    *Sep 15 06:49:09.680: //-1//VXML:/vxml_audio_proc: Enter
    *Sep 15 06:49:09.680: //1336/802474020901/VXML:/vxml_audio_proc:
             <audio>:
       URI(abs):/vxml/prompts/okThanks.au
       scheme=http
       host=10.35.241.6
       port=8080
       path=/vxml/prompts/okThanks.au caching=fast fetchhint=invalid fetchtimeout=0 maxage=-1 maxstale=-1
    *Sep 15 06:49:09.680: //-1//VXML:/vxml_audio_proc: Exit

    Subject: RE: VXML Record issue
    Replied by: VijayPrasad Neelamegam on 15-09-2009 10:22:36 AM
    Hi,
     
    The "audio/basic" enctype, which was supported in
    Cisco IOS Release 12.2(2)XB, is not supported in later releases of
    Cisco IOS software.
     
    So we can change the type in record element and moreover the final silence is mentioned as 3s.So if the user gives a interval of 3 secs while recording,then it will terminate the recording.
     
    Kindly send me the traces with following debusg
     
    debug voip application vxml error
    debug voip application vxml events
    debug voip application vxml grammer
     
    thanks
    Vijay

    Subject: Thread Splitted
    Replied by: Anusha Kannappan on 09-11-2011 02:50:58 AM
    The new thread can be found at http://developer.cisco.com/web/vgapi/forums/-/message_boards/view_message/2379703.