Re: [Dev] [MB] H2 in-memory exception when shutting down server
Hi Akalanka,
it already have ;DB_CLOSE_ON_EXIT=FALSE in db url. have to check what was
causing the issue. opened a jira[1] to track progress.
[1] https://wso2.org/jira/browse/MB-1158
Thanks,
Pumudu
On Mon, Jul 13, 2015 at 7:08 PM, Akalanka Pagoda Arachchi
darsha...@wso2.com wrote:
Hi Pumudu,
This occurs when MB tries to access the H2 database even after the
database is closed. If the database url does have the property mentioned in
the error (;DB_CLOSE_ON_EXIT=FALSE) then we have the check the flow and
see if MB somehow tries to access the database after database close is
called.
Thanks,
Akalanka.
On Mon, Jul 13, 2015 at 9:01 AM, Pumudu Ruhunage pum...@wso2.com wrote:
Hi,
I observed following sql exception[1] when starts MB in h2 in-memory
mode. Following are the steps followed.
1. Start MB node in in-memory mode.
2. gracefully shutdown the server.
Following exception[1] will appear. Even this exception appears server
shuts down without hanging.
Did anyone face this before?
[1]
WARN {org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl} - Rollback
failed on removing node information node id: NODElocalhost/127.0.0.1
org.h2.jdbc.JdbcSQLException: Database is already closed (to disable
automatic closing at VM shutdown, add ;DB_CLOSE_ON_EXIT=FALSE to the db
URL) [90121-140]
at org.h2.message.DbException.getJdbcSQLException(DbException.java:327)
at org.h2.message.DbException.get(DbException.java:167)
at org.h2.message.DbException.get(DbException.java:144)
at org.h2.message.DbException.get(DbException.java:133)
at org.h2.jdbc.JdbcConnection.checkClosed(JdbcConnection.java:1348)
at
org.h2.jdbc.JdbcConnection.checkClosedForWrite(JdbcConnection.java:1333)
at org.h2.jdbc.JdbcConnection.rollback(JdbcConnection.java:435)
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
org.apache.tomcat.jdbc.pool.ProxyConnection.invoke(ProxyConnection.java:126)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.wso2.carbon.ndatasource.rdbms.ConnectionRollbackOnReturnInterceptor.invoke(ConnectionRollbackOnReturnInterceptor.java:51)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.apache.tomcat.jdbc.pool.interceptor.AbstractCreateStatementInterceptor.invoke(AbstractCreateStatementInterceptor.java:71)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.apache.tomcat.jdbc.pool.interceptor.ConnectionState.invoke(ConnectionState.java:153)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at org.apache.tomcat.jdbc.pool.TrapException.invoke(TrapException.java:41)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.apache.tomcat.jdbc.pool.DisposableConnectionFacade.invoke(DisposableConnectionFacade.java:80)
at com.sun.proxy.$Proxy15.rollback(Unknown Source)
at
org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl.rollback(RDBMSAndesContextStoreImpl.java:947)
at
org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl.removeNodeData(RDBMSAndesContextStoreImpl.java:323)
at
org.wso2.andes.store.FailureObservingAndesContextStore.removeNodeData(FailureObservingAndesContextStore.java:168)
at
org.wso2.andes.server.cluster.ClusterManager.clearAllPersistedStatesOfDisappearedNode(ClusterManager.java:270)
at
org.wso2.andes.server.cluster.ClusterManager.shutDownMyNode(ClusterManager.java:170)
at
org.wso2.andes.kernel.disruptor.inbound.InboundKernelOpsEvent.gracefulShutdown(InboundKernelOpsEvent.java:235)
at org.wso2.andes.kernel.Andes.shutDown(Andes.java:263)
at
org.wso2.andes.kernel.AndesKernelBoot.shutDownAndesKernel(AndesKernelBoot.java:311)
at
org.wso2.carbon.andes.internal.QpidServiceComponent$1.startingShutdown(QpidServiceComponent.java:170)
at
org.wso2.carbon.core.ServerManagement.waitForServerTaskCompletion(ServerManagement.java:113)
at
org.wso2.carbon.core.ServerManagement.startMaintenanceForShutDown(ServerManagement.java:97)
at
org.wso2.carbon.core.init.CarbonServerManager.shutdownGracefully(CarbonServerManager.java:878)
at
org.wso2.carbon.core.init.CarbonServerManager$4.run(CarbonServerManager.java:901)
[2015-07-13 17:54:03,604] ERROR
{org.wso2.carbon.andes.internal.QpidServiceComponent} - Error while
shutting down Andes kernel.
org.wso2.andes.kernel.AndesException: Error occurred while removing node
information node id: NODElocalhost/127.0.0.1
at
org.wso2.andes.store.rdbms.RDBMSStoreUtils.convertSQLException(RDBMSStoreUtils.java:117)
at
org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl.removeNodeData(RDBMSAndesContextStoreImpl.java:324)
at
[Dev] [MB] H2 in-memory exception when shutting down server
Hi,
I observed following sql exception[1] when starts MB in h2 in-memory mode.
Following are the steps followed.
1. Start MB node in in-memory mode.
2. gracefully shutdown the server.
Following exception[1] will appear. Even this exception appears server
shuts down without hanging.
Did anyone face this before?
[1]
WARN {org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl} - Rollback
failed on removing node information node id: NODElocalhost/127.0.0.1
org.h2.jdbc.JdbcSQLException: Database is already closed (to disable
automatic closing at VM shutdown, add ;DB_CLOSE_ON_EXIT=FALSE to the db
URL) [90121-140]
at org.h2.message.DbException.getJdbcSQLException(DbException.java:327)
at org.h2.message.DbException.get(DbException.java:167)
at org.h2.message.DbException.get(DbException.java:144)
at org.h2.message.DbException.get(DbException.java:133)
at org.h2.jdbc.JdbcConnection.checkClosed(JdbcConnection.java:1348)
at org.h2.jdbc.JdbcConnection.checkClosedForWrite(JdbcConnection.java:1333)
at org.h2.jdbc.JdbcConnection.rollback(JdbcConnection.java:435)
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
org.apache.tomcat.jdbc.pool.ProxyConnection.invoke(ProxyConnection.java:126)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.wso2.carbon.ndatasource.rdbms.ConnectionRollbackOnReturnInterceptor.invoke(ConnectionRollbackOnReturnInterceptor.java:51)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.apache.tomcat.jdbc.pool.interceptor.AbstractCreateStatementInterceptor.invoke(AbstractCreateStatementInterceptor.java:71)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.apache.tomcat.jdbc.pool.interceptor.ConnectionState.invoke(ConnectionState.java:153)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at org.apache.tomcat.jdbc.pool.TrapException.invoke(TrapException.java:41)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.apache.tomcat.jdbc.pool.DisposableConnectionFacade.invoke(DisposableConnectionFacade.java:80)
at com.sun.proxy.$Proxy15.rollback(Unknown Source)
at
org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl.rollback(RDBMSAndesContextStoreImpl.java:947)
at
org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl.removeNodeData(RDBMSAndesContextStoreImpl.java:323)
at
org.wso2.andes.store.FailureObservingAndesContextStore.removeNodeData(FailureObservingAndesContextStore.java:168)
at
org.wso2.andes.server.cluster.ClusterManager.clearAllPersistedStatesOfDisappearedNode(ClusterManager.java:270)
at
org.wso2.andes.server.cluster.ClusterManager.shutDownMyNode(ClusterManager.java:170)
at
org.wso2.andes.kernel.disruptor.inbound.InboundKernelOpsEvent.gracefulShutdown(InboundKernelOpsEvent.java:235)
at org.wso2.andes.kernel.Andes.shutDown(Andes.java:263)
at
org.wso2.andes.kernel.AndesKernelBoot.shutDownAndesKernel(AndesKernelBoot.java:311)
at
org.wso2.carbon.andes.internal.QpidServiceComponent$1.startingShutdown(QpidServiceComponent.java:170)
at
org.wso2.carbon.core.ServerManagement.waitForServerTaskCompletion(ServerManagement.java:113)
at
org.wso2.carbon.core.ServerManagement.startMaintenanceForShutDown(ServerManagement.java:97)
at
org.wso2.carbon.core.init.CarbonServerManager.shutdownGracefully(CarbonServerManager.java:878)
at
org.wso2.carbon.core.init.CarbonServerManager$4.run(CarbonServerManager.java:901)
[2015-07-13 17:54:03,604] ERROR
{org.wso2.carbon.andes.internal.QpidServiceComponent} - Error while
shutting down Andes kernel.
org.wso2.andes.kernel.AndesException: Error occurred while removing node
information node id: NODElocalhost/127.0.0.1
at
org.wso2.andes.store.rdbms.RDBMSStoreUtils.convertSQLException(RDBMSStoreUtils.java:117)
at
org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl.removeNodeData(RDBMSAndesContextStoreImpl.java:324)
at
org.wso2.andes.store.FailureObservingAndesContextStore.removeNodeData(FailureObservingAndesContextStore.java:168)
at
org.wso2.andes.server.cluster.ClusterManager.clearAllPersistedStatesOfDisappearedNode(ClusterManager.java:270)
at
org.wso2.andes.server.cluster.ClusterManager.shutDownMyNode(ClusterManager.java:170)
at
org.wso2.andes.kernel.disruptor.inbound.InboundKernelOpsEvent.gracefulShutdown(InboundKernelOpsEvent.java:235)
at org.wso2.andes.kernel.Andes.shutDown(Andes.java:263)
at
org.wso2.andes.kernel.AndesKernelBoot.shutDownAndesKernel(AndesKernelBoot.java:311)
at
org.wso2.carbon.andes.internal.QpidServiceComponent$1.startingShutdown(QpidServiceComponent.java:170)
at
org.wso2.carbon.core.ServerManagement.waitForServerTaskCompletion(ServerManagement.java:113)
at
Re: [Dev] [MB] H2 in-memory exception when shutting down server
Hi Pumudu,
This occurs when MB tries to access the H2 database even after the database
is closed. If the database url does have the property mentioned in the
error (;DB_CLOSE_ON_EXIT=FALSE) then we have the check the flow and see
if MB somehow tries to access the database after database close is called.
Thanks,
Akalanka.
On Mon, Jul 13, 2015 at 9:01 AM, Pumudu Ruhunage pum...@wso2.com wrote:
Hi,
I observed following sql exception[1] when starts MB in h2 in-memory mode.
Following are the steps followed.
1. Start MB node in in-memory mode.
2. gracefully shutdown the server.
Following exception[1] will appear. Even this exception appears server
shuts down without hanging.
Did anyone face this before?
[1]
WARN {org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl} - Rollback
failed on removing node information node id: NODElocalhost/127.0.0.1
org.h2.jdbc.JdbcSQLException: Database is already closed (to disable
automatic closing at VM shutdown, add ;DB_CLOSE_ON_EXIT=FALSE to the db
URL) [90121-140]
at org.h2.message.DbException.getJdbcSQLException(DbException.java:327)
at org.h2.message.DbException.get(DbException.java:167)
at org.h2.message.DbException.get(DbException.java:144)
at org.h2.message.DbException.get(DbException.java:133)
at org.h2.jdbc.JdbcConnection.checkClosed(JdbcConnection.java:1348)
at org.h2.jdbc.JdbcConnection.checkClosedForWrite(JdbcConnection.java:1333)
at org.h2.jdbc.JdbcConnection.rollback(JdbcConnection.java:435)
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
org.apache.tomcat.jdbc.pool.ProxyConnection.invoke(ProxyConnection.java:126)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.wso2.carbon.ndatasource.rdbms.ConnectionRollbackOnReturnInterceptor.invoke(ConnectionRollbackOnReturnInterceptor.java:51)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.apache.tomcat.jdbc.pool.interceptor.AbstractCreateStatementInterceptor.invoke(AbstractCreateStatementInterceptor.java:71)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.apache.tomcat.jdbc.pool.interceptor.ConnectionState.invoke(ConnectionState.java:153)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at org.apache.tomcat.jdbc.pool.TrapException.invoke(TrapException.java:41)
at
org.apache.tomcat.jdbc.pool.JdbcInterceptor.invoke(JdbcInterceptor.java:109)
at
org.apache.tomcat.jdbc.pool.DisposableConnectionFacade.invoke(DisposableConnectionFacade.java:80)
at com.sun.proxy.$Proxy15.rollback(Unknown Source)
at
org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl.rollback(RDBMSAndesContextStoreImpl.java:947)
at
org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl.removeNodeData(RDBMSAndesContextStoreImpl.java:323)
at
org.wso2.andes.store.FailureObservingAndesContextStore.removeNodeData(FailureObservingAndesContextStore.java:168)
at
org.wso2.andes.server.cluster.ClusterManager.clearAllPersistedStatesOfDisappearedNode(ClusterManager.java:270)
at
org.wso2.andes.server.cluster.ClusterManager.shutDownMyNode(ClusterManager.java:170)
at
org.wso2.andes.kernel.disruptor.inbound.InboundKernelOpsEvent.gracefulShutdown(InboundKernelOpsEvent.java:235)
at org.wso2.andes.kernel.Andes.shutDown(Andes.java:263)
at
org.wso2.andes.kernel.AndesKernelBoot.shutDownAndesKernel(AndesKernelBoot.java:311)
at
org.wso2.carbon.andes.internal.QpidServiceComponent$1.startingShutdown(QpidServiceComponent.java:170)
at
org.wso2.carbon.core.ServerManagement.waitForServerTaskCompletion(ServerManagement.java:113)
at
org.wso2.carbon.core.ServerManagement.startMaintenanceForShutDown(ServerManagement.java:97)
at
org.wso2.carbon.core.init.CarbonServerManager.shutdownGracefully(CarbonServerManager.java:878)
at
org.wso2.carbon.core.init.CarbonServerManager$4.run(CarbonServerManager.java:901)
[2015-07-13 17:54:03,604] ERROR
{org.wso2.carbon.andes.internal.QpidServiceComponent} - Error while
shutting down Andes kernel.
org.wso2.andes.kernel.AndesException: Error occurred while removing node
information node id: NODElocalhost/127.0.0.1
at
org.wso2.andes.store.rdbms.RDBMSStoreUtils.convertSQLException(RDBMSStoreUtils.java:117)
at
org.wso2.andes.store.rdbms.RDBMSAndesContextStoreImpl.removeNodeData(RDBMSAndesContextStoreImpl.java:324)
at
org.wso2.andes.store.FailureObservingAndesContextStore.removeNodeData(FailureObservingAndesContextStore.java:168)
at
org.wso2.andes.server.cluster.ClusterManager.clearAllPersistedStatesOfDisappearedNode(ClusterManager.java:270)
at
org.wso2.andes.server.cluster.ClusterManager.shutDownMyNode(ClusterManager.java:170)
at
