java.io.FileNotFoundException (missing directories)

[MikeLegit]

I have moved some directories while organizing my music and normally subsonic picks up fine on the changes. Now with this latest release it just craps out the service. I tried many times to clean database in the settings page and it never cleans the invalid entries, even after completing the new music scan. This is obviously a huge problem as it kills the service. You can see at the end of the log here it cannot find coverart and it self destructs. Does anyone know how to fix this? Subsonic has been so stable all these years so this comes as a surprise to me.

License Subsonic Premium
Version 5.2.1 (build 4427) – March 4, 2015
Server jetty-6.1.x, java 1.8.0_06, Linux (86.9 MB / 132.8 MB)

Code: Select all

[/share/MD0_DATA/.qpkg/Subsonic/home] # cat subsonic_sh.log
59 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@12b1dae: display name [org.springframework.context.support.ClassPathXmlApplicationContext@12b1dae]; startup date [Sat Apr 18 02:57:27 GMT 2015]; root of context hierarchy
310 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [applicationContext-deployer.xml]
1588 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Bean factory for application context [org.springframework.context.support.ClassPathXmlApplicationContext@12b1dae]: org.springframework.beans.factory.support.DefaultListableBeanFactory@a7e666
1656 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@a7e666: defining beans [service,org.springframework.remoting.rmi.RmiServiceExporter#0]; root of factory hierarchy
1747 [main] INFO org.mortbay.log - Logging to org.slf4j.impl.SimpleLogger(org.mortbay.log) via org.mortbay.log.Slf4jLog
Using WAR file: /share/MD0_DATA/.qpkg/Subsonic/subsonic.war
Extracting webapp to /share/MD0_DATA/.qpkg/Subsonic/home/jetty/4427
Using WAR file: /share/MD0_DATA/.qpkg/Subsonic/subsonic.war
1992 [main] INFO org.mortbay.log - jetty-6.1.x
SLF4J: Class path contains multiple SLF4J bindings.
SLF4J: Found binding in [jar:file:/share/MD0_DATA/.qpkg/Subsonic/subsonic-booter-jar-with-dependencies.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/share/MD0_DATA/.qpkg/Subsonic/home/jetty/4427/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.
5623 [main] INFO /subsonic - Initializing Spring root WebApplicationContext
5623 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization started
5711 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@1d477ed: display name [Root WebApplicationContext]; startup date [Sat Apr 18 02:57:32 GMT 2015]; root of context hierarchy
5838 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-service.xml]
6150 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-security.xml]
6334 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-cache.xml]
6407 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-sonos.xml]
6544 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [META-INF/cxf/cxf.xml]
6618 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [META-INF/cxf/cxf-servlet.xml]
7524 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@1d477ed]: org.springframework.beans.factory.support.DefaultListableBeanFactory@d28fa7
8270 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@d28fa7: defining beans [playerDao,mediaFileDao,artistDao,albumDao,playlistDao,playQueueDao,internetRadioDao,musicFileInfoDao,musicFolderDao,userDao,transcodingDao,podcastDao,avatarDao,shareDao,bookmarkDao,daoHelper,mediaFileService,securityService,settingsService,mediaScannerService,searchService,networkService,playerService,playlistService,versionService,statusService,ratingService,musicIndexService,audioScrobblerService,transcodingService,shareService,podcastService,adService,jukeboxService,folderBasedContentDirectory,upnpService,lastFmService,sonosService,metaDataParserFactory,ajaxMultiService,ajaxNowPlayingService,ajaxPlayQueueService,ajaxPlaylistService,ajaxLyricsService,ajaxCoverArtService,ajaxStarService,ajaxTagService,ajaxTransferService,ajaxChatService,localeResolver,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,cxf,org.apache.cxf.bus.spring.BusWiringBeanFactoryPostProcessor,org.apache.cxf.bus.spring.Jsr250BeanPostProcessor,org.apache.cxf.bus.spring.BusExtensionPostProcessor,sonosEndpoint,sonosHelper]; root of factory hierarchy
8356 [main] INFO org.springframework.jdbc.datasource.DriverManagerDataSource - Loaded JDBC driver: org.hsqldb.jdbcDriver
56603 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
56637 [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]]
56638 [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]]
56640 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
61450 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization completed in 55826 ms
62666 [main] INFO /subsonic - Initializing Spring FrameworkServlet 'subsonic'
62666 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization started
62669 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@1d2e270: display name [WebApplicationContext for namespace 'subsonic-servlet']; startup date [Sat Apr 18 02:58:29 GMT 2015]; parent: org.springframework.web.context.support.XmlWebApplicationContext@1d477ed
62670 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/subsonic-servlet.xml]
62981 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@1d2e270]: org.springframework.beans.factory.support.DefaultListableBeanFactory@4ac145
63427 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@4ac145: defining beans [leftController,rightController,statusController,mainController,playlistController,playlistsController,importPlaylistController,topController,helpController,moreController,uploadController,lyricsController,allmusicController,podcastController,podcastReceiverController,podcastReceiverAdminController,setMusicFileInfoController,shareManagementController,setRatingController,randomPlayQueueController,changeCoverArtController,nowPlayingController,starredController,searchController,settingsController,playerSettingsController,dlnaSettingsController,sonosSettingsController,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,premiumController,multiController,wapController,restController,dbController,premiumValidator,userSettingsValidator,passwordSettingsValidator,urlMapping,messageSource,themeSource,themeResolver,viewResolver]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@d28fa7
69606 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization completed in 6940 ms
69654 [main] INFO org.mortbay.log - Started SelectChannelConnector@0.0.0.0:4040
70371 [main] INFO org.mortbay.log - Started SslSocketConnector@0.0.0.0:4041
Subsonic running on: http://localhost:4040/subsonic
                and: https://localhost:4041/subsonic
