First Last Prev Next    This bug is not in your last search results.
Bug 4985 - Unable to stream some stations on the internet through SlimServer.
: Unable to stream some stations on the internet through SlimServer.
Status: CLOSED WORKSFORME
Product: Logitech Media Server
Classification: Unclassified
Component: Plugins
: 6.5.2
: PC Windows XP
: P2 normal (vote)
: ---
Assigned To: Chris Owens
:
Depends on:
Blocks:
  Show dependency tree / graph
 
Reported: 2007-05-10 16:54 UTC by Wallace Lai
Modified: 2008-12-18 11:12 UTC (History)
2 users (show)

See Also:
Category: ---

Attachments
Add an attachment (proposed patch, testcase, etc.)

Note You need to log in before you can comment on or make changes to this bug.
Description Wallace Lai 2007-05-10 16:54:57 UTC 
System Info:  Gateway, P4, 2.0 GHz, 1.25 GB of RAM, XP SP2 ENU,
SlimServer Version: 6.5.2 - 11950 - Windows XP - EN - cp1252
Server IP address: 10.0.3.69
Perl Version: 5.8.8 MSWin32-x86-multi-thread
MySQL Version: 5.0.22-community-nt 

Steps to Reproduce:
1.  On a clean ghost of XP SP2 ENU, install SlimServer 6.5.2.
2.  Start SlimServer.  Start its web page.
3.  Go to Slim Picks under Internet Radio.
4.  Go to By City.  Go to Hong Kong.  A list of available radioes should appear.
5.  Play the "Apple FM | oldies".  It works.
6.  Play "RTHK Radio 5 | talk".
7.  Notice the SqueezeBox says "CONNECTING ... After awhile, it says "CONNECTION TIMED OUT".
8.  Click the "RTHK Radio 5 | talk" link.  On the next page, click the URL.
9.  Notice Windows Media Player 9 pops up, and the station streams fine.
10.  There are a few more stations that behave like this.  They stream with Media Player, but not SlimServer.

Here is the log:

