REST API: Broken Stream

[nupplaPhil]

Hi,

Since some weeks, some strange REST API errors makes my madsonic server nearly unuseable :

Except of playing directly at the madsonic webURL with the Java Player, I have a lot of issues during playing music.

I tried different Clients:
WP8 - SilverClient
WP8 - Subsonic Client
Win7 - SubClient
Win7 - Periscope

I tried with 3 different Madsonic Versions, everytime purging the complete settings:
20130307_subsonic-MashupMod-4.8.3360.beta3
20130504_madsonic-5.0.3470.beta1XE
20130508_madsonic-5.0.3480.beta2XE

My Server Config is:
jetty-6.1.x, java 1.7.0_07, Linux (101.7 MB / 145.0 MB)
Debian Squeeze

My Music Folder is a samba share, automounted in the fstab with options:
192.168.X.X:/media/ /media cifs soft,intr,rsize=8192,wsize=8192,user=***,domain=***,pass=**** 0 0

And I think, the REST API is, I don't know how this can happen, broken!
Sometimes, I can hear music without a problem, but sometimes, some errors occur. Here's my log:

From here on, everything works as expected:

[...]
[5/16/13 6:35:38 PM CEST] DEBUG TranscodeInputStream Starting transcoder: [/var/subsonic/transcode/ffmpeg] [-i] [/media/Music/Artists/Ill Niño/2008 - Enigma (album)/02 - Pieces of the Sun.mp3] [-ab] [192k] [-v] [0] [-f] [mp3] [-]
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) ffmpeg version N-31780-gd5d74cf, Copyright (c) 2000-2011 the FFmpeg developers
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) built on Aug 9 2011 14:18:27 with gcc 4.5.2
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) configuration: --disable-ffplay --disable-ffprobe --disable-ffserver --enable-gpl --enable-nonfree --enable-postproc --enable-pthreads --enable-libfaac --enable-libmp3lame --enable-libvpx --enable-libtheora --enable-libvorbis --enable-libx264 --enable-libxvid --enable-zlib --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libvpx --enable-version3 --enable-bzlib --enable-static --disable-shared --extra-libs=-static --extra-cflags=--static
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) libavutil 51. 11. 1 / 51. 11. 1
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) libavcodec 53. 9. 1 / 53. 9. 1
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) libavformat 53. 6. 0 / 53. 6. 0
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) libavdevice 53. 2. 0 / 53. 2. 0
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) libavfilter 2. 28. 1 / 2. 28. 1
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) libswscale 2. 0. 0 / 2. 0. 0
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) libpostproc 51. 2. 0 / 51. 2. 0
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) [mp3 @ 0xa061a20] max_analyze_duration 5000000 reached at 5015510
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Input #0, mp3, from '/media/Music/Artists/Ill Niño/2008 - Enigma (album)/02 - Pieces of the Sun.mp3':
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Metadata:
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) title : Pieces of the Sun
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) artist : Ill Niño
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) track : 2/13
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) album : Enigma
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) disc : 1/1
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TMED : CD
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) SCRIPT : Latn
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Acoustid Id : dd9db0fe-13ac-4402-bbfc-0a5a45b2be10
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Type: album
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Artist Id: 91e2e08f-abd7-44d6-9a84-b8a4afa8a265
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Artist Id: 91e2e08f-abd7-44d6-9a84-b8a4afa8a265
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) BARCODE : 891146100125
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Status: official
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Id: 98e0a41e-232b-4ec0-a3da-4e678b88acab
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TSO2 : Ill Niño
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) album_artist : Ill Niño
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) CATALOGNUMBER : CSR-2003
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TORY : 2008
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) artist-sort : Ill Niño
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Release Country: US
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) ASIN : B0011V7P6O
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) publisher : Cement Shoes Records
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) date : 2008-03-11
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Duration: 00:04:17.88, start: 0.000000, bitrate: 241 kb/s
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Stream #0.0: Audio: mp3, 44100 Hz, stereo, s16, 192 kb/s
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Output #0, mp3, to 'pipe:':
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Metadata:
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TIT2 : Pieces of the Sun
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TPE1 : Ill Niño
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TRCK : 2/13
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TALB : Enigma
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TPOS : 1/1
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TMED : CD
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) SCRIPT : Latn
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Acoustid Id : dd9db0fe-13ac-4402-bbfc-0a5a45b2be10
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Type: album
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Artist Id: 91e2e08f-abd7-44d6-9a84-b8a4afa8a265
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Artist Id: 91e2e08f-abd7-44d6-9a84-b8a4afa8a265
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) BARCODE : 891146100125
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Status: official
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Id: 98e0a41e-232b-4ec0-a3da-4e678b88acab
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TSO2 : Ill Niño
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TPE2 : Ill Niño
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) CATALOGNUMBER : CSR-2003
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TORY : 2008
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TSOP : Ill Niño
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) MusicBrainz Album Release Country: US
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) ASIN : B0011V7P6O
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TPUB : Cement Shoes Records
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TDRL : 2008-03-11
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) TSSE : Lavf53.6.0
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Stream #0.0: Audio: libmp3lame, 44100 Hz, stereo, s16, 192 kb/s
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Stream mapping:
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Stream #0.0 -> #0.0
[5/16/13 6:35:38 PM CEST] DEBUG InputStreamReaderThread (/var/subsonic/transcode/ffmpeg) Press [q] to stop, [?] for help

