3.7 Beta - can't start subsonic with Tomcat

[pheckel]

Hi all,

I tried upgrading to the 3.7 beta today and can't get tomcat to start it. Went back to 3.6 to ensure my tomcat instance isn't the culprit...3.6 works fine. Here is my config:

Ubuntu 9.04 - amd64
Tomcat 6.0.18
Java 1.6.0_13-b03
Subsonic 3.7 beta (war file deployment)

Subsonic doesn't start after starting tomcat via command line.

Trying to start subsonic via the tomcat manager GUI I receive: "FAIL - Application at context path /subsonic could not be started"

/var/subsonic/subsonic.log indicates:

[2009-05-09 08:50:15,403] 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]:

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)

Not sure if this error is related to the root cause. I should also mention that I tried rebooting the server (a feeble attempt to free up any strange locking behavior). I'm happy to post my full tomcat log if anyone thinks that may help. Any assistance would be appreciated!

[pheckel]

More info regarding my post above. I cleaned out all logs and started tomcat with the 3.7 beta .war in the webapps directory. Here is my tomcat log:

INFO: Initializing Spring root WebApplicationContext
May 9, 2009 9:30:02 AM org.apache.catalina.core.StandardContext listenerStart
SEVERE: Exception sending context initialized event to listener instance of class org.springframework.web.context.ContextLoaderListener
org.springframework.beans.factory.CannotLoadBeanClassException: Cannot find class [org.springframework.aop.framework.ProxyFactoryBean] for bean with name 'ajaxTagServiceSecure' defined in ServletContext resource [/WEB-INF/applicationContext-security.xml]; nested exception is java.lang.ClassNotFoundException: org.springframework.aop.framework.ProxyFactoryBean
at org.springframework.beans.factory.support.AbstractBeanFactory.resolveBeanClass(AbstractBeanFactory.java:1160)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.predictBeanType(AbstractAutowireCapableBeanFactory.java:581)
at org.springframework.beans.factory.support.AbstractBeanFactory.isFactoryBean(AbstractBeanFactory.java:1364)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:406)
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.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:525)
at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:830)
at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:719)
at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:490)
at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1149)
at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:311)
at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:117)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
at org.apache.catalina.core.StandardHost.start(StandardHost.java:719)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
at org.apache.catalina.core.StandardService.start(StandardService.java:516)
at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
at org.apache.catalina.startup.Catalina.start(Catalina.java:578)
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:288)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:413)
Caused by: java.lang.ClassNotFoundException: org.springframework.aop.framework.ProxyFactoryBean
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1387)
at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1233)
at org.springframework.util.ClassUtils.forName(ClassUtils.java:230)
at org.springframework.beans.factory.support.AbstractBeanDefinition.resolveBeanClass(AbstractBeanDefinition.java:371)
at org.springframework.beans.factory.support.AbstractBeanFactory.resolveBeanClass(AbstractBeanFactory.java:1157)
... 32 more
May 9, 2009 9:30:02 AM org.apache.catalina.core.ApplicationContext log
INFO: Closing Spring root WebApplicationContext
May 9, 2009 9:30:02 AM org.apache.catalina.core.ApplicationContext log
INFO: ContextListener: contextInitialized()
May 9, 2009 9:30:02 AM org.apache.catalina.core.ApplicationContext log
INFO: SessionListener: contextInitialized()

[sindre_mehus]

Will be fixed in 3.7.beta2. Thanks for reporting!

[flacflac]

Sindre, I get the same error message

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

You had a hotfix for another problem with Tomcat and the latest beta, is there a hotfix for this too, by any chance?

The jukebox feature as well as the fix for streaming transcodes would be soooo good to have!

Thank you,
flacflac

[flacflac]

Oh yeah, problem solved, man!

1. Removed all folders following you installation instructions for .WAR releases.

2. Copied .WAR into Tomcat's webapps

3. Started Tomcat

4. Stopped Tomcat

5. Deleted subsonic\db folder

6. Restarted Tomcat

