Share links not working?

[Boutros]

So, I'm spinning this off from this thread from yesterday, as it seems like it may (?) be a different issue.

I created a Share link last night and posted it to Facebook to see if anyone could play it. The almost-unanimous response was that the page and the player loaded, but then it just spun forever, never actually playing. One of my friends left the page open for 20 minutes, wondering if it might eventually load, but it never did. One user reported getting "Error #2302"

The only people who WERE able to play it were folks with active accounts on my server - I saw them show up in the "Now Playing" panel in the web UI.

Here's the link - wondering if anyone could derive any insight from it:

*link removed, as I saw in the logs that people were trying to load this, then making dozens of attempts to login to my server with unfamiliar usernames.

[shadow.8]

Hmm, I have the same issue if I'm not logged in. Just spins never plays. I've never shared music before so is this how it is supposed to work?

http://tlp.69.mu/share/KPAiP

[shadow.8]

I created another share, just to try, and it doesn't seem to work either:

http://tlp.69.mu/share/QIHQL

Here's the relevant log:

Code: Select all

[1/11/13 10:50:09 AM UTC]   INFO   ShareService   Created share 'QIHQL' with 14 file(s).
[1/11/13 10:50:15 AM UTC]   DEBUG   ExternalPlayerController   pass songs to externalplayer: [/mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/01 Reroute to Remain.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/02 System.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/04 Trigger.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/05 Cloud Connected.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/06 Transparent.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/07 Dawn of a New Day.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/08 Egonomic.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/09 Minus.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/10 Dismiss the Cynics.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/11 Free Fall.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/12 Dark Signs.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/13 Metaphor.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/14 Black & White.mp3]
[1/11/13 10:50:15 AM UTC]   DEBUG   ExternalPlayerController   pass coverArts to externalplayer: [/mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3]
[1/11/13 10:50:16 AM UTC]   DEBUG   StreamController   got mfId = 1092 from param 1092
[1/11/13 10:50:16 AM UTC]   INFO   PlaylistInputStream   admin listening to "2002 Reroute to Remain/01 Reroute to Remain.mp3"
[1/11/13 10:50:16 AM UTC]   DEBUG   AudioScrobblerService   Last.fm not enabled. Not scrobbling for admin.
[1/11/13 10:50:34 AM UTC]   DEBUG   ExternalPlayerController   pass songs to externalplayer: [/mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/01 Reroute to Remain.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/02 System.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/04 Trigger.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/05 Cloud Connected.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/06 Transparent.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/07 Dawn of a New Day.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/08 Egonomic.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/09 Minus.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/10 Dismiss the Cynics.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/11 Free Fall.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/12 Dark Signs.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/13 Metaphor.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/14 Black & White.mp3]
[1/11/13 10:50:34 AM UTC]   DEBUG   ExternalPlayerController   pass coverArts to externalplayer: [/mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3, /mnt/2.5TB/Music/Artists/In Flames/2002 Reroute to Remain/03 Drifter.mp3]
[1/11/13 10:50:35 AM UTC]   INFO   PlayerDao   Created player 16.
[1/11/13 10:50:35 AM UTC]   WARN   StreamController   throwable caught!
[1/11/13 10:50:38 AM UTC]   INFO   PlayerDao   Created player 17.
[1/11/13 10:50:38 AM UTC]   WARN   StreamController   throwable caught!
[1/11/13 10:50:39 AM UTC]   INFO   PlayerDao   Created player 18.
[1/11/13 10:50:39 AM UTC]   WARN   StreamController   throwable caught!
[1/11/13 10:50:40 AM UTC]   INFO   PlayerDao   Created player 19.
[1/11/13 10:50:40 AM UTC]   WARN   StreamController   throwable caught!
[1/11/13 10:50:43 AM UTC]   INFO   PlayerDao   Created player 20.
[1/11/13 10:50:43 AM UTC]   WARN   StreamController   throwable caught!
[1/11/13 10:50:48 AM UTC]   INFO   PlayerDao   Created player 21.
[1/11/13 10:50:48 AM UTC]   WARN   StreamController   throwable caught!
[1/11/13 10:50:56 AM UTC]   DEBUG   SecurityService   Load user travis
[1/11/13 10:50:56 AM UTC]   DEBUG   LeftController   return last modified as Fri Jan 11 10:39:10 UTC 2013

