subsonic does not play next track

[toolman]

Running Subsonic 6.1.3 with over 180.000 tracks. Using Chrome version 65.0.3325.162
Loaded a playlist containing 3824 songs. Plays fine, track after track. Next I set it to shuffle, Subsonic doesn't budge and keeps playing track after track.
The only thing I can think off is that it's some Chrome plug in that's creating your problem.

[kilgoretrout64]

I am having this problem too. After certain songs end, the next song will not play. If the song sequence is A,B,C, and the player will not advance at the end of B, if B is removed from the playlist then song C plays after song A no problem. Some kind or EOF issue? I began noticing this a few weeks ago.

It is affecting my mp3 files, not sure if it affects other formats. Not all songs are affected, only some. It is happening to all songs from certain albums, same rip session. None of my rips, at least not yet. In the first case I noticed, all the songs on the album had "incomplete last frame" noted in a properties feature of a program I sometimes use to fix issues called 'mpTrim'. I used the program to fix that problem, updated the server library, and the problem went away. Then I noticed more instances of the problem where there were no 'incomplete last frame' issues, so I tried updating the tags and removed the embedded album art. This did not fix the issue. None of these songs had this problem previously. They are part of a long playlist I have been using for over 6 months. I would have noticed. No errors in the subsonic log when this happens. Any help would be appreciated.

This is happening in Chrome browser on 2 different windows PC's (win 7 and win 10), and when casting to a chromecast device. It does not happen on my android phone using Dsub, where the files are cached locally. Only affecting streaming?

I have been using this subsonic on this server for about 4 years.
Server Box: Intel Core i3-3120M CPU @2.5 GHz, RAM 4.0 GB
OS: Win 10 Pro, 64 bit, Version 1709, Build 16299.309
Subsonic Version: 6.1.3 (build e408c9) – October 31, 2017
Server: jetty-6.1.x, java 1.8.0_161, HSQLDB, Windows 10 (80.1 MB / 145.0 MB)

[kilgoretrout64]

I was wrong about the log, needed to refresh. I see the same sequence as the earlier post. Also I tried the MS Edge browser and it has no problem with the same song sequence that has a repeatable problem on Chrome. Did google make a change? I can use Edge on the PC easily enough, but that fact that it is affecting my chromecast audio device is bumming me out.

Code: Select all

