achmetinternet wrote:PieterB wrote:achmetinternet wrote:Hi Pieter,
I have the correct paths entered
/var/hda/files/music
What is weird (and significant IMHO) that the behavior of the new install seems so unpredictable. Could it be that a Windows install causes less problems then a Linux or Unix install? Apart from some minor problems (sometimes slow M3U upload to the player, but not always; some strange transcoding behavior - maybe connected) everything works - without having to fix anything. I am still using Windows XP SP3.
I will send my log-file to Sindre and hope he can see what is causing this strange behaviour.
Here is the log from subsonic.log:
[2012-08-28 08:08:39,758] INFO DaoHelper - Checking database schema.
[2012-08-28 08:08:40,218] INFO DaoHelper - Done checking database schema.
[2012-08-28 08:08:40,305] INFO VersionService - Resolved local Subsonic version to: 4.7.beta3
[2012-08-28 08:08:40,556] INFO PlayerDao - Deleted 1 player(s) that haven't been used after Fri Jun 29 08:08:40 CEST 2012
[2012-08-28 08:08:40,636] INFO MediaScannerService - Deleted old index file: /var/subsonic/subsonic14.index
[2012-08-28 08:08:40,637] INFO MediaScannerService - Automatic media library scanning scheduled to run every 1 day(s), starting at Wed Aug 29 03:00:00 CEST 2012
[2012-08-28 08:08:40,706] INFO PodcastService - Automatic Podcast update scheduled to run every 24 hour(s), starting at Tue Aug 28 08:13:40 CEST 2012
[2012-08-28 08:08:43,799] INFO NetworkService - Deleted port mapping for port 4040
[2012-08-28 08:08:52,350] INFO PlayerDao - Created player 1.
[2012-08-28 08:08:52,950] INFO VersionService - Resolved latest Subsonic final version to: 4.6
[2012-08-28 08:08:52,950] INFO VersionService - Resolved latest Subsonic beta version to: 4.7.beta3
[2012-08-28 08:13:40,707] INFO PodcastService - Starting scheduled Podcast refresh.
[2012-08-28 08:13:40,708] INFO PodcastService - Completed scheduled Podcast refresh.
[2012-08-28 09:03:17,524] INFO PlayerDao - Created player 2.
[2012-08-28 09:05:25,784] INFO MediaScannerService - Automatic media library scanning scheduled to run every 1 day(s), starting at Wed Aug 29 03:00:00 CEST 2012
[2012-08-28 09:05:29,218] INFO MediaScannerService - Starting to scan media library.
[2012-08-28 09:05:29,297] INFO MediaScannerService - Scanned media library with 0 entries.
[2012-08-28 09:05:29,297] INFO MediaScannerService - Marking non-present files.
[2012-08-28 09:05:29,300] INFO MediaScannerService - Marking non-present artists.
[2012-08-28 09:05:29,301] INFO MediaScannerService - Marking non-present albums.
[2012-08-28 09:05:29,306] INFO MediaScannerService - Completed media library scan.
[2012-08-28 09:09:28,231] INFO MediaScannerService - Automatic media library scanning scheduled to run every 1 day(s), starting at Wed Aug 29 03:00:00 CEST 2012
[2012-08-28 09:09:30,526] INFO MediaScannerService - Starting to scan media library.
[2012-08-28 09:09:30,530] INFO MediaScannerService - Scanned media library with 0 entries.
[2012-08-28 09:09:30,530] INFO MediaScannerService - Marking non-present files.
[2012-08-28 09:09:30,531] INFO MediaScannerService - Marking non-present artists.
[2012-08-28 09:09:30,531] INFO MediaScannerService - Marking non-present albums.
[2012-08-28 09:09:30,532] INFO MediaScannerService - Completed media library scan.
[2012-08-28 09:09:37,795] INFO MediaScannerService - Starting to scan media library.
[2012-08-28 09:09:37,799] INFO MediaScannerService - Scanned media library with 0 entries.
[2012-08-28 09:09:37,800] INFO MediaScannerService - Marking non-present files.
[2012-08-28 09:09:37,806] INFO MediaScannerService - Marking non-present artists.
[2012-08-28 09:09:37,807] INFO MediaScannerService - Marking non-present albums.
[2012-08-28 09:09:37,808] INFO MediaScannerService - Completed media library scan.
[2012-08-28 09:09:48,243] INFO MediaScannerService - Automatic media library scanning scheduled to run every 1 day(s), starting at Wed Aug 29 03:00:00 CEST 2012
[2012-08-28 09:09:51,929] INFO MediaScannerService - Starting to scan media library.
[2012-08-28 09:09:51,934] INFO MediaScannerService - Scanned media library with 0 entries.
[2012-08-28 09:09:51,934] INFO MediaScannerService - Marking non-present files.
[2012-08-28 09:09:51,935] INFO MediaScannerService - Marking non-present artists.
[2012-08-28 09:09:51,936] INFO MediaScannerService - Marking non-present albums.
[2012-08-28 09:09:51,938] INFO MediaScannerService - Completed media library scan.
[2012-08-28 09:10:26,258] INFO MediaScannerService - Starting to scan media library.
[2012-08-28 09:10:26,309] INFO MediaScannerService - Scanned media library with 0 entries.
[2012-08-28 09:10:26,309] INFO MediaScannerService - Marking non-present files.
[2012-08-28 09:10:26,310] INFO MediaScannerService - Marking non-present artists.
[2012-08-28 09:10:26,311] INFO MediaScannerService - Marking non-present albums.
[2012-08-28 09:10:26,312] INFO MediaScannerService - Completed media library scan.
[2012-08-28 09:11:20,946] INFO MediaScannerService - Automatic media library scanning scheduled to run every 1 day(s), starting at Wed Aug 29 03:00:00 CEST 2012
[2012-08-28 09:11:23,065] INFO MediaScannerService - Starting to scan media library.
[2012-08-28 09:11:23,071] INFO MediaScannerService - Scanned media library with 0 entries.
[2012-08-28 09:11:23,071] INFO MediaScannerService - Marking non-present files.
[2012-08-28 09:11:23,072] INFO MediaScannerService - Marking non-present artists.
[2012-08-28 09:11:23,073] INFO MediaScannerService - Marking non-present albums.
[2012-08-28 09:11:23,073] INFO MediaScannerService - Completed media library scan.
[2012-08-28 09:12:45,636] INFO MusicFolderDao - Created music folder /var/hda/files/Torrents
[2012-08-28 09:12:45,638] INFO MediaScannerService - Automatic media library scanning scheduled to run every 1 day(s), starting at Wed Aug 29 03:00:00 CEST 2012
[2012-08-28 09:12:48,917] INFO MediaScannerService - Starting to scan media library.
[2012-08-28 09:12:48,922] INFO MediaScannerService - Scanned media library with 0 entries.
[2012-08-28 09:12:48,922] INFO MediaScannerService - Marking non-present files.
[2012-08-28 09:12:48,923] INFO MediaScannerService - Marking non-present artists.
[2012-08-28 09:12:48,924] INFO MediaScannerService - Marking non-present albums.
[2012-08-28 09:12:48,926] INFO MediaScannerService - Completed media library scan.
[2012-08-28 09:13:23,113] INFO MediaScannerService - Automatic media library scanning scheduled to run every 1 day(s), starting at Wed Aug 29 03:00:00 CEST 2012
[2012-08-28 09:13:41,844] INFO MediaScannerService - Starting to scan media library.
[2012-08-28 09:13:41,847] INFO MediaScannerService - Scanned media library with 0 entries.
[2012-08-28 09:13:41,847] INFO MediaScannerService - Marking non-present files.
[2012-08-28 09:13:41,848] INFO MediaScannerService - Marking non-present artists.
[2012-08-28 09:13:41,848] INFO MediaScannerService - Marking non-present albums.
[2012-08-28 09:13:41,849] INFO MediaScannerService - Completed media library scan.
[2012-08-28 19:16:54,715] INFO PlayerDao - Created player 3.
And here is the log from subsonic_sh.log:
24 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@5a8a0d5d: display name [org.springframework.context.support.ClassPathXmlApplicationContext@5a8a0d5d]; startup date [Tue Aug 28 08:08:38 CEST 2012]; root of context hierarchy
66 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [applicationContext-deployer.xml]
331 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Bean factory for application context [org.springframework.context.support.ClassPathXmlApplicationContext@5a8a0d5d]: org.springframework.beans.factory.support.DefaultListableBeanFactory@199836ed
340 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@199836ed: defining beans [service,org.springframework.remoting.rmi.RmiServiceExporter#0]; root of factory hierarchy
356 [main] INFO org.mortbay.log - Logging to org.slf4j.impl.SimpleLogger(org.mortbay.log) via org.mortbay.log.Slf4jLog
Using WAR file: /usr/share/subsonic/subsonic.war
Extracting webapp to /var/subsonic/jetty/3060
Using WAR file: /usr/share/subsonic/subsonic.war
380 [main] INFO org.mortbay.log - jetty-6.1.x
404 [main] INFO org.mortbay.log - Extract jar:file:/usr/share/subsonic/subsonic.war!/ to /var/subsonic/jetty/3060/webapp
[GC 15488K->586K(59264K), 0.0020560 secs]
[GC 16074K->682K(59264K), 0.0011460 secs]
[GC 16165K->1056K(59264K), 0.0018050 secs]
SLF4J: Class path contains multiple SLF4J bindings.
SLF4J: Found binding in [jar:file:/usr/share/subsonic/subsonic-booter-jar-with-dependencies.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/var/subsonic/jetty/3060/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.
1282 [main] INFO / - Initializing Spring root WebApplicationContext
1282 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization started
1315 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@6a969c29: display name [Root WebApplicationContext]; startup date [Tue Aug 28 08:08:39 CEST 2012]; root of context hierarchy
1367 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-service.xml]
[GC 16544K->1468K(59264K), 0.0023530 secs]
1484 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-security.xml]
1532 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-cache.xml]
1559 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@6a969c29]: org.springframework.beans.factory.support.DefaultListableBeanFactory@8691dee
1706 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@8691dee: 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
1743 [main] INFO org.springframework.jdbc.datasource.DriverManagerDataSource - Loaded JDBC driver: org.hsqldb.jdbcDriver
[GC 16956K->3783K(58880K), 0.0034600 secs]
[GC 18887K->5033K(58688K), 0.0076690 secs]
[GC 19113K->6633K(59712K), 0.0073170 secs]
2846 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
2854 [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]]
2854 [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]]
2854 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
2876 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization completed in 1594 ms
2904 [main] INFO / - Initializing Spring FrameworkServlet 'subsonic'
2904 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization started
2904 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@92ca580: display name [WebApplicationContext for namespace 'subsonic-servlet']; startup date [Tue Aug 28 08:08:40 CEST 2012]; parent: org.springframework.web.context.support.XmlWebApplicationContext@6a969c29
2904 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/subsonic-servlet.xml]
2988 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@92ca580]: org.springframework.beans.factory.support.DefaultListableBeanFactory@4bb963c4
[GC 20713K->7843K(74176K), 0.0150880 secs]
3090 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@4bb963c4: 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@8691dee
3513 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization completed in 609 ms
3523 [main] INFO org.mortbay.log - Started
SelectChannelConnector@0.0.0.0:4040Subsonic running on:
http://localhost:4040/3549 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Looking for RMI registry at port '9412'
3554 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Could not detect RMI registry - creating new one
3576 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Binding service 'SubsonicDeployerService' to RMI registry: RegistryImpl[UnicastServerRef [liveRef: [endpoint:[127.0.1.1:9412](local),objID:[0:0:0, 0]]]]
[GC 32670K->9589K(75008K), 0.0173120 secs]
[Full GC 9589K->9472K(75008K), 0.0642560 secs]
[GC 36800K->10506K(76288K), 0.0055320 secs]
[GC 38730K->11786K(76224K), 0.0043960 secs]
[GC 40010K->12811K(85440K), 0.0086210 secs]
[GC 50571K->14741K(85376K), 0.0044470 secs]
15172 [btpool0-3] INFO org.directwebremoting.log.startup - Starting: DwrServlet v3.0.0.116.rc1 on jetty-6.1.x / JDK 1.6.0_24 from Sun Microsystems Inc. at
[GC 52501K->19117K(84864K), 0.0102000 secs]
[GC 56749K->22346K(84736K), 0.0132910 secs]
[GC 59978K->31951K(76672K), 0.0746360 secs]
[GC 59983K->31615K(80832K), 0.0157760 secs]
[GC 59647K->32067K(80384K), 0.0039410 secs]
[GC 60163K->32617K(80640K), 0.0027600 secs]
[GC 60713K->32788K(77312K), 0.0044040 secs]
[GC 41278K->32847K(68608K), 0.0030450 secs]
[Full GC 32847K->28187K(68608K), 0.1391360 secs]
[GC 55387K->28763K(76416K), 0.0029490 secs]
[GC 46054K->28651K(67904K), 0.0041330 secs]
[Full GC 28651K->28235K(67904K), 0.1199630 secs]
[GC 30204K->28363K(76480K), 0.0014900 secs]
[Full GC 28363K->26060K(76480K), 0.1325160 secs]
[GC 52415K->26524K(67328K), 0.0024480 secs]
[GC 52124K->26436K(73344K), 0.0022930 secs]
[GC 51268K->26476K(65728K), 0.0013200 secs]
[GC 50604K->26484K(71168K), 0.0027830 secs]
[GC 49908K->26652K(64384K), 0.0019260 secs]
[GC 30523K->26436K(70464K), 0.0016030 secs]
[Full GC 26436K->26406K(70464K), 0.1116340 secs]
[GC 49126K->26694K(63616K), 0.0018400 secs]
[GC 48774K->26742K(67648K), 0.0016880 secs]
[GC 48182K->26718K(62336K), 0.0024410 secs]
[GC 47582K->26694K(65856K), 0.0018340 secs]
[GC 46982K->26734K(61248K), 0.0016950 secs]
[GC 36684K->26614K(65472K), 0.0009250 secs]
[Full GC 26614K->26575K(65472K), 0.1435410 secs]
[GC 46351K->26799K(60736K), 0.0024060 secs]
[GC 46063K->26823K(63360K), 0.0021420 secs]
[GC 45575K->26807K(59648K), 0.0022680 secs]
[GC 45047K->26879K(61888K), 0.0016550 secs]
[GC 44671K->26887K(58816K), 0.0016770 secs]
[GC 44231K->26895K(60544K), 0.0017130 secs]
[GC 31064K->26711K(58176K), 0.0015790 secs]
[Full GC 26711K->26685K(58176K), 0.1114140 secs]
[GC 43581K->26941K(59904K), 0.0017460 secs]
[GC 43453K->26933K(57600K), 0.0020390 secs]
[GC 43061K->26877K(58752K), 0.0065310 secs]
[GC 42621K->26917K(56768K), 0.0016010 secs]
[GC 42277K->26893K(57920K), 0.0014670 secs]
[GC 41933K->26901K(56128K), 0.0014350 secs]
[GC 41621K->26909K(57024K), 0.0020290 secs]
[GC 31732K->26853K(55744K), 0.0015450 secs]
[Full GC 26853K->25541K(55744K), 0.1243640 secs]
[GC 39941K->25733K(56448K), 0.0016960 secs]
[GC 39813K->25717K(55168K), 0.0016030 secs]
[GC 39477K->25757K(55680K), 0.0016840 secs]
[GC 39197K->25925K(54784K), 0.0014300 secs]
[GC 39109K->25885K(55040K), 0.0017550 secs]
[GC 38813K->25893K(54144K), 0.0021170 secs]
[GC 38565K->25933K(54272K), 0.0049770 secs]
[GC 38349K->25909K(53632K), 0.0015470 secs]
[GC 35426K->26013K(54208K), 0.0018520 secs]
[Full GC 26013K->25972K(54208K), 0.1121730 secs]
[GC 32431K->26100K(54272K), 0.0103700 secs]
[Full GC 26100K->26020K(54272K), 0.1132840 secs]
[GC 27402K->26084K(54144K), 0.0010550 secs]
[Full GC 26084K->26023K(54144K), 0.1213180 secs]
[GC 27316K->26055K(54208K), 0.0008940 secs]
[Full GC 26055K->25492K(54208K), 0.1187170 secs]
[GC 26874K->25556K(54080K), 0.0009810 secs]
[Full GC 25556K->25495K(54080K), 0.1143020 secs]
[GC 26788K->25527K(54144K), 0.0009670 secs]
[Full GC 25527K->25495K(54144K), 0.1727640 secs]