I am facing random issue in one of my project while returning the control from RDM2 to application. This is happening randomly at different places with no clear pattern. "error handler issue for RDM2 invoked via Local call.



Environment:-

Avaya Orchestration Designer : 6.0.0.1301
Avaya Experience Portal : 6.0.3.0.0402
Apache Tomcat Application Server : 7.0.53
Oracle Java : JDK 7 update 55

From application trace

17/10/2014 12:47:53:552 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | outputVariables:confidence : 0.7699999809265137
17/10/2014 12:47:53:552 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | outputVariables:inputType : voice
17/10/2014 12:47:53:553 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | outputVariables:returnCode : SUCCESS
17/10/2014 12:47:53:553 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | outputVariables:value : 1430
17/10/2014 12:47:53:553 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | outputVariables:utterance : two thirty p m
17/10/2014 12:47:53:553 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | outputVariables:noMatchsRemaining : 3
17/10/2014 12:47:53:554 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | outputVariables:noInputRemaining : 3
17/10/2014 12:47:53:554 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | outputVariables:confirmationsRemaining : 3
17/10/2014 12:47:53:554 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | PromptAndCollect:OTHER_SLOT : undefined
17/10/2014 12:47:53:554 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | nBestValuesCollection : 1430
17/10/2014 12:47:53:555 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : session id:srvyclabaaep-2014290184709-20 | RDM2:logResult | TRACE | disallowCollection :
17/10/2014 12:47:53:555 INFO - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : Using SCESession 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main servlet : exit
17/10/2014 12:47:53:556 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : request url copy is [http://10.222.130.100:8080/RDM2/exit]
17/10/2014 12:47:53:557 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : location is [/WestJet_FLIFO_Multiple_Flights/DS_3025_iaGet_Approx_Time_Evaluate?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main]
17/10/2014 12:47:53:557 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : redirecting to [http://10.222.130.100:8080/WestJet_FLIFO_Multiple_Flights/DS_3025_iaGet_Approx_Time_Evaluate?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main]
17/10/2014 12:47:53:565 INFO - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : Using SCESession 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main servlet : AvayaReturnErrorHandler
17/10/2014 12:47:53:566 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : Return Error actived in module invoked via a local call, returning to caller
17/10/2014 12:47:53:567 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : request url copy is [http://10.222.130.100:8080/RDM2/AvayaReturnErrorHandler]
17/10/2014 12:47:53:567 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : location is [/WestJet_FLIFO_Multiple_Flights/DS_3025_iaGet_Approx_Time_Evaluate?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main]
17/10/2014 12:47:53:567 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : redirecting to [http://10.222.130.100:8080/WestJet_FLIFO_Multiple_Flights/DS_3025_iaGet_Approx_Time_Evaluate?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main]
17/10/2014 12:47:53:572 INFO - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : Using SCESession 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main servlet : DS_3025_iaGet_Approx_Time_Evaluate
17/10/2014 12:47:53:573 INFO - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : ** Popped Stack Frame [/RDM2]
17/10/2014 12:47:53:574 INFO - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : Using SCESession 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main servlet : AvayaReturnErrorThrow
17/10/2014 12:47:53:576 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : Removing connection.disconnect from events since event is connection.disconnect.*
17/10/2014 12:47:53:576 DEBUG - 20AB123D96D0958A7297E401A8FE341E:/FLIFO_Main : Throwing returned error event: error.badfetch.http.404' message :Error fetching document evaluateConstraintList?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E%3A%2FFLIFO_Main

From Voice Browser Logs

@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:5039:VXI::CollectPhase - (block)|srvyclabaaep####
@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:5072:VXI::block_element()|srvyclabaaep####
@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:3498:VXI::execute_content()|srvyclabaaep####
@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:3565:VXI::executable_element - THROW|srvyclabaaep####
@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:3992:VXI::throw_element - throwing (Error fetching document evaluateConstraintList?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E%3A%2FWestJet_FLIFO_Main)|srvyclabaaep####
@2014-10-17 12:47:53,602|PAVB_03335|INFO|VB|30120|Session=srvyclabaaep-2014290184709-20|Got VoiceXML exception: error.badfetch.http.404 in http://10.222.130.100:8080/WestJet_FLIFO_Multiple_Flights/DS_3025_iaGet_Approx_Time_Evaluate?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E:/WestJet_FLIFO_Main|srvyclabaaep####
@2014-10-17 12:47:53,602||FINE|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI::DoEvent - Enter|srvyclabaaep####
@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:3214:VXI::playingPrompts = false|srvyclabaaep####
@2014-10-17 12:47:53,602||FINEST|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:9349:VXI::SendUserEvent() - event --> error.badfetch.http.404 is not an event for CCXML processor.|srvyclabaaep####
@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:3498:VXI::execute_content()|srvyclabaaep####
@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:3565:VXI::executable_element - IF|srvyclabaaep####
@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:4148:VXI::if_element()|srvyclabaaep####
@2014-10-17 12:47:53,602||FINER|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|VXI.cpp:3565:VXI::executable_element - LOG|srvyclabaaep####
@2014-10-17 12:47:53,602||FINE|LogTag|30120|Session=srvyclabaaep-2014290184709-20|(SessionID: srvyclabaaep-2014290184709-20)Error: error.badfetch.http.404 , Error fetching document evaluateConstraintList?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E%3A%2FWestJet_FLIFO_Main|srvyclabaaep####
@2014-10-17 12:47:53,602||FINEST|VB|30120|Session=srvyclabaaep-2014290184709-20|******** Entering Utility.cpp:478:LogIPCMessage|srvyclabaaep####
@2014-10-17 12:47:53,602||FINEST|VB|30120|Session=srvyclabaaep-2014290184709-20|VXML_LOG_TAG_DATA
{
dialogHandle: 242
label: ""
contents: "Error: error.badfetch.http.404 , Error fetching document evaluateConstraintList?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E%3A%2FWestJet_FLIFO_Main"
timeSec: 1413571673
timeMs: 602
}


Can anybody please tell me the cause and how to overcome this error

From the logs, the app seems to have executed fine:

17/10/2014 12:47:53:536 INFO - 20AB123D96D0958A7297E401A8FE341E:/WestJet_FLIFO_Main : Using SCESession 20AB123D96D0958A7297E401A8FE341E:/WestJet_FLIFO_Main servlet : evaluateConstraintList


But the AVB says there was an error returned:

@2014-10-17 12:47:53,558||FINEST|VB|30120|Session=srvyclabaaep-2014290184709-20|******** Leaving Utility.cpp:478:LogIPCMessage|srvyclabaaep####
@2014-10-17 12:47:53,558|PAVB_00224|ERROR|VB|30120|Session=srvyclabaaep-2014290184709-20|Socket Error in function SWIsocket::is_readready:select, WSA_ERROR_NAME: errno, errorCode: 104|srvyclabaaep####
@2014-10-17 12:47:53,558||FINEST|CommonVXI.Cache|30120|Session=srvyclabaaep-2014290184709-20|SBinetHttpStream.cpp:454:waitReady returned IO ERROR, src=-12|srvyclabaaep####
@2014-10-17 12:47:53,558||FINE|CommonVXI.Cache|30120|Session=srvyclabaaep-2014290184709-20|Failed in POST with return code = 52|srvyclabaaep####
@2014-10-17 12:47:53,558||FINE|CommonVXI.Cache|30120|Session=srvyclabaaep-2014290184709-20|Undefined HTTP status -1234 for url = http://10.222.130.100:8080/RDM2/evaluateConstraintList?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E%3A%2FWestJet_FLIFO_Main|srvyclabaaep####
@2014-10-17 12:47:53,558||FINEST|CommonVXI.Cache|30120|Session=srvyclabaaep-2014290184709-20|SBinetChannel.cpp:315:stream open returns 52|srvyclabaaep####
@2014-10-17 12:47:53,558||FINE|CommonVXI.Interpreter|30120|Session=srvyclabaaep-2014290184709-20|DocumentParser.cpp:684:Failed to load document from URL evaluateConstraintList?___DDSESSIONID=20AB123D96D0958A7297E401A8FE341E%3A%2FWestJet_FLIFO_Main|srvyclabaaep####
@2014-10-17 12:47:53,558||FINE|VB|30120|Session=srvyclabaaep-2014290184709-20|an error was encountered running this application|srvyclabaaep####
@2014-10-17 12:47:53,558||FINEST|VB|30120|Session=srvyclabaaep-2014290184709-20|******** Entering Utility.cpp:478:LogIPCMessage|srvyclabaaep####
@2014-10-17 12:47:53,558||FINEST|VB|30120|Session=srvyclabaaep-2014290184709-20|VXML_APP_ERROR
{


I suggest you take this issue to Avaya Support and have them take look at the AVB log to determine what exactly the error is.

Hello,

I am having the same issue with RDM2, catalina.out shows the following:

com.avaya.sce.runtimecommon.SCERuntimeException: Runtime Error, session lost while application was executing in evaluateConstraintList
Runtime Error, session lost while application was executing in evaluateConstraintList
com.avaya.sce.runtimecommon.SCERuntimeException: Runtime Error, session lost while application was executing in evaluateConstraintList
Runtime Error, session lost while application was executing in evaluateConstraintList
** received session id [011ADBF1AD0B2951CBC791C8C465973A:/TUFTS_BH_CallStart] in the request
** created session id [null] since there was not one in the request
** query string [___DDSESSIONID=011ADBF1AD0B2951CBC791C8C465973A%3A%2FTUFTS_BH_CallStart]
Request Param :_event as -> error.badfetch.http.500
Request Param :___DDSESSIONID as -> 011ADBF1AD0B2951CBC791C8C465973A:/TUFTS_BH_CallStart
Request Param :_message as -> Error fetching document evaluateConstraintList?___DDSESSIONID=011ADBF1AD0B2951CBC791C8C465973A%3A%2FTUFTS_BH_CallStart
EXCEPTION>
com.avaya.sce.runtimecommon.SCERuntimeException: Runtime Error, session lost while application was executing in AvayaReturnErrorHandler
at com.avaya.sce.runtimecommon.SCESession.getSession(SCESession.java:318)
at com.avaya.sce.runtimecommon.SCESession.getSession(SCESession.java:591)
at com.avaya.sce.runtime.SCEServlet.requestHandler(SCEServlet.java:199)
at com.avaya.sce.runtime.SCEServlet.doPost(SCEServlet.java:190)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:637)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:298)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:857)
at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:588)
at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:489)
at java.lang.Thread.run(Thread.java:701)
com.avaya.sce.runtimecommon.SCERuntimeException: Runtime Error, session lost while application was executing in AvayaReturnErrorHandler
Runtime Error, session lost while application was executing in AvayaReturnErrorHandler
com.avaya.sce.runtimecommon.SCERuntimeException: Runtime Error, session lost while application was executing in AvayaReturnErrorHandler
"catalina.out" [readonly] 30498L, 2601212C


is there any resolution to this issue?

Thank you!

This exception does not explain how the session ended before the flow finished executing. Do you have more logs prior to the exception?