Subsonic server crashing within 5 minutes of starting

[evanhuang]

Hello all, apologies if this problem has been previously resolved. I spent time looking through the help forum and searching but came up with no results that could help me.

Problem Description: Upon starting the subsonic server the process dies within 5 minutes every time. I am able to log on to the web client and play music/ediit configuration files while it remains up. The time it takes to die is not like clock work. I timed it a few times and it varied from 1:10 of uptime to 5:05. It should also be noted that the server crashes regardless of whether or not I access it via the web interface and regardless of whether or not I stream any music from it.


Troubleshooting Steps: Upped Java memory from default of 100 to 256 and then 512 (but back to 256 because my server is on a vps with relatively small memory). Checked and rebuilt java package.


Playback Application and version: Mozilla/5.0 (X11; Linux x86_64; rv:2.0.1) Gecko/20110429 Firefox/4.0.1 | also tried Firefox 4.0.1 on Windows

Subsonic Version: 4.4 (build 2169) – February 6, 2011

Server Version: jetty-6.1.x, java 1.6.0_17, Linux (39.7 MB / 96.6 MB)

Hardware Platform: Linux CentOS 5.5 on a VPS

Java Memory Limit: 256 but also tried 512

Output from ffmpeg -i:

Code: Select all

[root@vps subsonic]# ffmpeg -i test
FFmpeg version SVN-r15261, Copyright (c) 2000-2008 Fabrice Bellard, et al.
  configuration: --prefix=/usr --incdir=/usr/include/ffmpeg --libdir=/usr/lib64 --shlibdir=/usr/lib64 --mandir=/usr/share/man --arch=x86_64 --extra-cflags=-O2 -g -pipe -Wall -Wp,-D_FORTIFY_SOURCE=2 -fexceptions -fstack-protector --param=ssp-buffer-size=4 -m64 -mtune=generic -D_ISOC99_SOURCE -D_POSIX_C_SOURCE=200112 -fasm -std=c99 -fno-math-errno --enable-libdc1394 --enable-libfaac --enable-libfaad --enable-libgsm --enable-libmp3lame --enable-libtheora --enable-libvorbis --enable-libx264 --enable-libxvid --enable-x11grab --enable-avfilter --enable-avfilter-lavf --enable-postproc --enable-swscale --enable-pthreads --disable-static --enable-shared --enable-gpl --disable-debug --disable-optimizations --disable-stripping
  libavutil     49.10. 0 / 49.10. 0
  libavcodec    51.71. 0 / 51.71. 0
  libavformat   52.22. 1 / 52.22. 1
  libavdevice   52. 1. 0 / 52. 1. 0
  libavfilter    0. 1. 0 /  0. 1. 0
  libswscale     0. 6. 1 /  0. 6. 1
  libpostproc   51. 2. 0 / 51. 2. 0
  built on Feb  4 2009 19:41:05, gcc: 4.1.2 20071124 (Red Hat 4.1.2-42)

Log file lines in their entirities (I deleted the logs and service subsonic restart)
subsonic.log

Code: Select all

[2011-04-30 13:08:55,792] INFO DaoHelper - Checking database schema.
[2011-04-30 13:08:56,274] INFO DaoHelper - Done checking database schema.
[2011-04-30 13:08:56,750] INFO SearchService - Automatic index creation scheduled to run every 1 day(s), starting at Sun May 01 03:00:00 MDT 2011
[2011-04-30 13:08:57,051] INFO PodcastService - Automatic Podcast update scheduled to run every 24 hour(s), starting at Sat Apr 30 13:13:57 MDT 2011
[2011-04-30 13:09:00,002] WARN NetworkService - Failed to find UPnP router using WeUPnP library.
java.lang.StringIndexOutOfBoundsException: String index out of range: -1
        at java.lang.String.substring(String.java:1949)
        at org.wetorrent.upnp.GatewayDiscover.parseMSearchReplay(GatewayDiscover.java:175)
        at org.wetorrent.upnp.GatewayDiscover.discover(GatewayDiscover.java:130)
        at net.sourceforge.subsonic.domain.WeUPnPRouter.findRouter(WeUPnPRouter.java:39)
        at net.sourceforge.subsonic.service.NetworkService$PortForwardingTask.findRouter(NetworkService.java:197)
        at net.sourceforge.subsonic.service.NetworkService$PortForwardingTask.execute(NetworkService.java:132)
        at net.sourceforge.subsonic.service.NetworkService$Task.run(NetworkService.java:309)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
        at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351)
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:165)
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:267)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
        at java.lang.Thread.run(Thread.java:636)

