Subsonic stops working intermittently requiring restart

[rockguitardude]

I have subsonic running on a windows 8 pc. When it works, it works flawlessly. However every day or so, sometimes less, the service seems I stop working and I am unable to get it to restart. When I restart windows 8 it works again.

I reverted to java 6 and a, still having this problem. Any ideas?

Thanks.

[rockguitardude]

Just some additional information, when this happens, typically the "Service Status field" in status tab of the subsonic control panel will go blank when this happens. There is no "visible" crash causing this.

Any ideas?

[GJ51]

Are you using SSL?

[rockguitardude]

Not using SSL. Using port 2112. Previously was using ports 8080 & 8088. Same issues.

Thanks.

[BKKKPewsey]

Check in services.msc to see if SS service is running OK.
Also check your event log for any pertinent information.

[rockguitardude]

Right now I am able to see the service and it allows me to start and stop it. When it stops working, it the start and stop buttons become greyed out and I am unable to restart the service. Furthermore, the "Start Subsonic Service" also doesn't work but I presume that would try to do the same thing? Once it stops working the only way I've managed to get it back up and running is via a full restart.

Here's the log file:

[3/10/13 8:15:08 PM EDT] INFO DaoHelper Checking database schema.
[3/10/13 8:15:11 PM EDT] INFO DaoHelper Done checking database schema.
[3/10/13 8:15:11 PM EDT] INFO VersionService Resolved local Subsonic version to: 4.7
[3/10/13 8:15:11 PM EDT] INFO MediaScannerService Automatic media library scanning scheduled to run every 1 day(s), starting at Mon Mar 11 03:00:00 EDT 2013
[3/10/13 8:15:11 PM EDT] INFO PodcastService Automatic Podcast update scheduled to run every 24 hour(s), starting at Sun Mar 10 20:20:11 EDT 2013
[3/10/13 8:20:11 PM EDT] INFO PodcastService Starting scheduled Podcast refresh.
[3/10/13 8:20:11 PM EDT] INFO PodcastService Completed scheduled Podcast refresh.
[3/10/13 8:20:25 PM EDT] INFO NetworkService Successfully forwarding port 2112.
[3/10/13 9:23:29 PM EDT] INFO NetworkService Successfully forwarding port 2112.
[3/10/13 10:04:31 PM EDT] INFO PlayerDao Created player 98.
[3/10/13 10:04:31 PM EDT] INFO VersionService Resolved latest Subsonic final version to: 4.7
[3/10/13 10:04:31 PM EDT] INFO VersionService Resolved latest Subsonic beta version to: 4.7.beta3


Unfortunately, when it stops working, I can't get to the web interface to see this.

[rockguitardude]

I just "purposely" made it not work by stopping the subsonic service using the "stop" button in the system tray subsonic control panel.

Whenever I do this, the service just "hangs" in perpetual "stopping" mode.

The log says this (appears to be some errors at the end):


