[daisy] [JIRA] Commented: (DSY-457) Automatic shutdown of Daisy
repository
service does not clean up write lock (and prevents startup)
Bruno Dumon (JIRA)
issues at cocoondev.org
Fri Apr 27 04:41:20 CDT 2007
[ http://issues.cocoondev.org//browse/DSY-457?page=comments#action_13141 ]
Bruno Dumon commented on DSY-457:
---------------------------------
jBPM/hibernate does indeed use the same JDBC connection pool as the Daisy repository itself to access the database.
Upon shutdown, the workflow component is disposed first (which includes stopping the JobExecutor threads and waiting for them to stop), only later on the database connection pool is closed.
Do you have this error consistently on each shutdown? Are you making active use of workflow? Are there more errors above this part from the log?
Since you mention you only have this error when the computer is shut down, it might also be that it is because MySQL is stopped first, however then you'd probably have another exception.
So for now I can't help much...
> Automatic shutdown of Daisy repository service does not clean up write lock (and prevents startup)
> --------------------------------------------------------------------------------------------------
>
> Key: DSY-457
> URL: http://issues.cocoondev.org//browse/DSY-457
> Project: Daisy
> Type: Bug
> Components: Repository
> Versions: 2.0.1
> Environment: Windows XP SP2
> Reporter: Geoffrey De Smet
>
> When I directly call the repo service shutdown, it cleans up the write lock.
> When windows calls it when I turn off my machine, it doesn't clean up write lock (see the log below).
> This prevents the repo from starting up when I turn on my machine.
> And windows's services properties show that the repo is dependend on MySQL, so it should shutdown before mysql.
> Here's the service.log:
> INFO | jvm 1 | 2007/04/20 12:32:19 | org.jbpm.persistence.JbpmPersistenceException: hibernate close session failed
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.jbpm.persistence.db.DbPersistenceService.close(DbPersistenceService.java:240)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.jbpm.svc.Services.close(Services.java:222)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.jbpm.JbpmContext.close(JbpmContext.java:139)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.jbpm.job.executor.JobExecutorThread.acquireJobs(JobExecutorThread.java:141)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.jbpm.job.executor.JobExecutorThread.run(JobExecutorThread.java:56)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.jbpm.job.executor.DaisyJobExecutorThread.run(DaisyJobExecutorThread.java:48)
> INFO | jvm 1 | 2007/04/20 12:32:24 | Caused by: org.hibernate.exception.GenericJDBCException: Cannot release connection
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.exception.SQLStateConverter.handledNonSpecificException(SQLStateConverter.java:103)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:91)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:29)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.jdbc.ConnectionManager.closeConnection(ConnectionManager.java:449)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.jdbc.ConnectionManager.aggressiveRelease(ConnectionManager.java:400)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.jdbc.ConnectionManager.afterTransaction(ConnectionManager.java:287)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.jdbc.JDBCContext.afterTransactionCompletion(JDBCContext.java:221)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.jdbc.JDBCContext.connectionCleanedUp(JDBCContext.java:97)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.jdbc.ConnectionManager.cleanup(ConnectionManager.java:388)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.jdbc.ConnectionManager.close(ConnectionManager.java:318)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.impl.SessionImpl.close(SessionImpl.java:298)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.jbpm.persistence.db.DbPersistenceService.closeSession(DbPersistenceService.java:310)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.jbpm.persistence.db.DbPersistenceService.close(DbPersistenceService.java:237)
> INFO | jvm 1 | 2007/04/20 12:32:24 | ... 5 more
> INFO | jvm 1 | 2007/04/20 12:32:24 | Caused by: java.sql.SQLException: Already closed.
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.apache.commons.dbcp.PoolableConnection.close(PoolableConnection.java:66)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.apache.commons.dbcp.PoolingDataSource$PoolGuardConnectionWrapper.close(PoolingDataSource.java:180)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.outerj.daisy.workflow.serverimpl.DaisyConnectionProvider.closeConnection(DaisyConnectionProvider.java:49)
> INFO | jvm 1 | 2007/04/20 12:32:24 | at org.hibernate.jdbc.ConnectionManager.closeConnection(ConnectionManager.java:445)
> INFO | jvm 1 | 2007/04/20 12:32:24 | ... 14 more
> ERROR | wrapper | 2007/04/20 12:32:26 | Shutdown failed: Timed out waiting for the JVM to terminate.
> ERROR | wrapper | 2007/04/20 12:32:26 | JVM did not exit on request, terminated
> STATUS | wrapper | 2007/04/20 12:32:27 | <-- Wrapper Stopped
> STATUS | wrapper | 2007/04/23 08:26:08 | --> Wrapper Started as Service
> STATUS | wrapper | 2007/04/23 08:26:08 | Launching a JVM...
> INFO | jvm 1 | 2007/04/23 08:26:09 | Wrapper (Version 3.2.3) http://wrapper.tanukisoftware.org
> INFO | jvm 1 | 2007/04/23 08:26:09 | Copyright 1999-2006 Tanuki Software, Inc. All Rights Reserved.
> INFO | jvm 1 | 2007/04/23 08:26:09 |
> INFO | jvm 1 | 2007/04/23 08:26:15 | ---- exception report ----------------------------------------------------------
> INFO | jvm 1 | 2007/04/23 08:26:15 | Exception: org.apache.avalon.merlin.KernelException
> INFO | jvm 1 | 2007/04/23 08:26:15 | Message: Kernel startup failure.
> INFO | jvm 1 | 2007/04/23 08:26:15 | ---- cause ---------------------------------------------------------------------
> INFO | jvm 1 | 2007/04/23 08:26:15 | Exception: org.apache.avalon.merlin.KernelException
> INFO | jvm 1 | 2007/04/23 08:26:15 | Message: Cannot deploy application.
> INFO | jvm 1 | 2007/04/23 08:26:15 | ---- cause ---------------------------------------------------------------------
> INFO | jvm 1 | 2007/04/23 08:26:15 | Exception: org.apache.avalon.activation.LifecycleException
> INFO | jvm 1 | 2007/04/23 08:26:15 | Message: Unable to create a new component instance in appliance [/daisy/repository/fullTextIndex] due to a component deployment failure.
> INFO | jvm 1 | 2007/04/23 08:26:15 | ---- cause ---------------------------------------------------------------------
> INFO | jvm 1 | 2007/04/23 08:26:15 | Exception: java.io.IOException
> INFO | jvm 1 | 2007/04/23 08:26:15 | Message: Lock obtain timed out: SimpleFSLock at C:\app\cms\daisy-2.0.1\data\indexstore\write.lock
> INFO | jvm 1 | 2007/04/23 08:26:15 | ---- stack trace ---------------------------------------------------------------
> INFO | jvm 1 | 2007/04/23 08:26:15 | java.io.IOException: Lock obtain timed out: SimpleFSLock at C:\app\cms\daisy-2.0.1\data\indexstore\write.lock
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.lucene.store.Lock.obtain(Lock.java:69)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.lucene.index.IndexWriter.init(IndexWriter.java:367)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.lucene.index.IndexWriter.init(IndexWriter.java:352)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:314)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.outerj.daisy.ftindex.FullTextIndexImpl.constructIndexWriter(FullTextIndexImpl.java:307)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.outerj.daisy.ftindex.FullTextIndexImpl.updateWriter(FullTextIndexImpl.java:303)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.outerj.daisy.ftindex.FullTextIndexImpl.initialize(FullTextIndexImpl.java:109)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.framework.container.ContainerUtil.initialize(ContainerUtil.java:244)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.activation.impl.DefaultComponentFactory.incarnation(DefaultComponentFactory.java:402)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.activation.impl.DefaultComponentFactory.incarnate(DefaultComponentFactory.java:130)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.activation.impl.SingletonLifestyleManager.refreshReference(SingletonLifestyleManager.java:144)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.activation.impl.SingletonLifestyleManager.commission(SingletonLifestyleManager.java:60)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.activation.impl.DefaultAppliance.commission(DefaultAppliance.java:94)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.activation.impl.DefaultRuntime.commission(DefaultRuntime.java:86)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.composition.model.impl.DefaultSystemContext.commission(DefaultSystemContext.java:362)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.composition.model.impl.DefaultDeploymentModel.commission(DefaultDeploymentModel.java:122)
> INFO | jvm 1 | 2007/04/23 08:26:15 | org.apache.avalon.composition.model.impl.Commissioner.run(Commissioner.java:185)
> INFO | jvm 1 | 2007/04/23 08:26:15 | java.lang.Thread.run(Unknown Source)
> INFO | jvm 1 | 2007/04/23 08:26:15 | --------------------------------------------------------------------------------
--
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
http://issues.cocoondev.org//secure/Administrators.jspa
-
For more information on JIRA, see:
http://www.atlassian.com/software/jira
More information about the daisy
mailing list