error on upgrade 7.7.2 to 7.8.0

1
0
-1

Hello,
Currently i am using 7.7.2 version and i want to upgrade but i can't start bonita portal.I migrate my database with migration tool without any error, then i updated tomcat files and bundle, then i started bonita but portal not starting. And i am getting

 java.lang.OutOfMemoryError: Java heap space
Dumping heap to java_pid36340.hprof ...

error.

And these are detail error logs;

2018-12-11 14:41:28.704 +0300 SEVERE: org.apache.catalina.core.ContainerBase.[Catalina].[localhost].[/bonita] Exception sending context initialized event to listener instance of class [org.bonitasoft.engine.api.internal.servlet.EngineInitializerListener]
java.lang.RuntimeException: Error while initializing the Engine
    at org.bonitasoft.engine.api.internal.servlet.EngineInitializerListener.contextInitialized(EngineInitializerListener.java:35)
    at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4792)
    at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5256)
    at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
    at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:754)
    at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:730)
    at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:734)
    at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:629)
    at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1839)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)
Caused by: org.bonitasoft.engine.platform.StartNodeException: Platform starting failed.
    at org.bonitasoft.engine.api.impl.PlatformAPIImpl.startNode(PlatformAPIImpl.java:211)
    at org.bonitasoft.engine.EngineInitializer.initializeEngine(EngineInitializer.java:75)
    at org.bonitasoft.engine.api.internal.servlet.EngineInitializerListener.contextInitialized(EngineInitializerListener.java:33)
    ... 13 more
Caused by: org.bonitasoft.engine.commons.exceptions.SBonitaRuntimeException: java.lang.OutOfMemoryError: Java heap space
    at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:282)
    at org.bonitasoft.engine.api.impl.PlatformAPIImpl.startServicesOfTenants(PlatformAPIImpl.java:397)
    at org.bonitasoft.engine.api.impl.PlatformAPIImpl.startNode(PlatformAPIImpl.java:195)
    ... 15 more
Caused by: java.lang.OutOfMemoryError: Java heap space
    at java.lang.reflect.Array.newArray(Native Method)
    at java.lang.reflect.Array.newInstance(Array.java:75)
    at org.hibernate.type.descriptor.java.ArrayMutabilityPlan.deepCopyNotNull(ArrayMutabilityPlan.java:43)
    at org.hibernate.type.descriptor.java.MutableMutabilityPlan.deepCopy(MutableMutabilityPlan.java:52)
    at org.hibernate.type.AbstractStandardBasicType.deepCopy(AbstractStandardBasicType.java:321)
    at org.hibernate.type.AbstractStandardBasicType.deepCopy(AbstractStandardBasicType.java:317)
    at org.hibernate.type.TypeHelper.deepCopy(TypeHelper.java:67)
    at org.hibernate.engine.internal.TwoPhaseLoad.doInitializeEntity(TwoPhaseLoad.java:268)
    at org.hibernate.engine.internal.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:144)
    at org.hibernate.loader.plan.exec.process.internal.AbstractRowReader.performTwoPhaseLoad(AbstractRowReader.java:244)
    at org.hibernate.loader.plan.exec.process.internal.AbstractRowReader.finishUp(AbstractRowReader.java:215)
    at org.hibernate.loader.plan.exec.process.internal.ResultSetProcessorImpl.extractResults(ResultSetProcessorImpl.java:140)
    at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:138)
    at org.hibernate.loader.plan.exec.internal.AbstractLoadPlanBasedLoader.executeLoad(AbstractLoadPlanBasedLoader.java:102)
    at org.hibernate.loader.entity.plan.AbstractLoadPlanBasedEntityLoader.load(AbstractLoadPlanBasedEntityLoader.java:186)
    at org.hibernate.persister.entity.AbstractEntityPersister.load(AbstractEntityPersister.java:4126)
    at org.hibernate.event.internal.DefaultLoadEventListener.loadFromDatasource(DefaultLoadEventListener.java:503)
    at org.hibernate.event.internal.DefaultLoadEventListener.doLoad(DefaultLoadEventListener.java:468)
    at org.hibernate.event.internal.DefaultLoadEventListener.load(DefaultLoadEventListener.java:213)
    at org.hibernate.event.internal.DefaultLoadEventListener.proxyOrLoad(DefaultLoadEventListener.java:275)
    at org.hibernate.event.internal.DefaultLoadEventListener.onLoad(DefaultLoadEventListener.java:151)
    at org.hibernate.internal.SessionImpl.fireLoad(SessionImpl.java:1106)
    at org.hibernate.internal.SessionImpl.access$2000(SessionImpl.java:176)
    at org.hibernate.internal.SessionImpl$IdentifierLoadAccessImpl.load(SessionImpl.java:2587)
    at org.hibernate.internal.SessionImpl.get(SessionImpl.java:991)
    at org.bonitasoft.engine.persistence.TenantHibernatePersistenceService.selectById(TenantHibernatePersistenceService.java:158)
    at org.bonitasoft.engine.persistence.AbstractHibernatePersistenceService.selectById(AbstractHibernatePersistenceService.java:352)
    at org.bonitasoft.engine.dependency.impl.DependencyServiceImpl.getDependency(DependencyServiceImpl.java:237)
    at org.bonitasoft.engine.dependency.impl.AbstractDependencyService.lambda$getDependenciesResources$0(AbstractDependencyService.java:93)
    at org.bonitasoft.engine.dependency.impl.AbstractDependencyService$$Lambda$49/1662045670.apply(Unknown Source)
    at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
    at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1382)

