Subsonic suddenly stopped working on Mac - JDBC error

[jwow313]

Hi all,

I've been a happy donation Mac user of Subsonic for about a year now, and it suddenly stopped working! I upgraded to 4.6 with no problem a few months back, and just noticed today I'm no longer able to connect. When I open the control panel, I get the dreaded HTTP 503 error. I tried completely deinstalling, reinstalling, even downgrading to 4.5. The issue seems to be somewhere in Java. See error in the logs below. Any help would be much appreciated! I'm missing my Subsonic!

2012-01-05 22:16:43,266] INFO DaoHelper - Checking database schema.
[2012-01-05 22:16:43,783] INFO Schema25 - Database table 'version' not found. Creating it.
[2012-01-05 22:16:44,082] ERROR DaoHelper - Failed to initialize database.
org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: error in script file line: 2588 java.io.IOException: org.hsqldb.HsqlException: Value too long
at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:82)
at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:382)
at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:429)
at net.sourceforge.subsonic.dao.schema.Schema25.execute(Schema25.java:36)
at net.sourceforge.subsonic.dao.DaoHelper.checkDatabase(DaoHelper.java:108)
at net.sourceforge.subsonic.dao.DaoHelper.<init>(DaoHelper.java:65)
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:100)
at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:61)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.instantiateBean(AbstractAutowireCapableBeanFactory.java:877)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:839)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:440)
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.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:269)
at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:104)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.applyPropertyValues(AbstractAutowireCapableBeanFactory.java:1245)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1010)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:472)
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.mortbay.jetty.handler.ContextHandler.startContext(ContextHandler.java:530)
at org.mortbay.jetty.servlet.Context.startContext(Context.java:135)
at org.mortbay.jetty.webapp.WebAppContext.startContext(WebAppContext.java:1218)
at org.mortbay.jetty.handler.ContextHandler.doStart(ContextHandler.java:500)
at org.mortbay.jetty.webapp.WebAppContext.doStart(WebAppContext.java:448)
at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
at org.mortbay.jetty.handler.HandlerWrapper.doStart(HandlerWrapper.java:117)
at org.mortbay.jetty.Server.doStart(Server.java:217)
at org.mortbay.component.AbstractLifeCycle.start(AbstractLifeCycle.java:40)
at net.sourceforge.subsonic.booter.deployer.SubsonicDeployer.deployWebApp(SubsonicDeployer.java:131)
at net.sourceforge.subsonic.booter.deployer.SubsonicDeployer.<init>(SubsonicDeployer.java:65)
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:100)
at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:61)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.instantiateBean(AbstractAutowireCapableBeanFactory.java:877)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:839)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:440)
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.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:83)
at net.sourceforge.subsonic.booter.Main.<init>(Main.java:16)
at net.sourceforge.subsonic.booter.Main.main(Main.java:24)
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 apple.launcher.LaunchRunner.run(LaunchRunner.java:116)
at apple.launcher.LaunchRunner.callMain(LaunchRunner.java:51)
at apple.launcher.JavaApplicationLauncher.launch(JavaApplicationLauncher.java:52)
Caused by: java.sql.SQLException: error in script file line: 2588 java.io.IOException: org.hsqldb.HsqlException: Value too long
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)
... 83 more

[BKKKPewsey]

It looks to me more like a possible database corruption.
If you dont mind losing your settings then delete the subsonic\db folder and re-install.
If you have lots of users etc and want to try to keep their settings etc then backup the subsonics.script file in that directory first.
It might be a good idea to backup that file anyhow (if it is still intact?)
Have you anything else on your mac that uses java?
If so try it and see if there are any problems.

[gawee]

did you just change the structure of your files/folders? cause i got the same problem just now and it gave me the same error. i just logged out, entirely quit SS and rebooted. that should eliminate the error.
hope this helps.

-Mac OS X-

[jwow313]

Thanks for the suggestions. I can't think of any other Java apps on my Mac to test... Any suggestions?

No, I haven't changed the structure of my files/folders. I've taken it several steps further than these suggestions. I bit the bullet and dragged Subsonic to the Trash. I confirmed that this also nukes the db folder, along with everything else in /Library/Application Support.

I then tried reinstalling multiple times both the latest version and one version back. Still no luck.

Any other ideas? I'm stumped.

[BKKKPewsey]

Knowing nothing about mac's apart from they keep you dry in the rain I cannot help
but try this https://wiki.csuchico.edu/confluence/display/help/How+to+Check+Your+Java+Version+(MAC)
I presume it is opening up a java console window which may shed some light.

[jwow313]

It reports that I have version 1.6.0_24.

[BKKKPewsey]

Hmm! it looks like Java is installed ok so I am as stumped as you.
With the new install does the SS log still show the same messages?

As I said before I know nothing about your OS so I wont make any other suggestions as I would be guessing in the dark
Lets hope another apple user pops by and helps out.

[ytechie]

This might be going too far, but maybe try uninstalling and reinstalling java?

It looks like everything is having issues. Connecting to the database using JDBC, starting jetty, and so on.

