While Publishing the Promotion on v6.7.0.4 getting exception java.lang.IllegalStateException: Another instance of the rule engine job is currently executing, retry later

[rama_naga_vasu_kottu]

Hi experts,

I am migrating 6.1 to 6.7. I created some promotions and tried to publish them, getting java.lang.IllegalStateException: Another instance of the rule engine job is currently executing, retry later.

Could you please me here, what could be the issue?

Thanks, Ram.

Log Trace:

ERROR [ajp-nio-8010-exec-17] [10.32.82.46] [AbstractRuleCompileForModuleComposer] Exception during rule compilation and publication. INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | java.lang.IllegalStateException: Another instance of the rule engine job is currently executing, retry later INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at de.hybris.platform.ruleengineservices.jobs.impl.DefaultRuleEngineCronJobLauncher.verifyIfJobsAreNotRunning(DefaultRuleEngineCronJobLauncher.java:218) ~[ruleengineservicesserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at de.hybris.platform.ruleengineservices.jobs.impl.DefaultRuleEngineCronJobLauncher.triggerCompileAndPublish(DefaultRuleEngineCronJobLauncher.java:66) ~[ruleengineservicesserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at de.hybris.platform.ruleenginebackoffice.actions.AbstractRuleCompileForModuleComposer.doCompileAndPublishRules(AbstractRuleCompileForModuleComposer.java:162) ~[?:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at de.hybris.platform.ruleenginebackoffice.actions.RuleCompileForModuleComposer.doCompileAndPublishRules(RuleCompileForModuleComposer.java:55) ~[?:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at de.hybris.platform.ruleenginebackoffice.actions.AbstractRuleCompileForModuleComposer.perform(AbstractRuleCompileForModuleComposer.java:106) ~[?:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_172] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_172] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_172] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_172] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at com.hybris.cockpitng.util.ViewAnnotationAwareComposer.invokeListenerMethod(ViewAnnotationAwareComposer.java:107) ~[cockpitframework-6.7.0.1-RC1.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at com.hybris.cockpitng.util.ViewAnnotationAwareComposer.lambda$applyEventListener$0(ViewAnnotationAwareComposer.java:124) ~[cockpitframework-6.7.0.1-RC1.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.ui.AbstractComponent.onEvent(AbstractComponent.java:3162) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.ui.AbstractComponent.service(AbstractComponent.java:3132) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.ui.AbstractComponent.service(AbstractComponent.java:3074) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.ui.impl.EventProcessor.process(EventProcessor.java:138) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.ui.impl.UiEngineImpl.processEvent(UiEngineImpl.java:1846) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.ui.impl.UiEngineImpl.process(UiEngineImpl.java:1618) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.ui.impl.UiEngineImpl.execUpdate(UiEngineImpl.java:1321) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.au.http.DHtmlUpdateServlet.process(DHtmlUpdateServlet.java:606) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.au.http.DHtmlUpdateServlet.doGet(DHtmlUpdateServlet.java:482) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.zkoss.zk.au.http.DHtmlUpdateServlet.doPost(DHtmlUpdateServlet.java:490) [zk-8.5.0.jar:3.6.4] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at javax.servlet.http.HttpServlet.service(HttpServlet.java:661) [servlet-api.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at javax.servlet.http.HttpServlet.service(HttpServlet.java:742) [servlet-api.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:231) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) [tomcat-websocket.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:208) [spring-security-web-4.2.6.RELEASE.jar:4.2.6.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:177) [spring-security-web-4.2.6.RELEASE.jar:4.2.6.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:347) [spring-web-4.3.17.RELEASE.jar:4.3.17.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:263) [spring-web-4.3.17.RELEASE.jar:4.3.17.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.418 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:99) [spring-web-4.3.17.RELEASE.jar:4.3.17.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) [spring-web-4.3.17.RELEASE.jar:4.3.17.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at com.hybris.backoffice.mobile.filter.BackofficeMobileFilter.doFilter(BackofficeMobileFilter.java:63) [classes/:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.WebAppMediaFilter.doFilter(WebAppMediaFilter.java:140) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:347) [spring-web-4.3.17.RELEASE.jar:4.3.17.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:263) [spring-web-4.3.17.RELEASE.jar:4.3.17.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:330) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$StatisticsGatewayFilter.doFilter(AbstractPlatformFilterChain.java:419) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.DynamicCatalogVersionActivationFilter.doFilter(DynamicCatalogVersionActivationFilter.java:90) [platformservicesserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.DataSourceSwitchingFilter.doFilter(DataSourceSwitchingFilter.java:70) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.SessionFilter.doFilter(SessionFilter.java:99) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.session.HybrisSpringSessionFilter.doFilter(HybrisSpringSessionFilter.java:69) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at com.hybris.cockpitng.modules.spring.filter.ExternalModuleContextClassLoaderFilter.doFilter(ExternalModuleContextClassLoaderFilter.java:44) [cockpit-module-aggregator-6.7.0.1-RC1.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.RedirectWhenSystemIsNotInitializedFilter.doFilter(RedirectWhenSystemIsNotInitializedFilter.java:101) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.TenantActivationFilter.doFilter(TenantActivationFilter.java:88) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.Log4JFilter.doFilter(Log4JFilter.java:44) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at com.hybris.backoffice.filter.responseheaders.BackofficeResponseHeadersFilter.doFilter(BackofficeResponseHeadersFilter.java:38) [classes/:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain$InternalFilterChain.doFilter(AbstractPlatformFilterChain.java:300) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain.processStandardFilterChain(AbstractPlatformFilterChain.java:209) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.AbstractPlatformFilterChain.doFilterInternal(AbstractPlatformFilterChain.java:186) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) [spring-web-4.3.17.RELEASE.jar:4.3.17.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:347) [spring-web-4.3.17.RELEASE.jar:4.3.17.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:263) [spring-web-4.3.17.RELEASE.jar:4.3.17.RELEASE] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.XSSFilter.processPatternsAndDoFilter(XSSFilter.java:358) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at de.hybris.platform.servicelayer.web.XSSFilter.doFilter(XSSFilter.java:306) [coreserver.jar:?] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:193) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:166) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:199) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:96) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:478) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:140) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:81) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:87) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:650) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:342) [catalina.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.coyote.ajp.AjpProcessor.service(AjpProcessor.java:486) [tomcat-coyote.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:66) [tomcat-coyote.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:868) [tomcat-coyote.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1459) [tomcat-coyote.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) [tomcat-coyote.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_172] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_172] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) [tomcat-util.jar:8.5.23] INFO | jvm 1 | main | 2018/09/18 13:04:26.419 | at java.lang.Thread.run(Thread.java:836) [?:1.8.0_172]

[former_member1190134]

Hi , Did you get solution ? I have same problem.

[former_member896530]

could you please tell me what is the solution for this. Even i have the same problem

[rama_naga_vasu_kottu]

By making the the attribute "lock acquire" to false using impex solved the issue. INSERT_UPDATE DroolsKIEModule;name[unique=true];lockAcquired ;promotions-module;false

[former_member646566]

search jobs which names in [rules -> Compilation and Publishing for [promotions-module], rules -> Undeploy for [promotions-module], rules -> Module Init for [promotions-module], rules -> All Modules Init] with unknown status and delete them ..

[vinay_malempati]

This is common issue in promotion engine.

This happens when you are trying to publish promotions either too fast or multiple people trying at the same time or may be you your self published one promotion and that is not yet completed.

On the whole this is not error, rather info to you saying the promotion engine is already in progress of publishing and it is required for you to wait until that is done.

Causes: Mostly due to the slowness of the Engine due to number of promotions or Spec of the server . Hope this helps 🙂