[2011-04-30 13:09:00,008] WARN NetworkService - No UPnP router found.



subsonic_sh.log

Code: Select all

43 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@13d4c61c: display name [org.springframework.context.support.ClassPathXmlApplicationContext@13d4c61c]; startup date [Sat Apr 30 13:08:50 MDT 2011]; root of context hierarchy
492 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [applicationContext-deployer.xml]
2028 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Bean factory for application context [org.springframework.context.support.ClassPathXmlApplicationContext@13d4c61c]: org.springframework.beans.factory.support.DefaultListableBeanFactory@5d5bdc50
2075 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@5d5bdc50: defining beans [service,org.springframework.remoting.rmi.RmiServiceExporter#0]; root of factory hierarchy
2173 [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/2169
Using WAR file: /usr/share/subsonic/subsonic.war
2270 [main] INFO org.mortbay.log - jetty-6.1.x
4022 [main] INFO / - Initializing Spring root WebApplicationContext
4022 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization started
4052 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@79038de7: display name [Root WebApplicationContext]; startup date [Sat Apr 30 13:08:55 MDT 2011]; root of context hierarchy
4222 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-service.xml]
4363 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-security.xml]
4465 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-cache.xml]
4503 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@79038de7]: org.springframework.beans.factory.support.DefaultListableBeanFactory@28787c16
4684 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@28787c16: defining beans [playerDao,internetRadioDao,musicFileInfoDao,musicFolderDao,userDao,transcodingDao,podcastDao,avatarDao,daoHelper,musicFileService,securityService,settingsService,searchService,luceneSearchService,networkService,playerService,playlistService,versionService,statusService,musicInfoService,musicIndexService,audioScrobblerService,transcodingService,podcastService,adService,jukeboxService,metaDataParserFactory,ajaxMultiService,ajaxNowPlayingService,ajaxPlaylistService,ajaxLyricsService,ajaxCoverArtService,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,cacheManager,userCache,musicFileCache,childDirCache,coverArtCache,musicFolderCache,chatCache]; root of factory hierarchy
4782 [main] INFO org.springframework.jdbc.datasource.DriverManagerDataSource - Loaded JDBC driver: org.hsqldb.jdbcDriver
5471 [main] INFO org.springframework.cache.ehcache.EhCacheManagerFactoryBean - Initializing EHCache CacheManager
Apr 30, 2011 1:08:56 PM net.sf.ehcache.CacheManager addShutdownHookIfRequired
INFO: The CacheManager shutdown hook is enabled because net.sf.ehcache.enableShutdownHook is set to true.
Apr 30, 2011 1:08:56 PM net.sf.ehcache.Cache initialise
WARNING: Cache: chatCache has a maxElementsInMemory of 0. It is strongly recommended to have a maximumSize of at least 1. Performance is halved by not using a MemoryStore.
6630 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
6642 [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]]
6642 [main] INFO org.acegisecurity.intercept.method.MethodDefinitionMap - Adding secure method [public java.lang.String net.sourceforge.subsonic.ajax.TagService.setTags(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String)] with attributes [[ROLE_COVERART]]
6642 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
6688 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization completed in 2666 ms
6770 [main] INFO / - Initializing Spring FrameworkServlet 'subsonic'
6770 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization started
6770 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@62d844a9: display name [WebApplicationContext for namespace 'subsonic-servlet']; startup date [Sat Apr 30 13:08:57 MDT 2011]; parent: org.springframework.web.context.support.XmlWebApplicationContext@79038de7
6771 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/subsonic-servlet.xml]
6877 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@62d844a9]: org.springframework.beans.factory.support.DefaultListableBeanFactory@156a9424
6956 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@156a9424: defining beans [leftController,rightController,statusController,mainController,topController,helpController,moreController,uploadController,lyricsController,allmusicController,podcastController,podcastReceiverController,podcastReceiverAdminController,setMusicFileInfoController,setRatingController,randomPlaylistController,loadPlaylistController,savePlaylistController,changeCoverArtController,nowPlayingController,searchController,settingsController,playerSettingsController,musicFolderSettingsController,networkSettingsController,transcodingSettingsController,internetRadioSettingsController,podcastSettingsController,searchSettingsController,generalSettingsController,advancedSettingsController,personalSettingsController,avatarUploadController,userSettingsController,passwordSettingsController,homeController,editTagsController,playlistController,xspfPlaylistController,coverArtController,avatarController,proxyController,statusChartController,userChartController,m3uController,streamController,videoPlayerController,downloadController,donateController,multiController,wapController,restController,dbController,savePlaylistValidator,donateValidator,userSettingsValidator,passwordSettingsValidator,urlMapping,messageSource,themeSource,localeResolver,themeResolver,viewResolver]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@28787c16
7804 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization completed in 1034 ms
7919 [main] INFO org.mortbay.log - Started SelectChannelConnector@0.0.0.0:4040
8057 [main] INFO org.mortbay.log - Started SslSocketConnector@0.0.0.0:4041
Subsonic running on: http://localhost:4040/
                and: https://localhost:4041/
