504 Gateway Timeout on android phone...

[silvioBurch]

Hello everybody,

I successfully set up subsonic on my raspberry PI. I forwarded the port (4040) to my raspberry. I can access the Server via http://publicip:4040 with no problem. (I bought 1 year Premium)

If i want to access the Server from my android phone browser via 3g / 4g, i get the " 504Gateway Timeout" error. If i access the same server in any WIFI(work, home, starbucks...), it works. BOTH OVER THE PUBLIC IP! Therefore i cant connect to my Subsonic server via the android app when im not in a wifi.

Its a realy strange error and i mess around for now a week. Any hints? :/

Any suggestions?

[silvioBurch]

Good evening

I've got some news. After i formated the SD card and reinstalled everything from scratch, it worked like a charm.Everything worked fine, i could connect from my phone via the mobile network(3g / 4g).

After i restarted the raspberry, i've got the same problem as i described above. I just can connect via a computer with the public ip or with the phone but just via wifi. When i connect via the mobile network, i got now new the 503 HTTP Error. This dirty little bastard

Anyway, i've got some logfiles for you

P.S: I've got upnp enabled, the port 4040 forwarded to my PI and of course a static IP

subsonic.log

Code: Select all

[2014-11-11 19:46:50,951] INFO DaoHelper - Checking database schema.
[2014-11-11 19:46:58,446] INFO DaoHelper - Done checking database schema.
[2014-11-11 19:47:01,249] INFO VersionService - Resolved local Subsonic version to: 5.0
[2014-11-11 19:47:05,576] INFO PlayerDao - Deleted 2 player(s) that haven't been used after Fri Sep 12 19:47:05 UTC 2014
[2014-11-11 19:47:07,252] INFO MediaScannerService - Automatic media library scanning scheduled to run every 1 day(s), starting at Wed Nov 12 03:00:00 UTC 2014
[2014-11-11 19:47:09,116] INFO UPnPService - Starting UPnP service...
[2014-11-11 19:47:10,087] INFO PodcastService - Automatic Podcast update scheduled to run every 24 hour(s), starting at Tue Nov 11 19:52:10 UTC 2014
[2014-11-11 19:47:20,185] INFO UPnPService - Enabling UPnP/DLNA media server
[2014-11-11 19:47:20,199] INFO UPnPService - Starting UPnP service - Done!
[2014-11-11 19:47:20,242] INFO NetworkService - Deleted port mapping for port 4040
[2014-11-11 19:47:24,849] INFO FolderBasedContentDirectory - UPnP request - objectId: 0, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:27,274] INFO FolderBasedContentDirectory - UPnP request - objectId: folder-0, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:28,073] INFO FolderBasedContentDirectory - UPnP request - objectId: playlists, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:28,563] INFO FolderBasedContentDirectory - UPnP request - objectId: folder-2, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:29,167] INFO PlayerDao - Created player 3.
[2014-11-11 19:47:33,676] INFO FolderBasedContentDirectory - UPnP request - objectId: folder-53, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:35,988] INFO FolderBasedContentDirectory - UPnP request - objectId: playlist-0, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:38,752] INFO FolderBasedContentDirectory - UPnP request - objectId: playlist-2, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:43,722] INFO FolderBasedContentDirectory - UPnP request - objectId: 0, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:43,991] INFO FolderBasedContentDirectory - UPnP request - objectId: folder-0, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:44,353] INFO FolderBasedContentDirectory - UPnP request - objectId: playlists, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:44,669] INFO FolderBasedContentDirectory - UPnP request - objectId: folder-2, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:46,834] INFO FolderBasedContentDirectory - UPnP request - objectId: folder-53, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:48,839] INFO FolderBasedContentDirectory - UPnP request - objectId: playlist-0, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:47:51,358] INFO FolderBasedContentDirectory - UPnP request - objectId: playlist-2, browseFlag: BrowseDirectChildren, filter: res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, firstResult: 0, maxResults: 100
[2014-11-11 19:52:10,073] INFO PodcastService - Starting scheduled Podcast refresh.
[2014-11-11 19:52:10,080] INFO PodcastService - Completed scheduled Podcast refresh.
---------------------------------------------------------------------------------------------------------------------------SINCE HERE I JUST CAN CONNECT VIA WIFI AFTER I RESTARTED THE PI
[2014-11-11 20:20:03,091] INFO PlayQueueInputStream - admin listening to "2 dark techno/4730298_Bee Good_Original Mix.mp3"
[2014-11-11 20:21:59,286] INFO PlayQueueInputStream - admin listening to "2 dark techno/5239348_Ruthless_Original Mix.mp3"
[2014-11-11 20:25:20,770] INFO PlayQueueInputStream - admin listening to "2 dark techno/5794854_Get Loud_Original Mix.mp3"
[2014-11-11 20:32:47,735] INFO PlayerDao - Created player 4.
[2014-11-11 20:32:49,851] INFO VersionService - Resolved latest Subsonic final version to: 5.0
[2014-11-11 20:32:49,853] INFO VersionService - Resolved latest Subsonic beta version to: 5.0.beta2
[2014-11-11 20:33:19,895] INFO NetworkService - Deleted port mapping for port 4040
[2014-11-11 20:36:45,281] WARN NetworkService - Failed to create port forwarding.
java.lang.Exception: 500 Internal Server Error: Error: ConflictInMappingEntry (HTTP response was: 500 Internal Server Error)
   at net.sourceforge.subsonic.service.upnp.ClingRouter.addPortMappingImpl(ClingRouter.java:116)
   at net.sourceforge.subsonic.service.upnp.ClingRouter.addPortMapping(ClingRouter.java:89)
   at net.sourceforge.subsonic.service.NetworkService$PortForwardingTask.execute(NetworkService.java:149)
   at net.sourceforge.subsonic.service.NetworkService$Task.run(NetworkService.java:306)
   at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
   at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
   at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
   at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   at java.lang.Thread.run(Thread.java:744)