70595 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Looking for RMI registry at port '9412'
70633 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Could not detect RMI registry - creating new one
70796 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Binding service 'SubsonicDeployerService' to RMI registry: RegistryImpl[UnicastServerRef [liveRef: [endpoint:[192.168.1.10:9412](local),objID:[0:0:0, 0]]]]
105581 [btpool0-5] INFO org.directwebremoting.log.startup - Starting: DwrServlet v3.0.0.116.rc1 on jetty-6.1.x / JDK 1.8.0_06 from Oracle Corporation at /subsonic
3162807 [btpool0-6] WARN org.mortbay.log - /subsonic/coverArt.view: java.io.FileNotFoundException: /share/MD0_DATA/Music/Blues/[2006] You & Me/06 - Tamp Em Up  Solid .mp3 (No such file or directory)
3357681 [btpool0-6] WARN org.mortbay.log - /subsonic/coverArt.view: java.io.FileNotFoundException: /share/MD0_DATA/Music/Blues/[2006] You & Me/06 - Tamp Em Up  Solid .mp3 (No such file or directory)
64924202 [btpool0-255] WARN org.mortbay.log - /subsonic/coverArt.view: java.io.FileNotFoundException: /share/MD0_DATA/Music/Alternative/Cold.Play/No Stranger to Shame/cover.jpg (No such file or directory)
85897361 [btpool0-283] WARN org.mortbay.log - /subsonic/coverArt.view: java.io.FileNotFoundException: /share/MD0_DATA/Music/Alternative/Cold.Play/No Stranger to Shame/cover.jpg (No such file or directory)
[/share/MD0_DATA/.qpkg/Subsonic/home] #


[madhusker]

Alright, since everyone is stumped on this one, I think I have a fix for ya. If you create the missing directory and re-index subsonic, and clean database, it will then properly see the bad directory without crashing! Then remove the directory again and clean database AND refresh library. I had to do this 3 times and it worked! It used to handle it perfectly when you deleted a directory, but now it throws it into a crash!

I agree, this last "stable" release is not stable at all. I have to restart the service every day now and it just goes funct on me! Logs don't show anything died, but it's borked until you restart service. Not good!!