12 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@758fc9: display name [org.springframework.context.support.ClassPathXmlApplicationContext@758fc9]; startup date [Sun Mar 10 22:06:43 EDT 2013]; root of context hierarchy
53 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [applicationContext-deployer.xml]
301 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Bean factory for application context [org.springframework.context.support.ClassPathXmlApplicationContext@758fc9]: org.springframework.beans.factory.support.DefaultListableBeanFactory@11121f6
309 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@11121f6: defining beans [service,org.springframework.remoting.rmi.RmiServiceExporter#0]; root of factory hierarchy
326 [main] INFO org.mortbay.log - Logging to org.slf4j.impl.SimpleLogger(org.mortbay.log) via org.mortbay.log.Slf4jLog
Using WAR file: C:\Program Files (x86)\Subsonic\subsonic.war
Extracting webapp to c:\subsonic\jetty\3106
Using WAR file: C:\Program Files (x86)\Subsonic\subsonic.war
354 [main] INFO org.mortbay.log - jetty-6.1.x
SLF4J: Class path contains multiple SLF4J bindings.
SLF4J: Found binding in [jar:file:/C:/Program%20Files%20(x86)/Subsonic/subsonic-booter-jar-with-dependencies.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/C:/subsonic/jetty/3106/webapp/WEB-INF/lib/slf4j-log4j12-1.6.1.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
815 [main] INFO / - Initializing Spring root WebApplicationContext
815 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization started
834 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@136a43c: display name [Root WebApplicationContext]; startup date [Sun Mar 10 22:06:44 EDT 2013]; root of context hierarchy
868 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-service.xml]
928 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-security.xml]
956 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-cache.xml]
970 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@136a43c]: org.springframework.beans.factory.support.DefaultListableBeanFactory@e64686
1134 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@e64686: defining beans [playerDao,mediaFileDao,artistDao,albumDao,playlistDao,internetRadioDao,musicFileInfoDao,musicFolderDao,userDao,transcodingDao,podcastDao,avatarDao,shareDao,daoHelper,mediaFileService,securityService,settingsService,mediaScannerService,searchService,networkService,playerService,playlistService,versionService,statusService,musicInfoService,musicIndexService,audioScrobblerService,transcodingService,shareService,podcastService,adService,jukeboxService,metaDataParserFactory,ajaxMultiService,ajaxNowPlayingService,ajaxPlayQueueService,ajaxPlaylistService,ajaxLyricsService,ajaxCoverArtService,ajaxStarService,ajaxTagService,ajaxTransferService,ajaxChatService,filterChainProxy,httpSessionContextIntegrationFilter,logoutFilter,authenticationProcessingFilter,basicProcessingFilter,restRequestParameterProcessingFilter,basicProcessingFilterEntryPoint,securityContextHolderAwareRequestFilter,rememberMeProcessingFilter,anonymousProcessingFilter,exceptionTranslationFilter,basicExceptionTranslationFilter,filterInvocationInterceptor,accessDecisionManager,rememberMeServices,authenticationManager,daoAuthenticationProvider,userCacheWrapper,ldapAuthenticationProvider,bindAuthenticator,userDetailsServiceBasedAuthoritiesPopulator,ajaxServiceInterceptor,ajaxTagServiceSecure,ajaxTransferServiceSecure,cacheFactory,userCache,mediaFileMemoryCache,musicFileMemoryCache]; root of factory hierarchy
1206 [main] INFO org.springframework.jdbc.datasource.DriverManagerDataSource - Loaded JDBC driver: org.hsqldb.jdbcDriver
2804 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
2809 [main] INFO org.acegisecurity.intercept.method.MethodDefinitionMap - Adding secure method [public net.sourceforge.subsonic.ajax.UploadInfo net.sourceforge.subsonic.ajax.TransferService.getUploadInfo()] with attributes [[ROLE_UPLOAD]]
2809 [main] INFO org.acegisecurity.intercept.method.MethodDefinitionMap - Adding secure method [public java.lang.String net.sourceforge.subsonic.ajax.TagService.setTags(int,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String)] with attributes [[ROLE_COVERART]]
2809 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
2822 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization completed in 2007 ms
2852 [main] INFO / - Initializing Spring FrameworkServlet 'subsonic'
2852 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization started
2853 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@1c6a99d: display name [WebApplicationContext for namespace 'subsonic-servlet']; startup date [Sun Mar 10 22:06:46 EDT 2013]; parent: org.springframework.web.context.support.XmlWebApplicationContext@136a43c
2853 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/subsonic-servlet.xml]
2889 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@1c6a99d]: org.springframework.beans.factory.support.DefaultListableBeanFactory@1b82d69
2942 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@1b82d69: defining beans [leftController,rightController,statusController,mainController,playlistController,importPlaylistController,topController,helpController,moreController,uploadController,lyricsController,allmusicController,podcastController,podcastReceiverController,podcastReceiverAdminController,setMusicFileInfoController,shareManagementController,setRatingController,randomPlayQueueController,changeCoverArtController,nowPlayingController,starredController,searchController,settingsController,playerSettingsController,shareSettingsController,musicFolderSettingsController,networkSettingsController,transcodingSettingsController,internetRadioSettingsController,podcastSettingsController,generalSettingsController,advancedSettingsController,personalSettingsController,avatarUploadController,userSettingsController,passwordSettingsController,homeController,editTagsController,playQueueController,coverArtController,avatarController,proxyController,statusChartController,userChartController,m3uController,streamController,hlsController,videoPlayerController,externalPlayerController,downloadController,donateController,multiController,wapController,restController,dbController,donateValidator,userSettingsValidator,passwordSettingsValidator,urlMapping,messageSource,themeSource,localeResolver,themeResolver,viewResolver]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@e64686
3221 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization completed in 369 ms
3231 [main] INFO org.mortbay.log - Started SelectChannelConnector@0.0.0.0:2112
Subsonic running on: http://localhost:2112/
3265 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Looking for RMI registry at port '9412'
4273 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Could not detect RMI registry - creating new one
4292 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Binding service 'SubsonicDeployerService' to RMI registry: RegistryImpl[UnicastServerRef [liveRef: [endpoint:[192.168.1.167:9412](local),objID:[0:0:0, 0]]]]
6194 [btpool0-1] ERROR org.mortbay.log - /rest/stream.view
java.lang.IllegalStateException: STREAM
at org.mortbay.jetty.Response.getWriter(Response.java:583)
at net.sourceforge.subsonic.controller.RESTController.error(RESTController.java:1681)
at net.sourceforge.subsonic.filter.RESTFilter.handleException(RESTFilter.java:66)
at net.sourceforge.subsonic.filter.RESTFilter.doFilter(RESTFilter.java:53)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:73)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:712)
at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:139)
at org.mortbay.jetty.Server.handle(Server.java:313)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:506)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:830)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:514)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:381)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:396)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:442)
8362 [btpool0-1] INFO org.directwebremoting.log.startup - Starting: DwrServlet v3.0.0.116.rc1 on jetty-6.1.x / JDK 1.6.0_27 from Sun Microsystems Inc. at
8747 [btpool0-2] ERROR org.mortbay.log - /rest/stream.view
java.lang.IllegalStateException: STREAM
at org.mortbay.jetty.Response.getWriter(Response.java:583)
at net.sourceforge.subsonic.controller.RESTController.error(RESTController.java:1681)
at net.sourceforge.subsonic.filter.RESTFilter.handleException(RESTFilter.java:66)
at net.sourceforge.subsonic.filter.RESTFilter.doFilter(RESTFilter.java:53)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:712)
at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:139)
at org.mortbay.jetty.Server.handle(Server.java:313)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:506)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:830)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:514)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:381)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:396)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:442)
Shutting down database.
Done.