Nothing seems to be wrong here... I do see several "WARN StreamController throwable caught!" errors, not sure if that means anything.

EDIT: I deleted the share so this won't be accessible.

[Boutros]

Yes, I'm getting the same behavior when I try to open your share - I see the player, and I see the playlist, but it just spins (been about 15 minutes now). Previously, these worked great, and were totally public - I actually had an unfortunate incident a few months ago (prior to switching to MusicCabinet) where I posted a share to a friend of mine who is a fairly prominent musician, and he posted it on Facebook where it went viral - my server was hammered the rest of that afternoon, with several hundred people playing the track

These shares also used to display the album art in the player, which is no longer happening - even though it's clearly trying to pass the album art.

Here's what I'm seeing in the log this morning after posting this here:

Code: Select all

[1/11/13 12:29:41 PM EST]   DEBUG   ExternalPlayerController   pass songs to externalplayer: [J:\Compiled\American Primitive Vol. I - Raw Pre-War Gospel (1926-1936)\06 Rev. I.B. Ware - You Better Quit Drinking Shine.mp3]
[1/11/13 12:29:41 PM EST]   DEBUG   ExternalPlayerController   pass coverArts to externalplayer: [J:\Compiled\American Primitive Vol. I - Raw Pre-War Gospel (1926-1936)\06 Rev. I.B. Ware - You Better Quit Drinking Shine.mp3]
[1/11/13 12:29:45 PM EST]   INFO   PlayerDao   Created player 169.
[1/11/13 12:29:45 PM EST]   WARN   StreamController   throwable caught!
[1/11/13 12:30:24 PM EST]   WARN   StreamController   throwable caught!
[1/11/13 12:30:25 PM EST]   WARN   StreamController   throwable caught!
[1/11/13 12:33:25 PM EST]   DEBUG   LeftController   return last modified as Fri Jan 11 12:33:23 EST 2013
[1/11/13 12:33:25 PM EST]   DEBUG   LeftController   hasArtists = true, method = null
[1/11/13 12:36:54 PM EST]   DEBUG   ExternalPlayerController   pass songs to externalplayer: [J:\Compiled\American Primitive Vol. I - Raw Pre-War Gospel (1926-1936)\06 Rev. I.B. Ware - You Better Quit Drinking Shine.mp3]
[1/11/13 12:36:54 PM EST]   DEBUG   ExternalPlayerController   pass coverArts to externalplayer: [J:\Compiled\American Primitive Vol. I - Raw Pre-War Gospel (1926-1936)\06 Rev. I.B. Ware - You Better Quit Drinking Shine.mp3]
[1/11/13 12:36:55 PM EST]   WARN   StreamController   throwable caught!
[1/11/13 12:38:04 PM EST]   DEBUG   SecurityService   Load user guest

[shadow.8]

I noticed my server created a user named "guest" but didn't post it in the log. I assume it was created with no password so if I give the user a password and then login as that user, it streams just fine. I suppose that's to be expected. Is there maybe an issue with the guest user it creates? For what it's worth, I tried deleting and creating a new guest user but I can't make one without a password, which defeats the whole purpose.

EDIT: Nevermind, it did create the user, I just didn't catch it before. Deleting the user and allowing it recreate the guest user, it shows up in my log:

Code: Select all

[1/11/13 11:11:49 AM UTC]   INFO   SecurityService   Created user guest
[1/11/13 11:11:49 AM UTC]   INFO   PlayerDao   Created player 28.

[Boutros]

Maybe so, yeah - back when this worked, if I created a share, I'd see a whole succession of "guest" users in the "Now Playing" panel in the UI, though I can't recall for sure now if it actually created a new user in the log for each or not.

[hakko]

I can't reproduce this.

Code: Select all