subsonic_sh.log

Code: Select all

331 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Refreshing org.springframework.context.support.ClassPathXmlApplicationContext@1496d9f: display name [org.springframework.context.support.ClassPathXmlApplicationContext@1496d9f]; startup date [Tue Nov 11 19:17:21 UTC 2014]; root of context hierarchy
[GC (Allocation Failure)  2173K->910K(7936K), 0.0349800 secs]
1332 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from class path resource [applicationContext-deployer.xml]
[GC (Allocation Failure)  3086K->1395K(7936K), 0.0253550 secs]
[GC (Allocation Failure)  3571K->1707K(7936K), 0.0143080 secs]
[GC (Allocation Failure)  3883K->2116K(7936K), 0.0173530 secs]
[GC (Allocation Failure)  4292K->2733K(7936K), 0.0404190 secs]
1753535 [main] INFO org.springframework.context.support.ClassPathXmlApplicationContext - Bean factory for application context [org.springframework.context.support.ClassPathXmlApplicationContext@1496d9f]: org.springframework.beans.factory.support.DefaultListableBeanFactory@199d22e
1754128 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@199d22e: defining beans [service,org.springframework.remoting.rmi.RmiServiceExporter#0]; root of factory hierarchy
[GC (Allocation Failure)  4909K->3300K(7936K), 0.0362560 secs]
1754552 [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/4100
Using WAR file: /usr/share/subsonic/subsonic.war
1755049 [main] INFO org.mortbay.log - jetty-6.1.x
[GC (Allocation Failure)  5476K->3855K(7936K), 0.0264030 secs]
[GC (Allocation Failure)  5960K->4360K(7936K), 0.0287760 secs]
[GC (Allocation Failure)  6529K->4501K(7936K), 0.0288690 secs]
[GC (Allocation Failure)  6674K->4415K(7936K), 0.0088880 secs]
[GC (Allocation Failure)  6589K->4421K(7936K), 0.0038700 secs]
[GC (Allocation Failure)  6597K->4552K(7936K), 0.0073950 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/4100/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.
[GC (Allocation Failure)  6728K->4741K(7936K), 0.0162650 secs]
[GC (Allocation Failure)  6917K->4707K(7936K), 0.0162110 secs]
[GC (Allocation Failure)  6883K->4656K(7936K), 0.0095730 secs]
[GC (Allocation Failure)  6832K->4658K(7936K), 0.0049430 secs]
[GC (Allocation Failure)  6834K->4654K(7936K), 0.0044920 secs]
[GC (Allocation Failure)  6830K->4673K(7936K), 0.0050020 secs]
[GC (Allocation Failure)  6849K->4667K(7936K), 0.0061710 secs]
[GC (Allocation Failure)  6843K->4665K(7936K), 0.0060290 secs]
[GC (Allocation Failure)  6841K->4666K(7936K), 0.0057640 secs]
[GC (Allocation Failure)  6842K->4669K(7936K), 0.0059690 secs]
[GC (Allocation Failure)  6845K->4810K(7936K), 0.0097870 secs]
[GC (Allocation Failure)  6986K->5224K(7936K), 0.0198070 secs]
1764175 [main] INFO / - Initializing Spring root WebApplicationContext
1764176 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization started
1764508 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@12f203f: display name [Root WebApplicationContext]; startup date [Tue Nov 11 19:46:45 UTC 2014]; root of context hierarchy
[GC (Allocation Failure)  7382K->5565K(7936K), 0.0194180 secs]
[GC (Allocation Failure)  7741K->5626K(7936K), 0.0122920 secs]
1765042 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-service.xml]
[GC (Allocation Failure)  7802K->5789K(8064K), 0.0186930 secs]
[Full GC (Allocation Failure)  5789K->3278K(8064K), 0.1415500 secs]
1766545 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-security.xml]
1767343 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/applicationContext-cache.xml]
[GC (Allocation Failure)  5582K->3635K(8064K), 0.0190040 secs]
1767779 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@12f203f]: org.springframework.beans.factory.support.DefaultListableBeanFactory@3958e7
[GC (Allocation Failure)  5939K->3615K(8064K), 0.0134630 secs]
[GC (Allocation Failure)  5919K->3814K(8064K), 0.0100770 secs]
1768866 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@3958e7: defining beans [playerDao,mediaFileDao,artistDao,albumDao,playlistDao,internetRadioDao,musicFileInfoDao,musicFolderDao,userDao,transcodingDao,podcastDao,avatarDao,shareDao,bookmarkDao,daoHelper,mediaFileService,securityService,settingsService,mediaScannerService,searchService,networkService,playerService,playlistService,versionService,statusService,musicInfoService,musicIndexService,audioScrobblerService,transcodingService,shareService,podcastService,adService,jukeboxService,folderBasedContentDirectory,upnpService,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]; root of factory hierarchy
[GC (Allocation Failure)  6118K->3898K(8064K), 0.0278760 secs]
1769415 [main] INFO org.springframework.jdbc.datasource.DriverManagerDataSource - Loaded JDBC driver: org.hsqldb.jdbcDriver
[GC (Allocation Failure)  6202K->4091K(8064K), 0.0240510 secs]
[GC (Allocation Failure)  6393K->5290K(8064K), 0.0260270 secs]
[GC (Allocation Failure)  7502K->6018K(8448K), 0.0316380 secs]
[Full GC (Allocation Failure)  6018K->4185K(8448K), 0.1846390 secs]
[GC (Allocation Failure)  7252K->5461K(10304K), 0.0263530 secs]
[GC (Allocation Failure)  8533K->5625K(10304K), 0.0138830 secs]
[GC (Allocation Failure)  8697K->5714K(10304K), 0.0132190 secs]
[Full GC (System.gc())  6223K->5057K(10304K), 0.1971760 secs]
[GC (Allocation Failure)  8638K->5281K(12400K), 0.0099410 secs]
[GC (Allocation Failure)  8864K->5225K(12400K), 0.0072790 secs]
[GC (Allocation Failure)  8809K->5779K(12400K), 0.0228190 secs]
[GC (Allocation Failure)  9363K->5973K(12400K), 0.0245320 secs]
[GC (Allocation Failure)  9557K->6387K(12400K), 0.0336000 secs]
[GC (Allocation Failure)  9971K->6820K(12400K), 0.0708700 secs]
[GC (Allocation Failure)  10404K->7062K(12400K), 0.0272080 secs]
[GC (Allocation Failure)  10646K->7616K(12400K), 0.0414910 secs]
[GC (Allocation Failure)  11200K->8159K(12400K), 0.0393720 secs]
[GC (Allocation Failure)  11743K->8557K(12400K), 0.0348170 secs]
[GC (Allocation Failure)  12140K->8749K(12400K), 0.0297140 secs]
[GC (Allocation Failure)  12333K->8940K(12528K), 0.0215950 secs]
[Full GC (Allocation Failure)  8940K->7652K(12528K), 0.3503490 secs]
[GC (Allocation Failure)  12772K->8503K(18516K), 0.0317380 secs]
[GC (Allocation Failure)  13623K->8922K(18516K), 0.0387820 secs]
[GC (Allocation Failure)  14042K->8993K(18516K), 0.0441300 secs]
[GC (Allocation Failure)  14113K->9039K(18516K), 0.0519050 secs]
[GC (Allocation Failure)  14159K->9098K(18516K), 0.0344660 secs]
[GC (Allocation Failure)  14218K->9581K(18516K), 0.0449500 secs]
[GC (Allocation Failure)  14701K->9809K(18516K), 0.0429140 secs]
[GC (Allocation Failure)  14929K->9855K(18516K), 0.0404580 secs]
[GC (Allocation Failure)  14829K->10382K(18516K), 0.0482550 secs]
[GC (Allocation Failure)  15435K->11302K(18516K), 0.0612510 secs]
[GC (Allocation Failure)  16422K->11583K(18516K), 0.0428030 secs]
[GC (Allocation Failure)  16703K->11700K(18516K), 0.0519530 secs]
[GC (Allocation Failure)  16820K->11846K(18516K), 0.0342140 secs]
[GC (Allocation Failure)  16940K->11956K(18516K), 0.0321130 secs]
[GC (Allocation Failure)  17076K->12338K(18516K), 0.0278600 secs]
[GC (Allocation Failure)  17458K->12802K(18516K), 0.0529760 secs]
[GC (Allocation Failure)  17922K->13186K(18644K), 0.0486510 secs]
[Full GC (Allocation Failure)  13186K->11847K(18644K), 0.5185290 secs]
[GC (Allocation Failure)  19908K->12502K(28964K), 0.0421480 secs]
[GC (Allocation Failure)  20644K->13113K(28964K), 0.0444040 secs]
[GC (Allocation Failure)  21305K->13198K(28964K), 0.0288630 secs]
[GC (Allocation Failure)  21324K->13700K(28964K), 0.0352390 secs]
[GC (Allocation Failure)  21892K->13678K(28964K), 0.0431970 secs]
[GC (Allocation Failure)  21870K->14443K(28964K), 0.0464770 secs]
1822610 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
[GC (Allocation Failure)  22635K->14359K(28964K), 0.0441610 secs]
1822986 [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]]
1822992 [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]]
1822996 [main] INFO org.acegisecurity.intercept.AbstractSecurityInterceptor - Validated configuration attributes
[GC (Allocation Failure)  22551K->14185K(28964K), 0.0303980 secs]
1823607 [main] INFO org.springframework.web.context.ContextLoader - Root WebApplicationContext: initialization completed in 59430 ms
[GC (Allocation Failure)  22362K->14736K(28964K), 0.0325950 secs]
[GC (Allocation Failure)  22924K->14637K(28964K), 0.0332000 secs]
[GC (Allocation Failure)  22829K->15202K(28964K), 0.0400020 secs]
1829540 [main] INFO / - Initializing Spring FrameworkServlet 'subsonic'
1829541 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization started
1829598 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Refreshing org.springframework.web.context.support.XmlWebApplicationContext@1876704: display name [WebApplicationContext for namespace 'subsonic-servlet']; startup date [Tue Nov 11 19:47:50 UTC 2014]; parent: org.springframework.web.context.support.XmlWebApplicationContext@12f203f
1829601 [main] INFO org.springframework.beans.factory.xml.XmlBeanDefinitionReader - Loading XML bean definitions from ServletContext resource [/WEB-INF/subsonic-servlet.xml]
[GC (Allocation Failure)  23394K->15449K(28964K), 0.0515320 secs]
1831406 [main] INFO org.springframework.web.context.support.XmlWebApplicationContext - Bean factory for application context [org.springframework.web.context.support.XmlWebApplicationContext@1876704]: org.springframework.beans.factory.support.DefaultListableBeanFactory@91490c
1832436 [main] INFO org.springframework.beans.factory.support.DefaultListableBeanFactory - Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@91490c: 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,dlnaSettingsController,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@3958e7
[GC (Allocation Failure)  23641K->15203K(28964K), 0.0295660 secs]
[GC (Allocation Failure)  23395K->15344K(28964K), 0.0301210 secs]
[GC (Allocation Failure)  23536K->15569K(28964K), 0.0385980 secs]
[GC (Allocation Failure)  23761K->15848K(28964K), 0.0475640 secs]
[GC (Allocation Failure)  24040K->16088K(28964K), 0.0522640 secs]
[GC (Allocation Failure)  24280K->16338K(28964K), 0.0438170 secs]
[GC (Allocation Failure)  24530K->16647K(28964K), 0.0510830 secs]
[GC (Allocation Failure)  24839K->17023K(28964K), 0.0465180 secs]
[GC (Allocation Failure)  25215K->17423K(28964K), 0.0562030 secs]
1844744 [main] INFO org.springframework.web.servlet.DispatcherServlet - FrameworkServlet 'subsonic': initialization completed in 15201 ms
[GC (Allocation Failure)  25615K->18001K(28964K), 0.0629230 secs]
1845018 [main] INFO org.mortbay.log - Started SelectChannelConnector@0.0.0.0:4040
Subsonic running on: http://localhost:4040/
1845483 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Looking for RMI registry at port '9412'
1845626 [main] INFO org.springframework.remoting.rmi.RmiServiceExporter - Could not detect RMI registry - creating new one
1845957 [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 (Allocation Failure)  26193K->18487K(28964K), 0.0788570 secs]
[GC (Allocation Failure)  26676K->18952K(28964K), 0.0678140 secs]
[GC (Allocation Failure)  27144K->19927K(28964K), 0.0723290 secs]
[GC (Allocation Failure)  28119K->19574K(28964K), 0.0466490 secs]
[GC (Allocation Failure)  27766K->19566K(28964K), 0.0189450 secs]
[GC (Allocation Failure)  27758K->19570K(28964K), 0.0204297 secs]
[GC (Allocation Failure)  27762K->19576K(28964K), 0.0184126 secs]
[GC (Allocation Failure)  27768K->19577K(28964K), 0.0198442 secs]
[GC (Allocation Failure)  27769K->19575K(28964K), 0.0203994 secs]
[GC (Allocation Failure)  27767K->19769K(28964K), 0.0376939 secs]
[GC (Allocation Failure)  27961K->20081K(28964K), 0.0299982 secs]
[GC (Allocation Failure)  28273K->19975K(28964K), 0.0286412 secs]
[GC (Allocation Failure)  28167K->19972K(28964K), 0.0353876 secs]
[GC (Allocation Failure)  28164K->19981K(28964K), 0.0335484 secs]
[GC (Allocation Failure)  28173K->19990K(28964K), 0.0299584 secs]
[GC (Allocation Failure)  28182K->20210K(28964K), 0.0343387 secs]
[GC (Allocation Failure)  28402K->20199K(29092K), 0.0359957 secs]
[Full GC (Allocation Failure)  20199K->15836K(29092K), 0.8254771 secs]
[GC (Allocation Failure)  26716K->16923K(38556K), 0.0894900 secs]
4528014 [btpool0-7] INFO org.directwebremoting.log.startup - Starting: DwrServlet v3.0.0.116.rc1 on jetty-6.1.x / JDK 1.8.0 from Oracle Corporation at
[GC (Allocation Failure)  27803K->18017K(38556K), 0.0722282 secs]
[GC (Allocation Failure)  28897K->18558K(38556K), 0.0613113 secs]
[GC (Allocation Failure)  29438K->18746K(38556K), 0.0594073 secs]
[GC (Allocation Failure)  29311K->21363K(38556K), 0.1687750 secs]
[GC (Allocation Failure)  32243K->24424K(38556K), 0.2052905 secs]
[GC (Allocation Failure)  35301K->27401K(38556K), 0.1981036 secs]
[GC (Allocation Failure)  38272K->28649K(39624K), 0.1036757 secs]
[Full GC (Allocation Failure)  28649K->28151K(39624K), 1.2730555 secs]
[GC (Allocation Failure)  45893K->30764K(66508K), 0.3173561 secs]
[GC (Allocation Failure)  49196K->31762K(66508K), 0.1477373 secs]
[GC (Allocation Failure)  50194K->31562K(66508K), 0.0631291 secs]
[GC (Allocation Failure)  49994K->32055K(66508K), 0.0484193 secs]
[GC (Allocation Failure)  50487K->32065K(66508K), 0.0381844 secs]