Bugzilla – Bug 160
Playlist problems with tracks on remote server
Last modified: 2008-09-15 14:37:04 UTC
I reported a problem playing files on a remote server a couple of weeks ago - the problem still exists on the latest build (SlimServer_v2004-01-23.dmg) The problem also exists when I tried a W2K Slimserver and 2 friends also reproduced the problem on their systems (Squeezebox + MacOS and Squeezebox + Linux) I have included more debug information and the playlist file below. 2004-01-23 18:35:48 00:04:20:05:04:d6: Switching to mode stop 2004-01-23 18:35:48 New play mode: stop 2004-01-23 18:35:48 Stopping and clearing out old chunks for client 00:04:20:05:04:d6 2004-01-23 18:35:48 00:04:20:05:04:d6: Current playmode: stop 2004-01-23 18:35:48 00:04:20:05:04:d6: Switching to mode stop 2004-01-23 18:35:48 Already in playmode stop : ignoring mode change 2004-01-23 18:35:48 00:04:20:05:04:d6: Current playmode: stop 2004-01-23 18:35:48 22004-01-23 18:35:48 00:04:20:05:04:d6: Switching to mode play 2004-01-23 18:35:48 openSong on: http://remote-server-url/Billy%20Bragg/Back%20To%20Basics/The%20Milkman%20of %20Human%20Kindness.mp3 2004-01-23 18:35:48 Opening connection to http://remote-server-url/Billy%20Bragg/Back%20To%20Basics/The%20Milkman%20of %20Human%20Kindness.mp3: [remote-server-url on port 80 with path /path/Billy%20Bragg/Back%20To%20Basics/The%20Milkman%20of%20Human%20Kindness .mp3] 2004-01-23 18:35:48 Request: GET /path/Billy%20Bragg/Back%20To%20Basics/The%20Milkman%20of%20Human%20Kindness .mp3 HTTP/1.0 Host: remote-server-url:80 User-Agent: iTunes/3.0 (darwin; SlimServer 5.0.1) Accept: */* Cache-Control: no-cache Connection: close Icy-MetaData:1 2004-01-23 18:35:48 Response: HTTP/1.1 200 OK 2004-01-23 18:35:48 header: Date: Fri, 23 Jan 2004 18:35:48 GMT 2004-01-23 18:35:48 header: Server: Apache/1.3.27 (Darwin) tomcat/1.0 mod_ssl/2.8.10 OpenSSL/0.9.6b PHP/4.3.4 2004-01-23 18:35:48 header: Cache-Control: max-age=3600 2004-01-23 18:35:48 header: Expires: Fri, 23 Jan 2004 19:35:48 GMT 2004-01-23 18:35:48 header: Last-Modified: Sun, 10 Feb 2002 02:46:32 GMT 2004-01-23 18:35:48 header: ETag: "1109b-3e199c-3c65df08" 2004-01-23 18:35:48 header: Accept-Ranges: bytes 2004-01-23 18:35:48 header: Content-Length: 4069788 2004-01-23 18:35:48 header: Connection: close 2004-01-23 18:35:48 header: Content-Type: audio/mpeg 2004-01-23 18:35:48 header: 2004-01-23 18:35:48 Recieved final blank line... 2004-01-23 18:35:48 opened stream! 2004-01-23 18:35:48 New play mode: play 2004-01-23 18:35:48 00:04:20:05:04:d6: Current playmode: play 2004-01-23 18:35:49 read a chunk of 28583 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 read a chunk of 10136 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 read a chunk of 11584 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 read a chunk of 13032 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 read a chunk of 10136 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 read a chunk of 11584 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 read a chunk of 1448 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 read a chunk of 10136 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 read a chunk of 8688 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 read a chunk of 1448 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 would have blocked, will try again later 2004-01-23 18:35:49 would have blocked, will try again later 2004-01-23 18:35:49 read a chunk of 1448 length 2004-01-23 18:35:49 metadata now: 0 2004-01-23 18:35:49 would have blocked, will try again later 2004-01-23 18:35:50 would have blocked, will try again later 2004-01-23 18:35:50 would have blocked, will try again later 2004-01-23 18:35:50 would have blocked, will try again later 2004-01-23 18:35:50 would have blocked, will try again later 2004-01-23 18:35:50 would have blocked, will try again later 2004-01-23 18:35:50 would have blocked, will try again later 2004-01-23 18:35:50 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 read a chunk of 4344 length 2004-01-23 18:35:51 metadata now: 0 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:51 read a chunk of 2896 length 2004-01-23 18:35:51 metadata now: 0 2004-01-23 18:35:51 read a chunk of 1448 length 2004-01-23 18:35:51 metadata now: 0 2004-01-23 18:35:51 would have blocked, will try again later 2004-01-23 18:35:52 read a chunk of 2896 length 2004-01-23 18:35:52 metadata now: 0 2004-01-23 18:35:52 would have blocked, will try again later 2004-01-23 18:35:52 read a chunk of 4344 length 2004-01-23 18:35:52 metadata now: 0 2004-01-23 18:35:52 would have blocked, will try again later 2004-01-23 18:35:52 read a chunk of 10742 length 2004-01-23 18:38:19 read a chunk of 32768 length 2004-01-23 18:38:19 metadata now: 0 2004-01-23 18:38:21 read a chunk of 32768 length 2004-01-23 18:38:21 metadata now: 0 2004-01-23 18:38:22 read a chunk of 32768 length 2004-01-23 18:38:22 metadata now: 0 2004-01-23 18:38:23 read a chunk of 32768 length 2004-01-23 18:38:23 metadata now: 0 2004-01-23 18:38:25 read a chunk of 32768 length 2004-01-23 18:38:25 metadata now: 0 2004-01-23 18:38:26 read a chunk of 32768 length 2004-01-23 18:38:26 metadata now: 0 2004-01-23 18:38:28 read a chunk of 28528 length 2004-01-23 18:38:28 metadata now: 0 2004-01-23 18:38:29 read a chunk of 9367 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 2004-01-23 18:38:29 read a chunk of 0 length 2004-01-23 18:38:29 metadata now: 0 .m3u file extract http://remote-server-url/Billy%20Bragg/Back%20To%20Basics/The%20Milkman%20of %20Human%20Kindness.mp3 http://remote-server-url/Billy%20Bragg/Back%20To%20Basics/To%20Have%20and%20 to%20Have%20Not.mp3 http://remote-server-url/Billy%20Bragg/Back%20To%20Basics/Richard.mp3 > On Jan 11, 2004, at 11:44 AM, Michael Robinson wrote: > >> I�m having a problem with playlists (m3u files) where the tracks are >> on a remote server. >> >> If I select the playlist on my Squeezebox menu, the first track plays >> without any problem. �However, when the track is finished, the >> Squeezebox doesn�t move on to the next track in the playlist. �There >> is silence and the display shows the elapsed time for the track >> incrementing for at least 10 minutes after the end of the track (I >> haven�t had the patience to wait longer than this) >> >> If I press the forward key on the remote control, the Squeezebox moves >> on to the next track in the playlist without any problem. �Again, the >> second track does not move on to the next track after it finishes. >> >> �iTunes can play the tracks at the remote URL without any problem and >> moves on to the next track after the song has finished. >> >> I don�t have any problems with playlists where the tracks are on my >> local machine. >> >> Any ideas? �I�m using Squeezebox + Slimserver 5.0.1 + Mac with OS >> 10.3.2 >> >> I looked at some debug traces and saw the message �Underrun while this >> mode: play� occur for all tracks about 5 or so seconds after the track >> SHOULD have finished. >> >> 2004-01-11 18:37:13.6825 00:04:20:05:04:d6: Current playmode: stop >> 2004-01-11 18:37:13 22004-01-11 18:37:13.6841 00:04:20:05:04:d6: >> Switching to mode play >> 2004-01-11 18:37:13.6858 openSong >> on:http://remote-server-url/Billy%20Bragg/Back%20To%20Basics/ >> To%20Have%20and%20to%20Have%20Not.mp3 >> 2004-01-11 18:37:13.9801 00:04:20:05:04:d6 New play mode: play >> 2004-01-11 18:37:13.9909 00:04:20:05:04:d6: Current playmode: play >> 2004-01-11 18:37:14.0038 00:04:20:05:04:d6: Switching to mode play >> 2004-01-11 18:37:14.0049 �Already in playmode play : ignoring mode >> change >> 2004-01-11 18:37:14.0053 00:04:20:05:04:d6: Current playmode: play >> 2004-01-11 18:37:14.0691 would have blocked, will try again later >> 2004-01-11 18:37:14.1230 would have blocked, will try again later >> 2004-01-11 18:37:14.1772 would have blocked, will try again later >> *** Debug logs deleted *** >> 2004-01-11 18:37:23.8883 would have blocked, will try again later >> 2004-01-11 18:37:23.9437 would have blocked, will try again later >> 2004-01-11 18:37:23.9978 would have blocked, will try again later >> *** track is 2:49 mins. Log occurs after 2:54 mins *** >> 2004-01-11 18:39:51.6050 00:04:20:05:04:d6: Underrun while this mode: >> play >> *** Forward pressed on remote *** >> 2004-01-11 18:40:20.1765 00:04:20:05:04:d6: Switching to mode stop >> 2004-01-11 18:40:20.1772 00:04:20:05:04:d6 New play mode: stop >> 2004-01-11 18:40:20.1779 Stopping and clearing out old chunks for >> client 00:04:20:05:04:d6 >> 2004-01-11 18:40:20.1846 00:04:20:05:04:d6: Current playmode: stop >> 2004-01-11 18:40:20 22004-01-11 18:40:20.1865 00:04:20:05:04:d6: >> Switching to mode play >> 2004-01-11 18:40:20.1879 openSong >> on:http://remote-server-url/Billy%20Bragg/Back%20To%20Basics/ >> Richard.mp3 >> 2004-01-11 18:40:20.4657 00:04:20:05:04:d6 New play mode: play >> 2004-01-11 18:40:20.4761 00:04:20:05:04:d6: Current playmode: play >> 2004-01-11 18:40:20.4901 00:04:20:05:04:d6: Switching to mode play >> 2004-01-11 18:40:20.4906 �Already in playmode play : ignoring mode >> change >> 2004-01-11 18:40:20.4911 00:04:20:05:04:d6: Current playmode: play >> 2004-01-11 18:40:20.5526 would have blocked, will try again later >> 2004-01-11 18:40:20.6076 would have blocked, will try again later >> 2004-01-11 18:40:20.6619 would have blocked, will try again later >> *** Debug logs deleted *** >> 2004-01-11 18:40:28.9175 would have blocked, will try again later >> 2004-01-11 18:40:28.9721 would have blocked, will try again later >> *** track is 2:33 mins. Log occurs after 2:37 mins *** >> 2004-01-11 18:43:14.6751 00:04:20:05:04:d6: Underrun while this mode: >> play >> >> >> >>
I believe that this is fixed in the latest nightly build. Please verify.
There are 536 bugs in the database with targets of '---' that were fixed prior to new year 2006. I am setting them to targets of 6.2.1 to keep them from showing up in my queries.