E1: ORCH: Orchestrator Does Not Work With Custom Application - "Demand generation failed"

Last updated on MARCH 22, 2018

Applies to:

Symptoms

When attempting to use Iot using a custom app, the following error occurs.

Iot for P01012 works fine and the custom application works fine using HTML client.

Error: jasdebug on the html server.
---------
09 Oct 2015 12:17:57,760 [APP ] KPIT2 - [JDBJ] SELECT FSJDEVERS,FSMRGMOD,FSMRGOPT,FSFFU1,FSFFU2,FSFDABLOB,FSGNCID2,FSWEVENT,FSGNCID3 FROM COPY910.F98751YF150824 WHERE (FSOBNM = ? AND FSRCRDTP = ? AND FSFMNM = ? ) ORDER BY FSGNCID2 ASC , FSWEVENT ASC , FSGNCID3 ASC
09 Oct 2015 12:17:57,760 [APP ] KPIT2 - [JDBJ] SQL statement parameter marker values are :
09 Oct 2015 12:17:57,760 [APP ] KPIT2 - [JDBJ] Param1 : P5742123_Types.VARCHAR,
09 Oct 2015 12:17:57,760 [APP ] KPIT2 - [JDBJ] Param2 : 17_Types.NUMERIC,
09 Oct 2015 12:17:57,760 [APP ] KPIT2 - [JDBJ] Param3 : W5742123_Types.VARCHAR,
09 Oct 2015 12:17:57,760 [DEBUG ] KPIT2 - [JDBJ] Using runtime property JDBC Column Security default value false.
09 Oct 2015 12:17:57,760 [DEBUG ] KPIT2 - [JDBJ] (Ignored any system or jdbj.ini setting for JDBC Column Security because it can only be set programmatically on a specific logical connection.)
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Result Set created : Type = 1003 Concurrency = 1007
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Using runtime property Result Set Timeout jdbj.ini value 300000.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Using runtime property Usage Tracking jdbj.ini value false.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [METADATA] Exiting JDESpecAccess.select() with success.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [METADATA] Entering SpecResultSetImpl.fetchNext().
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Last row in a non scrollable result set was fetched. Result set and physical connection are closed implicitly.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Connection represented by poolKey JDEMMX02_JDE checked into pool - com.jdedwards.database.impl.physical.JDBPooledConnection@1305f1c7
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [METADATA] Entering SpecDatabaseService.newSpecData().
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [METADATA] ExitingSpecDatabaseService.newSpecData() with no data.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [METADATA] Exiting SpecResultSetImpl.fetchNext().
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [METADATA] Closing JDBj result set.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [METADATA] Closed JDBj resultSet.
09 Oct 2015 12:17:57,854 [SEVERE] KPIT2 - [GENERATOR] JavaGen error code = SPEC_ACCESS_ERROR com.jdedwards.runtime.generator.JavaGenException: JDESpecException Occurred! Error Message : The object with the key, FDASPECPrimarySpecKey: OBNM=P5742123,RCRDTP=17,FMNM=W5742123,GNCID2=null,WEVENT=null,GNCID3=null, does not exist.
09 Oct 2015 12:17:57,854 [WARN ] KPIT2 - [RUNTIME] JavaGenException , while trying to Generate with stack trace
C=com.jdedwards.runtime.generator.engine.MD.AppGenEngineMD,M=formOrPortletNameExistsEx,L=-1
C=com.jdedwards.runtime.generator.engine.AppGenEngine,M=formOrPortletNameExists,L=-1
C=com.jdedwards.runtime.generator.webjiti.WebJITIParser,M=buildAppSet,L=-1
C=com.jdedwards.runtime.generator.webjiti.WebJITI,M=buildSets,L=-1
C=com.jdedwards.runtime.generator.webjiti.WebJITI,M=generateInternal,L=-1
C=com.jdedwards.runtime.generator.webjiti.WebJITI,M=generate,L=-1
C=com.jdedwards.database.services.spec.SerializedSpecMap,M=callWebJiti,L=-1
C=com.jdedwards.database.services.spec.SerializedSpecMap,M=getSpecByUserAndLanguageInternal,L=-1
C=com.jdedwards.database.services.spec.SerializedSpecMap,M=getSpecByUserAndLanguage,L=-1
C=com.jdedwards.jas.net.NetManager,M=getPersonalSpec,L=-1
C=com.jdedwards.jas.net.NetManager,M=getSpec,L=-1
C=com.jdedwards.runtime.vtcomponent.form.VTForm,M=loadForm,L=-1
C=com.jdedwards.runtime.virtual.OWVirtual,M=loadForm,L=-1
C=com.jdedwards.runtime.er.OWShortcut,M=launchShortcut,L=-1
C=com.jdedwards.runtime.virtual.SystemEventProcessor,M=processVTSystemEvent,L=-1
C=com.jdedwards.runtime.virtual.OWVirtual,M=processVTEvent,L=-1
C=com.jdedwards.runtime.virtual.OWVirtual,M=processEventLoop,L=-1
C=com.jdedwards.runtime.virtual.OWVirtual,M=run,L=-1
C=com.jdedwards.base.util.ThreadPool$WorkerThread,M=run,L=-1
 
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [GENERATOR] Processing... 0% done.Generation complete.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Transaction 30 state changing from auto commit to closed.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Transaction 29 state changing from auto commit to closed.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Transaction 31 state changing from auto commit to closed.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [METADATA] Closing JDBj logical connection.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Transaction 35 state changing from auto commit to closed.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Transaction 34 state changing from auto commit to closed.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [JDBJ] Transaction 36 state changing from auto commit to closed.
09 Oct 2015 12:17:57,854 [DEBUG ] KPIT2 - [METADATA] Closed JDBj logical connection.
09 Oct 2015 12:17:57,854 [SEVERE] KPIT2 - [BASE] com.jdedwards.database.base.JDBException: [WEBJITI_ERROR] Demand generation failed for APP-P5742123-W5742123: null
09 Oct 2015 12:17:57,854 [SEVERE] KPIT2 - [BASE] com.jdedwards.database.base.JDBException: [WEBJITI_ERROR] Demand generation failed for APP-P5742123-W5742123: null com.jdedwards.database.base.JDBException: [WEBJITI_ERROR] Demand generation failed for APP-P5742123-W5742123: null
09 Oct 2015 12:17:57,854 [SEVERE] KPIT2 - [JAS] JDBException caught in NetManager.getSpec(): | An error occured when trying to get the serialized spec object, User Name : KPIT2, Environment : JPY910AM, Role : *ALL com.jdedwards.database.base.JDBException: [WEBJITI_ERROR] Demand generation failed for APP-P5742123-W5742123: null
09 Oct 2015 12:17:57,854 [WARN ] KPIT2 - [RUNTIME] OWClient.FetchObject failed #3: caught JASServiceException (OID = P5742123_W5742123) Error Message: , Error list: :

