IBM UrbanCode Deploy-A ResourcePool could not acquire a resource from its primary factory or source


IBM UrbanCode Deploy-A ResourcePool could not acquire a resource from its primary factory or source

Execute ./server run and observe any errors (Marked in Red here).

Run ./server run again.

[root@localhost bin]# ./server run

2015-08-02 07:11:52,527 INFO  main com.urbancode.ds.persistence.database.DerbyHelper – Starting Derby…
2015-08-02 07:11:53,798 INFO  main com.urbancode.ds.persistence.database.DerbyHelper – Waiting for Derby to start…
2015-08-02 07:11:53,956 INFO  main com.urbancode.ds.persistence.database.DerbyHelper – The Derby instance on localhost:11377 is started
2015-08-02 07:12:33,370 WARN  com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread-#2 com.mchange.v2.resourcepool.BasicResourcePool – com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask@f5ca1bd — Acquisition Attempt Failed!!! Clearing pending acquires. While trying to acquire a needed new resource, we failed to succeed more than the maximum number of allowed acquisition attempts (30). Last acquisition attempt exception:
java.sql.SQLNonTransientConnectionException: java.net.ConnectException : Error connecting to server localhost on port 11377 with message Connection refused.
at org.apache.derby.client.am.SQLExceptionFactory40.getSQLException(Unknown Source)
at org.apache.derby.client.am.SqlException.getSQLException(Unknown Source)
at org.apache.derby.jdbc.ClientDriver.connect(Unknown Source)
at com.mchange.v2.c3p0.DriverManagerDataSource.getConnection(DriverManagerDataSource.java:134)
at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:182)
at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:171)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:137)
at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1007)
at com.mchange.v2.resourcepool.BasicResourcePool.access$800(BasicResourcePool.java:32)
at com.mchange.v2.resourcepool.BasicResourcePool$AcquireTask.run(BasicResourcePool.java:1783)
at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:547)
Caused by: org.apache.derby.client.am.DisconnectException: java.net.ConnectException : Error connecting to server localhost on port 11377 with message Connection refused.
at org.apache.derby.client.net.NetAgent.<init>(Unknown Source)
at org.apache.derby.client.net.NetConnection.newAgent_(Unknown Source)
at org.apache.derby.client.am.Connection.<init>(Unknown Source)
at org.apache.derby.client.net.NetConnection.<init>(Unknown Source)
at org.apache.derby.client.net.NetConnection40.<init>(Unknown Source)
at org.apache.derby.client.net.ClientJDBCObjectFactoryImpl40.newNetConnection(Unknown Source)
… 9 more
Caused by: java.net.ConnectException: Connection refused
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339)
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200)
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
at java.net.Socket.connect(Socket.java:579)
at java.net.Socket.connect(Socket.java:528)
at java.net.Socket.<init>(Socket.java:425)
at java.net.Socket.<init>(Socket.java:208)
at javax.net.DefaultSocketFactory.createSocket(SocketFactory.java:271)
at org.apache.derby.client.net.OpenSocketAction.run(Unknown Source)
at java.security.AccessController.doPrivileged(Native Method)
… 15 more
2015-08-02 07:12:33,383 WARN  main org.hibernate.cfg.SettingsFactory – Could not obtain connection to query metadata
java.sql.SQLException: Connections could not be acquired from the underlying database!
at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:106)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:529)
at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:128)
at org.hibernate.connection.C3P0ConnectionProvider.getConnection(C3P0ConnectionProvider.java:78)
at org.hibernate.cfg.SettingsFactory.buildSettings(SettingsFactory.java:114)
at org.hibernate.cfg.Configuration.buildSettingsInternal(Configuration.java:2836)
at org.hibernate.cfg.Configuration.buildSettings(Configuration.java:2832)
at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1843)
at com.urbancode.ds.PersistenceConfig.initialize(PersistenceConfig.java:310)
at com.urbancode.ds.PersistenceConfig.configureFromProperties(PersistenceConfig.java:121)
at com.urbancode.ds.UDeployServer.startPersistence(UDeployServer.java:1155)
at com.urbancode.ds.UDeployServer.start(UDeployServer.java:380)
at com.urbancode.container.tomcat.Container.run(Container.java:103)
at com.urbancode.ds.UDeployServer.main(UDeployServer.java:294)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.urbancode.launcher.Launcher.invokeMainMethod(Launcher.java:235)
at com.urbancode.launcher.Launcher.launch(Launcher.java:161)
at com.urbancode.launcher.Launcher.main(Launcher.java:89)
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:1307)
at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:551)
at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:471)
at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:525)
… 19 more
2015-08-02 07:12:33,436 ERROR main com.urbancode.container.tomcat.Container – Failed to start IBM UrbanCode Deploy
java.lang.RuntimeException: Failed to start IBM UrbanCode Deploy
at com.urbancode.ds.UDeployServer.start(UDeployServer.java:445)
at com.urbancode.container.tomcat.Container.run(Container.java:103)
at com.urbancode.ds.UDeployServer.main(UDeployServer.java:294)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.urbancode.launcher.Launcher.invokeMainMethod(Launcher.java:235)
at com.urbancode.launcher.Launcher.launch(Launcher.java:161)
at com.urbancode.launcher.Launcher.main(Launcher.java:89)
Caused by: org.hibernate.HibernateException: ‘hibernate.dialect’ must be set when no Connection available
at org.hibernate.dialect.resolver.DialectFactory.buildDialect(DialectFactory.java:106)
at org.hibernate.cfg.SettingsFactory.buildSettings(SettingsFactory.java:148)
at org.hibernate.cfg.Configuration.buildSettingsInternal(Configuration.java:2836)
at org.hibernate.cfg.Configuration.buildSettings(Configuration.java:2832)
at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1843)
at com.urbancode.ds.PersistenceConfig.initialize(PersistenceConfig.java:310)
at com.urbancode.ds.PersistenceConfig.configureFromProperties(PersistenceConfig.java:121)
at com.urbancode.ds.UDeployServer.startPersistence(UDeployServer.java:1155)
at com.urbancode.ds.UDeployServer.start(UDeployServer.java:380)
… 9 more
2015-08-02 07:12:33,438 INFO  main com.urbancode.ds.UDeployServer – Checking in server license…
2015-08-02 07:12:33,440 ERROR main com.urbancode.ds.UDeployServer – Error checking in server license
java.lang.IllegalStateException: No TX manager set
at com.urbancode.ds.persistence.database.UDTxManagerLookup.get(UDTxManagerLookup.java:34)
at com.urbancode.ds.UDeployServer.unlicenseServer(UDeployServer.java:688)
at com.urbancode.ds.UDeployServer.shutdown(UDeployServer.java:830)
at com.urbancode.container.tomcat.Container.run(Container.java:114)
at com.urbancode.ds.UDeployServer.main(UDeployServer.java:294)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.urbancode.launcher.Launcher.invokeMainMethod(Launcher.java:235)
at com.urbancode.launcher.Launcher.launch(Launcher.java:161)
at com.urbancode.launcher.Launcher.main(Launcher.java:89)
2015-08-02 07:12:33,446 INFO  main com.urbancode.ds.UDeployServer –     done
2015-08-02 07:12:33,446 INFO  main com.urbancode.ds.UDeployServer – Stopping codestation…
2015-08-02 07:12:33,446 INFO  main com.urbancode.ds.UDeployServer –     stopped
2015-08-02 07:12:33,447 INFO  main com.urbancode.ds.UDeployServer – Stopping resource role scheduler…
2015-08-02 07:12:33,459 INFO  main com.urbancode.ds.UDeployServer –     stopped
2015-08-02 07:12:33,460 INFO  main com.urbancode.ds.UDeployServer – Stopping scheduler…
2015-08-02 07:12:33,475 INFO  main com.urbancode.ds.UDeployServer –     scheduler stopped
2015-08-02 07:12:33,475 INFO  main com.urbancode.ds.UDeployServer – Stopping event service…
2015-08-02 07:12:33,477 INFO  main com.urbancode.ds.UDeployServer –     event services stopped
2015-08-02 07:12:33,478 INFO  main com.urbancode.ds.UDeployServer –     done
2015-08-02 07:12:33,478 INFO  main com.urbancode.ds.UDeployServer – Stopping agent task processor…
2015-08-02 07:12:33,491 INFO  main com.urbancode.ds.UDeployServer –     done
2015-08-02 07:12:33,492 INFO  main com.urbancode.ds.UDeployServer – Stopping network connectors…
2015-08-02 07:12:33,492 ERROR main com.urbancode.ds.UDeployServer – Stopping network connectors
java.lang.IllegalStateException: No TX manager set
at com.urbancode.ds.persistence.database.UDTxManagerLookup.get(UDTxManagerLookup.java:34)
at com.urbancode.ds.UDeployServer.stopNetworkRelayConnectors(UDeployServer.java:1774)
at com.urbancode.ds.UDeployServer.shutdown(UDeployServer.java:900)
at com.urbancode.container.tomcat.Container.run(Container.java:114)
at com.urbancode.ds.UDeployServer.main(UDeployServer.java:294)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.urbancode.launcher.Launcher.invokeMainMethod(Launcher.java:235)
at com.urbancode.launcher.Launcher.launch(Launcher.java:161)
at com.urbancode.launcher.Launcher.main(Launcher.java:89)
2015-08-02 07:12:33,496 INFO  main com.urbancode.ds.UDeployServer –     done
2015-08-02 07:12:33,496 INFO  main com.urbancode.ds.UDeployServer – Stopping security system…
2015-08-02 07:12:33,497 INFO  main com.urbancode.ds.UDeployServer –     done
2015-08-02 07:12:33,497 INFO  main com.urbancode.ds.UDeployServer – Stopping auditing service…
2015-08-02 07:12:33,538 INFO  main com.urbancode.ds.UDeployServer –     done
2015-08-02 07:12:33,541 INFO  main com.urbancode.ds.UDeployServer – Cleaning up Hibernate transactions…
2015-08-02 07:12:33,541 INFO  main com.urbancode.ds.UDeployServer – Stopping hibernate…
2015-08-02 07:12:33,542 ERROR main com.urbancode.ds.UDeployServer – Stopping Hibernate session factory
java.lang.IllegalStateException: No TX manager set
at com.urbancode.ds.persistence.database.UDTxManagerLookup.get(UDTxManagerLookup.java:34)
at com.urbancode.ds.UDeployServer.shutdown(UDeployServer.java:1025)
at com.urbancode.container.tomcat.Container.run(Container.java:114)
at com.urbancode.ds.UDeployServer.main(UDeployServer.java:294)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.urbancode.launcher.Launcher.invokeMainMethod(Launcher.java:235)
at com.urbancode.launcher.Launcher.launch(Launcher.java:161)
at com.urbancode.launcher.Launcher.main(Launcher.java:89)
2015-08-02 07:12:33,543 INFO  main com.urbancode.ds.persistence.database.DerbyHelper – Shutting down Derby…
java.lang.RuntimeException: Shutting down Derby
at com.urbancode.ds.UDeployServer.shutdown(UDeployServer.java:1039)
at com.urbancode.container.tomcat.Container.run(Container.java:114)
at com.urbancode.ds.UDeployServer.main(UDeployServer.java:294)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.urbancode.launcher.Launcher.invokeMainMethod(Launcher.java:235)
at com.urbancode.launcher.Launcher.launch(Launcher.java:161)
at com.urbancode.launcher.Launcher.main(Launcher.java:89)
Caused by: java.lang.Exception: DRDA_NoIO.S:Could not connect to Derby Network Server on host 127.0.0.1, port 11377: Connection refused
at org.apache.derby.impl.drda.NetworkServerControlImpl.consolePropertyMessageWork(Unknown Source)
at org.apache.derby.impl.drda.NetworkServerControlImpl.consolePropertyMessage(Unknown Source)
at org.apache.derby.impl.drda.NetworkServerControlImpl.setUpSocket(Unknown Source)
at org.apache.derby.impl.drda.NetworkServerControlImpl.shutdown(Unknown Source)
at org.apache.derby.drda.NetworkServerControl.shutdown(Unknown Source)
at com.urbancode.ds.persistence.database.DerbyHelper.shutdown(DerbyHelper.java:69)
at com.urbancode.ds.UDeployServer.shutdown(UDeployServer.java:1036)
… 9 more

