Bugzilla – Bug 2326
some wma streams (from eonstreams.com) look like they're playing but you get no audio
Last modified: 2008-12-18 11:38:58 UTC
2005-10-20 14:08:27.3158 openSong on: mms://66.250.84.31:80/ccri_ca_sanjose_kcnl_fm.asf 2005-10-20 14:08:27.4526 00:04:20:81:22:33 New play mode: play 2005-10-20 14:08:27.4597 This player supports direct streaming for mms://66.250.84.31:80/ccri_ca_sanjose_kcnl_fm.asf as mms://66.250.84.31:80/ccri_ca_sanjose_kcnl_fm.asf, let's do it. 2005-10-20 14:08:27.4630 setting up direct stream (1123701791:80) autostart: 3. 2005-10-20 14:08:27.4632 request string: GET /ccri_ca_sanjose_kcnl_fm.asf HTTP/1.0 Accept: */* User-Agent: NSPlayer/4.1.0.3856 Host: 66.250.84.31 Pragma: xClientGUID={99d81b25-1d82-e999-9f35-42c8987384f6} Pragma: no-cache,rate=1.0000000,stream-time=0,stream-offset=0:0,request-context=2,max-duration=0 Pragma: xPlayStrm=1 Pragma: stream-switch-count=1 Pragma: stream-switch-entry=ffff:1:0 2005-10-20 14:08:27.4668 00:04:20:81:22:33: Current playmode: play 2005-10-20 14:08:27.8637 processing headers for direct streaming 2005-10-20 14:08:27.9413 $VAR1 = 'HTTP/1.0 200 OK Content-Type: application/x-mms-framed Server: Cougar/9.01.01.3814 Date: Thu, 20 Oct 2005 21:23:22 GMT Pragma: no-cache, client-id=3113792062, xResetStrm=1, features="broadcast", AccelBW=0, AccelDuration=0, Speed=1.000 Cache-Control: no-cache Last-Modified: Thu, 20 Oct 2005 21:23:22 GMT Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile Connection: keep-alive '; 2005-10-20 14:08:27.9468 Backtrace: frame 0: Slim::Player::Squeezebox2::directHeaders (/PerlApp/Slim/Networking/Slimproto.pm line 405) frame 1: Slim::Networking::Slimproto::process_slimproto_frame (/PerlApp/Slim/Networking/Slimproto.pm line 236) frame 2: Slim::Networking::Slimproto::client_readable (/PerlApp/Slim/Networking/Select.pm line 111) frame 3: Slim::Networking::Select::select (slimserver.pl line 634) frame 4: main::idle (slimserver.pl line 38) frame 5: PerlSvc::Startup (perlsvc.pl line 1481) frame 6: PerlSvc::_startup (slimserver.pl line 0) frame 7: (eval) (slimserver.pl line 0) 2005-10-20 14:08:27.9477 processing 8 headers 2005-10-20 14:08:27.9480 header: Content-Type: application/x-mms-framed 2005-10-20 14:08:27.9486 header: Server: Cougar/9.01.01.3814 2005-10-20 14:08:27.9494 header: Date: Thu, 20 Oct 2005 21:23:22 GMT 2005-10-20 14:08:27.9496 header: Pragma: no-cache, client-id=3113792062, xResetStrm=1, features="broadcast", AccelBW=0, AccelDuration=0, Speed=1.000 2005-10-20 14:08:27.9499 header: Cache-Control: no-cache 2005-10-20 14:08:27.9502 header: Last-Modified: Thu, 20 Oct 2005 21:23:22 GMT 2005-10-20 14:08:27.9504 header: Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile 2005-10-20 14:08:27.9507 header: Connection: keep-alive 2005-10-20 14:08:27.9509 it looks like a WMA file2005-10-20 14:08:28.0862 got a stream type:: wma bitrate: title: 2005-10-20 14:08:28.0906 Beginning direct stream! 2005-10-20 14:08:32.9767 Setting maxBitRate for 10.0.3.80 to: 0 2005-10-20 14:08:32.9778 Setting maxBitRate for 10.0.3.80 to: 0 (nothing happens here, no audio, no visualizer)
oops, recategorizing as a firmware bug due to direct streaming. here is a newer log, as well... still observable in 6.2 branch r5328, but with d_directstream there's a little more information about the stream: Windows Media io 9.1" 32 kbps, 44 kHz, mono 1-pass CBR 2005-11-18 14:39:46.2258 00:04:20:81:22:33: Switching to mode stop from stop 2005-11-18 14:39:46.2271 Already in playmode stop : ignoring mode change 2005-11-18 14:39:46.2285 Adding song index 0 to song queue 2005-11-18 14:39:46.2294 Clearing out song queue first 2005-11-18 14:39:46.2301 Song queue is now 0 2005-11-18 14:39:46.4811 00:04:20:81:22:33: Switching to mode stop from stop 2005-11-18 14:39:46.4824 Already in playmode stop : ignoring mode change 2005-11-18 14:39:46.4833 Adding song index 0 to song queue 2005-11-18 14:39:46.4840 Clearing out song queue first 2005-11-18 14:39:46.4847 Song queue is now 0 2005-11-18 14:39:46.4858 00:04:20:81:22:33: Switching to mode play from stop 2005-11-18 14:39:46.5499 openSong on: mms://66.250.84.31:80/ccri_ca_sanjose_kcnl_fm.asf 2005-11-18 14:39:46.9410 00:04:20:81:22:33 New play mode: play 2005-11-18 14:39:46.9592 This player supports direct streaming for mms://66.250.84.31:80/ccri_ca_sanjose_kcnl_fm.asf as mms://66.250.84.31:80/ccri_ca_sanjose_kcnl_fm.asf, let's do it. 2005-11-18 14:39:46.9681 setting up direct stream (1123701791:80) autostart: 3. 2005-11-18 14:39:46.9693 request string: GET /ccri_ca_sanjose_kcnl_fm.asf HTTP/1.0 Accept: */* User-Agent: NSPlayer/4.1.0.3856 Host: 66.250.84.31 Pragma: xClientGUID={5ff40681-e5ad-ef17-455d-74639713a177} Pragma: no-cache,rate=1.0000000,stream-time=0,stream-offset=0:0,request-context=1,max-duration=0 Connection: Close 2005-11-18 14:39:46.9720 00:04:20:81:22:33: Current playmode: play 2005-11-18 14:39:47.8410 Setting maxBitRate for scratched to: 0 2005-11-18 14:39:47.8425 Setting maxBitRate for scratched to: 0 2005-11-18 14:39:47.8689 processing headers for direct streaming 2005-11-18 14:39:47.8778 $VAR1 = 'HTTP/1.0 200 OK Content-Type: application/vnd.ms.wms-hdr.asfv1 Server: Cougar/9.01.01.3814 Content-Length: 5375 Date: Fri, 18 Nov 2005 22:55:23 GMT Pragma: no-cache, client-id=1336917310, xResetStrm=1, features="broadcast" Cache-Control: no-cache, x-wms-stream-type="broadcast" Last-Modified: Sat, 30 Dec 1899 00:00:00 GMT Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile '; 2005-11-18 14:39:47.9004 Backtrace: frame 0: Slim::Player::Squeezebox2::directHeaders (/Users/kpearsall/BRANCH_6_2_x/Slim/Networking/Slimproto.pm line 413) frame 1: Slim::Networking::Slimproto::process_slimproto_frame (/Users/kpearsall/BRANCH_6_2_x/Slim/Networking/Slimproto.pm line 244) frame 2: Slim::Networking::Slimproto::client_readable (/Users/kpearsall/BRANCH_6_2_x/Slim/Networking/Select.pm line 111) frame 3: Slim::Networking::Select::select (./slimserver.pl line 634) frame 4: main::idle (./slimserver.pl line 570) frame 5: main::main (./slimserver.pl line 1226) 2005-11-18 14:39:47.9027 processing 8 headers 2005-11-18 14:39:47.9037 header: Content-Type: application/vnd.ms.wms-hdr.asfv1 2005-11-18 14:39:47.9047 header: Server: Cougar/9.01.01.3814 2005-11-18 14:39:47.9054 header: Content-Length: 5375 2005-11-18 14:39:47.9061 header: Date: Fri, 18 Nov 2005 22:55:23 GMT 2005-11-18 14:39:47.9068 header: Pragma: no-cache, client-id=1336917310, xResetStrm=1, features="broadcast" 2005-11-18 14:39:47.9074 header: Cache-Control: no-cache, x-wms-stream-type="broadcast" 2005-11-18 14:39:47.9081 header: Last-Modified: Sat, 30 Dec 1899 00:00:00 GMT 2005-11-18 14:39:47.9087 header: Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile 2005-11-18 14:39:47.9094 it looks like a WMA file2005-11-18 14:39:48.2690 got a stream type:: wma bitrate: title: 2005-11-18 14:39:48.2703 Direct stream is list, get body to explode 2005-11-18 14:39:48.2766 got some body from the player, length 976: $H�
oops, a large portion of that got munged. trying from console isntead of log.txt... 2005-11-18 14:39:47.9094 it looks like a WMA file2005-11-18 14:39:48.2690 got a stream type:: wma bitrate: title: 2005-11-18 14:39:48.2703 Direct stream is list, get body to explode 2005-11-18 14:39:48.2766 got some body from the player, length 976: $H? ?0&?u?f????b?l??u?{?F???`?ɢ?,@Q0?? ?ܫ?G????? Seh?O???qN?&?<???P?0&?????????? ??^??_.????? Se?ӫ?????? Se??FC|???K?)9>?A\?' en-us˥?r?2C???iR[ZX(N? (N? ?˥?r?2C?????ܷ?????? Ser@?i?M[????_\D+P?�?a??????b?laD??? ?=??˥?r?2C???iR[ZX?? ?? ]??&?E?G?_eR????ů[wH?g??D?L?? IsVBR4DeviceConformanceTemplateL2 IsVBR4DeviceConformanceTemplateL2t??? E????˖?? 2005-11-18 14:39:48.2786 saving away that body message until we get an empty body 2005-11-18 14:39:48.6166 got some body from the player, length 1460: 2005-11-18 14:39:48.7467 saving away that body message until we get an empty body 2005-11-18 14:39:48.7482 got some body from the player, length 1459: 2005-11-18 14:39:48.7857 saving away that body message until we get an empty body 2005-11-18 14:39:48.7877 got some body from the player, length 1460: ?I??uGpF?n55uf?.*???5??4??I??ܷ?????? Ser@?i?M[????_\D+P?�?a??????b?laD?? ? ? ???ܷ?????? SeN???Y?Y?????H?W? U[????_\D+b?l@?????????^?P?WMFSDKVersion10.00.00.3646WMFSDKNeeded0.0.0.0000 IsVBR@Rц1?????H??ARц1?????H?Windows Media io 9.1" 32 kbps, 44 kHz, mono 1-pass CBRa6&?u?f????b?l2?O??? 2005-11-18 14:39:48.8109 saving away that body message until we get an empty body 2005-11-18 14:39:48.8127 got some body from the player, length 1: q 2005-11-18 14:39:48.8129 saving away that body message until we get an empty body 2005-11-18 14:39:48.8139 got some body from the player, length 19: N?&?<???P 2005-11-18 14:39:48.8140 saving away that body message until we get an empty body 2005-11-18 14:39:48.8156 MMS protocol handler received response body 2005-11-18 14:39:48.8332 Parsed body as WMA header. 2005-11-18 14:39:48.8346 00:04:20:81:22:33: Switching to mode play from playout-play 2005-11-18 14:39:48.8353 openSong on: mms://66.250.84.31:80/ccri_ca_sanjose_kcnl_fm.asf 2005-11-18 14:39:49.1680 00:04:20:81:22:33 New play mode: play 2005-11-18 14:39:49.1716 This player supports direct streaming for mms://66.250.84.31:80/ccri_ca_sanjose_kcnl_fm.asf as mms://66.250.84.31:80/ccri_ca_sanjose_kcnl_fm.asf, let's do it. 2005-11-18 14:39:49.1746 setting up direct stream (1123701791:80) autostart: 3. 2005-11-18 14:39:49.1757 request string: GET /ccri_ca_sanjose_kcnl_fm.asf HTTP/1.0 Accept: */* User-Agent: NSPlayer/4.1.0.3856 Host: 66.250.84.31 Pragma: xClientGUID={948ade1a-c23b-e42a-dac4-c34eb7e6d6de} Pragma: no-cache,rate=1.0000000,stream-time=0,stream-offset=0:0,request-context=2,max-duration=0 Pragma: xPlayStrm=1 Pragma: stream-switch-count=1 Pragma: stream-switch-entry=ffff:1:0
Here is a sample: http://ccri.eonstreams.com/ccri_tn_memphis_wegr_fm.asf /pbz
The above eonstreams url is now dead. /pbz
more eonstreams that don't work... kevin:~ kpearsall$ wget http://ccri.eonstreams.com/ccri_ny_newyork_wltw_fm.asf --11:13:53-- http://ccri.eonstreams.com/ccri_ny_newyork_wltw_fm.asf Resolving ccri.eonstreams.com... 63.223.53.17 Connecting to ccri.eonstreams.com|63.223.53.17|:80... connected. HTTP request sent, awaiting response... 200 OK Length: 131 [video/x-ms-asf] Saving to: `ccri_ny_newyork_wltw_fm.asf' 100%[=======================================>] 131 --.-K/s in 0s 11:13:54 (10.4 MB/s) - `ccri_ny_newyork_wltw_fm.asf' saved [131/131] kevin:~ kpearsall$ cat ccri_ny_newyork_wltw_fm.asf [Reference] Ref1=http://ccri.eonstreams.com/ccri_ny_newyork_wltw_fm.asf Ref2=http://63.223.53.17:80/ccri_ny_newyork_wltw_fm.asf
Kevin - can you try with a 6.5 nightly? There have been a bunch of changes here. Thanks
Ross, can you try to reproduce this bug? If the streams are no longer there or if it now works, close the bug as WORKSFORME. If they still fail, assign to C.O.
http://ccri.eonstreams.com/ccri_ny_newyork_wltw_fm.asf Above stream is now dead. If this is still an issue, please re-open the bug with a working stream for us to test.