2018-12-11 14:41:29.249 +0300 SEVERE: org.bonitasoft.engine.api.impl.ServerAPIImpl THREAD_ID=18 | HOSTNAME=DenemeBPM | Node not started. Method 'org.bonitasoft.engine.api.PlatformAPI.getClientPlatformConfigurations' cannot be called until node has been started (PlatformAPI.startNode()). Exact class: org.bonitasoft.engine.api.impl.PlatformAPIImpl
2018-12-11 14:41:29.250 +0300 SEVERE: org.apache.catalina.core.ContainerBase.[Catalina].[localhost].[/bonita] Exception sending context initialized event to listener instance of class [org.bonitasoft.console.common.server.servlet.PlatformTenantListener]
org.bonitasoft.engine.platform.NodeNotStartedException: USERNAME=local | The current node has not been started yet. Method PlatformAPI.startNode() must be called previously.
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.checkMethodAccessibility(ServerAPIImpl.java:287)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl$2.call(ServerAPIImpl.java:430)
    at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.invokeAPIInTransaction(ServerAPIImpl.java:438)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.invokeAPI(ServerAPIImpl.java:272)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.invokeMethod(ServerAPIImpl.java:124)
    at org.bonitasoft.engine.api.impl.ClientInterceptor.invoke(ClientInterceptor.java:86)
    at com.sun.proxy.$Proxy43.getClientPlatformConfigurations(Unknown Source)
    at org.bonitasoft.console.common.server.utils.PlatformManagementUtils.retrievePlatformConfiguration(PlatformManagementUtils.java:101)
    at org.bonitasoft.console.common.server.utils.PlatformManagementUtils.initializePlatformConfiguration(PlatformManagementUtils.java:113)
    at org.bonitasoft.console.common.server.servlet.PlatformTenantListener.contextInitialized(PlatformTenantListener.java:52)
    at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4792)
    at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5256)
    at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
    at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:754)
    at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:730)
    at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:734)
    at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:629)
    at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1839)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)
1 answer

1
0
-1

Hello,

This is a Java heap space issue, could you try restarting with more memory in the JVM?

To add more memory in the JVM, you will need to use the Bonita Platform Setup tool to pull, edit and push the "CATALINA_OPTS" property (attributes "Xms" and "Xmx") in the file "tomcat-templates/setenv.sh" for example if you are running with a Tomcat on Linux.

If this does not help in any way then it might be related to a memory leak due to migration or the targetted version, keep us posted!

HIH,

Comments

Submitted by msakirkutlu_1359205 on Thu, 12/13/2018 - 10:02