org.mortbay.jetty.EofException
   at org.mortbay.jetty.HttpGenerator.flush(HttpGenerator.java:760)
   at org.mortbay.jetty.AbstractGenerator$Output.blockForOutput(AbstractGenerator.java:546)
   at org.mortbay.jetty.AbstractGenerator$Output.flush(AbstractGenerator.java:567)
   at org.mortbay.jetty.HttpConnection$Output.flush(HttpConnection.java:907)
   at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:628)
   at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:575)
   at com.google.common.io.ByteStreams.copy(ByteStreams.java:74)
   at com.google.common.io.ByteSource.copyTo(ByteSource.java:220)
   at net.sourceforge.subsonic.controller.StreamController.sendFile(StreamController.java:272)
   at net.sourceforge.subsonic.controller.StreamController.handleRequest(StreamController.java:178)
   at net.sourceforge.subsonic.controller.RESTController.stream(RESTController.java:1529)
   at sun.reflect.GeneratedMethodAccessor405.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
   at java.lang.reflect.Method.invoke(Unknown Source)
   at org.springframework.web.servlet.mvc.multiaction.MultiActionController.invokeNamedMethod(MultiActionController.java:473)
   at org.springframework.web.servlet.mvc.multiaction.MultiActionController.handleRequestInternal(MultiActionController.java:410)
   at org.springframework.web.servlet.mvc.AbstractController.handleRequest(AbstractController.java:153)
   at org.springframework.web.servlet.mvc.SimpleControllerHandlerAdapter.handle(SimpleControllerHandlerAdapter.java:48)
   at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:875)
   at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:807)
   at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:571)
   at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:501)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
   at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)
   at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:487)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1093)
   at org.mortbay.servlet.UserAgentFilter.doFilter(UserAgentFilter.java:81)
   at org.mortbay.servlet.GzipFilter.doFilter(GzipFilter.java:132)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:265)
   at org.acegisecurity.intercept.web.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:107)
   at org.acegisecurity.intercept.web.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:72)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
   at org.acegisecurity.ui.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:166)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
   at org.acegisecurity.providers.anonymous.AnonymousProcessingFilter.doFilter(AnonymousProcessingFilter.java:125)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
   at org.acegisecurity.ui.rememberme.RememberMeProcessingFilter.doFilter(RememberMeProcessingFilter.java:142)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
   at org.acegisecurity.wrapper.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:81)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
   at net.sourceforge.subsonic.security.RESTRequestParameterProcessingFilter.doFilter(RESTRequestParameterProcessingFilter.java:95)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
   at org.acegisecurity.ui.basicauth.BasicProcessingFilter.doFilter(BasicProcessingFilter.java:173)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
   at org.acegisecurity.ui.logout.LogoutFilter.doFilter(LogoutFilter.java:110)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
   at org.acegisecurity.context.HttpSessionContextIntegrationFilter.doFilter(HttpSessionContextIntegrationFilter.java:249)
   at org.acegisecurity.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:275)
   at org.acegisecurity.util.FilterChainProxy.doFilter(FilterChainProxy.java:149)
   at org.acegisecurity.util.FilterToBeanProxy.doFilter(FilterToBeanProxy.java:98)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at net.sourceforge.subsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:25)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at net.sourceforge.subsonic.filter.RESTFilter.doFilter(RESTFilter.java:42)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:36)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:41)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
   at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
   at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
   at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:712)
   at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
   at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:139)
   at org.mortbay.jetty.Server.handle(Server.java:313)
   at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:506)
   at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:830)
   at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:514)
   at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
   at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:381)
   at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:396)
   at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:442)
Caused by: java.io.IOException: An existing connection was forcibly closed by the remote host
   at sun.nio.ch.SocketDispatcher.write0(Native Method)
   at sun.nio.ch.SocketDispatcher.write(Unknown Source)
   at sun.nio.ch.IOUtil.writeFromNativeBuffer(Unknown Source)
   at sun.nio.ch.IOUtil.write(Unknown Source)
   at sun.nio.ch.SocketChannelImpl.write(Unknown Source)
   at org.mortbay.io.nio.ChannelEndPoint.flush(ChannelEndPoint.java:166)
   at org.mortbay.io.nio.SelectChannelEndPoint.flush(SelectChannelEndPoint.java:208)
   at org.mortbay.jetty.HttpGenerator.flush(HttpGenerator.java:693)
   ... 73 more

[2018-03-17 19:30:39,852] ERROR JAXBWriter - Failed to marshal JAXB
java.lang.IllegalStateException: STREAM
   at org.mortbay.jetty.Response.getWriter(Response.java:583)
   at net.sourceforge.subsonic.controller.JAXBWriter.writeResponse(JAXBWriter.java:122)
   at net.sourceforge.subsonic.controller.JAXBWriter.writeErrorResponse(JAXBWriter.java:136)
   at net.sourceforge.subsonic.filter.RESTFilter.handleException(RESTFilter.java:58)
   at net.sourceforge.subsonic.filter.RESTFilter.doFilter(RESTFilter.java:44)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:36)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:41)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
   at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
   at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
   at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:712)
   at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
   at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:139)
   at org.mortbay.jetty.Server.handle(Server.java:313)
   at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:506)
   at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:830)
   at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:514)
   at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
   at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:381)
   at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:396)
   at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:442)

[2018-03-17 19:30:39,852] ERROR RESTFilter - Failed to write error response.
java.lang.IllegalStateException: STREAM
   at org.mortbay.jetty.Response.getWriter(Response.java:583)
   at net.sourceforge.subsonic.controller.JAXBWriter.writeResponse(JAXBWriter.java:122)
   at net.sourceforge.subsonic.controller.JAXBWriter.writeErrorResponse(JAXBWriter.java:136)
   at net.sourceforge.subsonic.filter.RESTFilter.handleException(RESTFilter.java:58)
   at net.sourceforge.subsonic.filter.RESTFilter.doFilter(RESTFilter.java:44)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at net.sourceforge.subsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:36)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at net.sourceforge.subsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:41)
   at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1084)
   at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360)
   at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)
   at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
   at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:712)
   at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405)
   at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:139)
   at org.mortbay.jetty.Server.handle(Server.java:313)
   at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:506)
   at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:830)
   at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:514)
   at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211)
   at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:381)
   at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:396)
   at org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:442)

