Subsonic doesn't start after server restart(WAR with tomcat6

[glic3rinu]

Today I restart my linux server runing subsonic 4.4 with tomcat6 and after restart subsonic doesn't run anymore. I try removing files on work and webapp/subsonic but it doesn't make any difference. Tomcat remports this errors on their log:


Mar 26, 2011 12:18:22 AM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
Mar 26, 2011 12:18:22 AM org.apache.catalina.core.StandardContext start
SEVERE: Context [] startup failed due to previous errors
Mar 26, 2011 12:18:22 AM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
SEVERE: The web application [] registered the JBDC driver [org.hsqldb.jdbcDriver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
Mar 26, 2011 12:18:22 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [Timer-1] but has failed to stop it. This is very likely to create a memory leak.
Mar 26, 2011 12:18:22 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [pool-4-thread-1] but has failed to stop it. This is very likely to create a memory leak.
Mar 26, 2011 12:18:22 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [pool-4-thread-2] but has failed to stop it. This is very likely to create a memory leak.
Mar 26, 2011 12:18:22 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [DiscoveryListener daemon] but has failed to stop it. This is very likely to create a memory leak.
Mar 26, 2011 12:18:23 AM org.apache.catalina.loader.WebappClassLoader loadClass
INFO: Illegal access: this web application instance has been stopped already. Could not load org.apache.http.impl.io.SocketInputBuffer. The eventual following stack trace is caused by an error thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access, and has no functional impact.
java.lang.IllegalStateException
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1531)
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1491)
at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:320)
at org.apache.http.impl.SocketHttpClientConnection.createSessionInputBuffer(SocketHttpClientConnection.java:99)
at org.apache.http.impl.conn.DefaultClientConnection.createSessionInputBuffer(DefaultClientConnection.java:165)
at org.apache.http.impl.SocketHttpClientConnection.bind(SocketHttpClientConnection.java:162)
at org.apache.http.impl.conn.DefaultClientConnection.openCompleted(DefaultClientConnection.java:123)
at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:130)
at org.apache.http.impl.conn.AbstractPoolEntry.open(AbstractPoolEntry.java:147)
at org.apache.http.impl.conn.AbstractPooledConnAdapter.open(AbstractPooledConnAdapter.java:108)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:415)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:576)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:554)
at net.sourceforge.subsonic.service.NetworkService$URLRedirectionTask.execute(NetworkService.java:250)
at net.sourceforge.subsonic.service.NetworkService$Task.run(NetworkService.java:309)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:181)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:205)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
Mar 26, 2011 12:18:23 AM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
Mar 26, 2011 12:18:23 AM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/111 config=null
Mar 26, 2011 12:18:23 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 8050 ms
Mar 26, 2011 12:18:25 AM org.apache.catalina.loader.WebappClassLoader loadClass
INFO: Illegal access: this web application instance has been stopped already. Could not load org.wetorrent.upnp.GatewayDiscover. The eventual following stack trace is caused by an error thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access, and has no functional impact.
java.lang.IllegalStateException
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1531)
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1491)
at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:320)
at net.sourceforge.subsonic.domain.WeUPnPRouter.findRouter(WeUPnPRouter.java:38)
at net.sourceforge.subsonic.service.NetworkService$PortForwardingTask.findRouter(NetworkService.java:197)
at net.sourceforge.subsonic.service.NetworkService$PortForwardingTask.execute(NetworkService.java:132)
at net.sourceforge.subsonic.service.NetworkService$Task.run(NetworkService.java:309)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:181)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:20
5)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)



Any idea?
Thanks!!!!

[glic3rinu]