Hello,
I did what you told me to but this time i get dependency missing error. But interesting thing is every time i run bonita it asks me diffirent dependency. I am not adding anything, just stopping bonita and starting again and diffirent dependency is missing. When i go to database and check them, they are not my dependencies. They are not related to any of my processes. They are came with bonita i guess.

the dependency with 1814 id is "castor-1.2.jar"
Here is error log;

2018-12-13 11:32:43.993 +0300 INFO: org.bonitasoft.engine.EngineInitializer Initializing Bonita Engine...
2018-12-13 11:33:01.439 +0300 INFO: org.bonitasoft.engine.EngineInitializer Platform is already initialized.
2018-12-13 11:33:01.439 +0300 INFO: org.bonitasoft.engine.EngineInitializer Starting node...
2018-12-13 11:34:05.762 +0300 WARNING: bitronix.tm.BitronixTransaction transaction timed out: a Bitronix Transaction with GTRID [626F6E6974612D746F6D6361742D62746D2D6E6F646530000000004232DE770000000B], status=MARKED_ROLLBACK, 1 resource(s) enlisted (started Tue Jan 13 22:30:30 EET 1970)
2018-12-13 11:34:06.018 +0300 WARN: org.hibernate.engine.jdbc.spi.SqlExceptionHelper SQL Error: 0, SQLState: null
2018-12-13 11:34:06.019 +0300 ERROR: org.hibernate.engine.jdbc.spi.SqlExceptionHelper error enlisting a JdbcConnectionHandle of a JdbcPooledConnection from datasource jdbc/bonitaDSXA in state ACCESSIBLE with usage count 1 wrapping org.postgresql.xa.PGXAConnection@32565de4 on Pooled connection wrapping physical connection org.postgresql.jdbc4.Jdbc4Connection@3f7ae5e4
2018-12-13 11:34:06.045 +0300 SEVERE: org.apache.catalina.core.ContainerBase.[Catalina].[localhost].[/bonita] Exception sending context initialized event to listener instance of class [org.bonitasoft.engine.api.internal.servlet.EngineInitializerListener]
java.lang.RuntimeException: Error while initializing the Engine
    at org.bonitasoft.engine.api.internal.servlet.EngineInitializerListener.contextInitialized(EngineInitializerListener.java:35)
    at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4792)
    at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5256)
    at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
    at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:754)
    at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:730)
    at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:734)
    at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:629)
    at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1839)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)
Caused by: org.bonitasoft.engine.platform.StartNodeException: Platform starting failed while initializing platform classloaders.
    at org.bonitasoft.engine.api.impl.PlatformAPIImpl.startNode(PlatformAPIImpl.java:207)
    at org.bonitasoft.engine.EngineInitializer.initializeEngine(EngineInitializer.java:75)
    at org.bonitasoft.engine.api.internal.servlet.EngineInitializerListener.contextInitialized(EngineInitializerListener.java:33)
    ... 13 more
Caused by: org.bonitasoft.engine.dependency.SDependencyException: Can't get dependencies
    at org.bonitasoft.engine.dependency.impl.AbstractDependencyService.getDependencyIds(AbstractDependencyService.java:121)
    at org.bonitasoft.engine.dependency.impl.AbstractDependencyService.getDependenciesResources(AbstractDependencyService.java:89)
    at org.bonitasoft.engine.dependency.impl.DependencyServiceImpl.refreshClassLoader(DependencyServiceImpl.java:259)
    at org.bonitasoft.engine.api.impl.transaction.SetServiceState.refreshClassloaderOfProcessDefinitions(SetServiceState.java:100)
    at org.bonitasoft.engine.api.impl.transaction.SetServiceState.call(SetServiceState.java:63)
    at org.bonitasoft.engine.api.impl.transaction.SetServiceState.call(SetServiceState.java:1)
    at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
    at org.bonitasoft.engine.api.impl.PlatformAPIImpl.startServicesOfTenants(PlatformAPIImpl.java:397)
    at org.bonitasoft.engine.api.impl.PlatformAPIImpl.startNode(PlatformAPIImpl.java:195)
    ... 15 more
