Testing subsonic on Qnap NAS

[sindre_mehus]

Hey.

I have been trying to get subsonic running my NAS.
Installed Java- JDK and Apache-Tomcat

In Apache - Tomcat page i uploaded the war file, latest beta.
Subsonic is now listed as an application, but it wont start. Gives me this message:

"FAIL - Application at context path /subsonic could not be started"

Like previously stated i am new to linux.

If you would like to help me get Subsonic running on the NAS Sindre I am willing to spend spend some time geting it running. Better create a new thread if you or someone else is interested to help.

L4ngley

I'll try to help. The first step would be to logon to the NAS using ssh (or telnet if it's supported) and locate the logs for Tomcat and Subsonic. The latter should be at /var/subsonic/logs.

Sindre
[/quote]

[L4ngley]

Hey, here is the subsonic log

Code: Select all

[2009-05-14 17:39:09,021] INFO DaoHelper - Checking database schema.
[2009-05-14 17:39:09,190] INFO Schema25 - Database table 'version' not found.  Creating it.
[2009-05-14 17:39:09,198] ERROR DaoHelper - Failed to initialize database.
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]:
   at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:82)
   at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:357)
   at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:404)
   at net.sourceforge.subsonic.dao.schema.Schema25.execute(Schema25.java:36)
   at net.sourceforge.subsonic.dao.DaoHelper.checkDatabase(DaoHelper.java:85)
   at net.sourceforge.subsonic.dao.DaoHelper.<init>(DaoHelper.java:42)
   at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
   at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
   at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
   at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
   at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:83)
   at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:61)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.instantiateBean(AbstractAutowireCapableBeanFactory.java:911)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:873)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:514)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:485)
   at java.security.AccessController.doPrivileged(Native Method)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:455)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:251)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:169)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:248)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:170)
   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:268)
   at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:104)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.applyPropertyValues(AbstractAutowireCapableBeanFactory.java:1274)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1042)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:539)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:485)
   at java.security.AccessController.doPrivileged(Native Method)
   at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:455)
   at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:251)
   at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:169)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:248)
   at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:170)
   at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:413)
   at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:735)
   at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:369)
   at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:251)
   at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:190)
   at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:45)
   at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3843)
   at org.apache.catalina.core.StandardContext.start(StandardContext.java:4342)
   at org.apache.catalina.manager.ManagerServlet.start(ManagerServlet.java:1247)
   at org.apache.catalina.manager.HTMLManagerServlet.start(HTMLManagerServlet.java:604)
   at org.apache.catalina.manager.HTMLManagerServlet.doGet(HTMLManagerServlet.java:129)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
   at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
   at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
   at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:525)
   at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
   at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
   at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
   at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
   at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:845)
   at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
   at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
   at java.lang.Thread.run(Thread.java:619)
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]:
   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:283)
   at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnectionFromDriverManager(DriverManagerDataSource.java:271)
   at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnectionFromDriverManager(DriverManagerDataSource.java:255)
   at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnection(DriverManagerDataSource.java:236)
   at org.springframework.jdbc.datasource.DataSourceUtils.doGetConnection(DataSourceUtils.java:113)
   at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:79)
   ... 59 more

[2009-05-14 17:39:09,344] INFO SearchService - Automatic index creation scheduled to run every 1 day(s), starting at Fri May 15 03:00:00 CEST 2009


Can only find the original Apache log file, nothing even somes up when using

Code: Select all

find 'tomcat'

Any ide where it can be installed?

If you like i could install some remote support software like "GoToMeeting"? and you could look around while i watch that is.

[sindre_mehus]

That error normally indicates that there are two Subsonic instances running.

We need to find the Tomcat installation. Maybe you can try the following command:

Code: Select all

find / -name \*tomcat\*

[L4ngley]

Thanx, found it, there are several logfiles under apache-tomcat/logs
EDIT: The code sections get messed up.. to long, next post contins link to them

Here is :

catalina.2009-05-14.log

Code: Select all