[jwow313]

Well, I couldn't find any way to uninstall Java on a Mac or reinstall for that matter, so I went through the arduous process of installing an update to the OS that would then let me install the latest version of Java (1.6.0_29, I think it was). After all was said and done, I still got the exact same issue! Checked the logs, same errors...

Are there any tests or debugging I can do to narrow down if it's a code issue or a system issue?

[ytechie]

Well, you could try installing subsonic using Apache Tomcat. It may be a little annoying, but I think it might do the trick.

[Kerrnel]

I fixed this on my FreeBSD system as follows... And it is a bug that needs fixing in the source if someone knows how to file one.

I was dying with the log below... Which in my case was due to the Podcast entries in subsonic.script being "too long" for the database presumably, causing INSERT to fail and the whole shabang to come down. (This was with MadSonic build 3600).

The solution was to

1. Stop tomcat (or subsonic or whatever)
2. cd /var/subsonic/db (or wherever you've moved this)
3. sudo rm subsonic.data (and backup if there is one)
4. sudo (favorite-editor) subsonic.script
5. < snip offending lines > ... this takes sleuthing. In my case I cut out all lines with INSERT INTO PODCAST_EPISODE
6. Restart service

Steps 4/5 can be done if you know which lines are bad with:
4a. sudo grep -v 'INSERT INTO PODCAST_EPISODE' subsonic.script > /tmp/subsonic.newscript
5a. sudo cp subsonic.script subsonic.oldscript; sudo sh -c 'cat /tmp/subsonic.newscript > subsonic.script'

Hope this helps someone.

G


2013-09-08_19:42:30.06186 org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'mediaFileService' defined in ServletContext resource [/WEB-INF/applicationContext-service.xml]:
Cannot resolve reference to bean 'metaDataParserFactory' while setting bean property 'metaDataParserFactory'; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean wit
h name 'metaDataParserFactory' defined in ServletContext resource [/WEB-INF/applicationContext-service.xml]: Cannot create inner bean 'net.sourceforge.subsonic.service.metadata.FFmpegParser#40e16993' of type [n
et.sourceforge.subsonic.service.metadata.FFmpegParser] while setting bean property 'parsers' with key [1]; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with n
ame 'net.sourceforge.subsonic.service.metadata.FFmpegParser#40e16993' defined in ServletContext resource [/WEB-INF/applicationContext-service.xml]: Cannot resolve reference to bean 'transcodingService' while se
tting bean property 'transcodingService'; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'transcodingService' defined in ServletContext resource [/WEB
-INF/applicationContext-service.xml]: Cannot resolve reference to bean 'playerService' while setting bean property 'playerService'; nested exception is org.springframework.beans.factory.BeanCreationException: E
rror creating bean with name 'playerService' defined in ServletContext resource [/WEB-INF/applicationContext-service.xml]: Invocation of init method failed; nested exception is org.springframework.jdbc.CannotGe
tJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: error in script file line: 906 java.io.IOException: org.hsqldb.HsqlException: Value too long
2013-09-08_19:42:30.06199 at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:275)
2013-09-08_19:42:30.06203 at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:104)
2013-09-08_19:42:30.06211 at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.applyPropertyValues(AbstractAutowireCapableBeanFactory.java:1245)
2013-09-08_19:42:30.06215 at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1010)
2013-09-08_19:42:30.06218 at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:472)
2013-09-08_19:42:30.06220 at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory$1.run(AbstractAutowireCapableBeanFactory.java:409)
2013-09-08_19:42:30.06224 at java.security.AccessController.doPrivileged(Native Method)
2013-09-08_19:42:30.06227 at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:380)
2013-09-08_19:42:30.06231 at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:264)
2013-09-08_19:42:30.06235 at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
2013-09-08_19:42:30.06238 at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:261)
2013-09-08_19:42:30.06243 at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:185)
2013-09-08_19:42:30.06246 at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:164)
2013-09-08_19:42:30.06249 at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:429)
2013-09-08_19:42:30.06252 at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:728)
2013-09-08_19:42:30.06256 at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:380)
2013-09-08_19:42:30.06259 at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:255)
2013-09-08_19:42:30.06262 at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:199)
2013-09-08_19:42:30.06266 at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:45)
2013-09-08_19:42:30.06269 at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4887)
2013-09-08_19:42:30.06272 at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5381)
2013-09-08_19:42:30.06275 at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150)
2013-09-08_19:42:30.06278 at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901)
2013-09-08_19:42:30.06281 at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877)
2013-09-08_19:42:30.06284 at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:633)
2013-09-08_19:42:30.06287 at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:977)
2013-09-08_19:42:30.06290 at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1654)
2013-09-08_19:42:30.06293 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
2013-09-08_19:42:30.06296 at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
2013-09-08_19:42:30.06300 at java.util.concurrent.FutureTask.run(FutureTask.java:166)
2013-09-08_19:42:30.06303 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
2013-09-08_19:42:30.06306 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
2013-09-08_19:42:30.06310 at java.lang.Thread.run(Thread.java:724)