2007-05-10 17:16:02.0097 Formats::XML: async request: http://www.slimdevices.com/picks/radio.opml
2007-05-10 17:16:02.1314 Formats::XML: got http://www.slimdevices.com/picks/radio.opml
2007-05-10 17:16:02.1317 Formats::XML: content type is text/xml
2007-05-10 17:16:02.2371 Formats::XML: caching parsed XML for 300 seconds
2007-05-10 17:16:02.2621 XMLBrowser: playing/adding http://www.rthk.org.hk/live5.asx
2007-05-10 17:16:02.2718 Adding song index 0 to song queue
2007-05-10 17:16:02.2720 Clearing out song queue first
2007-05-10 17:16:02.2722 Song queue is now 0
2007-05-10 17:16:02.2729 00:04:20:06:19:df: Switching to mode stop from stop
2007-05-10 17:16:02.2731  Already in playmode stop : ignoring mode change
2007-05-10 17:16:02.3087 00:04:20:06:19:df: Switching to mode stop from stop
2007-05-10 17:16:02.3090  Already in playmode stop : ignoring mode change
2007-05-10 17:16:02.3093 Adding song index 0 to song queue
2007-05-10 17:16:02.3095 Clearing out song queue first
2007-05-10 17:16:02.3097 Song queue is now 0
2007-05-10 17:16:03.6430 00:04:20:06:19:df: Switching to mode stop from stop
2007-05-10 17:16:03.6432  Already in playmode stop : ignoring mode change
2007-05-10 17:16:03.6434 Adding song index 0 to song queue
2007-05-10 17:16:03.6436 Clearing out song queue first
2007-05-10 17:16:03.6438 Song queue is now 0
2007-05-10 17:16:03.6444 00:04:20:06:19:df: Switching to mode play from stop
2007-05-10 17:16:03.6463 openSong on: mms://202.177.192.111/radio5
2007-05-10 17:16:03.6469 undermax = 1, type = wma, squeezebox2 = 00:04:20:06:19:df
2007-05-10 17:16:03.6472 checking formats for: wma-wma-squeezebox2-00:04:20:06:19:df
2007-05-10 17:16:03.6474 checking formats for: wma-wma-*-00:04:20:06:19:df
2007-05-10 17:16:03.6476 checking formats for: wma-wma-squeezebox2-*
2007-05-10 17:16:03.6477 Checking to see if wma-wma-squeezebox2-* is enabled
2007-05-10 17:16:03.6479    enabled
2007-05-10 17:16:03.6480   Found command: -
2007-05-10 17:16:03.6483 Matched Format: wma Type: wma Command: - 
2007-05-10 17:16:03.6852 00:04:20:06:19:df New play mode: play
2007-05-10 17:16:03.6929 undermax = 1, type = wma, squeezebox2 = 00:04:20:06:19:df
2007-05-10 17:16:03.6932 checking formats for: wma-wma-squeezebox2-00:04:20:06:19:df
2007-05-10 17:16:03.6934 checking formats for: wma-wma-*-00:04:20:06:19:df
2007-05-10 17:16:03.6936 checking formats for: wma-wma-squeezebox2-*
2007-05-10 17:16:03.6938 Checking to see if wma-wma-squeezebox2-* is enabled
2007-05-10 17:16:03.6939    enabled
2007-05-10 17:16:03.6941   Found command: -
2007-05-10 17:16:03.6943 Matched Format: wma Type: wma Command: - 
2007-05-10 17:16:03.7007 00:04:20:06:19:df: Current playmode: play
2007-05-10 17:16:03.8227 Buffering... 0 / 20480
2007-05-10 17:16:03.9635 Buffering... 0 / 20480
2007-05-10 17:16:04.1097 Buffering... 0 / 20480
2007-05-10 17:16:04.2502 Buffering... 0 / 20480
2007-05-10 17:16:04.3923 Buffering... 0 / 20480
2007-05-10 17:16:04.5286 Buffering... 0 / 20480
2007-05-10 17:16:04.6572 Buffering... 0 / 20480
2007-05-10 17:16:04.7960 Buffering... 0 / 20480
2007-05-10 17:16:04.9365 Buffering... 0 / 20480
2007-05-10 17:16:05.0772 Buffering... 0 / 20480
2007-05-10 17:16:05.2193 Buffering... 0 / 20480
2007-05-10 17:16:05.3615 Buffering... 0 / 20480
2007-05-10 17:16:05.5003 Buffering... 0 / 20480
2007-05-10 17:16:05.6408 Buffering... 0 / 20480
2007-05-10 17:16:05.7813 Buffering... 0 / 20480
2007-05-10 17:16:05.9218 Buffering... 0 / 20480
2007-05-10 17:16:06.0624 Buffering... 0 / 20480
2007-05-10 17:16:06.2031 Buffering... 0 / 20480
2007-05-10 17:16:06.3440 Buffering... 0 / 20480
2007-05-10 17:16:06.4845 Buffering... 0 / 20480
2007-05-10 17:16:06.6257 Buffering... 0 / 20480
2007-05-10 17:16:06.7659 Buffering... 0 / 20480
2007-05-10 17:16:06.9065 Buffering... 0 / 20480
2007-05-10 17:16:07.0470 Buffering... 0 / 20480
2007-05-10 17:16:07.1878 Buffering... 0 / 20480
2007-05-10 17:16:07.3302 Buffering... 0 / 20480
2007-05-10 17:16:07.4691 Buffering... 0 / 20480
2007-05-10 17:16:07.6096 Buffering... 0 / 20480
2007-05-10 17:16:07.7503 Buffering... 0 / 20480
2007-05-10 17:16:07.8909 Buffering... 0 / 20480
2007-05-10 17:16:08.0315 Buffering... 0 / 20480
2007-05-10 17:16:08.1722 Buffering... 0 / 20480
2007-05-10 17:16:08.3128 Buffering... 0 / 20480
2007-05-10 17:16:08.4534 Buffering... 0 / 20480
2007-05-10 17:16:08.5940 Buffering... 0 / 20480
2007-05-10 17:16:08.7347 Buffering... 0 / 20480
2007-05-10 17:16:08.8753 Buffering... 0 / 20480
2007-05-10 17:16:09.0159 Buffering... 0 / 20480
2007-05-10 17:16:09.1566 Buffering... 0 / 20480
2007-05-10 17:16:09.2991 Buffering... 0 / 20480
2007-05-10 17:16:09.4377 Buffering... 0 / 20480
2007-05-10 17:16:09.5784 Buffering... 0 / 20480
2007-05-10 17:16:09.7132 Buffering... 0 / 20480
2007-05-10 17:16:09.8538 Buffering... 0 / 20480
2007-05-10 17:16:10.9943 Buffering... 0 / 20480
2007-05-10 17:16:10.1257 Buffering... 0 / 20480
2007-05-10 17:16:10.2660 Buffering... 0 / 20480
2007-05-10 17:16:10.4065 Buffering... 0 / 20480
2007-05-10 17:16:10.5471 Buffering... 0 / 20480
2007-05-10 17:16:10.6876 Buffering... 0 / 20480
2007-05-10 17:16:10.8282 Buffering... 0 / 20480
2007-05-10 17:16:10.9688 Buffering... 0 / 20480
2007-05-10 17:16:11.1094 Buffering... 0 / 20480
2007-05-10 17:16:11.2505 Buffering... 0 / 20480
2007-05-10 17:16:11.3908 Buffering... 0 / 20480
2007-05-10 17:16:11.5330 Buffering... 0 / 20480
2007-05-10 17:16:11.6720 Buffering... 0 / 20480
2007-05-10 17:16:11.8126 Buffering... 0 / 20480
2007-05-10 17:16:11.9532 Buffering... 0 / 20480
2007-05-10 17:16:12.0939 Buffering... 0 / 20480
2007-05-10 17:16:12.2344 Buffering... 0 / 20480
2007-05-10 17:16:12.3750 Buffering... 0 / 20480
2007-05-10 17:16:12.5156 Buffering... 0 / 20480
2007-05-10 17:16:12.6562 Buffering... 0 / 20480
2007-05-10 17:16:12.7969 Buffering... 0 / 20480
2007-05-10 17:16:12.9375 Buffering... 0 / 20480
2007-05-10 17:16:13.0781 Buffering... 0 / 20480
2007-05-10 17:16:13.2188 Buffering... 0 / 20480
2007-05-10 17:16:13.3596 Buffering... 0 / 20480
2007-05-10 17:16:13.5001 Buffering... 0 / 20480
2007-05-10 17:16:13.6406 Buffering... 0 / 20480
2007-05-10 17:16:13.7069 Error opening current track, so mark it as already played
2007-05-10 17:16:13.7074 Backtrace:

   frame 0: Slim::Player::Source::errorOpening (/PerlApp/Slim/Player/Squeezebox2.pm line 579)
   frame 1: Slim::Player::Squeezebox2::failedDirectStream (/PerlApp/Slim/Networking/Slimproto.pm line 527)
   frame 2: Slim::Networking::Slimproto::_disco_handler (/PerlApp/Slim/Networking/Slimproto.pm line 387)
   frame 3: Slim::Networking::Slimproto::client_readable (/PerlApp/Slim/Networking/Select.pm line 238)
   frame 4: (eval) (/PerlApp/Slim/Networking/Select.pm line 238)
   frame 5: Slim::Networking::Select::select (slimserver.pl line 493)
   frame 6: main::idle (slimserver.pl line 446)
   frame 7: main::main (slimserver.pl line 58)
   frame 8: PerlSvc::Interactive (perlsvc.pl line 1486)
   frame 9: PerlSvc::_interactive (slimserver.pl line 0)
   frame 10: (eval) (slimserver.pl line 0)