This is the entire log after delete worker/* webapps/subsonic and /var/subsonic

It talks about /var/subsonic/db/subsonic.lck but I can guarantee that I delete all stuff in /var/subsonic/.

Mar 26, 2011 1:07:05 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 803 ms
Mar 26, 2011 1:07:05 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Mar 26, 2011 1:07:05 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.28
Mar 26, 2011 1:07:05 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive subsonic.war
Mar 26, 2011 1:07:09 AM net.sf.ehcache.Cache initialise
WARNING: Cache: chatCache has a maxElementsInMemory of 0. It is strongly recommended to have a maximumSize of at least 1. Performance is halved by not using a MemoryStore.
Mar 26, 2011 1:07:09 AM net.sf.ehcache.Cache initialise
WARNING: Cache: musicFolderCache has a maxElementsInMemory of 0. It is strongly recommended to have a maximumSize of at least 1. Performance is halved by not using a MemoryStore.
Mar 26, 2011 1:07:12 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
Mar 26, 2011 1:07:13 AM net.sf.ehcache.Cache initialise
WARNING: Cache: chatCache has a maxElementsInMemory of 0. It is strongly recommended to have a maximumSize of at least 1. Performance is halved by not using a MemoryStore.
Mar 26, 2011 1:07:13 AM net.sf.ehcache.Cache initialise
WARNING: Cache: musicFolderCache has a maxElementsInMemory of 0. It is strongly recommended to have a maximumSize of at least 1. Performance is halved by not using a MemoryStore.
[2011-03-26 01:07:14,245] ERROR org.springframework.web.context.ContextLoader - Context initialization failed
org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'podcastService' defined in ServletContext resource [/WEB-INF/applicationContext-service.xml]: Invocation of init method failed; nested exception is org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: The database is already in use by another process: org.hsqldb.persist.NIOLockFile@2d6451a6[file =/var/subsonic/db/subsonic.lck, exists=true, locked=false, valid=false, fl =null]: java.lang.Exception: checkHeartbeat(): lock file [/var/subsonic/db/subsonic.lck] is presumably locked by another process.
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1338)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:473)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:409)
at java.security.AccessController.doPrivileged(Native Method)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:380)
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:264)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:261)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:185)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:164)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:429)
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:728)
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:380)
at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:255)
at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:199)
at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:45)
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4135)
at org.apache.catalina.core.StandardContext.start(StandardContext.java:4630)
at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:546)
at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1041)
at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:964)
at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:502)
at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)
at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
at org.apache.catalina.core.StandardHost.start(StandardHost.java:785)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:445)
at org.apache.catalina.core.StandardService.start(StandardService.java:519)
at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)
Caused by: org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: The database is already in use by another process: org.hsqldb.persist.NIOLockFile@2d6451a6[file =/var/subsonic/db/subsonic.lck, exists=true, locked=false, valid=false, fl =null]: java.lang.Exception: checkHeartbeat(): lock file [/var/subsonic/db/subsonic.lck] is presumably locked by another process.
at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:82)
at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:577)
at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:641)
at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:670)
at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:678)
at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:710)
at net.sourceforge.subsonic.dao.AbstractDao.query(AbstractDao.java:58)
at net.sourceforge.subsonic.dao.PodcastDao.getAllChannels(PodcastDao.java:67)
at net.sourceforge.subsonic.service.PodcastService.getAllChannels(PodcastService.java:167)
at net.sourceforge.subsonic.service.PodcastService.init(PodcastService.java:100)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeCustomInitMethod(AbstractAutowireCapableBeanFactory.java:1414)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1375)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1335)
... 39 more
Caused by: java.sql.SQLException: The database is already in use by another process: org.hsqldb.persist.NIOLockFile@2d6451a6[file =/var/subsonic/db/subsonic.lck, exists=true, locked=false, valid=false, fl =null]: java.lang.Exception: checkHeartbeat(): lock file [/var/subsonic/db/subsonic.lck] is presumably locked by another process.
at org.hsqldb.jdbc.Util.sqlException(Unknown Source)
at org.hsqldb.jdbc.jdbcConnection.<init>(Unknown Source)
at org.hsqldb.jdbcDriver.getConnection(Unknown Source)
at org.hsqldb.jdbcDriver.connect(Unknown Source)
at java.sql.DriverManager.getConnection(DriverManager.java:582)
at java.sql.DriverManager.getConnection(DriverManager.java:154)
at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnectionFromDriverManager(DriverManagerDataSource.java:174)
at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnectionFromDriver(DriverManagerDataSource.java:165)
at org.springframework.jdbc.datasource.AbstractDriverBasedDataSource.getConnectionFromDriver(AbstractDriverBasedDataSource.java:149)
at org.springframework.jdbc.datasource.AbstractDriverBasedDataSource.getConnection(AbstractDriverBasedDataSource.java:119)
at org.springframework.jdbc.datasource.DataSourceUtils.doGetConnection(DataSourceUtils.java:113)
at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:79)
... 55 more
Mar 26, 2011 1:07:14 AM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
Mar 26, 2011 1:07:14 AM org.apache.catalina.core.StandardContext start
SEVERE: Context [] startup failed due to previous errors
Mar 26, 2011 1:07:14 AM org.apache.catalina.loader.WebappClassLoader clearReferencesJdbc
SEVERE: The web application [] registered the JBDC driver [org.hsqldb.jdbcDriver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
Mar 26, 2011 1:07:14 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [Timer-1] but has failed to stop it. This is very likely to create a memory leak.
Mar 26, 2011 1:07:14 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [pool-4-thread-1] but has failed to stop it. This is very likely to create a memory leak.
Mar 26, 2011 1:07:14 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [pool-4-thread-2] but has failed to stop it. This is very likely to create a memory leak.
Mar 26, 2011 1:07:14 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [DiscoveryListener daemon] but has failed to stop it. This is very likely to create a memory leak.
Mar 26, 2011 1:07:14 AM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
Mar 26, 2011 1:07:14 AM org.apache.catalina.loader.WebappClassLoader loadClass
INFO: Illegal access: this web application instance has been stopped already. Could not load org.apache.http.message.ParserCursor. The eventual following stack trace is caused by an error thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access, and has no functional impact.
java.lang.IllegalStateException
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1531)
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1491)
at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:320)
at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResponseParser.java:103)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:210)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:271)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:227)
at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHeader(AbstractClientConnAdapter.java:209)
at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:231)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:483)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:641)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:576)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:554)
at net.sourceforge.subsonic.service.NetworkService$URLRedirectionTask.execute(NetworkService.java:250)
at net.sourceforge.subsonic.service.NetworkService$Task.run(NetworkService.java:309)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:181)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:205)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)
Mar 26, 2011 1:07:15 AM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/315 config=null
Mar 26, 2011 1:07:15 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 9664 ms
Mar 26, 2011 1:07:17 AM org.apache.catalina.loader.WebappClassLoader loadClass
INFO: Illegal access: this web application instance has been stopped already. Could not load org.wetorrent.upnp.GatewayDiscover. The eventual following stack trace is caused by an error thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access, and has no functional impact.
java.lang.IllegalStateException
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1531)
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1491)
at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:320)
at net.sourceforge.subsonic.domain.WeUPnPRouter.findRouter(WeUPnPRouter.java:38)
at net.sourceforge.subsonic.service.NetworkService$PortForwardingTask.findRouter(NetworkService.java:197)
at net.sourceforge.subsonic.service.NetworkService$PortForwardingTask.execute(NetworkService.java:132)
at net.sourceforge.subsonic.service.NetworkService$Task.run(NetworkService.java:309)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:181)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:205)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:619)

[baaldemon]

Run ls -l /var/subsonic/ and ls -l /var/subsonic/db and post the the output

[glic3rinu]

I deleted these directories, restarted the server and installed the subsonic but the error still there.

Now I have subsonic running again but I had to create a fresh environment for it. (debootstrap + chroot).

[nelgin]

Caused by: java.sql.SQLException: The database is already in use by another process: org.hsqldb.persist.NIOLockFile@2d6451a6[file =/var/subsonic/db/subsonic.lck, exists=true, locked=false, valid=false, fl =null]: java.lang.Exception: checkHeartbeat(): lock file [/var/subsonic/db/subsonic.lck] is presumably locked by another process.

Maybe you just needed to delete the lock file from within the db directory?

[glic3rinu]

Notice that the database doesn't exists when I start tomcat after delete /var/subsonic. This error appeared during subsonic deployment.

As anecdote subsonic starts if I execute this during the start proces:
while true; do rm /var/subsonic/db/subsonic.lck; done

[baaldemon]

I dont understand why you would blow away the entire /var/subsonic/ directory for a simple failed restart of subsonic with tomcat. The reason why I asked you to post the output of those directories is because I didnt think you actually deleted them, hence the locked database.

Where are you adding the code to remove the lck file, and thats not really a good way to handle it.

The error in your first post "SEVERE: Error listenerStart" suggests that you should have looked into your subsonic.log to see what the actually error message was, rather than simply blowing it away. But from where Ive seen that before it was that another service was already running on the port. Was a separate instance of subsonic (or any service) running on that port already, did you previously have the standalone version installed and did that get started when you restarted your machine?

You post where you say you got it running again you mention you had to chroot it, which kind of implies that something else may be using your /var/subsonic/ directory assuming you chrooted it to something else and it works with the <newRoot>/var/subsonic/ as the directory its running off of. It sounds like you have/had multiple instances of subsonic running at the same time.

What flavor of *nix are you running?

[-Ender]

I've run into this exact same error after updating my system. The subsonic.log files in the /var/subsonic directory (at least for me) are empty. Output of ls -l /var/subsonic/

drwxr-xr-x 2 tomcat6 tomcat6 4096 2011-04-04 13:54 db
drwxr-xr-x 3 tomcat6 tomcat6 4096 2010-07-10 19:02 jetty
drwxr-xr-x 5 tomcat6 tomcat6 4096 2011-01-06 22:03 lucene
drwxr-xr-x 2 tomcat6 tomcat6 4096 2010-07-10 21:47 playlists
-rw-r--r-- 1 tomcat6 tomcat6 3920467 2011-04-01 03:00 subsonic12.index
-rw-r--r-- 1 tomcat6 tomcat6 0 2011-04-04 13:54 subsonic.log
-rw-r--r-- 1 tomcat6 tomcat6 3385 2011-04-04 13:54 subsonic.properties
-rw-r--r-- 1 tomcat6 tomcat6 358656 2010-07-10 20:50 subsonic_sh.log
drwxr-xr-x 9 tomcat6 tomcat6 4096 2011-02-03 22:56 thumbs
drwxr-xr-x 2 tomcat6 tomcat6 4096 2011-02-05 16:46 transcode


Output of ls -l /var/subsonic/db

-rw-r--r-- 1 tomcat6 tomcat6 30588 2011-04-04 13:54 subsonic.backup
-rw-r--r-- 1 tomcat6 tomcat6 1048576 2011-04-04 13:54 subsonic.data
-rw-r--r-- 1 tomcat6 tomcat6 16 2011-04-04 13:54 subsonic.lck
-rw-r--r-- 1 tomcat6 tomcat6 0 2011-04-04 13:54 subsonic.log
-rw-r--r-- 1 tomcat6 tomcat6 419 2011-04-04 13:54 subsonic.properties
-rw-r--r-- 1 tomcat6 tomcat6 58 2011-04-04 13:54 subsonic.script

If I stop Tomcat, the .lck file goes away. This instance of Subsonic was running fine until the system update, after which subsonic would no longer start. I can't speak for the op, but I'm running Ubuntu 10.04 server.

[-Ender]

No ideas from anyone? I'm at a loss of what to try next. I've tried upgrading my server, hoping that some kind of error that was introduced would be resolved, but to no avail. I also tried updating my SS install to 4.4, again, to no avail.

[Edit]
I have also tried removing the tomcat work directory. Also, I figured I should provide some other pertinent details on what I'm running:
Tomcat 6.0.24
Ubuntu 10.04 Server edition
JVM 1.6.0_24
Kernel 2.6.32-30
[/Edit]

[jaquense]

First as an FYI Subsonic WILL NOT run if you don't have:

/var/subsonic
/var/subsonic/transcode
directories

Second have you tried baaldemon suggestions? It sounds like there are multiple instances of subsonic running. Did you have a Standalone install before tomcat? was your server working fine for a while? retrace your steps what did you alter/delete/move before you restarted tomcat?

[-Ender]

/var/subsonic/transcode exists. There are not multiple instances of subsonic running, and no, I never ran the standalone version on this server. This instance was running fine for ~8 months until I performed a system update about three weeks ago. Now Subsonic won't launch. When I try to start it manually in tomcat, I get this failure message: "FAIL - Application at context path /subsonic could not be started." Again, then only thing I did was perform a system update and a reboot, and after the reboot Subsonic stopped working properly. I don't remember what libraries/applications the update modified.

[Aethies]

No ideas from anyone? I'm at a loss of what to try next. I've tried upgrading my server, hoping that some kind of error that was introduced would be resolved, but to no avail. I also tried updating my SS install to 4.4, again, to no avail.

[Edit]
I have also tried removing the tomcat work directory. Also, I figured I should provide some other pertinent details on what I'm running:
Tomcat 6.0.24
Ubuntu 10.04 Server edition
JVM 1.6.0_24
Kernel 2.6.32-30
[/Edit]

There are a couple ways to check this. Try to see if the subsonic service is still running by typing --
ps -ef | grep subsonic

Anything running will show up along with the command you just ran.
Kill the process by typing --
kill -9 <process id>

Another way to see if the port is in use is to type --
netstat -l

this will output every port your server is listening on. It will be towards the top section of the output. You will see something that has your port listed, ie.... 8180

I do know that before I install any upgrades, I would use the following:

sudo /etc/init.d/tomcat6 stop
or
service tomcat6 stop
to ensure the server is not running. If you install the upgrade without stopping the subsonic server, you WILL corrupt the database. Trust me. Cause I have done it.

Hope this helps.

[-Ender]

I could have sworn that I tried to restore the db from an earlier backup. At any rate, it appears that as Aethies stated, my db was corrupted by leaving the service running during an update. I was able to successfully restore the db from a backup a made about a month ago. I'm guessing that the last time I tried a db restore, I must have forgotten to chown the restored files to my tomcat user. Thanks for pointing me in the right direction, Aethies and jaquense.