May 14, 2009 2:49:30 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /share/MD0_DATA/.qpkg/Java-SDK/jre/lib/i386/server:/share/MD0_DATA/.qpkg/Java-SDK/jre/lib/i386:/share/MD0_DATA/.qpkg/Java-SDK/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
May 14, 2009 2:49:30 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8085
May 14, 2009 2:49:30 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 746 ms
May 14, 2009 2:49:31 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
May 14, 2009 2:49:31 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.18
May 14, 2009 2:49:31 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8085
May 14, 2009 2:49:31 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
May 14, 2009 2:49:31 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/31  config=null
May 14, 2009 2:49:31 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 780 ms
May 14, 2009 2:50:57 PM org.apache.coyote.http11.Http11Protocol pause
INFO: Pausing Coyote HTTP/1.1 on http-8085
May 14, 2009 2:50:58 PM org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
May 14, 2009 2:50:58 PM org.apache.coyote.http11.Http11Protocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-8085
May 14, 2009 2:51:07 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /share/MD0_DATA/.qpkg/Java-SDK/jre/lib/i386/server:/share/MD0_DATA/.qpkg/Java-SDK/jre/lib/i386:/share/MD0_DATA/.qpkg/Java-SDK/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
May 14, 2009 2:51:07 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8085
May 14, 2009 2:51:07 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 516 ms
May 14, 2009 2:51:07 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
May 14, 2009 2:51:07 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.18
May 14, 2009 2:51:08 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8085
May 14, 2009 2:51:08 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
May 14, 2009 2:51:08 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/34  config=null
May 14, 2009 2:51:08 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 768 ms
May 14, 2009 2:59:48 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive subsonic.war
May 14, 2009 2:59:50 PM 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.
May 14, 2009 2:59:50 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 2:59:50 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors
May 14, 2009 3:00:22 PM 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.
May 14, 2009 3:00:22 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 3:00:22 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors
May 14, 2009 3:00:36 PM 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.
May 14, 2009 3:00:36 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 3:00:36 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors
May 14, 2009 3:05:29 PM 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.
May 14, 2009 3:05:29 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 3:05:29 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors
May 14, 2009 3:11:25 PM 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.
May 14, 2009 3:11:25 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 3:11:25 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors
May 14, 2009 5:28:19 PM org.apache.coyote.http11.Http11Protocol pause
INFO: Pausing Coyote HTTP/1.1 on http-8085
May 14, 2009 5:28:20 PM org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
May 14, 2009 5:28:20 PM org.apache.coyote.http11.Http11Protocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-8085
May 14, 2009 5:32:44 PM org.apache.catalina.core.AprLifecycleListener init
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /share/MD0_DATA/.qpkg/Java-SDK/jre/lib/i386/server:/share/MD0_DATA/.qpkg/Java-SDK/jre/lib/i386:/share/MD0_DATA/.qpkg/Java-SDK/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
May 14, 2009 5:32:44 PM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8085
May 14, 2009 5:32:44 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 714 ms
May 14, 2009 5:32:44 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
May 14, 2009 5:32:44 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.18
May 14, 2009 5:32:44 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive subsonic.war
May 14, 2009 5:32:46 PM 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.
May 14, 2009 5:32:47 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 5:32:47 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors
May 14, 2009 5:32:47 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8085
May 14, 2009 5:32:47 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
May 14, 2009 5:32:47 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/81  config=null
May 14, 2009 5:32:47 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 3633 ms
May 14, 2009 5:37:04 PM 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.
May 14, 2009 5:37:04 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 5:37:04 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors
May 14, 2009 5:38:55 PM 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.
May 14, 2009 5:38:55 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 5:38:55 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors
May 14, 2009 5:39:00 PM 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.
May 14, 2009 5:39:00 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 5:39:00 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors
May 14, 2009 5:39:09 PM 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.
May 14, 2009 5:39:09 PM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 14, 2009 5:39:09 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/subsonic] startup failed due to previous errors


[L4ngley]

The logs are to long,
Here you go: REMOVED

[sindre_mehus]

Thanks! I see what the problem is. The war version of 3.7.beta1 is unfortunately broken (mentioned elsewhere on this forum).

What I suggest you do is:

o Stop Tomcat
o Remove everything related to Subsonic in TOMCAT_HOME/webapps
o Remove everything in TOMCAT_HOME/work
o Remove /var/subsonic

Then deploy the 3.6 version of Subsonic and see how that goes.

[L4ngley]

Good news Sindre. It works. Thanx for all the help, if you want to check it out i wil PM you login.

This was actually quite easy.
If the war would have worked last time, anyone, even me could have made it.

Now wil try and add some music and test some perfomrance.
The NAS has a a Core 2 Duo 2.4 GHz and 2 gig ram, so its not very representative for the average NAS out there but it should give you an idea on how it it performs on the NAS.

[sindre_mehus]

Excellent!

If you could post a small summary in the Tutorial section, that would be really great.

Thanks,
Sindre

[L4ngley]

Wildo

[L4ngley]

Was trying to access Subsonic today, got an error page, wich gives me the info below:Everyting else on the nas seems to be running and working fine.
Subsonic log can be downloaded from here:
Iif you stil are intereseted in "Subsonic for NAS" you migt wnat to look at the logfile: http://www.cia.as/subsonic.log


Error
Subsonic encountered an internal error. You can report this error in the Subsonic Forum. Please include the information below.