[root@localhost bin]# ./server run

2015-08-02 07:13:02,168 INFO  main com.urbancode.ds.persistence.database.DerbyHelper – Starting Derby…
2015-08-02 07:13:03,740 INFO  main com.urbancode.ds.persistence.database.DerbyHelper – Waiting for Derby to start…
2015-08-02 07:13:03,960 INFO  main com.urbancode.ds.persistence.database.DerbyHelper – The Derby instance on localhost:11377 is started
2015-08-02 07:13:18,560 WARN  main net.sf.ehcache.hibernate.AbstractEhcacheProvider – Could not find a specific ehcache configuration for cache named [com.urbancode.air.workflow.DispatchedTask]; using defaults.
2015-08-02 07:13:21,695 INFO  main com.urbancode.ds.UDeployServer – Configuring Security System…
2015-08-02 07:13:21,696 INFO  main com.urbancode.ds.UDeployServer – Configured authentication realms in JAAS.
2015-08-02 07:13:21,778 INFO  main com.urbancode.ds.UDeployServer –  done
2015-08-02 07:13:21,778 INFO  main com.urbancode.ds.UDeployServer – Starting Versioned Configuration…
2015-08-02 07:13:21,838 INFO  main com.urbancode.ds.UDeployServer –   done
2015-08-02 07:13:24,193 INFO  main com.urbancode.ds.UDeployServer – Starting Versioned File Storage…
2015-08-02 07:13:24,302 INFO  RecordMetadataService com.urbancode.air.vc.metadata.RecordMetadataService – All versioned metadata generators have been processed.
2015-08-02 07:13:24,305 INFO  main com.urbancode.ds.UDeployServer –   done
2015-08-02 07:13:24,475 INFO  main com.urbancode.ds.UDeployServer – Configuring Event Service…
2015-08-02 07:13:24,510 INFO  main com.urbancode.ds.UDeployServer –   done
2015-08-02 07:13:24,510 INFO  main com.urbancode.ds.UDeployServer – Starting calendar event scheduler…
2015-08-02 07:13:24,516 INFO  main com.urbancode.ds.subsys.calendar.service.CalendarEntryNotificationService – Started
2015-08-02 07:13:24,516 INFO  main com.urbancode.ds.UDeployServer –   done
2015-08-02 07:13:24,517 INFO  main com.urbancode.ds.UDeployServer – Configuring Notifications…
2015-08-02 07:13:26,298 INFO  main com.urbancode.ds.UDeployServer –   done
2015-08-02 07:13:26,299 INFO  main com.urbancode.ds.UDeployServer – Configuring email spooler…
2015-08-02 07:13:26,353 INFO  main com.urbancode.ds.UDeployServer –   done
2015-08-02 07:13:26,492 INFO  main com.urbancode.ds.UDeployServer – Workflow Engine Started
2015-08-02 07:13:28,179 INFO  main com.urbancode.ds.UDeployServer – Configuring Replication…
2015-08-02 07:13:28,180 INFO  main com.urbancode.ds.UDeployServer –  replication disabled
2015-08-02 07:13:28,181 INFO  main com.urbancode.ds.UDeployServer –  done
2015-08-02 07:13:28,181 INFO  main com.urbancode.ds.UDeployServer – Configuring Replicated Codestation…
2015-08-02 07:13:28,466 INFO  main com.urbancode.ds.UDeployServer –  done
2015-08-02 07:13:28,470 INFO  codestation.upgrader com.urbancode.ds.repl.codestation.upgrade.CodestationUpgrader – Upgrader Running.
2015-08-02 07:13:28,969 INFO  codestation.upgrader com.urbancode.ds.repl.codestation.upgrade.CodestationUpgrader – Finished upgrading versions.
2015-08-02 07:13:30,054 WARN  main com.urbancode.ds.UDeployServer – Property encryption.keystore.password not set. Using value from property server.keystore.password
2015-08-02 07:13:30,589 INFO  main com.urbancode.ds.UDeployServer – Configuring Agent Network System for single-server setup…
2015-08-02 07:13:43,130 INFO  main com.urbancode.ds.UDeployServer –  done
2015-08-02 07:13:43,922 INFO  main com.urbancode.ds.UDeployServer – IBM UrbanCode Deploy server started.

Advertisements

Leave a Reply

Fill in your details below or click an icon to log in:

WordPress.com Logo

You are commenting using your WordPress.com account. Log Out / Change )

Twitter picture

You are commenting using your Twitter account. Log Out / Change )

Facebook photo

You are commenting using your Facebook account. Log Out / Change )

Google+ photo

You are commenting using your Google+ account. Log Out / Change )

Connecting to %s