Caused by: org.bonitasoft.engine.persistence.SBonitaReadException: org.hibernate.exception.GenericJDBCException: could not prepare statement
    at org.bonitasoft.engine.persistence.AbstractHibernatePersistenceService.selectList(AbstractHibernatePersistenceService.java:466)
    at org.bonitasoft.engine.dependency.impl.AbstractDependencyService.getDependencyIds(AbstractDependencyService.java:119)
    ... 23 more
Caused by: org.hibernate.exception.GenericJDBCException: could not prepare statement
    at org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:54)
    at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:126)
    at org.hibernate.engine.jdbc.internal.StatementPreparerImpl$StatementPreparationTemplate.prepareStatement(StatementPreparerImpl.java:196)
    at org.hibernate.engine.jdbc.internal.StatementPreparerImpl.prepareQueryStatement(StatementPreparerImpl.java:160)
    at org.hibernate.loader.Loader.prepareQueryStatement(Loader.java:1885)
    at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1862)
    at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1839)
    at org.hibernate.loader.Loader.doQuery(Loader.java:910)
    at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:355)
    at org.hibernate.loader.Loader.doList(Loader.java:2554)
    at org.hibernate.loader.Loader.doList(Loader.java:2540)
    at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2370)
    at org.hibernate.loader.Loader.list(Loader.java:2365)
    at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:497)
    at org.hibernate.hql.internal.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:387)
    at org.hibernate.engine.query.spi.HQLQueryPlan.performList(HQLQueryPlan.java:236)
    at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1300)
    at org.hibernate.internal.QueryImpl.list(QueryImpl.java:103)
    at org.bonitasoft.engine.persistence.AbstractHibernatePersistenceService.selectList(AbstractHibernatePersistenceService.java:457)
    ... 24 more
Caused by: java.sql.SQLException: error enlisting a JdbcConnectionHandle of a JdbcPooledConnection from datasource jdbc/bonitaDSXA in state ACCESSIBLE with usage count 1 wrapping org.postgresql.xa.PGXAConnection@32565de4 on Pooled connection wrapping physical connection org.postgresql.jdbc4.Jdbc4Connection@3f7ae5e4
    at bitronix.tm.resource.jdbc.JdbcConnectionHandle.enlistResource(JdbcConnectionHandle.java:87)
    at bitronix.tm.resource.jdbc.JdbcConnectionHandle.prepareStatement(JdbcConnectionHandle.java:242)
    at sun.reflect.GeneratedMethodAccessor57.invoke(Unknown Source)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:498)
    at bitronix.tm.resource.jdbc.BaseProxyHandlerClass.invoke(BaseProxyHandlerClass.java:64)
    at com.sun.proxy.$Proxy15.prepareStatement(Unknown Source)
    at org.hibernate.engine.jdbc.internal.StatementPreparerImpl$5.doPrepare(StatementPreparerImpl.java:162)
    at org.hibernate.engine.jdbc.internal.StatementPreparerImpl$StatementPreparationTemplate.prepareStatement(StatementPreparerImpl.java:186)
    ... 40 more
Caused by: bitronix.tm.internal.BitronixSystemException: transaction timed out
    at bitronix.tm.resource.common.TransactionContextHelper.enlistInCurrentTransaction(TransactionContextHelper.java:63)
    at bitronix.tm.resource.jdbc.JdbcConnectionHandle.enlistResource(JdbcConnectionHandle.java:85)
    ... 48 more