[2013-01-13 12:46:12,616] DEBUG ExternalPlayerController - pass songs to externalplayer: [C:\Users\hakko\Music\Library\The Gentleman Losers\Rural Route No. 5\01 All That Is Solid Melts Into Air.mp3, C:\Users\hakko\Music\Library\The Gentleman Losers\Rural Route No. 5\02 Valley Green.mp3, C:\Users\hakko\Music\Library\The Gentleman Losers\Rural Route No. 5\03 At Dawn I Am the Morning Clouds, at Evening the Falling Rain.mp3]
[2013-01-13 12:46:12,616] DEBUG ExternalPlayerController - pass coverArts to externalplayer: [C:\Users\hakko\Music\Library\The Gentleman Losers\Rural Route No. 5\01 All That Is Solid Melts Into Air.mp3, C:\Users\hakko\Music\Library\The Gentleman Losers\Rural Route No. 5\01 All That Is Solid Melts Into Air.mp3, C:\Users\hakko\Music\Library\The Gentleman Losers\Rural Route No. 5\01 All That Is Solid Melts Into Air.mp3]
[2013-01-13 12:46:12,617] INFO PlayerDao - Created player 1.
[2013-01-13 12:46:13,616] DEBUG StreamController - got mfId = 5085 from param 5085
[2013-01-13 12:46:13,617] INFO PlaylistInputStream - guest listening to "Rural Route No. 5\01 All That Is Solid Melts Into Air.mp3"
[2013-01-13 12:46:13,617] DEBUG AudioScrobblerService - Last.fm not enabled. Not scrobbling for guest.
[2013-01-13 12:46:15,656] DEBUG AudioScrobblerService - Last.fm not enabled. Not scrobbling for guest.


.. and it plays fine, as "guest". (a "guest" account is automatically created by Subsonic, with a password of 30 random characters)

If you paste the full subsonic.log here (you have to view it using a text editor to see the full stack trace, not only "StreamController throwable caught"), I'd be interested to have a look and see if that gives any more information.

The whole Subsonic player handling is a bit confusing but have you tried deleting all players from Settings -> Players?

[Boutros]

Hi hakko -

Here's what I'm seeing in the log around what I believe was shadow.8 attempting to reach my Share on Friday (this should be the same time as the details I posted from the UI at the time). Apologies if I'm including too much/too little - I trust you'll let me know if you need more:

Code: Select all

[2013-01-11 12:29:41,780] DEBUG ExternalPlayerController - pass songs to externalplayer: [J:\Compiled\American Primitive Vol. I - Raw Pre-War Gospel (1926-1936)\06 Rev. I.B. Ware - You Better Quit Drinking Shine.mp3]
[2013-01-11 12:29:41,780] DEBUG ExternalPlayerController - pass coverArts to externalplayer: [J:\Compiled\American Primitive Vol. I - Raw Pre-War Gospel (1926-1936)\06 Rev. I.B. Ware - You Better Quit Drinking Shine.mp3]
[2013-01-11 12:29:45,915] INFO PlayerDao - Created player 169.
[2013-01-11 12:29:45,916] WARN StreamController - throwable caught!
java.lang.NullPointerException
   at net.sourceforge.subsonic.controller.StreamController.handleRequest(StreamController.java:88)
   at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48)
   at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:900)
   at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:827)
   at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
   at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:778)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:735)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
   at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:648)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1336)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:311)
   at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:116)
   at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:83)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:101)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.rememberme.RememberMeAuthenticationFilter.doFilter(RememberMeAuthenticationFilter.java:139)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:54)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:182)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:105)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:173)
   at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346)
   at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
   at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
   at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:559)
   at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
   at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1072)
   at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
   at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
   at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
   at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
   at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
   at org.eclipse.jetty.server.Server.handle(Server.java:365)
   at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
   at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:926)
   at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:988)
   at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:635)
   at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
   at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
   at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:627)
   at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:51)
   at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
   at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
   at java.lang.Thread.run(Unknown Source)

[2013-01-11 12:30:24,050] WARN StreamController - throwable caught!
java.lang.NullPointerException
   at net.sourceforge.subsonic.controller.StreamController.handleRequest(StreamController.java:88)
   at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48)
   at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:900)
   at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:827)
   at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
   at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:778)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:735)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
   at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:648)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1336)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:311)
   at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:116)
   at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:83)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:101)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.rememberme.RememberMeAuthenticationFilter.doFilter(RememberMeAuthenticationFilter.java:139)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:54)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:182)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:105)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:173)
   at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346)
   at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
   at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
   at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:559)
   at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
   at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1072)
   at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
   at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
   at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
   at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
   at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
   at org.eclipse.jetty.server.Server.handle(Server.java:365)
   at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
   at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:926)
   at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:988)
   at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:635)
   at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
   at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
   at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:627)
   at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:51)
   at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
   at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
   at java.lang.Thread.run(Unknown Source)