[rockguitardude]

any ideas?

[GJ51]

9 times out of 10, unibstalling Java and then doing a clean install of the latest Java will fis this sort of thing.

[rockguitardude]

Ok, I tried that, we'll see how it goes! I'll report back!

[rockguitardude]

Still getting these errors after uninstalling and reinstalling java:

Subsonic running on: http://localhost:2112/
5852 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Looking for RMI registry at port '9412'
6860 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Could not detect RMI registry - creating new one
6891 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Binding service 'SubsonicDeployerService' to RMI registry: RegistryImpl[UnicastServerRef [liveRef: [endpoint:[192.168.1.167:9412](local),objID:[0:0:0, 0]]]]
67374 [btpool0-5] ERROR org.mortbay.log - /rest/stream.view
java.lang.IllegalStateException: STREAM
at org.mortbay.jetty.Response.getWriter(Response.java:583)
at net.sourceforge.subsonic.controller.RESTController.error(RESTController.java:1681)
at net.sourceforge.subsonic.filter.RESTFilter.handleException(RESTFilter.java:66)
at net.sourceforge.subsonic.filter.RESTFilter.doFilter(RESTFilter.java:53)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:712)
at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:139)
at org.mortbay.jetty.Server.handle(Server.java:313)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:506)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:830)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:514)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:381)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:396)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:442)
2445640 [btpool0-10] ERROR org.mortbay.log - /rest/stream.view
java.lang.IllegalStateException: STREAM
at org.mortbay.jetty.Response.getWriter(Response.java:583)
at net.sourceforge.subsonic.controller.RESTController.error(RESTController.java:1681)
at net.sourceforge.subsonic.filter.RESTFilter.handleException(RESTFilter.java:66)
at net.sourceforge.subsonic.filter.RESTFilter.doFilter(RESTFilter.java:53)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:712)
at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:139)
at org.mortbay.jetty.Server.handle(Server.java:313)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:506)
at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:844)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:644)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:381)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:396)
at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:442)
2754823 [btpool0-12] ERROR org.mortbay.log - /rest/stream.view
java.lang.IllegalStateException: STREAM
at org.mortbay.jetty.Response.getWriter(Response.java:583)

Any other ideas?

[nti1094]

I'm having almost the exact same issue, and a clean install also does not help. I think java got an automatic update and then this started happening. How do I go back to an earlier version?

[GJ51]

This type of error is usually caused by an update not finishing properly giving everything a chance to complete properly and can be caused by premature shutdowns/restarts.

Rolling back to different versions of Java probably won't make a difference. The version used isn't as important as getting whichever version is used installed properly.

I consistently use and upgrade to the latest Java on all my Subsonic sites, all of which are on Windows platforms. With the brief exception of a period of time during the 4.7 development there was one instance of version incompatibility that has long since been resolved. This continues to be successful even with updates installed as recently as today.

There are many causes that contribute to installation/update failures. OS problems and impatience with the process running to completion are the most common. The Java install/upgrade typically "disappears" from any evidence that the process is actually taking place while running in the background. If a user gets impatient or wrongly assumes that the update is done and initiates a restart early it will screw the pooch.

If just reinstalling Java doesn't fix the problem, you may have to do a complete ground up reinstall of Subsonic. There are additional options already discussed in detail in other forum posts that outline how to first back up your data prior to doing a clean reinstall.