AWA: Processing delays with external dependencies

Document ID : KB000117251
Last Modified Date : 10/10/2018
Show Technical Document Details
Issue:
In specific configurations and when the Automation Engine has a high workload it is possible that the checking of external condition leads to slowness when the period between checks of the External Dependencies is too short.

When this occurs messages like the following are present in the logs of the Automation Engine:
UCUDB: ===> Time critical DB call!       OPC: 'SLCU' time: '35:474.677.999'
20180927/070408.575 - U00029131 UCUDB - List of bind parameters:
20180927/070408.575 - U00029132 UCUDB: 'EH_AH_Idnr         = <Idnr of Workflow waiting for dependency'
20180927/070408.575 - U00003525 UCUDB: ===> 'SELECT * FROM EH WHERE EH_AH_Idnr = ?'
20180927/070450.067 - U00003524 UCUDB: ===> Time critical DB call!       OPC: 'SLCO' time: '41:487.231.000'
20180927/070450.067 - U00029131 UCUDB - List of bind parameters:
20180927/070450.067 - U00029132 UCUDB: 'AH_Client          = 1000'
20180927/070450.067 - U00029132 UCUDB: 'AJPP_Object        = <Task of External Dependency>'
20180927/070450.067 - U00029132 UCUDB: 'AH_TimeStamp4      = 2018-9-27 6:3:32.0'
20180927/070450.067 - U00029132 UCUDB: 'AH_Client          = 1000'
20180927/070450.067 - U00029132 UCUDB: 'AJPP_Object        = <Task of External Dependency>'
20180927/070450.067 - U00029132 UCUDB: 'AH_TimeStamp4      = 2018-9-27 6:3:32.0'
20180927/070450.067 - U00029132 UCUDB: 'AH_Client          = 1000'
20180927/070450.067 - U00029132 UCUDB: 'AJPP_Object        = <Task of External Dependency>'
20180927/070450.067 - U00029132 UCUDB: 'AH_TimeStamp4      = 2018-9-27 6:3:32.0'
20180927/070450.067 - U00003525 UCUDB: ===> 'SELECT 0             AJPP_AH_Idnr, AH_Idnr       AJPP_TaskIdnr, AH_Status     AJPP_JobStatus, AH_RetCode  AJPP_RetCode, AH_TimeStamp4 AJPP_EndTime FROM AH WHERE AH_Client     = ? AND AH_Name       = ? AND AH_TimeStamp4 > ? UNION ALL SELECT AJPP_AH_Idnr, AJPP_TaskIdnr, AJPP_JobStatus, AJPP_RetCode, AJPP_EndTime FROM AH, AJPP WHERE AH_Client    = ? AND AH_Idnr      = AJPP_AH_Idnr AND AJPP_Object  = ? AND AJPP_EndTime >= ? AND AJPP_OType  <> '<XTRNL>' UNION ALL SELECT EJPP_AH_Idnr   AJPP_AH_Idnr, EJPP_TaskIdnr  AJPP_TaskIdnr, EJPP_JobStatus AJPP_JobStatus, EJPP_RetCode   AJPP_RetCode, EJPP_EndTime   AJPP_EndTime FROM EH, EJPP WHERE EH_Client    = ? AND EH_AH_Idnr   = EJPP_AH_Idnr AND EJPP_Object  = ? AND EJPP_EndTime >= ? AND EJPP_Status  > 1799 AND EJPP_OType  <> '<XTRNL>' ORDER BY 5 asc'
20180927/070450.088 - U00003434 Server routine  'JPEXEC_R/EXT_CHK' required '1' minutes and '16,989' seconds for processing.
Environment:
This has so far only been detected in v11.2.x
Resolution:
Workaround:
Set UC_SYSTEM_SETTINGS > EXTERNAL_CHECK_INTERVAL to a higher values for instance 120 seconds