[2013-01-11 12:30:25,599] WARN StreamController - throwable caught!
java.lang.NullPointerException
   at net.sourceforge.subsonic.controller.StreamController.handleRequest(StreamController.java:88)
   at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48)
   at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:900)
   at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:827)
   at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
   at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:778)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:735)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
   at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:648)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1336)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:311)
   at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:116)
   at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:83)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:101)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.rememberme.RememberMeAuthenticationFilter.doFilter(RememberMeAuthenticationFilter.java:139)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:54)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:182)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:105)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:173)
   at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346)
   at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
   at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
   at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:559)
   at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
   at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1072)
   at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
   at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
   at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
   at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
   at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
   at org.eclipse.jetty.server.Server.handle(Server.java:365)
   at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
   at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:926)
   at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:988)
   at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:635)
   at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
   at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
   at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:627)
   at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:51)
   at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
   at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
   at java.lang.Thread.run(Unknown Source)

Would you suggest trying to delete all players now, or would you like to take a look at anything else before I do so (namely the Share link I posted above, which should still be "active")?

[hakko]

Thanks! That error is related to the Subsonic handling of players/users. It seems that Subsonic has somehow created a player without an associated user. So yes, I still think it is a good idea to remove all players and see if it works better. I added some debug code to help provide some insight next time this happens.

[Boutros]

Great. I just deleted all players, and all previous Shares to boot, did a Folder Scan, and Stopped/Restarted the Service to be on the safe side. After this, I created a new share - can anyone see if they can play it now? If not, I'll pull the logs again and see what we can see.

*link removed, as I saw in the logs that people were trying to load this, then making dozens of attempts to login to my server with unfamiliar usernames.

[hakko]

I think we need more logs here... http://i.imgur.com/8RSun.png

I'll have to google for what #2302 means in JWPlayer.

[Boutros]

Here's what I'm seeing this time - this is the complete log from when I re-started the service about half an hour ago - one of my users hopped on almost immediately and started downloading something, so there's a little bit of kluge in there, and I wanted to make sure not to skip anything you might need:

Code: Select all

