Bug 160 - Playlist problems with tracks on remote server
: Playlist problems with tracks on remote server
Status: RESOLVED FIXED
Product: Logitech Media Server
Classification: Unclassified
Component: Streaming To SlimServer
: unspecified
: All All
: P2 normal (vote)
: ---
Assigned To: Blackketter Dean
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2004-01-23 11:51 UTC by Michael Robinson
Modified: 2008-09-15 14:37 UTC (History)
0 users

See Also:
Category: ---


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Michael Robinson 2004-01-23 11:51:59 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
>> 
>> 
>> 
>>
Comment 1 Blackketter Dean 2004-02-04 12:19:24 UTC
I believe that this is fixed in the latest nightly build.  Please verify.
Comment 2 Chris Owens 2006-06-16 14:40:11 UTC
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.