Songs randomly stop playing right before the end

[Jargs]

<-------------------------Subsonic Help Request---------------------->
Problem Description: Probably about 10% of the time, the song I am listening to will just stop a few seconds from the end. The play/pause button changes to the spinning loading graphic, and nothing else happens, even if I let it sit for a while. The only way to continue is to either play another song from my library, or click on next or previous track.
Troubleshooting Steps: Not sure where to start, so I haven't tried anything yet.
Playback Application and version: Web interface version 6.0 (build 830afc)
Subsonic Version: 6.0 (build 830afc) – April 30, 2016
Server Version: jetty-6.1.x, java 1.8.0_31, Windows 8 (60.0 MB / 145.0 MB)
Hardware Platform: Windows 10
Java Memory Limit: 145MB
Problem Filename: Occurs with many different files, but the most recent one is "12 - Credits.mp3"
Output from ffmpeg -i: I'm away from the server computer at the moment, so I'll try to add this later.
Last ten log file lines:

Code: Select all

[5/19/17 9:13:41 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\7 - Wallpaper.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:14:44 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\8 - The Giant.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:16:09 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\9 - Suburbs.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:18:36 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\10 - Canabalt.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:21:16 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\11 - Last Stand.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:23:33 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:25:14 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:25:29 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:25:42 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:25:56 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:26:11 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:26:25 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:26:40 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:26:53 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:27:07 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:27:22 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:27:37 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:27:51 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:28:06 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:28:21 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:28:35 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:28:50 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:29:04 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:29:19 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -
[5/19/17 9:29:22 AM PDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i D:\Music\Shared\Soundtracks\Deadlight\13 - Alternate Credits.mp3 -map 0:0 -b:a 96k -v 0 -f mp3 -

<-------------------------Subsonic Help Request---------------------->

[Jargs]

Here's the output from ffmpeg -i

Code: Select all

ffmpeg version N-78758-g5156578 Copyright (c) 2000-2016 the FFmpeg developers
  built with gcc 5.3.0 (GCC)
  configuration: --enable-gpl --enable-version3 --disable-w32threads --enable-avisynth --enable-bzlib --enable-fontconfig --enable-frei0r --enable-gnutls --enable-iconv --enable-libass --enable-libbluray --enable-libbs2b --enable-libcaca --enable-libdcadec --enable-libfreetype --enable-libgme --enable-libgsm --enable-libilbc --enable-libmodplug --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopus --enable-librtmp --enable-libschroedinger --enable-libsoxr --enable-libspeex --enable-libtheora --enable-libtwolame --enable-libvidstab --enable-libvo-amrwbenc --enable-libvorbis --enable-libvpx --enable-libwavpack --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxavs --enable-libxvid --enable-libzimg --enable-lzma --enable-decklink --enable-zlib
  libavutil      55. 19.100 / 55. 19.100
  libavcodec     57. 27.100 / 57. 27.100
  libavformat    57. 26.100 / 57. 26.100
  libavdevice    57.  0.101 / 57.  0.101
  libavfilter     6. 37.100 /  6. 37.100
  libswscale      4.  0.100 /  4.  0.100
  libswresample   2.  0.101 /  2.  0.101
  libpostproc    54.  0.100 / 54.  0.100
[mp3 @ 05a33ac0] Skipping 0 bytes of junk at 4216.
[mp3 @ 05a33ac0] Estimating duration from bitrate, this may be inaccurate
Input #0, mp3, from 'D:\Music\Shared\Soundtracks\Deadlight\12 - Credits.mp3':
  Metadata:
    album           : Deadlight Soundtrack
    title           : Credits
    genre           : Soundtrack
    track           : 12
    date            : 2012
  Duration: 00:05:09.40, start: 0.000000, bitrate: 128 kb/s
    Stream #0:0: Audio: mp3, 44100 Hz, stereo, s16p, 128 kb/s
At least one output file must be specified

[jflorance]

I am having the same issue but using 6.1 beta2 (jetty-6.1.x, java 1.8.0_131, HSQLDB, Windows 7 (69.9 MB / 147.1 MB)).

I noticed many songs stop with 1 second left to play. I just get the spinning wheel. I have a few albums that will always advance. I'm assuming there is some embedded metadata that doesn't get read correctly.

Typical non-advancing songs:

Code: Select all

[5/22/17 11:51:49 AM CDT]   DEBUG   StreamController   GET /stream?player=7&id=12507&auth=1548971481&scrobble=false, Range: bytes=0-
[5/22/17 11:51:49 AM CDT]   DEBUG   StreamController   Content-Length: 9760000
[5/22/17 11:51:49 AM CDT]   DEBUG   StreamController   Content-Range: 0-9759999/9760000
[5/22/17 11:51:49 AM CDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i M:\Library\Music\Earth, Wind & Fire\The Best of Earth, Wind & Fire, Vol. 1\01-Got to Get You into My Life-The Best of Earth, Wind & Fire, Vol. 1-Earth, Wind & Fire.flac -map 0:0 -b:a 320k -v 0 -f mp3 -
[5/22/17 11:53:35 AM CDT]   DEBUG   StreamController   GET /stream?player=7&id=12507&auth=1548971481&scrobble=false, Range: bytes=7700480-9759999
[5/22/17 11:53:35 AM CDT]   DEBUG   StreamController   Content-Length: 2059520
[5/22/17 11:53:35 AM CDT]   DEBUG   StreamController   Content-Range: 7700480-9759999/9760000
[5/22/17 11:53:35 AM CDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i M:\Library\Music\Earth, Wind & Fire\The Best of Earth, Wind & Fire, Vol. 1\01-Got to Get You into My Life-The Best of Earth, Wind & Fire, Vol. 1-Earth, Wind & Fire.flac -map 0:0 -b:a 320k -v 0 -f mp3 -


This songs advance just fine:

Code: Select all

[5/22/17 11:59:52 AM CDT]   DEBUG   StreamController   GET /stream?player=7&id=11718&auth=210684954&scrobble=false, Range: bytes=0-
[5/22/17 11:59:52 AM CDT]   DEBUG   StreamController   Content-Length: 7880000
[5/22/17 11:59:52 AM CDT]   DEBUG   StreamController   Content-Range: 0-7879999/7880000
[5/22/17 11:59:52 AM CDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i M:\Library\Music\Various Artists\Oxford American Southern Music CD #9\01-"If I Were a Carpenter"-Oxford American Southern Music CD #9-Eldridge Holmes.flac -map 0:0 -b:a 320k -v 0 -f mp3 -
[5/22/17 12:00:59 PM CDT]   DEBUG   StreamController   GET /stream?player=7&id=11734&auth=59790529&scrobble=false, Range: bytes=0-
[5/22/17 12:00:59 PM CDT]   DEBUG   StreamController   Content-Length: 7720000
[5/22/17 12:00:59 PM CDT]   DEBUG   StreamController   Content-Range: 0-7719999/7720000
[5/22/17 12:00:59 PM CDT]   INFO   TranscodeInputStream   Starting transcoder: c:\subsonic\transcode\ffmpeg -i M:\Library\Music\Various Artists\Oxford American Southern Music CD #9\02-"Look Out Dog, Slow Down Train"-Oxford American Southern Music CD #9-Hackensaw Boys.flac -map 0:0 -b:a 320k -v 0 -f mp3 -


Any guidance will be appreciated.

[spazmochad]

I'm also seeing this issue. Subsonic 6.1.1 (build fc853b) Windows 2016. Seems to have started after upgrading to 6

[spazmochad]

I have updated c:\subsonic\ffmpeg.exe to version 3.3.2. If that doesn't cure it then I'll report back.

[spazmochad]

Still doing it

[Exrace]

Have you verified the flac file directly using a player?
I assume the file is OK but worth a check.
Wonder if your reverse proxy or something else is truncating the stream to your client.

[Wolke]

I have the same problem. Also I don't think it has to do with FLAC files being corrupt, since it worked fine before.

[jeremer]

I also have this problem and it is super annoying. It only happens with the Web player.

Someone mentioned that the reverse proxy might be an issue. Question: wouldn't that affect all players, not just the web player?

[Deviant0ne]

I'm having the same issue, though I thought it was related to using an external database, i.e. PostgreSQL, MySQL, etc. I was able to get the songs to transcode and play when using the built-in Subsonic database...

[jeremer]

Here is the error from the Chrome console.

Code: Select all

Failed to load resource: the server responded with a status of 502 ()     VM98 playQueue.view:1078 stream?player=1&id=29536&auth=208937107&scrobble=false

It seems that my proxy server (IIS) is getting a bad response or dropped connection to Subsonic. Both are on the same server, so I am not sure what could cause a dropped connection. I restarted IIS and it is still happening.

[Deviant0ne]

I am getting a different error in the Chrome console:

Code: Select all

GET http://10.0.1.15:4040/stream?player=1&id=2139&auth=123945414&scrobble=false net::ERR_CONTENT_LENGTH_MISMATCH

That error repeats itself over and over until the track is skipped; that error also seems to coincide with the repeated transcoding messages in the Subsonic log. Interestingly enough, this issue does not occur unless the track is being transcoded (using either FFMpeg or FLAC + LAME); if I play the FLAC files that I'm experiencing this issue with directly in Subsonic, i.e. removing the FLAC transcoding instructions, the tracks advance/play without issue.

EDIT: even more interesting that the same issue doesn't appear to be happening in the same playlist location using Firefox; Firefox is able to advance to the next song in my setup, only to stop on song 2 instead of 1...

[Wolke]

For me it also happens in the web player on Chrome. But it also happens for me when using SubFire (which uses the API as far as I understood it) on the InternetExplorer.

Another thing I noticed in the web player on Chrome is, that when I hit pause and resume after a few minutes the song won't continue and I have to jump to the next or previous song to continue the playback, this wasn't the case before. (when I resume shortly after I press pause it does continue though)

[Deviant0ne]

Maybe we can get a developer to weigh-in on this issue? It sounds like the issue is with Subsonic itself since multiple people on different platforms are having the same experience.

[zcab911]

I had the same issue setting it on a RPI3 (hoping to conserve power - and set up a DAC driven player). Abandoned the effort (though now I am getting error 503 on both my power systems (Linux and Windoze), so I am planning on giving this another shot).