[2013-01-13 10:02:46,880] DEBUG DaoHelper - DAO Helper constructor.
[2013-01-13 10:02:46,969] DEBUG DaoHelper - Data source URL: jdbc:hsqldb:file:c:\subsonic/db/subsonic
[2013-01-13 10:02:46,969] INFO DaoHelper - Checking database schema.
[2013-01-13 10:02:47,623] INFO DaoHelper - Done checking database schema.
[2013-01-13 10:02:47,805] INFO VersionService - Resolved local Subsonic version to: 0.7
[2013-01-13 10:02:49,424] INFO SearchService - Automatic index creation scheduled to run every 1 day(s), starting at Mon Jan 14 03:00:00 EST 2013
[2013-01-13 10:02:49,516] INFO PlayerDao - Deleted 2 player(s) that haven't been used after Wed Nov 14 10:02:49 EST 2012
[2013-01-13 10:02:49,812] INFO PodcastService - Automatic Podcast update scheduled to run every 24 hour(s), starting at Sun Jan 13 10:07:49 EST 2013
[2013-01-13 10:02:55,180] DEBUG SecurityService - Load user UserX
[2013-01-13 10:02:55,357] DEBUG SecurityService - Load user UserX
[2013-01-13 10:02:55,649] DEBUG SecurityService - Load user UserX
[2013-01-13 10:02:55,690] INFO PlayerDao - Created player 4.
[2013-01-13 10:02:55,727] DEBUG SecurityService - Load user UserX
[2013-01-13 10:02:55,779] DEBUG SecurityService - Load user UserX
[2013-01-13 10:02:55,835] DEBUG SecurityService - Load user UserX
[2013-01-13 10:02:55,877] DEBUG SecurityService - Load user UserX
[2013-01-13 10:02:55,978] INFO NetworkService - Successfully forwarding port 8080.
[2013-01-13 10:03:37,585] WARN CoverArtController - Failed to create thumbnail for J:\Compiled\Musique Mnong Gar du Vietnam [ocora OCR 80]\05 Tambour et gongs plats.mp3
javax.imageio.IIOException: Invalid icc profile: invalid number of icc markers
   at com.sun.imageio.plugins.jpeg.JPEGImageReader.readImageHeader(Native Method)
   at com.sun.imageio.plugins.jpeg.JPEGImageReader.readNativeHeader(Unknown Source)
   at com.sun.imageio.plugins.jpeg.JPEGImageReader.checkTablesOnly(Unknown Source)
   at com.sun.imageio.plugins.jpeg.JPEGImageReader.gotoImage(Unknown Source)
   at com.sun.imageio.plugins.jpeg.JPEGImageReader.readHeader(Unknown Source)
   at com.sun.imageio.plugins.jpeg.JPEGImageReader.readInternal(Unknown Source)
   at com.sun.imageio.plugins.jpeg.JPEGImageReader.read(Unknown Source)
   at javax.imageio.ImageIO.read(Unknown Source)
   at javax.imageio.ImageIO.read(Unknown Source)
   at net.sourceforge.subsonic.controller.CoverArtController.getCachedImage(CoverArtController.java:142)
   at net.sourceforge.subsonic.controller.CoverArtController.handleRequest(CoverArtController.java:94)
   at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48)
   at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:900)
   at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:827)
   at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
   at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:778)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:735)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
   at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:648)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1336)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:311)
   at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:116)
   at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:83)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:101)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.rememberme.RememberMeAuthenticationFilter.doFilter(RememberMeAuthenticationFilter.java:146)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:54)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:182)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:105)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
   at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
   at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:173)
   at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346)
   at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
   at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
   at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
   at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
   at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:559)
   at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
   at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1072)
   at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
   at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
   at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
   at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
   at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
   at org.eclipse.jetty.server.Server.handle(Server.java:365)
   at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
   at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:926)
   at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:988)
   at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:635)
   at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
   at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
   at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:627)
   at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:51)
   at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
   at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
   at java.lang.Thread.run(Unknown Source)