but suddenly .. Here we are:

[5/16/13 6:35:44 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:35:45 PM CEST] INFO PlayQueueInputStream philipp listening to "2003 - Hellraiser_ Best of the Epic Years (compilation - GB)/01 - The One to Sing the Blues.mp3"
[5/16/13 6:35:46 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:37:30 PM CEST] INFO CoverArtController Created thumbnail cache /var/subsonic/thumbs/800
[5/16/13 6:38:26 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - BBC Radio 1 Live Lounge/01 - Closer to the Edge (Xfm).mp3"
[5/16/13 6:38:49 PM CEST] INFO CoverArtController Created thumbnail cache /var/subsonic/thumbs/240
[5/16/13 6:38:56 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:39:01 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - BBC Radio 1 Live Lounge/04 - This is War (Xfm).mp3"
[5/16/13 6:39:28 PM CEST] INFO PlayQueueInputStream philipp listening to "2012 - Epidemia (album)/01 - The Depression.mp3"
[5/16/13 6:39:58 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:41:42 PM CEST] INFO PlayQueueInputStream philipp listening to "2012 - Epidemia (album)/02 - Only the Unloved.mp3"
[5/16/13 6:41:52 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:43:22 PM CEST] INFO PlayQueueInputStream philipp listening to "2012 - Epidemia (album)/02 - Only the Unloved.mp3"
[5/16/13 6:43:32 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:44:09 PM CEST] INFO PlayQueueInputStream philipp listening to "2012 - Epidemia (album)/01 - The Depression.mp3"
[5/16/13 6:44:13 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:44:13 PM CEST] INFO PlayQueueInputStream philipp listening to "2006 - The Best of Ill Niño (compilation)/10 - What You Deserve.mp3"
[5/16/13 6:44:15 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:44:16 PM CEST] INFO PlayQueueInputStream philipp listening to "2006 - The Best of Ill Niño (compilation)/09 - Te Amo...I Hate You.mp3"
[5/16/13 6:45:07 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:45:12 PM CEST] INFO PlayQueueInputStream philipp listening to "2006 - The Best of Ill Niño (compilation)/10 - What You Deserve.mp3"
[5/16/13 6:45:37 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 6:45:42 PM CEST] INFO PlayQueueInputStream philipp listening to "2006 - The Best of Ill Niño (compilation)/11 - This Is War.mp3"
[5/16/13 6:50:40 PM CEST] INFO PlayQueueInputStream philipp listening to "2006 - The Best of Ill Niño (compilation)/12 - Turns to Gray.mp3"
[5/16/13 7:07:07 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 8:41:33 PM CEST] INFO PlayQueueInputStream philipp listening to "Capricorn (A Brand New Name) (single)/02 - End of the Beginning.mp3"
[5/16/13 8:42:40 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 8:42:42 PM CEST] INFO StreamController Got range: Range[139264,8770967]
[5/16/13 8:42:42 PM CEST] INFO PlayQueueInputStream philipp listening to "2012 - Epidemia (album)/02 - Only the Unloved.mp3"
[5/16/13 8:43:12 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/16/13 8:43:14 PM CEST] INFO PlayQueueInputStream philipp listening to "2012 - Epidemia (album)/03 - La Epidemia.mp3"
[5/16/13 8:43:44 PM CEST] WARN RESTFilter Error in REST API: broken Stream

Look at the returned Range at the end of the log! There are horrible ranges, which cannot be true.

And now there's no way out .. Except exiting all of the clients and reopen it... But 50% it doesn't help too so I've to wait..
The problem is that there are no real patterns which constelation leads to this error.

I don't have any idea, what to do now.. At least, I can completly format my server and reinstall it, but I hope that I don't need to, because there are other services on it too

Can somebody help me? Are there any ideas how to deal with that?

Thanks in advance!

Phil

[nupplaPhil]

Ok I got a patter.. At wp8, the stream works correctly for about 1min +- 3seconds and than the stream stuck and at the same time, the log displays the "stream broken" error.. Is there any deeper debug flags at subsonic?

[nupplaPhil]

Things getting stranger and stranger
- Installed fresh debian
- update / upgrade
- installed java7 from oracle
jetty-6.1.x, java 1.7.0_21, Linux (92.6 MB / 145.0 MB)

- installed subsonic
5.0.3490.beta2

- mounted my lib
192.168.X.X:/media /media/server cifs soft,intr,rsize=8192,wsize=8192,user=**,domain=**,pass=** 0 0
--> "mount -a" succeed

- changed subsonic admin password
- setting media folder
- activating LDAP auth
- waiting for media folder refreshing

- WP8 Subsonic music stream
-- No Music
-- No Folder Covers
-- "Downloading 0,0 MB" each song
Log:

[5/23/13 12:00:11 PM CEST] INFO CoverArtController Created thumbnail cache /var/subsonic/thumbs/358
[5/23/13 12:00:11 PM CEST] INFO StreamController Got range: Range[0,9223372036854775807]
[5/23/13 12:00:11 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:00:41 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:00:49 PM CEST] INFO StreamController Got range: Range[994,9223372036854775807]
[5/23/13 12:00:49 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:00:59 PM CEST] INFO PlayerDao Created player 4.
[5/23/13 12:01:19 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:01:26 PM CEST] INFO StreamController Got range: Range[1988,9223372036854775807]
[5/23/13 12:01:26 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:01:56 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:02:04 PM CEST] INFO StreamController Got range: Range[2982,9223372036854775807]
[5/23/13 12:02:04 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:02:34 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:02:43 PM CEST] INFO StreamController Got range: Range[3976,9223372036854775807]
[5/23/13 12:02:43 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:03:13 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:03:21 PM CEST] INFO StreamController Got range: Range[4970,9223372036854775807]
[5/23/13 12:03:21 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:03:51 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:03:59 PM CEST] INFO StreamController Got range: Range[5964,9223372036854775807]
[5/23/13 12:03:59 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:04:29 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:04:38 PM CEST] INFO StreamController Got range: Range[6958,9223372036854775807]
[5/23/13 12:04:38 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:05:08 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:05:16 PM CEST] INFO StreamController Got range: Range[7952,9223372036854775807]
[5/23/13 12:05:16 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:05:46 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:05:54 PM CEST] INFO StreamController Got range: Range[8946,9223372036854775807]
[5/23/13 12:05:54 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"
[5/23/13 12:06:24 PM CEST] WARN RESTFilter Error in REST API: broken Stream
[5/23/13 12:06:33 PM CEST] INFO StreamController Got range: Range[9940,9223372036854775807]
[5/23/13 12:06:33 PM CEST] INFO PlayQueueInputStream philipp listening to "2010 - Closer To The Edge/01 - Closer to the Edge.mp3"

- WP8 Silversonic
-- Testing Connection:

XmlSerializeError

Nothing more..

Strange .. Realy strange ..

Further Steps, I'll try:
- Testing the connection at intranet without port-forwarding
- mounting the network share with other options
But I think that this steps cannot be the reason for the errors.

Something realy wrong happend in my system ..

[nupplaPhil]

Finally .. I made it ..

Thanks to Windows Phone 8 for being buggy at all

Because of some mistakes, I had to fully wipe the phone. After the a full reconfiguration of my phone, the subsonic client now plays all of the music without any troubles.

The strange errors occur, but they don't have any effects on the WP8 Subsonic Client.

*uff*