Community
Other links
CloudPortal Business Manager 2.x
CPBM 2.1 - Portal not starting up
CPBM 2.1 - Portal not starting up
Having started the server, CPBM 2.1 is not starting up. The following log extract shows where the error starts. Any idea what is causing this?
[2013-10-24 20:02:34.244] INFO start-signalling-2 org.eclipse.virgo.medic.eventlog.default DE0005I Started bundle 'vmops.admin' version '2.1.0'.
[2013-10-24 20:02:36.533] WARN deployer-recovery org.springframework.aop.framework.Cglib2AopProxy Unable to proxy method [public final java.lang.String org.springframework.context.support.AbstractMessageSource.getMessage(java.lang.String,java.lang.Object[],java.lang.String,java.util.Locale)] because it is final: All calls to this method via a proxy will be routed directly to the proxy.
[2013-10-24 20:02:36.534] WARN deployer-recovery org.springframework.aop.framework.Cglib2AopProxy Unable to proxy method [public final java.lang.String org.springframework.context.support.AbstractMessageSource.getMessage(java.lang.String,java.lang.Object[],java.util.Locale) throws org.springframework.context.NoSuchMessageException] because it is final: All calls to this method via a proxy will be routed directly to the proxy.
[2013-10-24 20:02:36.534] WARN deployer-recovery org.springframework.aop.framework.Cglib2AopProxy Unable to proxy method [public final java.lang.String org.springframework.context.support.AbstractMessageSource.getMessage(org.springframework.context.MessageSourceResolvable,java.util.Locale) throws org.springframework.context.NoSuchMessageException] because it is final: All calls to this method via a proxy will be routed directly to the proxy.
[2013-10-24 20:02:37.038] WARN deployer-recovery org.springframework.aop.framework.Cglib2AopProxy Unable to proxy method [public final void com.vmops.event.listeners.EventListenerJmsProducer.setJmsTemplate(org.springframework.jms.core.JmsTemplate)] because it is final: All calls to this method via a proxy will be routed directly to the proxy.
[2013-10-24 20:02:37.039] WARN deployer-recovery org.springframework.aop.framework.Cglib2AopProxy Unable to proxy method [public final void com.vmops.event.listeners.EventListenerJmsProducer.setDestinationqueue(java.lang.String)] because it is final: All calls to this method via a proxy will be routed directly to the proxy.
[2013-10-24 20:02:37.562] ERROR deployer-recovery org.eclipse.virgo.kernel.core.internal.BundleStartTracker Recording application context construction failure 'org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'schedulerFactoryBean' defined in URL [bundleentry://139.fwk2063246672/META-INF/spring/applicationContext-scheduler.xml]: Invocation of init method failed; nested exception is org.quartz.JobPersistenceException: Couldn't retrieve trigger: Communications link failure
The last packet successfully received from the server was 26,044 milliseconds ago. The last packet sent successfully to the server was 125 milliseconds ago. [See nested exception: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure
The last packet successfully received from the server was 26,044 milliseconds ago. The last packet sent successfully to the server was 125 milliseconds ago.]' for bundle 'citrix.cpbm.custom.common_2.1.0 [139]'
[2013-10-24 20:02:37.564] INFO start-signalling-2 org.eclipse.virgo.medic.eventlog.default DE0010I Stopping plan 'com.citrix.cpbm.plan' version '2.1.0'.
[2013-10-24 20:02:37.566] INFO start-signalling-2 org.eclipse.virgo.medic.eventlog.default DE0010I Stopping configuration 'cloud' version '0.0.0'.
[2013-10-24 20:02:37.573] INFO start-signalling-2 org.eclipse.virgo.medic.eventlog.default DE0011I Stopped configuration 'cloud' version '0.0.0'.
[2013-10-24 20:02:37.575] INFO start-signalling-2 org.eclipse.virgo.medic.eventlog.default DE0010I Stopping bundle 'citrix.cpbm.model' version '2.1.0'.
[2013-10-24 20:02:37.579] INFO start-signalling-2 org.eclipse.virgo.medic.eventlog.default DE0011I Stopped bundle 'citrix.cpbm.model' version '2.1.0'.
[2013-10-24 20:02:37.587] INFO start-signalling-2 org.eclipse.virgo.medic.eventlog.default DE0010I Stopping bundle 'citrix.cpbm.service' version '2.1.0'.
[2013-10-24 20:02:37.590] INFO start-signalling-2 org.eclipse.virgo.medic.eventlog.default DE0011I Stopped bundle 'citrix.cpbm.service' version '2.1.0'.
[2013-10-24 20:02:37.600] INFO start-signalling-2 org.eclipse.virgo.medic.eventlog.default DE0010I Stopping bundle 'vmops.core' version '2.1.0'.
[2013-10-24 20:02:37.681] ERROR deployer-recovery org.eclipse.virgo.medic.eventlog.default AG0000E Application context creation failure for bundle 'citrix.cpbm.custom.common' version '2.1.0'. org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'schedulerFactoryBean' defined in URL [bundleentry://139.fwk2063246672/META-INF/spring/applicationContext-scheduler.xml]: Invocation of init method failed; nested exception is org.quartz.JobPersistenceException: Couldn't retrieve trigger: Communications link failure
TARIQ IQBAL
MEMBERS
Previous 5 comments
Hi,
yesterday my CPBM environment (CentOS 6.5 VM on VMware Workstation) was running successfully. I configured it using cloudportal webpage.
At the end of the day I shutdown my VM and wanted to go ahead this morning but CPBM is not running any more. MySQL server is running and DB user get access. When I start the cloud-portal service, the service is running but stopping a few minutes later, the output is:
[root@cloudportal ~]# service cloud-portal status
cloud-portal dead but pid file exists
Restarting the service has the same effect. Any idea what went wrong?
Thanks in advance - Tobi
Tobias Kirsch
MEMBERS
Comment actions
Hi Tobi, Are you able to post/attach the contents of your portal log file? Regards
TARIQ IQBAL
MEMBERS
Comment actions
Hi Tariq,
The last packet successfully received from the server was 565,571 milliseconds ago. The last packet sent successfully to the server was 1 milliseconds ago.
at sun.reflect.GeneratedConstructorAccessor239.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:534)
at com.mysql.jdbc.Util.handleNewInstance(Util.java:411)
at com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1117)
at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3603)
at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3492)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4043)
at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2503)
at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2664)
at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2788)
at com.mysql.jdbc.ConnectionImpl.setAutoCommit(ConnectionImpl.java:5282)
at com.mchange.v2.c3p0.impl.NewProxyConnection.setAutoCommit(NewProxyConnection.java:881)
at org.quartz.impl.jdbcjobstore.AttributeRestoringConnectionInvocationHandler.setAutoCommit(AttributeRestoringConnectionInvocationHandler.java:91)
at org.quartz.impl.jdbcjobstore.AttributeRestoringConnectionInvocationHandler.invoke(AttributeRestoringConnectionInvocationHandler.java:65)
at com.sun.proxy.$Proxy518.setAutoCommit(Unknown Source)
at org.quartz.impl.jdbcjobstore.JobStoreCMT.getNonManagedTXConnection(JobStoreCMT.java:191)
at org.quartz.impl.jdbcjobstore.JobStoreSupport.doCheckin(JobStoreSupport.java:3118)
... 2 common frames omitted
Caused by: java.io.EOFException: Can not read response from server. Expected to read 4 bytes, read 0 bytes before connection was unexpectedly lost.
at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:3052)
at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3503)
... 14 common frames omitted
[2014-01-07 23:38:24.223] WARN 1389132203597_ClusterManager o.q.i.jdbcjobstore.AttributeRestoringConnectionInvocationHandler Failed restore connection's original auto commit setting. com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: No operations allowed after connection closed.
at sun.reflect.GeneratedConstructorAccessor115.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:534)
at com.mysql.jdbc.Util.handleNewInstance(Util.java:411)
at com.mysql.jdbc.Util.getInstance(Util.java:386)
at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1014)
at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:988)
at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:974)
at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:919)
at com.mysql.jdbc.ConnectionImpl.throwConnectionClosedException(ConnectionImpl.java:1290)
at com.mysql.jdbc.ConnectionImpl.checkClosed(ConnectionImpl.java:1282)
at com.mysql.jdbc.ConnectionImpl.setAutoCommit(ConnectionImpl.java:5236)
at com.mchange.v2.c3p0.impl.NewProxyConnection.setAutoCommit(NewProxyConnection.java:881)
at org.quartz.impl.jdbcjobstore.AttributeRestoringConnectionInvocationHandler.restoreOriginalAtributes(AttributeRestoringConnectionInvocationHandler.java:134)
at org.quartz.impl.jdbcjobstore.JobStoreSupport.cleanupConnection(JobStoreSupport.java:3502)
at org.quartz.impl.jdbcjobstore.JobStoreSupport.doCheckin(JobStoreSupport.java:3138)
at org.quartz.impl.jdbcjobstore.JobStoreSupport$ClusterManager.manage(JobStoreSupport.java:3762)
at org.quartz.impl.jdbcjobstore.JobStoreSupport$ClusterManager.run(JobStoreSupport.java:3798)
Caused by: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure
The last packet successfully received from the server was 565,571 milliseconds ago. The last packet sent successfully to the server was 1 milliseconds ago.
at sun.reflect.GeneratedConstructorAccessor239.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:534)
at com.mysql.jdbc.Util.handleNewInstance(Util.java:411)
at com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1117)
at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3603)
at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3492)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4043)
at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2503)
at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2664)
at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2788)
at com.mysql.jdbc.ConnectionImpl.setAutoCommit(ConnectionImpl.java:5282)
at com.mchange.v2.c3p0.impl.NewProxyConnection.setAutoCommit(NewProxyConnection.java:881)
at org.quartz.impl.jdbcjobstore.AttributeRestoringConnectionInvocationHandler.setAutoCommit(AttributeRestoringConnectionInvocationHandler.java:91)
at org.quartz.impl.jdbcjobstore.AttributeRestoringConnectionInvocationHandler.invoke(AttributeRestoringConnectionInvocationHandler.java:65)
at com.sun.proxy.$Proxy518.setAutoCommit(Unknown Source)
at org.quartz.impl.jdbcjobstore.JobStoreCMT.getNonManagedTXConnection(JobStoreCMT.java:191)
at org.quartz.impl.jdbcjobstore.JobStoreSupport.doCheckin(JobStoreSupport.java:3118)
... 2 common frames omitted
Caused by: java.io.EOFException: Can not read response from server. Expected to read 4 bytes, read 0 bytes before connection was unexpectedly lost.
at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:3052)
at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3503)
... 14 common frames omitted
[2014-01-07 23:38:24.225] WARN 1389132203597_ClusterManager o.q.i.jdbcjobstore.AttributeRestoringConnectionInvocationHandler Failed restore connection's original auto commit setting. java.sql.SQLException: You can't operate on a closed Connection!!!
at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:106)
at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:65)
at com.mchange.v2.c3p0.impl.NewProxyConnection.setAutoCommit(NewProxyConnection.java:887)
at org.quartz.impl.jdbcjobstore.AttributeRestoringConnectionInvocationHandler.restoreOriginalAtributes(AttributeRestoringConnectionInvocationHandler.java:134)
at org.quartz.impl.jdbcjobstore.JobStoreSupport.cleanupConnection(JobStoreSupport.java:3502)
at org.quartz.impl.jdbcjobstore.JobStoreSupport.doCheckin(JobStoreSupport.java:3172)
at org.quartz.impl.jdbcjobstore.JobStoreSupport$ClusterManager.manage(JobStoreSupport.java:3762)
at org.quartz.impl.jdbcjobstore.JobStoreSupport$ClusterManager.run(JobStoreSupport.java:3798)
Caused by: java.lang.NullPointerException: null
at com.mchange.v2.c3p0.impl.NewProxyConnection.setAutoCommit(NewProxyConnection.java:881)
... 5 common frames omitted
Thanks for your assistance.
Tobias Kirsch
MEMBERS
Comment actions
Hi Tobi,
Can you try restarting the CPBM again and provide us the first exception/error that you see after restart. I am suspecting that the above stacktrace may not be the real cause.
Another thing that you may try is restarting the MySql server. Sometime some lock on some table is not released when the VM is stopped.
Thanks,
Manish
Manish Agarwal
CITRIX EMPLOYEES
Comment actions
Participate
Ask, Discuss, Answer