[2013-01-13 10:04:40,577] DEBUG DownloadController - download id param = [236118, 236122]
[2013-01-13 10:04:40,578] DEBUG DownloadController - ids = [236118, 236122]
[2013-01-13 10:04:40,578] INFO DownloadController - Starting to download 'Pharoah Sanders - Karma.zip' to Player 4 [UserX]
[2013-01-13 10:04:40,580] DEBUG MediaFileService - request for non indexed media file J:\A-Z\Sanders, Pharoah\[1969] Karma, cache as -476560174
[2013-01-13 10:04:43,424] INFO DownloadController - Downloading '[1969] Karma\01 The Creator Has a Master Plan.mp3' to Player 4 [UserX]
[2013-01-13 10:07:49,816] INFO PodcastService - Starting scheduled Podcast refresh.
[2013-01-13 10:07:49,841] INFO PodcastService - Completed scheduled Podcast refresh.
[2013-01-13 10:09:07,422] DEBUG ExternalPlayerController - pass songs to externalplayer: [J:\A-Z\Ngozi Family\[1977] 45,000 Volts\01 Everything Is Over.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\02 Nizaka Panga Ngozi.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\03 I Will Be With You.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\05 You Don't Love Me.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\06 Night of Fear.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\07 Hold On.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\08 Timwenge Tabene Tabene.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\09 Chisoni Kwanztu Azimbabwe.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\10 Chenjelani.mp3]
[2013-01-13 10:09:07,423] DEBUG ExternalPlayerController - pass coverArts to externalplayer: [J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3]
[2013-01-13 10:09:07,424] INFO PlayerDao - Created player 5.
[2013-01-13 10:10:32,470] DEBUG ExternalPlayerController - pass songs to externalplayer: [J:\A-Z\Ngozi Family\[1977] 45,000 Volts\01 Everything Is Over.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\02 Nizaka Panga Ngozi.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\03 I Will Be With You.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\05 You Don't Love Me.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\06 Night of Fear.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\07 Hold On.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\08 Timwenge Tabene Tabene.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\09 Chisoni Kwanztu Azimbabwe.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\10 Chenjelani.mp3]
[2013-01-13 10:10:32,470] DEBUG ExternalPlayerController - pass coverArts to externalplayer: [J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3, J:\A-Z\Ngozi Family\[1977] 45,000 Volts\04 Atate.mp3]
[2013-01-13 10:21:43,089] DEBUG MediaFileService - request for non indexed media file J:\A-Z\Sanders, Pharoah\[1969] Karma, cache as -476560174
[2013-01-13 10:21:49,725] INFO DownloadController - Downloading '[1969] Karma\02 Colors.mp3' to Player 4 [UserX]
[2013-01-13 10:24:43,993] INFO DownloadController - Downloaded 'Pharoah Sanders - Karma.zip' to Player 4 [UserX]
[2013-01-13 10:26:05,091] DEBUG SecurityService - Load user MOB
[2013-01-13 10:26:05,157] DEBUG LeftController - return last modified as Sun Jan 13 10:02:49 EST 2013
[2013-01-13 10:26:05,163] DEBUG LeftController - hasArtists = true, method = null


[shadow.8]

I just deleted the guest user and all of the players, then stopped/started the Subsonic service, created a share and then logged out and tried to listen to it.

Here's the share link:
http://tlp.69.mu/share/TGMbr

A couple things:

When I deleted the players, there were around 50 in there, most were players that looked like 'Player # [null]' instead of the expected 'Player # [user].' Not sure if that is to be expected or not.

The share created just fine, logging out seemed to work. I visited the share link above and it started playing. Jumping back into Subsonic, I found it was streaming to my username (travis) even though I was logged out... I logged out and tried it again a couple of times and it kept streaming. It did create the 'guest' user and a player for that user, but it didn't try to stream there. Before, it wouldn't stream at all if I was logged out and I assume it was trying to connect to that guest user.

Here's the full log found under /var/subsonic/subsonic.log:

Code: Select all

[2013-01-12 03:43:38,302] DEBUG DaoHelper - DAO Helper constructor.
[2013-01-12 03:43:38,327] DEBUG DaoHelper - Data source URL: jdbc:hsqldb:file:/var/subsonic/db/subsonic
[2013-01-12 03:43:38,327] INFO DaoHelper - Checking database schema.
[2013-01-12 03:43:40,181] INFO DaoHelper - Done checking database schema.
[2013-01-12 03:43:40,832] INFO VersionService - Resolved local Subsonic version to: 0.7
[2013-01-12 03:43:47,957] INFO SearchService - Automatic index creation scheduled to run every 1 day(s), starting at Sun Jan 13 03:00:00 UTC 2013
[2013-01-12 03:43:48,446] INFO PodcastService - Automatic Podcast update scheduled to run every 24 hour(s), starting at Sat Jan 12 03:48:48 UTC 2013
[2013-01-12 03:43:54,208] WARN NetworkService - No UPnP router found.
[2013-01-12 03:46:39,560] DEBUG SecurityService - Load user travis
[2013-01-12 03:46:39,917] DEBUG LeftController - return last modified as Sat Jan 12 03:43:47 UTC 2013
[2013-01-12 03:46:40,015] INFO PlayerDao - Created player 1.
[2013-01-12 03:46:40,114] DEBUG LeftController - hasArtists = true, method = null
[2013-01-12 03:47:28,168] INFO ShareService - Created share 'TGMbr' with 4 file(s).
[2013-01-12 03:47:46,033] DEBUG ExternalPlayerController - pass songs to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/Musi$
[2013-01-12 03:47:46,034] DEBUG ExternalPlayerController - pass coverArts to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/$
[2013-01-12 03:47:46,047] INFO SecurityService - Created user guest
[2013-01-12 03:47:46,055] INFO PlayerDao - Created player 2.
[2013-01-12 03:47:46,821] DEBUG StreamController - got mfId = 851 from param 851
[2013-01-12 03:47:46,832] INFO PlaylistInputStream - travis listening to "2009 Wither [Single]/01 Wither (Album Version).mp3"
[2013-01-12 03:48:03,243] DEBUG SecurityService - Load user travis
[2013-01-12 03:48:03,453] DEBUG LeftController - return last modified as Sat Jan 12 03:43:47 UTC 2013
[2013-01-12 03:48:11,874] DEBUG ExternalPlayerController - pass songs to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/Musi$
[2013-01-12 03:48:11,874] DEBUG ExternalPlayerController - pass coverArts to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/$
[2013-01-12 03:48:12,682] DEBUG StreamController - got mfId = 851 from param 851
[2013-01-12 03:48:12,688] INFO PlaylistInputStream - travis listening to "2009 Wither [Single]/01 Wither (Album Version).mp3"
[2013-01-12 03:48:18,813] WARN StreamController - throwable caught!
org.eclipse.jetty.io.EofException
        at org.eclipse.jetty.http.HttpGenerator.flushBuffer(HttpGenerator.java:914)
        at org.eclipse.jetty.http.AbstractGenerator.blockForOutput(AbstractGenerator.java:523)
        at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:170)
        at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
        at net.sourceforge.subsonic.controller.StreamController.handleRequest(StreamController.java:227)
        at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48)
        at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:900)
        at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:827)
        at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
        at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:778)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:735)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
        at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:648)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1336)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:311)
        at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:116)
        at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:83)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.rememberme.RememberMeAuthenticationFilter.doFilter(RememberMeAuthenticationFilter.java:139)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:54)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:182)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:105)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:173)
        at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346)
        at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
        at net.sourceforge.subsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
        at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
        at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
        at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
        at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:559)
        at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
        at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1072)
        at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
        at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
        at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
        at org.eclipse.jetty.server.Server.handle(Server.java:365)
        at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
        at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:926)
        at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:988)
        at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:635)
        at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
        at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:627)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:51)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
        at java.lang.Thread.run(Thread.java:722)
