2019-10-13 11:46:36,452Z (22:46) [main] INFO System.out - Creating a DevTest Registry, name=Registry 2019-10-13 11:46:36,496Z (22:46) [main] INFO com.itko.lisa.coordinator.TestRegistryImpl - User cache initialized with timeout in seconds 1200 2019-10-13 11:46:36,986Z (22:46) [main] INFO com.itko.lisa.coordinator.TestRegistryImpl - Authentication module class name=com.itko.lisa.acl.standards.ITKOAuthenticationModule 2019-10-13 11:46:37,010Z (22:46) [main] INFO com.itko.lisa.coordinator.TestRegistryImpl - Authentication module jar files are= 2019-10-13 11:46:38,769Z (22:46) [Derby DB] INFO System.out - Sun Oct 13 22:46:38 AEDT 2019 : Apache Derby Network Server - 10.8.3.0 - (1405108) started and ready to accept connections on port 1528 2019-10-13 11:46:43,282Z (22:46) [main] INFO com.itko.lisa.report.db.InternalDatabase - Internal database running on 0.0.0.0 port 1528 2019-10-13 11:46:43,901Z (22:46) [main] INFO com.itko.lisa.utils.db.ConnectionPoolManager - Created pool named common 2019-10-13 11:46:43,913Z (22:46) [main] INFO org.eclipse.persistence - EclipseLink, version: Eclipse Persistence Services - 2.3.2.v20111125-r10461 2019-10-13 11:47:06,400Z (22:47) [Event Sink Thread Pool Thread 2] INFO com.itko.lisa.coordinator.TestRegistryImpl - Coordinator Servers: 0 Simulator Servers: 0 VSEs: 0 Running vusers: 0 Labs: 1 Memory used 152mb, allocated 231mb, max 892mb (17%) Our cpu usage 0%, system cpu used 7% GC time 0% db ping: 1 ms 2019-10-13 11:47:19,215Z (22:47) [main] ERROR com.itko.lisa.utils.JpaUtil - Exception raised checking table existence: Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.3.2.v20111125-r10461): org.eclipse.persistence.exceptions.DatabaseException Internal Exception: java.sql.SQLException: Connections could not be acquired from the underlying database! Error Code: 0 javax.persistence.PersistenceException: Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.3.2.v20111125-r10461): org.eclipse.persistence.exceptions.DatabaseException Internal Exception: java.sql.SQLException: Connections could not be acquired from the underlying database! Error Code: 0 at org.eclipse.persistence.internal.jpa.EntityManagerSetupImpl.deploy(EntityManagerSetupImpl.java:517) at org.eclipse.persistence.internal.jpa.EntityManagerFactoryDelegate.getDatabaseSession(EntityManagerFactoryDelegate.java:188) at org.eclipse.persistence.internal.jpa.EntityManagerFactoryDelegate.createEntityManagerImpl(EntityManagerFactoryDelegate.java:277) at org.eclipse.persistence.internal.jpa.EntityManagerFactoryImpl.createEntityManagerImpl(EntityManagerFactoryImpl.java:294) at org.eclipse.persistence.internal.jpa.EntityManagerFactoryImpl.createEntityManager(EntityManagerFactoryImpl.java:272) at com.itko.lisa.utils.JpaUtil.allTablesExist(JpaUtil.java:599) at com.itko.lisa.utils.JpaUtil.(JpaUtil.java:490) at com.itko.lisa.utils.JpaUtil.getDradisUtil(JpaUtil.java:406) at com.itko.lisa.dradis.comm.JpaUtilWrapper.getAnnotations(JpaUtilWrapper.java:24) at com.itko.lisa.dradis.comm.DradisCommContext.doEnterpriseDashboardHandshake(DradisCommContext.java:133) at com.itko.lisa.coordinator.TestRegistryImpl.initialize(TestRegistryImpl.java:280) at com.itko.lisa.coordinator.TestRegistryImpl.(TestRegistryImpl.java:239) at com.itko.lisa.coordinator.TestRegistryImpl.main(TestRegistryImpl.java:2163) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.exe4j.runtime.LauncherEngine.launch(Unknown Source) at com.exe4j.runtime.WinLauncher$2.run(Unknown Source) Caused by: Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.3.2.v20111125-r10461): org.eclipse.persistence.exceptions.DatabaseException Internal Exception: java.sql.SQLException: Connections could not be acquired from the underlying database! Error Code: 0 at org.eclipse.persistence.exceptions.DatabaseException.sqlException(DatabaseException.java:309) at org.eclipse.persistence.sessions.JNDIConnector.connect(JNDIConnector.java:135) at org.eclipse.persistence.sessions.DatasourceLogin.connectToDatasource(DatasourceLogin.java:162) at org.eclipse.persistence.internal.databaseaccess.DatasourceAccessor.connectInternal(DatasourceAccessor.java:330) at org.eclipse.persistence.internal.databaseaccess.DatabaseAccessor.connectInternal(DatabaseAccessor.java:293) at org.eclipse.persistence.internal.databaseaccess.DatasourceAccessor.connect(DatasourceAccessor.java:418) at org.eclipse.persistence.sessions.server.ConnectionPool.buildConnection(ConnectionPool.java:216) at org.eclipse.persistence.sessions.server.ConnectionPool.startUp(ConnectionPool.java:504) at org.eclipse.persistence.sessions.server.ServerSession.connect(ServerSession.java:484) at org.eclipse.persistence.internal.sessions.DatabaseSessionImpl.login(DatabaseSessionImpl.java:633) at org.eclipse.persistence.internal.jpa.EntityManagerFactoryProvider.login(EntityManagerFactoryProvider.java:208) at org.eclipse.persistence.internal.jpa.EntityManagerSetupImpl.deploy(EntityManagerSetupImpl.java:488) ... 18 more Caused by: java.sql.SQLException: Connections could not be acquired from the underlying database! at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118) at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:690) at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140) at com.itko.lisa.utils.db.LisaWrappedDataSource.getConnection(LisaWrappedDataSource.java:38) at org.eclipse.persistence.sessions.JNDIConnector.connect(JNDIConnector.java:123) ... 28 more Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source. at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1463) at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:639) at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:549) at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:756) at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:683) ... 31 more 2019-10-13 11:47:19,217Z (22:47) [main] ERROR com.itko.lisa.utils.JpaUtil - ==== #################################### ==== 2019-10-13 11:47:19,217Z (22:47) [main] ERROR com.itko.lisa.utils.JpaUtil - Connection to the registry database cannot be established. Please make sure the database is running and the credential used to connect to database is valid. 2019-10-13 11:47:19,217Z (22:47) [main] ERROR com.itko.lisa.utils.JpaUtil - ==== #################################### ==== 2019-10-13 11:47:19,217Z (22:47) [main] ERROR System.err - Connection to the registry database cannot be established. Please make sure the database is running and the credential used to connect to database is valid. 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - java.lang.RuntimeException: Connection to the registry database cannot be established. Please make sure the database is running and the credential used to connect to database is valid. 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at com.itko.lisa.utils.JpaUtil.(JpaUtil.java:504) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at com.itko.lisa.utils.JpaUtil.getDradisUtil(JpaUtil.java:406) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at com.itko.lisa.dradis.comm.JpaUtilWrapper.getAnnotations(JpaUtilWrapper.java:24) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at com.itko.lisa.dradis.comm.DradisCommContext.doEnterpriseDashboardHandshake(DradisCommContext.java:133) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at com.itko.lisa.coordinator.TestRegistryImpl.initialize(TestRegistryImpl.java:280) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at com.itko.lisa.coordinator.TestRegistryImpl.(TestRegistryImpl.java:239) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at com.itko.lisa.coordinator.TestRegistryImpl.main(TestRegistryImpl.java:2163) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at java.lang.reflect.Method.invoke(Method.java:498) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at com.exe4j.runtime.LauncherEngine.launch(Unknown Source) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - at com.exe4j.runtime.WinLauncher$2.run(Unknown Source) 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - Caused by: javax.persistence.PersistenceException: Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.3.2.v20111125-r10461): org.eclipse.persistence.exceptions.DatabaseException 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - Internal Exception: java.sql.SQLException: Connections could not be acquired from the underlying database! 2019-10-13 11:47:19,218Z (22:47) [main] ERROR System.err - Error Code: 0 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.jpa.EntityManagerSetupImpl.deploy(EntityManagerSetupImpl.java:517) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.jpa.EntityManagerFactoryDelegate.getDatabaseSession(EntityManagerFactoryDelegate.java:188) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.jpa.EntityManagerFactoryDelegate.createEntityManagerImpl(EntityManagerFactoryDelegate.java:277) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.jpa.EntityManagerFactoryImpl.createEntityManagerImpl(EntityManagerFactoryImpl.java:294) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.jpa.EntityManagerFactoryImpl.createEntityManager(EntityManagerFactoryImpl.java:272) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at com.itko.lisa.utils.JpaUtil.allTablesExist(JpaUtil.java:599) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at com.itko.lisa.utils.JpaUtil.(JpaUtil.java:490) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - ... 12 more 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - Caused by: Exception [EclipseLink-4002] (Eclipse Persistence Services - 2.3.2.v20111125-r10461): org.eclipse.persistence.exceptions.DatabaseException 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - Internal Exception: java.sql.SQLException: Connections could not be acquired from the underlying database! 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - Error Code: 0 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.exceptions.DatabaseException.sqlException(DatabaseException.java:309) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.sessions.JNDIConnector.connect(JNDIConnector.java:135) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.sessions.DatasourceLogin.connectToDatasource(DatasourceLogin.java:162) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.databaseaccess.DatasourceAccessor.connectInternal(DatasourceAccessor.java:330) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.databaseaccess.DatabaseAccessor.connectInternal(DatabaseAccessor.java:293) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.databaseaccess.DatasourceAccessor.connect(DatasourceAccessor.java:418) 2019-10-13 11:47:19,219Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.sessions.server.ConnectionPool.buildConnection(ConnectionPool.java:216) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.sessions.server.ConnectionPool.startUp(ConnectionPool.java:504) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.sessions.server.ServerSession.connect(ServerSession.java:484) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.sessions.DatabaseSessionImpl.login(DatabaseSessionImpl.java:633) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.jpa.EntityManagerFactoryProvider.login(EntityManagerFactoryProvider.java:208) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.internal.jpa.EntityManagerSetupImpl.deploy(EntityManagerSetupImpl.java:488) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - ... 18 more 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - Caused by: java.sql.SQLException: Connections could not be acquired from the underlying database! 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:690) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at com.itko.lisa.utils.db.LisaWrappedDataSource.getConnection(LisaWrappedDataSource.java:38) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at org.eclipse.persistence.sessions.JNDIConnector.connect(JNDIConnector.java:123) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - ... 28 more 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source. 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1463) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:639) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:549) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:756) 2019-10-13 11:47:19,220Z (22:47) [main] ERROR System.err - at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:683) 2019-10-13 11:47:19,221Z (22:47) [main] ERROR System.err - ... 31 more 2019-10-13 11:47:19,222Z (22:47) [Thread-7] INFO com.itko.lisa.net.ActiveMQFactory - Closing AMQ connections