8218 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Looking for RMI registry at port '9412'
8352 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Could not detect RMI registry - creating new one
8424 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Binding service 'SubsonicDeployerService' to RMI registry: RegistryImpl[UnicastServerRef [liveRef: [endpoint:[173.0.48.52:9412](local),objID:[0:0:0, 0]]]]
Shutting down database.
Done.


[Aethies]

Hello all, apologies if this problem has been previously resolved. I spent time looking through the help forum and searching but came up with no results that could help me.

Problem Description: Upon starting the subsonic server the process dies within 5 minutes every time. I am able to log on to the web client and play music/ediit configuration files while it remains up. The time it takes to die is not like clock work. I timed it a few times and it varied from 1:10 of uptime to 5:05. It should also be noted that the server crashes regardless of whether or not I access it via the web interface and regardless of whether or not I stream any music from it.

Are you able to log into the server at all? Or does it shut down too fast?

Did you edit the subsonic.sh file and change the default settings? I may be way off base here, but it seems to be failing on the following settings from the site, under network:


Use the settings below to control how to access your Subsonic server over the Internet.
If you have problems, please consult the Getting started guide.

Automatically configure your router to allow incoming connections to Subsonic (using UPnP or NAT-PMP port forwarding).

If your router can't be configured automatically you can set it up manually. Follow the instructions on portforward.com. You must forward port 8180 to the computer running the Subsonic server.

Status: Port forwarding disabled.

Access your server over the Internet using an easy-to-remember address.

Are you having it automatically configure your route and using easy-to-remember address?
I am just thinking to shut those off and then try it again. Just trying to rule out everything that would make the server shut down.

[evanhuang]

Aethies,

I don't think it's a router issue. I have it set on a vps which has a static ip address and I'm connecting directly from a domain that I own (and am hosing my own dns) and all traffic of every kind is coming through to the box from that ip (even ports which are normally blocked on home connections like 25 incoming 53 etc).

I am able to log in to the server as I noted above before it shuts down but logging on is not what is causing it to crash as sometimes I do not log on and it still crashes.

I did edit the subsonic.sh, here is what that looks like:

Code: Select all

SUBSONIC_HOME=/var/subsonic
SUBSONIC_HOST=0.0.0.0
SUBSONIC_PORT=4040
SUBSONIC_HTTPS_PORT=4041
SUBSONIC_CONTEXT_PATH=/
SUBSONIC_MAX_MEMORY=256
SUBSONIC_PIDFILE=
SUBSONIC_DEFAULT_MUSIC_FOLDER=/var/music
SUBSONIC_DEFAULT_PODCAST_FOLDER=/var/music/Podcast
SUBSONIC_DEFAULT_PLAYLIST_FOLDER=/var/playlists

quiet=0


Any other suggestions? ^.^

[Aethies]

Aethies,