Caused by: java.io.IOException: Broken pipe
        at sun.nio.ch.FileDispatcherImpl.write0(Native Method)
        at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47)
        at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:89)
        at sun.nio.ch.IOUtil.write(IOUtil.java:60)
        at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:450)
        at org.eclipse.jetty.io.nio.ChannelEndPoint.flush(ChannelEndPoint.java:310)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint.flush(SelectChannelEndPoint.java:361)
        at org.eclipse.jetty.http.HttpGenerator.flushBuffer(HttpGenerator.java:853)
        ... 69 more

[2013-01-12 03:48:23,931] DEBUG SecurityService - Load user travis
[2013-01-12 03:48:24,077] DEBUG LeftController - return last modified as Sat Jan 12 03:43:47 UTC 2013
[2013-01-12 03:48:48,443] INFO PodcastService - Starting scheduled Podcast refresh.
[2013-01-12 03:48:48,445] INFO PodcastService - Completed scheduled Podcast refresh.
[2013-01-12 03:49:21,575] DEBUG ExternalPlayerController - pass songs to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/Musi$
[2013-01-12 03:49:21,576] DEBUG ExternalPlayerController - pass coverArts to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/$
[2013-01-12 03:49:22,305] DEBUG StreamController - got mfId = 851 from param 851
[2013-01-12 03:49:22,310] INFO StreamController - Got range: Range[5001714,5001714]
[2013-01-12 03:49:22,313] INFO PlaylistInputStream - travis listening to "2009 Wither [Single]/01 Wither (Album Version).mp3"
[2013-01-12 03:49:23,054] DEBUG StreamController - got mfId = 851 from param 851
[2013-01-12 03:49:23,066] INFO StreamController - Got range: Range[5001714,9125887]
[2013-01-12 03:49:23,076] INFO PlaylistInputStream - travis listening to "2009 Wither [Single]/01 Wither (Album Version).mp3"
[2013-01-12 03:49:33,324] DEBUG SecurityService - Load user travis
[2013-01-12 03:49:33,487] DEBUG LeftController - return last modified as Sat Jan 12 03:43:47 UTC 2013
[2013-01-12 03:51:47,944] DEBUG ExternalPlayerController - pass songs to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/Musi$
[2013-01-12 03:51:47,944] DEBUG ExternalPlayerController - pass coverArts to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/$
[2013-01-12 03:51:49,093] DEBUG StreamController - got mfId = 851 from param 851
[2013-01-12 03:51:49,100] INFO PlaylistInputStream - travis listening to "2009 Wither [Single]/01 Wither (Album Version).mp3"
[2013-01-12 03:51:57,572] WARN StreamController - throwable caught!
org.eclipse.jetty.io.EofException
        at org.eclipse.jetty.http.HttpGenerator.flushBuffer(HttpGenerator.java:914)
        at org.eclipse.jetty.http.AbstractGenerator.blockForOutput(AbstractGenerator.java:523)
        at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:170)
        at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
        at net.sourceforge.subsonic.controller.StreamController.handleRequest(StreamController.java:227)
        at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48)
        at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:900)
        at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:827)
        at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:882)
        at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:778)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:735)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
        at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:648)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1336)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:311)
        at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:116)
        at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:83)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:101)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.rememberme.RememberMeAuthenticationFilter.doFilter(RememberMeAuthenticationFilter.java:139)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:54)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.www.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:150)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:182)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:105)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:323)
        at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:173)
        at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:346)
        at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:259)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
        at net.sourceforge.subsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
        at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
        at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:54)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
        at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
        at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:559)
        at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
        at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1072)
        at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
        at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
        at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
        at org.eclipse.jetty.server.Server.handle(Server.java:365)
        at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
        at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:926)
        at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:988)
        at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:635)
        at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
        at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:627)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:51)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
        at java.lang.Thread.run(Thread.java:722)