Exception org.springframework.jdbc.CannotGetJdbcConnectionException
Message Could not get JDBC Connection; nested exception is java.sql.SQLException: File input/output error: /var/subsonic/db/subsonic.log
Java version Sun Microsystems Inc. 1.6.0_12
Operating system Linux 2.6.24
Server Apache Tomcat/6.0.18
Memory Used 95 of 144 MB
Stack trace org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: File input/output error: /var/subsonic/db/subsonic.log at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:82) at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:552) at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:616) at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:645) at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:653) at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:685) at net.sourceforge.subsonic.dao.AbstractDao.query(AbstractDao.java:40) at net.sourceforge.subsonic.dao.AbstractDao.queryOne(AbstractDao.java:44) at net.sourceforge.subsonic.dao.UserDao.getUserByName(UserDao.java:70) at net.sourceforge.subsonic.service.SecurityService.getUserByName(SecurityService.java:109) at net.sourceforge.subsonic.controller.MultiController.login(MultiController.java:61) at sun.reflect.GeneratedMethodAccessor155.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.springframework.web.servlet.mvc.multiaction.MultiActionController.invokeNamedMethod(MultiActionController.java:472) at org.springframework.web.servlet.mvc.multiaction.MultiActionController.handleRequestInternal(MultiActionController.java:409) at org.springframework.web.servlet.mvc.AbstractController.handleRequest(AbstractController.java:153) at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48) at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:874) at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:808) at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:476) at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:431) at javax.servlet.http.HttpServlet.service(HttpServlet.java:617) at javax.servlet.http.HttpServlet.service(HttpServlet.java:717) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:265) at org.acegisecurity.intercept.web.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:107) at org.acegisecurity.intercept.web.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:72) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275) at org.acegisecurity.ui.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:166) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275) at org.acegisecurity.providers.anonymous.AnonymousProcessingFilter.doFilter(AnonymousProcessingFilter.java:125) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275) at org.acegisecurity.ui.rememberme.RememberMeProcessingFilter.doFilter(RememberMeProcessingFilter.java:135) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275) at org.acegisecurity.wrapper.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:81) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275) at org.acegisecurity.ui.basicauth.BasicProcessingFilter.doFilter(BasicProcessingFilter.java:173) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275) at org.acegisecurity.ui.AbstractProcessingFilter.doFilter(AbstractProcessingFilter.java:271) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275) at org.acegisecurity.ui.logout.LogoutFilter.doFilter(LogoutFilter.java:110) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275) at org.acegisecurity.context.HttpSessionContextIntegrationFilter.doFilter(HttpSessionContextIntegrationFilter.java:249) at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275) at org.acegisecurity.util.FilterChainProxy.doFilter(FilterChainProxy.java:149) at org.acegisecurity.util.FilterToBeanProxy.doFilter(FilterToBeanProxy.java:98) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at net.sourceforge.subsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:845) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583) at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447) at java.lang.Thread.run(Thread.java:619) Caused by: java.sql.SQLException: File input/output error: /var/subsonic/db/subsonic.log at org.hsqldb.jdbc.Util.sqlException(Unknown Source) at org.hsqldb.jdbc.jdbcConnection.(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:283) at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnectionFromDriverManager(DriverManagerDataSource.java:271) at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnectionFromDriverManager(DriverManagerDataSource.java:255) at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnection(DriverManagerDataSource.java:236) at org.springframework.jdbc.datasource.DataSourceUtils.doGetConnection(DataSourceUtils.java:113) at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:79) ... 68 more

[L4ngley]

I totaly understand if you dont want to spend time on this, I can just revert to running it on a server. no big deal actually.

You can also se the error file on the URL you got, have delibertly not restarted the NAS yet.

L4ngley

[sindre_mehus]

Looks like some kind of problem with the (HSQL) database Subsonic is using.

To investigate it further, can you please provide me this file:
/var/subsonic/db/subsonic.log

Then I suggest just restarting Subsonic and see if the database is able to recover itself. If not, you could try to wipe out /var/subsonic/db. It will then be recreated next time you start Subsonic.

Thanks,
Sindre

[L4ngley]

Sure, that was a big log file. 22 megs.
Its now ziped and avilable here:
http://www.cia.as/subsonic.zip

Have restarted the application and now subsonic is working again.
Hope it was just a glitch. But I have not touched the NAS config i any way after we got subsonic running.

Thanx again.

[sindre_mehus]

Thanks, I got the log file, so feel free to remove it from your server.

I couldn't see anything special with it (except perhaps that it was quite big).

Can you please let me know if it happens again? And if it does, can you check how much disk space is available on /var. (df -k /var)

Thanks,
Sindre

[L4ngley]

Has not happened again, but
got curious on how much space there was in var,

Code: Select all

[/] # df -k /var
Filesystem           1k-blocks      Used Available Use% Mounted on

Didnt get much info out of that.
But when using WinSCP it says the var contains 15 MB. Tried to figure out how big Qnap made var on the TS-809, but no luck so far.