PDA

View Full Version : Radio station works on SoftSqueeze but not SqueezeBox2



rds
2006-08-22, 20:14
Does anyone have any idea why this radio station works perfectly fine on SoftSqueeze but will not work at all on a SqueezeBox 2? The SB2 just says "CONNECTING..." indefinitely.

http://68.142.72.68:80/bdcast_infinity_wbz-am?MSWMExt=.asf

I'm running SlimServer Version: 6.3.0 - 8148 - Windows XP - EN - cp1252.

Here's what I see in the log. The following entries consist of two attempts -- the first made from the web interface to the SB2, and the second from the SB2 remote.

2006-08-22 23:01:58.0983 Backtrace:

frame 0: Slim::Player::Squeezebox2::directHeaders (/PerlApp/Slim/Networking/Slimproto.pm line 439)
frame 1: Slim::Networking::Slimproto::process_slimproto_fra me (/PerlApp/Slim/Networking/Slimproto.pm line 256)
frame 2: Slim::Networking::Slimproto::client_readable (/PerlApp/Slim/Networking/Select.pm line 141)
frame 3: Slim::Networking::Select::select (slimserver.pl line 701)
frame 4: main::idle (slimserver.pl line 38)
frame 5: PerlSvc::Startup (perlsvc.pl line 1482)
frame 6: PerlSvc::_startup (slimserver.pl line 0)
frame 7: (eval) (slimserver.pl line 0)

