3.7 Beta - can't start subsonic with Tomcat

Need help? Post your questions here.

Moderator: moderators

3.7 Beta - can't start subsonic with Tomcat

Postby pheckel » Sat May 09, 2009 2:10 pm

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
 
Posts: 6
Joined: Thu Jan 25, 2007 3:45 am

3.7 beta trouble - More Log Info

Postby pheckel » Sat May 09, 2009 2:37 pm

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()
pheckel
 
Posts: 6
Joined: Thu Jan 25, 2007 3:45 am

Postby sindre_mehus » Mon May 11, 2009 11:30 am

Will be fixed in 3.7.beta2. Thanks for reporting!
User avatar
sindre_mehus
 
Posts: 1955
Joined: Tue Nov 29, 2005 6:19 pm
Location: Oslo, Norway

Postby flacflac » Sat Jun 06, 2009 10:47 am

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? :wink:

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

Thank you,
flacflac
flacflac
 
Posts: 141
Joined: Mon Jun 01, 2009 7:43 am

Solved...

Postby flacflac » Sat Jun 06, 2009 11:01 am

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!
flacflac
 
Posts: 141
Joined: Mon Jun 01, 2009 7:43 am

Postby predator7 » Fri Jun 26, 2009 9:56 am

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
 
Posts: 14
Joined: Wed Jun 24, 2009 10:22 am
Location: Germany

Postby predator7 » Fri Jun 26, 2009 10:48 am

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
 
Posts: 14
Joined: Wed Jun 24, 2009 10:22 am
Location: Germany

Postby predator7 » Fri Jun 26, 2009 1:53 pm

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.
predator7
 
Posts: 14
Joined: Wed Jun 24, 2009 10:22 am
Location: Germany


Return to Help

Who is online

Users browsing this forum: No registered users and 17 guests