2018-12-13 11:34:06.259 +0300 SEVERE: org.bonitasoft.engine.api.impl.ServerAPIImpl THREAD_ID=18 | HOSTNAME=DenemeBPM | Node not started. Method 'org.bonitasoft.engine.api.PlatformAPI.getClientPlatformConfigurations' cannot be called until node has been started (PlatformAPI.startNode()). Exact class: org.bonitasoft.engine.api.impl.PlatformAPIImpl
2018-12-13 11:34:06.260 +0300 SEVERE: org.apache.catalina.core.ContainerBase.[Catalina].[localhost].[/bonita] Exception sending context initialized event to listener instance of class [org.bonitasoft.console.common.server.servlet.PlatformTenantListener]
org.bonitasoft.engine.platform.NodeNotStartedException: USERNAME=local | The current node has not been started yet. Method PlatformAPI.startNode() must be called previously.
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.checkMethodAccessibility(ServerAPIImpl.java:287)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl$2.call(ServerAPIImpl.java:430)
    at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.invokeAPIInTransaction(ServerAPIImpl.java:438)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.invokeAPI(ServerAPIImpl.java:272)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.invokeMethod(ServerAPIImpl.java:124)
    at org.bonitasoft.engine.api.impl.ClientInterceptor.invoke(ClientInterceptor.java:86)
    at com.sun.proxy.$Proxy43.getClientPlatformConfigurations(Unknown Source)
    at org.bonitasoft.console.common.server.utils.PlatformManagementUtils.retrievePlatformConfiguration(PlatformManagementUtils.java:101)
    at org.bonitasoft.console.common.server.utils.PlatformManagementUtils.initializePlatformConfiguration(PlatformManagementUtils.java:113)
    at org.bonitasoft.console.common.server.servlet.PlatformTenantListener.contextInitialized(PlatformTenantListener.java:52)
    at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4792)
    at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5256)
    at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
    at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:754)
    at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:730)
    at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:734)
    at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:629)
    at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1839)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)
2018-12-13 11:34:06.278 +0300 INFO: org.bonitasoft.engine.EngineInitializer Stopping Bonita Engine...
2018-12-13 11:34:06.278 +0300 INFO: org.bonitasoft.engine.EngineInitializer Node is not started, nothing to do.
2018-12-13 11:34:06.299 +0300 INFO: org.bonitasoft.engine.EngineInitializer Bonita Engine stopped!
2018-12-13 11:38:45.631 +0300 INFO: org.bonitasoft.engine.EngineInitializer Initializing Bonita Engine...
2018-12-13 11:39:04.426 +0300 INFO: org.bonitasoft.engine.EngineInitializer Platform is already initialized.
2018-12-13 11:39:04.428 +0300 INFO: org.bonitasoft.engine.EngineInitializer Starting node...
2018-12-13 11:40:09.364 +0300 WARNING: bitronix.tm.BitronixTransaction transaction timed out: a Bitronix Transaction with GTRID [626F6E6974612D746F6D6361742D62746D2D6E6F6465300000000042386B5B0000000B], status=MARKED_ROLLBACK, 1 resource(s) enlisted (started Tue Jan 13 22:36:33 EET 1970)
2018-12-13 11:40:09.428 +0300 SEVERE: org.apache.catalina.core.ContainerBase.[Catalina].[localhost].[/bonita] Exception sending context initialized event to listener instance of class [org.bonitasoft.engine.api.internal.servlet.EngineInitializerListener]
java.lang.RuntimeException: Error while initializing the Engine
    at org.bonitasoft.engine.api.internal.servlet.EngineInitializerListener.contextInitialized(EngineInitializerListener.java:35)
    at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4792)
    at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5256)
    at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
    at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:754)
    at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:730)
    at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:734)
    at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:629)
    at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1839)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)
Caused by: org.bonitasoft.engine.platform.StartNodeException: Platform starting failed while initializing platform classloaders.
    at org.bonitasoft.engine.api.impl.PlatformAPIImpl.startNode(PlatformAPIImpl.java:207)
    at org.bonitasoft.engine.EngineInitializer.initializeEngine(EngineInitializer.java:75)
    at org.bonitasoft.engine.api.internal.servlet.EngineInitializerListener.contextInitialized(EngineInitializerListener.java:33)
    ... 13 more