Caused by: java.io.IOException: Broken pipe
        at sun.nio.ch.FileDispatcherImpl.write0(Native Method)
        at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47)
        at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:89)
        at sun.nio.ch.IOUtil.write(IOUtil.java:60)
        at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:450)
        at org.eclipse.jetty.io.nio.ChannelEndPoint.flush(ChannelEndPoint.java:310)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint.flush(SelectChannelEndPoint.java:361)
        at org.eclipse.jetty.http.HttpGenerator.flushBuffer(HttpGenerator.java:853)
        ... 69 more

[2013-01-12 03:52:03,578] DEBUG SecurityService - Load user travis
[2013-01-12 03:52:03,778] DEBUG LeftController - return last modified as Sat Jan 12 03:43:47 UTC 2013
[2013-01-12 03:52:03,861] DEBUG LeftController - hasArtists = true, method = null

Can you try the link and let me know if it streams?

[bushman4]

Not working here at 10:16am EST.

Glenn

[shadow.8]

This is what showed up in my log after bushman4 tried to stream:

Code: Select all

[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   request for non indexed media file /share/Downloads/Before The Dawn - Discography (2001 - 2012) [MP3@320KB]/2010 - Decade of Darkness [EP] (320KB), cache as -1816902062
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   request for non indexed media file /mnt/2.5TB/Music/Artists/Isis/2006 In The Absence Of Truth, cache as -1628854386
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   media file not in memory, load meta data from db!
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   media file not in memory, load meta data from db!
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   media file not in memory, load meta data from db!
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   media file not in memory, load meta data from db!
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   media file not in memory, load meta data from db!
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   media file not in memory, load meta data from db!
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   media file not in memory, load meta data from db!
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   media file not in memory, load meta data from db!
[1/12/13 3:59:32 AM UTC]   DEBUG   MediaFileService   request for non indexed media file /mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single], cache as -1576839561
[1/12/13 4:09:43 AM UTC]   DEBUG   ExternalPlayerController   pass songs to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/02 Wither (Piano Version).mp3, /mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/03 Wither (John Petrucci Vocal Demo).mp3, /mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/04 The Best of Times (Mike Portnoy Vocal Demo).mp3]
[1/12/13 4:09:43 AM UTC]   DEBUG   ExternalPlayerController   pass coverArts to externalplayer: [/mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3, /mnt/2.5TB/Music/Artists/Dream Theater/2009 Wither [Single]/01 Wither (Album Version).mp3]
[1/12/13 4:09:45 AM UTC]   INFO   PlayerDao   Created player 3.
[1/12/13 4:09:45 AM UTC]   WARN   StreamController   throwable caught!
[1/12/13 4:09:59 AM UTC]   WARN   StreamController   throwable caught!
[1/12/13 4:10:20 AM UTC]   WARN   StreamController   throwable caught!

Also, I just noticed the time and date are incorrect, I've since fixed it...