I don't think it's a router issue. I have it set on a vps which has a static ip address and I'm connecting directly from a domain that I own (and am hosing my own dns) and all traffic of every kind is coming through to the box from that ip (even ports which are normally blocked on home connections like 25 incoming 53 etc).

I am able to log in to the server as I noted above before it shuts down but logging on is not what is causing it to crash as sometimes I do not log on and it still crashes.

I did edit the subsonic.sh, here is what that looks like:

Code: Select all

SUBSONIC_HOME=/var/subsonic
SUBSONIC_HOST=0.0.0.0
SUBSONIC_PORT=4040
SUBSONIC_HTTPS_PORT=4041
SUBSONIC_CONTEXT_PATH=/
SUBSONIC_MAX_MEMORY=256
SUBSONIC_PIDFILE=
SUBSONIC_DEFAULT_MUSIC_FOLDER=/var/music
SUBSONIC_DEFAULT_PODCAST_FOLDER=/var/music/Podcast
SUBSONIC_DEFAULT_PLAYLIST_FOLDER=/var/playlists

quiet=0


Any other suggestions? ^.^

Well, I wasn't indicating a router issue... I was pointing to more of a server starting failure. Your Host IP needs to be set. That should be whatever your server ip is.
I.E.
SUBSONIC_HOST=192.168.1.5 or whatever your ip is.

That should do it.

[evanhuang]

I tried that before and that didn't solve my problems. I set it back to 0.0.0.0 when I saw this post by sindre_mehus (#2):
http://forum.subsonic.org/forum/viewtopic.php?p=10909

Either way, setting my ip or setting the default are not working for either setting.

Aethies,

I don't think it's a router issue. I have it set on a vps which has a static ip address and I'm connecting directly from a domain that I own (and am hosing my own dns) and all traffic of every kind is coming through to the box from that ip (even ports which are normally blocked on home connections like 25 incoming 53 etc).

I am able to log in to the server as I noted above before it shuts down but logging on is not what is causing it to crash as sometimes I do not log on and it still crashes.

I did edit the subsonic.sh, here is what that looks like:

Code: Select all

SUBSONIC_HOME=/var/subsonic
SUBSONIC_HOST=0.0.0.0
SUBSONIC_PORT=4040
SUBSONIC_HTTPS_PORT=4041
SUBSONIC_CONTEXT_PATH=/
SUBSONIC_MAX_MEMORY=256
SUBSONIC_PIDFILE=
SUBSONIC_DEFAULT_MUSIC_FOLDER=/var/music
SUBSONIC_DEFAULT_PODCAST_FOLDER=/var/music/Podcast
SUBSONIC_DEFAULT_PLAYLIST_FOLDER=/var/playlists

quiet=0


Any other suggestions? ^.^

Well, I wasn't indicating a router issue... I was pointing to more of a server starting failure. Your Host IP needs to be set. That should be whatever your server ip is.
I.E.
SUBSONIC_HOST=192.168.1.5 or whatever your ip is.

That should do it.

[evanhuang]

I'm still having this issue and pretty much seeing the same exact log every time. Anyone have any ideas as to what's going on or how I could fix this problem? Here is a log from a recent attempt (subsonic_sh):

Code: Select all