mError: 431
mSubText:
mAlphaDescriptionTemplate: JAS_MSG431: Fetch serialized object failed. Please contact your system administrator.
mGlossaryTextTemplate:
mErrorLevel: 1
mIdItem: -1
mFileName: null
mLineNumber: 0



09 Oct 2015 12:17:57,869 [APP ] - [RUNTIME] openApps after close:1 size:0
09 Oct 2015 12:17:57,869 [APP ] - [RUNTIME] unregister OWVirtual:jde:instanceName=W577_E1HTMS_PY_AM_00,targetType=webserver,metricName=owvirtual,sessionId=1308093615039746048,metricType=runtime,uniqueId=123403915262541
09 Oct 2015 12:17:58,899 [APP ] - [RUNTIME] removeOWVirtual(stackId:1) mOWVirtuals size:0



AIS debug log:
===

  "message" : "Server returned HTTP response code: 503 for URL: http://XXXX:8500/jde/FormServiceRequest",
  "localizedMessage" : "Server returned HTTP response code: 503 for URL: http://Xxxxxxx:8500/jde/FormServiceRequest"
} java.io.IOException: Server returned HTTP response code: 503 for URL: http://XXXXXX:8500/jde/FormServiceRequest
09 Oct 2015 11:17:57,682 [Line -1] [[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] KPIT2 - [AIS]      buildExceptionResponseString End  java.io.IOException: Server returned HTTP response code: 503 for URL: http://XXXXXX:8500/jde/FormServiceRequest
09 Oct 2015 11:17:57,682 [Line -1] [[ACTIVE] ExecuteThread: '0' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ] KPIT2 - [AIS]      Form Service Call Milliseconds: 2465
09 Oct 2015 11:17:57,682 [Line -1] [[ACTIVE] ExecuteThread: '3' for queue: 'weblogic.kernel.Default (self-tuning)'] [DEBUG ]  - [AIS]      AISClientServlet outputJSON {
  "FormRequest1" : {
    "message" : "Server returned HTTP response code: 503 for URL: http://XXXXXXX:8500/jde/FormServiceRequest",
    "exception" : "java.io.IOException",
    "timeStamp" : "2015-10-09:11.17.57"
  }
}

Changes

Cause