Caused by: org.bonitasoft.engine.dependency.SDependencyException: Cannot refresh classLoader with type'PROCESS' and id 6662005412427139991
    at org.bonitasoft.engine.dependency.impl.DependencyServiceImpl.refreshClassLoader(DependencyServiceImpl.java:263)
    at org.bonitasoft.engine.api.impl.transaction.SetServiceState.refreshClassloaderOfProcessDefinitions(SetServiceState.java:100)
    at org.bonitasoft.engine.api.impl.transaction.SetServiceState.call(SetServiceState.java:63)
    at org.bonitasoft.engine.api.impl.transaction.SetServiceState.call(SetServiceState.java:1)
    at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
    at org.bonitasoft.engine.api.impl.PlatformAPIImpl.startServicesOfTenants(PlatformAPIImpl.java:397)
    at org.bonitasoft.engine.api.impl.PlatformAPIImpl.startNode(PlatformAPIImpl.java:195)
    ... 15 more
Caused by: org.bonitasoft.engine.classloader.SClassLoaderException: org.bonitasoft.engine.commons.exceptions.SBonitaRuntimeException: org.bonitasoft.engine.dependency.SDependencyNotFoundException: Can't get dependency with id: 1814
    at org.bonitasoft.engine.classloader.ClassLoaderServiceImpl.refreshLocalClassLoader(ClassLoaderServiceImpl.java:200)
    at org.bonitasoft.engine.dependency.impl.DependencyServiceImpl.refreshClassLoader(DependencyServiceImpl.java:261)
    ... 21 more
Caused by: org.bonitasoft.engine.commons.exceptions.SBonitaRuntimeException: org.bonitasoft.engine.dependency.SDependencyNotFoundException: Can't get dependency with id: 1814
    at org.bonitasoft.engine.dependency.impl.AbstractDependencyService.lambda$getDependenciesResources$0(AbstractDependencyService.java:95)
    at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
    at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1382)
    at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481)
    at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471)
    at java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:151)
    at java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:174)
    at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
    at java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:418)
    at org.bonitasoft.engine.classloader.BonitaClassLoader.addResources(BonitaClassLoader.java:101)
    at org.bonitasoft.engine.classloader.BonitaClassLoader.<init>(BonitaClassLoader.java:70)
    at org.bonitasoft.engine.classloader.ClassLoaderServiceImpl.refreshClassLoader(ClassLoaderServiceImpl.java:210)
    at org.bonitasoft.engine.classloader.ClassLoaderServiceImpl.refreshLocalClassLoader(ClassLoaderServiceImpl.java:194)
    ... 22 more
Caused by: org.bonitasoft.engine.dependency.SDependencyNotFoundException: Can't get dependency with id: 1814
    at org.bonitasoft.engine.dependency.impl.DependencyServiceImpl.getDependency(DependencyServiceImpl.java:243)
    at org.bonitasoft.engine.dependency.impl.AbstractDependencyService.lambda$getDependenciesResources$0(AbstractDependencyService.java:93)
    ... 34 more
Caused by: org.bonitasoft.engine.persistence.SBonitaReadException: org.bonitasoft.engine.services.SPersistenceException: org.hibernate.HibernateException: Current transaction is not in progress
    at org.bonitasoft.engine.persistence.AbstractHibernatePersistenceService.selectById(AbstractHibernatePersistenceService.java:358)
    at org.bonitasoft.engine.dependency.impl.DependencyServiceImpl.getDependency(DependencyServiceImpl.java:237)
    ... 35 more
Caused by: org.bonitasoft.engine.services.SPersistenceException: org.hibernate.HibernateException: Current transaction is not in progress
    at org.bonitasoft.engine.persistence.AbstractHibernatePersistenceService.getSession(AbstractHibernatePersistenceService.java:206)
    at org.bonitasoft.engine.persistence.TenantHibernatePersistenceService.getSession(TenantHibernatePersistenceService.java:104)
    at org.bonitasoft.engine.persistence.AbstractHibernatePersistenceService.selectById(AbstractHibernatePersistenceService.java:351)
    ... 36 more
Caused by: org.hibernate.HibernateException: Current transaction is not in progress
    at org.hibernate.context.internal.JTASessionContext.currentSession(JTASessionContext.java:98)
    at org.hibernate.internal.SessionFactoryImpl.getCurrentSession(SessionFactoryImpl.java:1014)
    at org.bonitasoft.engine.persistence.AbstractHibernatePersistenceService.getSession(AbstractHibernatePersistenceService.java:204)
    ... 38 more