7. In Tomcat's manager hit "start" for subsonic

8. localhost/subsonic works!

Sweeet!

[predator7]

Hi,

i have the same problem to start subsonic 3.7 with tomcat6 on ubuntu 9.04 .

i install tomcat 6, i put the subsonic.war in /var/lib/tomcat6/webapps/. in tomcat manager i press start for subsonic, but this error message comes up (FAIL - Application at context path /subsonic could not be started).

what doing im wrong, is it mayby file rigths and file owner for subsonic.war file, or the created /subsonic directory ?

help please

[predator7]

here is the log:

Code: Select all

26.06.2009 12:30:11 org.apache.catalina.core.ApplicationContext log
INFO: HTMLManager: init: Associated with Deployer 'Catalina:type=Deployer,host=localhost'
26.06.2009 12:30:11 org.apache.catalina.core.ApplicationContext log
INFO: HTMLManager: init: Global resources are available
26.06.2009 12:30:11 org.apache.catalina.core.ApplicationContext log
INFO: HTMLManager: list: Listing contexts for virtual host 'localhost'
26.06.2009 12:30:18 org.apache.catalina.core.ApplicationContext log
INFO: HTMLManager: start: Starting web application at '/subsonic'
26.06.2009 12:30:20 org.apache.catalina.core.StandardContext listenerStart
SEVERE: Exception sending context initialized event to listener instance of class org.springframework.web.context.ContextLoaderListener
java.lang.ExceptionInInitializerError
   at org.springframework.web.context.ContextLoader.<init>(ContextLoader.java:144)
   at org.springframework.web.context.ContextLoaderListener.createContextLoader(ContextLoaderListener.java:53)
   at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:44)
   at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3843)
   at org.apache.catalina.core.StandardContext.start(StandardContext.java:4338)
   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 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.security.SecurityUtil$1.run(SecurityUtil.java:244)
   at java.security.AccessController.doPrivileged(Native Method)
   at javax.security.auth.Subject.doAsPrivileged(Subject.java:517)
   at org.apache.catalina.security.SecurityUtil.execute(SecurityUtil.java:276)
   at org.apache.catalina.security.SecurityUtil.doAsPrivilege(SecurityUtil.java:162)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:283)
   at org.apache.catalina.core.ApplicationFilterChain.access$000(ApplicationFilterChain.java:56)
   at org.apache.catalina.core.ApplicationFilterChain$1.run(ApplicationFilterChain.java:189)
   at java.security.AccessController.doPrivileged(Native Method)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:185)
   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.security.AccessControlException: access denied (java.util.PropertyPermission org.apache.commons.logging.LogFactory.HashtableImpl read)
   at java.security.AccessControlContext.checkPermission(AccessControlContext.java:323)
   at java.security.AccessController.checkPermission(AccessController.java:546)
   at java.lang.SecurityManager.checkPermission(SecurityManager.java:532)
   at java.lang.SecurityManager.checkPropertyAccess(SecurityManager.java:1285)
   at java.lang.System.getProperty(System.java:652)
   at org.apache.commons.logging.LogFactory.createFactoryStore(LogFactory.java:320)
   at org.apache.commons.logging.LogFactory.<clinit>(LogFactory.java:1725)
   ... 35 more
26.06.2009 12:30:20 org.apache.catalina.core.ApplicationContext log
INFO: HTMLManager: list: Listing contexts for virtual host 'localhost'
26.06.2009 12:40:18 org.apache.catalina.core.ApplicationContext log
INFO: SessionListener: contextDestroyed()
26.06.2009 12:40:18 org.apache.catalina.core.ApplicationContext log
INFO: ContextListener: contextDestroyed()
26.06.2009 12:41:46 org.apache.catalina.core.ApplicationContext log
INFO: ContextListener: contextInitialized()
26.06.2009 12:41:46 org.apache.catalina.core.ApplicationContext log

[predator7]

half day of searching to find out that tomcat is stsarting by default with a security option, that is changeble in /etc/init.d/tomcat6. now all works fine.