2006-08-22 23:01:58.0989 processing 8 headers
2006-08-22 23:01:58.0991 header: Content-Type: application/x-mms-framed
2006-08-22 23:01:58.0991 header: Server: Cougar/9.01.01.3814
2006-08-22 23:01:58.0992 header: Date: Wed, 23 Aug 2006 03:04:03 GMT
2006-08-22 23:01:58.0993 header: Pragma: no-cache, client-id=2748731188, xResetStrm=1, features="broadcast", AccelBW=0, AccelDuration=0, Speed=1.000
2006-08-22 23:01:58.0993 header: Cache-Control: no-cache
2006-08-22 23:01:58.0994 header: Last-Modified: Wed, 23 Aug 2006 03:04:03 GMT
2006-08-22 23:01:58.0995 header: Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile
2006-08-22 23:01:58.0996 header: Connection: keep-alive
2006-08-22 23:01:58.0996 it looks like a WMA file
2006-08-22 23:01:58.2930 got a stream type:: wma bitrate: title:
2006-08-22 23:01:58.2949 Beginning direct stream!
2006-08-22 23:02:02.5481 metadata (len: 132)
2006-08-22 23:02:02.5510 metadata (len: 204)
2006-08-22 23:02:02.5635 Setting bitrate to 32 from WMA metadata
2006-08-22 23:02:02.5643 metadata (len: 139)
2006-08-22 23:02:02.5657 metadata (len: 188)
2006-08-22 23:02:02.5671 metadata (len: 126)
2006-08-22 23:02:02.5685 metadata (len: 222)
2006-08-22 23:02:02.5699 metadata (len: 4052)
2006-08-22 23:04:39.6272 Opening connection to http://68.142.72.68:80/bdcast_infinity_wbz-am?MSWMExt=.asf: [68.142.72.68 on port 80 with path /bdcast_infinity_wbz-am?MSWMExt=.asf with timeout 5]
2006-08-22 23:04:39.6668 Request: GET /bdcast_infinity_wbz-am?MSWMExt=.asf HTTP/1.0
Accept: */*
Cache-Control: no-cache
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.3.0/8148
Icy-MetaData: 1
Connection: close
Host: 68.142.72.68

2006-08-22 23:04:39.7107 Response: HTTP/1.0 200 OK
2006-08-22 23:04:39.9202 header: Content-Type: video/x-ms-asf
2006-08-22 23:04:40.1171 header: Cache-Control: max-age=0, no-cache
2006-08-22 23:04:40.1178 header: Server: Cougar/9.01.01.3814
2006-08-22 23:04:40.1182 header: Content-Length: 140
2006-08-22 23:04:40.1188 header: Date: Wed, 23 Aug 2006 03:06:44 GMT
2006-08-22 23:04:40.1194 header: Pragma: no-cache, xResetStrm=1
2006-08-22 23:04:40.1209 header: Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile
2006-08-22 23:04:40.1212 header:
2006-08-22 23:04:40.1213 Recieved final blank line...
2006-08-22 23:04:40.1214 opened stream!
2006-08-22 23:04:40.1370 Slim::Player::Protocols::HTTP - in DESTROY
2006-08-22 23:04:40.1373 Slim::Player::Protocols::HTTP About to close socket to: [http://68.142.72.68:80/bdcast_infinity_wbz-am?MSWMExt=.asf]
2006-08-22 23:04:40.3726 processing headers for direct streaming
2006-08-22 23:04:40.3731 $VAR1 = 'HTTP/1.0 200 OK
Date: Wed, 23 Aug 2006 03:04:40 GMT
Server: SlimServer (6.3.0 - 8148)
Connection: close
Content-Type: audio/mpeg
Set-Cookie: SlimServer-player=00%3A04%3A20%3A05%3Ab7%3A78; path=/; expires=Thu, 23-Aug-2007 03:04:40 GMT

';
2006-08-22 23:04:40.3734 Backtrace:

frame 0: Slim::Player::Squeezebox2::directHeaders (/PerlApp/Slim/Networking/Slimproto.pm line 439)
frame 1: Slim::Networking::Slimproto::process_slimproto_fra me (/PerlApp/Slim/Networking/Slimproto.pm line 256)
frame 2: Slim::Networking::Slimproto::client_readable (/PerlApp/Slim/Networking/Select.pm line 141)
frame 3: Slim::Networking::Select::select (slimserver.pl line 701)
frame 4: main::idle (slimserver.pl line 38)
frame 5: PerlSvc::Startup (perlsvc.pl line 1482)
frame 6: PerlSvc::_startup (slimserver.pl line 0)
frame 7: (eval) (slimserver.pl line 0)

andyg
2006-08-22, 20:25
Please try the latest 6.5 nightly (tonight's contains major WMA changes). This station works fine for me.

rds
2006-08-22, 20:28
Thanks Andy. I'll give it a try.

Also, I noticed that SlimServer does not seem to be able to parse ASX playlists that are referenced from within other playlists (ASX, M3U or PLS). This is a major limitation since the underlying stream (i.e. the hidden mms stream) often changes or gets assigned dynamically, so it would be nice to be able to point to the ASX wrapper that usually stays constant. Is this something that has also been fixed in 6.5?

Thanks.

andyg
2006-08-22, 20:30
That's also fixed in 6.5.

rds
2006-08-22, 20:31
Wonderful! Thanks.

rds
2006-08-23, 21:17
Well, I installed the Aug 22 nightly (6.5b1 - 9093 - Windows XP - EN - cp1252) and the URL still doesn't work with the SB2 (although it still works with SoftSqueeze on 6.5, just as it did on 6.3.0). Anyway, when attempting to play the link on the SB2, SlimServer goes into an endless loop, continuously repeating the following in the log:

processing headers for direct streaming:
HTTP/1.0 200 OK
Content-Type: video/x-ms-asf
Cache-Control: max-age=0, no-cache
Server: Cougar/9.01.01.3814
Content-Length: 140
Date: Thu, 24 Aug 2006 04:10:10 GMT
Pragma: no-cache, xResetStrm=1
Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile

processing 7 headers
header-ds: Content-Type: video/x-ms-asf
header-ds: Cache-Control: max-age=0, no-cache
header-ds: Server: Cougar/9.01.01.3814
header-ds: Content-Length: 140
header-ds: Date: Thu, 24 Aug 2006 04:10:10 GMT
header-ds: Pragma: no-cache, xResetStrm=1
header-ds: Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile
got a stream type:: asx bitrate: 0 title:
Direct stream is list, get body to explode
got some body from the player, length 140
saving away that body message until we get an empty body
directBodyFrame: Saving to temp file: C:\WINDOWS\TEMP\ukRhVDNMaJ
got some body from the player, length 0
empty body means we should parse what we have for http://68.142.72.68/bdcast_infinity_wbz-am?MSWMExt=.asf
parseDirectBody: Parsing body for bitrate
This player supports direct streaming for http://68.142.72.68/bdcast_infinity_wbz-am?MSWMExt=.asf as http://68.142.72.68/bdcast_infinity_wbz-am?MSWMExt=.asf, let's do it.
setting up direct stream (1150175300:80) autostart: 2.
request string: GET /bdcast_infinity_wbz-am?MSWMExt=.asf HTTP/1.0
Accept: */*
Cache-Control: no-cache
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SlimServer/6.5b1/9093
Icy-MetaData: 1
Connection: close
Host: 68.142.72.68