21 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@13d4c61c: display name [org.springframework.context.support.ClassPathXmlApplicationContext@13d4c61c]; startup date [Mon May 02 11:57:04 MDT 2011]; root of context hierarchy
73 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [applicationContext-deployer.xml]
357 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Bean factory for application context [org.springframework.context.support.ClassPathXmlApplicationContext@13d4c61c]: org.springframework.beans.factory.support.DefaultListableBeanFactory@27b15692
368 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@27b15692: defining beans [service,org.springframework.remoting.rmi.RmiServiceExporter#0]; root of factory hierarchy
386 [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/2169
Using WAR file: /usr/share/subsonic/subsonic.war
422 [main] INFO org.mortbay.log - jetty-6.1.x
6232 [main] INFO / - Initializing Spring root WebApplicationContext
6232 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization started
6344 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@3c1a1399: display name [Root WebApplicationContext]; startup date [Mon May 02 11:57:10 MDT 2011]; root of context hierarchy
7442 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-service.xml]
7941 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-security.xml]
8356 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-cache.xml]
8475 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@3c1a1399]: org.springframework.beans.factory.support.DefaultListableBeanFactory@2d1e233
10928 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@2d1e233: defining beans [playerDao,internetRadioDao,musicFileInfoDao,musicFolderDao,userDao,transcodingDao,podcastDao,avatarDao,daoHelper,musicFileService,securityService,settingsService,searchService,luceneSearchService,networkService,playerService,playlistService,versionService,statusService,musicInfoService,musicIndexService,audioScrobblerService,transcodingService,podcastService,adService,jukeboxService,metaDataParserFactory,ajaxMultiService,ajaxNowPlayingService,ajaxPlaylistService,ajaxLyricsService,ajaxCoverArtService,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,cacheManager,userCache,musicFileCache,childDirCache,coverArtCache,musicFolderCache,chatCache]; root of factory hierarchy
11943 [main] INFO org.springframework.jdbc.datasource.DriverManagerDataSource - Loaded JDBC driver: org.hsqldb.jdbcDriver
82557 [main] INFO org.springframework.cache.ehcache.EhCacheManagerFactoryBean - Initializing EHCache CacheManager
May 2, 2011 11:58:27 AM net.sf.ehcache.CacheManager addShutdownHookIfRequired
INFO: The CacheManager shutdown hook is enabled because net.sf.ehcache.enableShutdownHook is set to true.
May 2, 2011 11:58:27 AM net.sf.ehcache.Cache initialise
WARNING: Cache: chatCache has a maxElementsInMemory of 0. It is strongly recommended to have a maximumSize of at least 1. Performance is halved by not using a MemoryStore.
96764 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
96859 [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]]
96859 [main] INFO org.acegisecurity.intercept.method.MethodDefinitionMap - Adding secure method [public java.lang.String net.sourceforge.subsonic.ajax.TagService.setTags(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String)] with attributes [[ROLE_COVERART]]
96859 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
96897 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization completed in 90665 ms
97949 [main] INFO / - Initializing Spring FrameworkServlet 'subsonic'
97949 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization started
97949 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@2c704cf5: display name [WebApplicationContext for namespace 'subsonic-servlet']; startup date [Mon May 02 11:58:42 MDT 2011]; parent: org.springframework.web.context.support.XmlWebApplicationContext@3c1a1399
97950 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/subsonic-servlet.xml]
98337 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@2c704cf5]: org.springframework.beans.factory.support.DefaultListableBeanFactory@b364dcb
98764 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@b364dcb: defining beans [leftController,rightController,statusController,mainController,topController,helpController,moreController,uploadController,lyricsController,allmusicController,podcastController,podcastReceiverController,podcastReceiverAdminController,setMusicFileInfoController,setRatingController,randomPlaylistController,loadPlaylistController,savePlaylistController,changeCoverArtController,nowPlayingController,searchController,settingsController,playerSettingsController,musicFolderSettingsController,networkSettingsController,transcodingSettingsController,internetRadioSettingsController,podcastSettingsController,searchSettingsController,generalSettingsController,advancedSettingsController,personalSettingsController,avatarUploadController,userSettingsController,passwordSettingsController,homeController,editTagsController,playlistController,xspfPlaylistController,coverArtController,avatarController,proxyController,statusChartController,userChartController,m3uController,streamController,videoPlayerController,downloadController,donateController,multiController,wapController,restController,dbController,savePlaylistValidator,donateValidator,userSettingsValidator,passwordSettingsValidator,urlMapping,messageSource,themeSource,localeResolver,themeResolver,viewResolver]; parent: org.springframework.beans.factory.support.DefaultListableBeanFactory@2d1e233
105532 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization completed in 7583 ms
105835 [main] INFO org.mortbay.log - Started SelectChannelConnector@0.0.0.0:4040
106982 [main] INFO org.mortbay.log - Started SslSocketConnector@0.0.0.0:4041
Subsonic running on: http://localhost:4040/
                and: https://localhost:4041/
108128 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Looking for RMI registry at port '9412'
113931 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Binding service 'SubsonicDeployerService' to RMI registry: RegistryImpl_Stub[UnicastRef [liveRef: [endpoint:[173.0.48.52:9412](remote),objID:[0:0:0, 0]]]]
Shutting down database.
Done.