2018-12-13 11:40:09.644 +0300 SEVERE: org.bonitasoft.engine.api.impl.ServerAPIImpl THREAD_ID=18 | HOSTNAME=DenemeBPM | Node not started. Method 'org.bonitasoft.engine.api.PlatformAPI.getClientPlatformConfigurations' cannot be called until node has been started (PlatformAPI.startNode()). Exact class: org.bonitasoft.engine.api.impl.PlatformAPIImpl
2018-12-13 11:40:09.645 +0300 SEVERE: org.apache.catalina.core.ContainerBase.[Catalina].[localhost].[/bonita] Exception sending context initialized event to listener instance of class [org.bonitasoft.console.common.server.servlet.PlatformTenantListener]
org.bonitasoft.engine.platform.NodeNotStartedException: USERNAME=local | The current node has not been started yet. Method PlatformAPI.startNode() must be called previously.
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.checkMethodAccessibility(ServerAPIImpl.java:287)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl$2.call(ServerAPIImpl.java:430)
    at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.invokeAPIInTransaction(ServerAPIImpl.java:438)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.invokeAPI(ServerAPIImpl.java:272)
    at org.bonitasoft.engine.api.impl.ServerAPIImpl.invokeMethod(ServerAPIImpl.java:124)
    at org.bonitasoft.engine.api.impl.ClientInterceptor.invoke(ClientInterceptor.java:86)
    at com.sun.proxy.$Proxy43.getClientPlatformConfigurations(Unknown Source)
    at org.bonitasoft.console.common.server.utils.PlatformManagementUtils.retrievePlatformConfiguration(PlatformManagementUtils.java:101)
    at org.bonitasoft.console.common.server.utils.PlatformManagementUtils.initializePlatformConfiguration(PlatformManagementUtils.java:113)
    at org.bonitasoft.console.common.server.servlet.PlatformTenantListener.contextInitialized(PlatformTenantListener.java:52)
    at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4792)
    at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5256)
    at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
    at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:754)
    at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:730)
    at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:734)
    at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:629)
    at org.apache.catalina.startup.HostConfig$DeployDescriptor.run(HostConfig.java:1839)
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)

2018-12-13 11:40:09.671 +0300 INFO: org.bonitasoft.engine.EngineInitializer Stopping Bonita Engine...
2018-12-13 11:40:09.672 +0300 INFO: org.bonitasoft.engine.EngineInitializer Node is not started, nothing to do.
2018-12-13 11:40:09.693 +0300 INFO: org.bonitasoft.engine.EngineInitializer Bonita Engine stopped!
Submitted by pierrick.voulet on Thu, 12/13/2018 - 14:52

It seems that Bitronix (the transaction manager used with Tomcat) takes too much time to load the dependencies and reach a transaction timeout. Because it is time related, it never fails loading the exact same dependency even though the issue is the same.

To fix that, the best is to increase the Bitronix transaction timeout (60 seconds by default as described here ). You will need to use the Bonita Platform Setup tool to pull, edit and push the "bitronix.tm.timer.defaultTransactionTimeout=120" property in the file "tomcat-templates/bitronix-config.properties".

This might be due to an unusual work to be done during the first run after the migration or DB connection latency. I suggest that you give another try rollbacking to the initial 60 seconds if you make it run smoothly with 120.

Let us know how it goes!

Submitted by msakirkutlu_1359205 on Mon, 12/17/2018 - 15:32

I tried to change Bitronix transaction timeout but i couldn't manage to that. I am not sure what i am doing wrong. The steps that i tried;
1- setup.sh pull
2- add line "bitronix.tm.timer.defaultTransactionTimeout=120" to "tomcat-templates/bitronix-resources.properties" file
3- setup.sh push

Didn't worked.

1-setup.sh pull
2- add line "bitronix.tm.timer.defaultTransactionTimeout=120" to "tomcat-templates/bitronix-resources.properties" file
3- setup.sh configure
4- setup.sh push

that didn't work either.

Notifications