2007-05-10 17:16:13.7108 00:04:20:06:19:df: Switching to mode stop from playout-play
2007-05-10 17:16:13.7115 00:04:20:06:19:df New play mode: stop
2007-05-10 17:16:13.7118 Stopping and clearing out old chunks for client 00:04:20:06:19:df
2007-05-10 17:16:13.7132 Resetting song queue
2007-05-10 17:16:13.7135 Song queue is now 0
2007-05-10 17:16:13.7145 00:04:20:06:19:df: Current playmode: stop
Comment 1 KDF 2007-05-10 17:03:45 UTC 
I'm seeing an mms: redirect in there.  This might be falling under the scope of bug 3383
Comment 2 Andy Grundman 2007-05-11 13:23:23 UTC 
RTHK Radio 5 works for me, probably was a temporary issue with the station.
Comment 3 Andy Grundman 2007-05-11 13:32:38 UTC 
Tried a few more times and found that the stream is pretty flaky, sometimes it will stall out while buffering, other times it plays fine.
Comment 4 Chris Owens 2007-05-15 16:31:46 UTC 
The trick is that apparently the stream always works okay on a software player.  Dan Evans noted he has one or more customers with similar issues.  We'll see if we can find a solid repro case here somewhere.
Comment 5 Dan Evans 2007-05-16 11:02:15 UTC 
I dug through my old notes and found this:  

 * TMID 15363 - Internet radio disconnecting ... customer trying to listen to RTHK 1 and 5 and it occasionally crashed the player.  I was able to reproduce this issue.

 * Based on the above I opened bug 4609

I hope this helps.
Comment 6 Michael Herger 2007-12-21 04:54:15 UTC 
Is this still an issue in 7.0? Please update or close this bug.
Comment 7 KDF 2008-01-25 11:10:18 UTC 
ping! any more recent reports of this one?
Comment 8 Dan Evans 2008-01-25 11:17:24 UTC 
Might want to reassign this back to QA to test/reproduce.
Comment 9 Wallace Lai 2008-01-25 12:23:10 UTC 
Verified the fix with 7.0 nightly built on 2008-01-23.  Bug as written is no longer seen.  "RTHK Radio 5 | talk" is streaming just fine.
Can someone mark it fixed, please?  I would like to close this as "FIXED", not "WORKSFORME".  This station had never  streamed for me before.  Someone must have fixed it.
Comment 10 Wallace Lai 2008-01-25 16:44:32 UTC 
Marking this as "WORKSFORME".
Comment 11 Wallace Lai 2008-01-25 16:48:13 UTC 
Verified again with this station, and regressed with other stations.  they all work.  Marking as "VERIFIED".
Comment 12 Wallace Lai 2008-01-25 16:49:00 UTC 
Both SqueezeBox 3 and Rays are able to stream all these stations.
Closing.
First Last Prev Next    This bug is not in your last search results.