[audiosteve]

Is anyone able to work on this problem looking at it? I see the problem described by a number of people in many places (including Reddit) yet no one seems to care. What is up with this community? I'll help debug this in any way I can but we need someone who really understand the code to lead this effort. Anyone?

[toolman]

@ audiosteve
Since it is unclear what causes the problem who do you want to solve it?
It could be a change in Chrome that's interfering with the streaming, it could be a change in Subsonic , allthough I doubt that.
A lot of people don't have any issues streaming their media in Chrome or else the reports would be much greater in number.
Also the fact that streaming goes well in other browsers seem to indicate that it's a Chrome problem. Perhaps Google has changed some things.
And to top things of: the logfiles all show End of File-errors, which would mean that the files that stall the streaming have some inconsistencies or faults.
Since I have no issues with streaming I can't help any further, but someone who has these issues might take a look at https://sourceforge.net/projects/mp3check/.
The idea is to check a number of files and to create a playlist with only files that have no errors.
Obviously if the streaming stalls again despite the fact that all the files are without errors you can look further by trying another browser or even a previous version of Chrome.
( http://www.oldversion.com/windows/google-chrome/ )

[audiosteve]

It's happening for a reason. And it started recently. So we should be able to find the cause.
It also does the same thing when I Chromecast from DSUB on my phone.

So it seems to be something common to 'chrome products'

But, I do not have the problem when playing back from DSUB on my phone.
How does DSUb differ in the way it handles files from Chrome?

I know my way around windows and linux command I/F's and would love to be the 'eyes and ears' for someone who has a stake in this code set so I can help them quickly find and correct the root cause.
Who would that be? I have made similar posts and offers in Sourceforge but so far no one has responded. This is very frustrating and disappointing.

[toolman]

You say "So it seems to be something common to 'chrome products'"
I guess that Google would be the party to address in this situation.
But somehow I doubt that Google will be very worried or concerned when informed about this issue.

[acroyear]

Actually they are surprisingly responsive (I filed bugs on the html5 audio tag back in the M42-M52 days when my apps were having problems, especially on Android), got one fixed and one ended up fixed anyways when they went and rewrote the audio handler for Android Chrome/Webkit. What is important, however, is having a replicable test case you can share with them.

But since getting consistent failures is one of the hard things about this bug, that's easier said than done.

[audiosteve]

If we could identify the failure mechanism then I am sure google would address it.... if it was an issue in their code. But we;re not to that point yet.
BWe must first ID the issue. That is up to the Subsonic community.

BTW, It happens to me consistently .... every time. Proper operation is the rare exception to the rule.
Mine is not an intermittent problem. All I need is someeon to work with who knows the subsonic code that can tell me what to do to help them ID the root cause.
Anybody ???

[audiosteve]

I have a control case! Most of my MP3 albums will not advance to the next song but I found one album that seems to consistently work properly.

What should I look for to try to debug this???

[ladeebishop]

This may not help, but I've solved my issue. I reset Chrome to the default settings. I also removed some of the extensions I had installed. Once logged into Subsonic, I enabled Flash. The only extensions I have running now are Pocket, Roboform, and UBlock Origins, with UBlock disabled for Subsonic.

[audiosteve]

That is good news. Do you recall what extensions you previuously had installed?

[ladeebishop]

That is good news. Do you recall what extensions you previously had installed?

Sure, they're still installed but turned off.
Cisco Web Extension
Google Docs
Google Docs Offline
Google Keep
mydlink services plugin
Google Sheets
Google Slides

[audiosteve]

Thank you!

[ladeebishop]

Maybe something else to try: Use Incognito mode with Flash enabled for the site.