andyg
2006-08-24, 02:49
You are not using the latest version, where this station works fine for me. Please get the latest nightly from Aug 24.

rds
2006-08-24, 20:13
Well, I did download the latest nightly that was available at that time. I guess you were referring to the Aug 23 nightly which hadn't been uploaded yet.

Anyway, I grabbed the Aug 24 nightly and this seems to fix the problem. Other radio stations also seem to start much quicker now. Of course, 6.5 seems to have some major stability problems, as the service keeps shutting down intermittently for no apparent reason during use. Fortunately, Windows services can be set to automatically restart after a failure, which is how I've configured the SlimServer service.

rds
2006-08-24, 20:32
After playing with it a bit more, it seems to be this URL that is causing the server to shutdown. Also, it turns out that the SlimServer Windows service doesn't actually stop (SlimServer justs stopped responding and devices can no longer contact it), so the auto-recovery options for services don't help here.

Andy, you said that it worked fine for you on this version. Try putting the URL in a PLS playlist and launch the station from the SqueezeBox itself. That's when the problem seems to occur. Also, it may work just fine the first few times, but seems to eventually cause the server to crash.

What debug options (aside from directstream) should I enable to figure out what's going on here?

andyg
2006-08-24, 20:35
Run from the command line (in your C:\Program Files\SlimServer\server dir):

slim.exe --d_scan --d_directstream --d_parse --logfile slim.log

Please file a bug and include this log file of the failing station.

rds
2006-08-25, 05:23
Andy, it turns out that it was the URL immediately after the one discussed above that is causing SlimServer to shutdown. I notice that sometimes when it can't connect on one playlist entry, it moves to the next while still displaying the previous entry. That appears to be what happened here. Anyway, it's the KCRW World News link that is causing SlimServer to terminate with:

"Can't locate object method "seek" via package "File::Temp" at /PerlApp/Slim/Player/Squeezebox2.pm line 494.

Should this be logged as a bug, or is this something that is already being worked on? Here's the full log:

Host: www.kcrw.com

2006-08-25 08:07:10.3868 processing headers for direct streaming:
HTTP/1.0 200 OK

Date: Thu, 24 Aug 2006 11:36:22 GMT

Server: Apache/1.3.31 (Unix) PHP/4.3.8

Last-Modified: Wed, 16 Aug 2006 21:34:32 GMT

ETag: "223722-194-44e38f68"

Accept-Ranges: bytes

Content-Length: 404

Content-Type: audio/x-scpls

X-Cache: HIT from www.kcrw.com

Connection: close

2006-08-25 08:07:10.3872 processing 9 headers
2006-08-25 08:07:10.3873 header-ds: Date: Thu, 24 Aug 2006 11:36:22 GMT
2006-08-25 08:07:10.3873 header-ds: Server: Apache/1.3.31 (Unix) PHP/4.3.8
2006-08-25 08:07:10.3874 header-ds: Last-Modified: Wed, 16 Aug 2006 21:34:32 GMT
2006-08-25 08:07:10.3874 header-ds: ETag: "223722-194-44e38f68"
2006-08-25 08:07:10.3876 header-ds: Accept-Ranges: bytes
2006-08-25 08:07:10.3877 header-ds: Content-Length: 404
2006-08-25 08:07:10.3877 header-ds: Content-Type: audio/x-scpls
2006-08-25 08:07:10.3878 header-ds: X-Cache: HIT from www.kcrw.com
2006-08-25 08:07:10.3878 header-ds: Connection: close
2006-08-25 08:07:10.4110 got a stream type:: pls bitrate: title:
2006-08-25 08:07:10.4115 Direct stream is list, get body to explode
2006-08-25 08:07:10.4164 got some body from the player, length 404
2006-08-25 08:07:10.4166 saving away that body message until we get an empty body
2006-08-25 08:07:10.4191 directBodyFrame: Saving to temp file: C:\DOCUME~1\OWNER~1.SAD\LOCALS~1\Temp\X20aTPnlx4
2006-08-25 08:07:10.4198 got some body from the player, length 0
2006-08-25 08:07:10.4199 empty body means we should parse what we have for http://www.kcrw.com/pls/kcrwworldnews.pls
2006-08-25 08:07:10.4201 Can't locate object method "seek" via package "File::Temp" at /PerlApp/Slim/Player/Squeezebox2.pm line 494.

andyg
2006-08-25, 06:17
I changed that seek command last night, so the latest nightly should not have that problem.

rds
2006-08-25, 20:55
That did the trick. Thanks.