Bug 11509 - Problem with Listen Again with BBC iPlayer
: Problem with Listen Again with BBC iPlayer
Status: RESOLVED INVALID
Product: Logitech Media Server
Classification: Unclassified
Component: Plugins
: 7.3.2
: PC Windows Vista
: -- normal (vote)
: ---
Assigned To: Adrian Smith
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2009-03-26 09:36 UTC by Richard Patterson
Modified: 2009-03-30 08:01 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 Richard Patterson 2009-03-26 09:36:02 UTC
Some programs on Listen Again do not play via the BBC iPlayer plug-in.
They do play on both the BBC iPlayer used in a web browser on a PC or via the Alien BBC plug-in.

Debug examples of a program that plays & one that fails follow below.

Obvious difference is the following from the non-working log
[15:40:49.2784] Use of uninitialized value in gethostbyname at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Squeezebox.pm line 756.

ANybody any ideas?

Listen again stream that worked (BBC7 Yes Minister)
[09-03-26 15:38:45.2461] Slim::Player::Song::new (64) index 0 -> iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b006nl4q
[09-03-26 15:38:45.2465] Slim::Player::StreamingController::_setStreamingSt ate (1815) new streaming state TRACKWAIT
[09-03-26 15:38:45.2468] Slim::Player::Song::getNextSong (178) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b006nl4q
[09-03-26 15:38:45.2471] Slim::Player::Song::getNextSong (200) scanning URL iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b006nl4q
[09-03-26 15:38:45.2511] Slim::Player::Song::getNextSong (178) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b006nl4q
[09-03-26 15:38:45.2515] Slim::Player::StreamingController::_nextTrackReady (639) 00:04:20:06:2a:33: nextTrack will be index 0
[09-03-26 15:38:45.2518] Slim::Player::StreamingController::_Stream (913) 00:04:20:06:2a:33: preparing to stream song index 0
[09-03-26 15:38:45.2521] Slim::Player::StreamingController::_Stream (928) Song queue is now 0
[09-03-26 15:38:45.2524] Slim::Player::Song::open (302) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b006nl4q
[09-03-26 15:38:45.2530] Slim::Player::Song::open (323) seek=false time=0 canSeek=1
[09-03-26 15:38:45.2536] Slim::Player::TranscodingHelper::getConvertCommand 2 (454) Matched: mp3->mp3 via: -
[09-03-26 15:38:45.2539] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=mp3
[09-03-26 15:38:45.2542] Slim::Player::Song::open (354) URL supports direct streaming [iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b006nl4q->iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b006nl4q]
[09-03-26 15:38:45.2570] Slim::Player::StreamingController::_Stream (969) 00:04:20:06:2a:33: stream
[09-03-26 15:38:45.3375] Slim::Player::StreamingController::_Stream (998) Song queue is now 0
[09-03-26 15:38:45.3379] Slim::Player::StreamingController::_setPlayingStat e (1802) new playing state BUFFERING
[09-03-26 15:38:45.3381] Slim::Player::StreamingController::_setStreamingSt ate (1815) new streaming state STREAMING
[09-03-26 15:38:45.4757] Slim::Player::Player::_buffering (1172) Buffering... 0 / 147456


Lesten again stream that failed (BBC7 Animal Farm)
[09-03-26 15:40:48.9461] Slim::Player::Song::new (64) index 0 -> iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b00c02l8
[09-03-26 15:40:48.9465] Slim::Player::StreamingController::_setStreamingSt ate (1815) new streaming state TRACKWAIT
[09-03-26 15:40:48.9468] Slim::Player::Song::getNextSong (178) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b00c02l8
[09-03-26 15:40:48.9472] Slim::Player::Song::getNextSong (200) scanning URL iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b00c02l8
[09-03-26 15:40:48.9507] Slim::Player::Song::getNextSong (178) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b00c02l8
[09-03-26 15:40:48.9512] Slim::Player::StreamingController::_nextTrackReady (639) 00:04:20:06:2a:33: nextTrack will be index 0
[09-03-26 15:40:48.9515] Slim::Player::StreamingController::_Stream (913) 00:04:20:06:2a:33: preparing to stream song index 0
[09-03-26 15:40:48.9518] Slim::Player::StreamingController::_Stream (928) Song queue is now 0
[09-03-26 15:40:48.9521] Slim::Player::Song::open (302) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b00c02l8
[09-03-26 15:40:48.9525] Slim::Player::Song::open (323) seek=false time=0 canSeek=1
[09-03-26 15:40:48.9532] Slim::Player::TranscodingHelper::getConvertCommand 2 (454) Matched: mp3->mp3 via: -
[09-03-26 15:40:48.9534] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=mp3
[09-03-26 15:40:48.9538] Slim::Player::Song::open (354) URL supports direct streaming [iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b00c02l8->iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b00c02l8]
[09-03-26 15:40:48.9563] Slim::Player::StreamingController::_Stream (969) 00:04:20:06:2a:33: stream
[09-03-26 15:40:49.0340] Slim::Player::StreamingController::_Stream (998) Song queue is now 0
[09-03-26 15:40:49.0344] Slim::Player::StreamingController::_setPlayingStat e (1802) new playing state BUFFERING
[09-03-26 15:40:49.0347] Slim::Player::StreamingController::_setStreamingSt ate (1815) new streaming state STREAMING
[09-03-26 15:40:49.0893] Slim::Player::Player::_buffering (1172) Buffering... 0 / 147456
[09-03-26 15:40:49.2786] Slim::Utils::Misc::msg (1122) Warning: [15:40:49.2784] Use of uninitialized value in gethostbyname at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Squeezebox.pm line 756.
[09-03-26 15:40:49.2815] Slim::Player::StreamingController::playerEndOfStre am (1711) 00:04:20:06:2a:33
[09-03-26 15:40:49.2818] Slim::Player::StreamingController::_setStreamingSt ate (1815) new streaming state STREAMOUT
[09-03-26 15:40:49.2823] Slim::Player::StreamingController::_setPlayingStat e (1802) new playing state PLAYING
[09-03-26 15:40:49.2826] Slim::Player::StreamingController::_Playing (352) Song 0 has now started playing
[09-03-26 15:40:49.2833] Slim::Player::StreamingController::_Playing (381) Song queue is now 0
[09-03-26 15:40:59.3003] Plugins::BBCiPlayer::iPlayer::handleDirectError (203) /iplayer/licence/error.shtml?statuscode=404 4 Connection timed out
[09-03-26 15:40:59.3006] Slim::Player::StreamingController::playerStreaming Failed (1685) 00:04:20:06:2a:33
[09-03-26 15:40:59.3012] Slim::Player::StreamingController::_playersMessage (684) iPlayer Failed to Direct Stream: Animal Farm
[09-03-26 15:40:59.3021] Slim::Player::StreamingController::_setStreamingSt ate (1815) new streaming state IDLE
[09-03-26 15:40:59.3024] Slim::Player::StreamingController::nextsong (730) Giving up because of too many consecutive errors: 1
Comment 1 Adrian Smith 2009-03-26 12:22:41 UTC
Yes I am afraid some content is not available in the format used by the iPlayer plugin - this looks to be symptoms of that - there were about 3 days last week when most of it was missing.  I suggest you maintain the iplayer and AlienBBC plugins as they play different content and give some reslience to the bbc feeds not being there.

I don't think this is a bug, so will suggest closing this.
Comment 2 Richard Patterson 2009-03-26 13:00:05 UTC
Hi Tridoe,

Could I please suggest a better reporting at the user interface - maybe a message such as "unsupported format" rather than no feedback at all?

Also would there be any chance of supporting the additional format?

Richard
Comment 3 Richard Patterson 2009-03-26 13:02:06 UTC
Sorry forgot to say. My recent experience is that a lot of Listen Again programmes are in falling into the unsupported format - so will make using the Listen Again feature of the iPlayer a lottery unless the format is supported.
Comment 4 Adrian Smith 2009-03-26 14:15:03 UTC
Well the problem is we can't guess when the content is not available.  I will look to see if we can add better error messages, but outside of this bug..
Comment 5 Richard Patterson 2009-03-27 04:52:42 UTC
Hi Triode,

I am sorry to say that I do not think that wrong format is the problem.

Today I have managed to get the same Listen Again program to play with no errors & then exhibit the error at next time of trying to play.

Unless teh BBC is changing the format of the show on a regular & random basis (which seems sort of unlikely) then I think the error most be in another place.

See two logs below for the same Listen Again program - one works - one fails.

Here it is working
[09-03-27 11:32:20.6217] Slim::Player::Song::new (64) index 0 -> iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 11:32:20.6222] Slim::Player::StreamingController::_setStreamingState (1815) new streaming state TRACKWAIT
[09-03-27 11:32:20.6225] Slim::Player::Song::getNextSong (178) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 11:32:20.6229] Slim::Player::Song::getNextSong (200) scanning URL iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 11:32:20.6283] Slim::Player::Song::getNextSong (178) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 11:32:20.6289] Slim::Player::StreamingController::_nextTrackReady (639) 00:04:20:06:2a:33: nextTrack will be index 0
[09-03-27 11:32:20.6292] Slim::Player::StreamingController::_Stream (913) 00:04:20:06:2a:33: preparing to stream song index 0
[09-03-27 11:32:20.6295] Slim::Player::StreamingController::_Stream (928) Song queue is now 0
[09-03-27 11:32:20.6298] Slim::Player::Song::open (302) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 11:32:20.6303] Slim::Player::Song::open (323) seek=false time=0 canSeek=1
[09-03-27 11:32:20.6310] Slim::Player::TranscodingHelper::getConvertCommand2 (454) Matched: mp3->mp3 via: -
[09-03-27 11:32:20.6313] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=mp3
[09-03-27 11:32:20.6318] Slim::Player::Song::open (354) URL supports direct streaming [iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m->iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m]
[09-03-27 11:32:20.6456] Slim::Player::StreamingController::_Stream (969) 00:04:20:06:2a:33: stream
[09-03-27 11:32:20.7759] Slim::Player::StreamingController::_Stream (998) Song queue is now 0
[09-03-27 11:32:20.7764] Slim::Player::StreamingController::_setPlayingState (1802) new playing state BUFFERING
[09-03-27 11:32:20.7767] Slim::Player::StreamingController::_setStreamingState (1815) new streaming state STREAMING
[09-03-27 11:32:20.8868] Slim::Player::Player::_buffering (1172) Buffering... 0 / 147456
[09-03-27 11:32:21.1373] Slim::Player::StreamingController::playerEndOfStream (1711) 00:04:20:06:2a:33
[09-03-27 11:32:21.1376] Slim::Player::StreamingController::_setStreamingState (1815) new streaming state STREAMOUT
[09-03-27 11:32:21.1382] Slim::Player::StreamingController::_setPlayingState (1802) new playing state PLAYING
[09-03-27 11:32:21.1385] Slim::Player::StreamingController::_Playing (352) Song 0 has now started playing
[09-03-27 11:32:21.1392] Slim::Player::StreamingController::_Playing (381) Song queue is now 0
[09-03-27 11:32:21.5880] Slim::Player::StreamingController::playerTrackStarted (1627) 00:04:20:06:2a:33
[09-03-27 11:32:21.5883] Slim::Player::StreamingController::_setPlayingState (1802) new playing state PLAYING
[09-03-27 11:32:21.5886] Slim::Player::StreamingController::_Playing (352) Song 0 has now started playing
[09-03-27 11:32:21.5892] Slim::Player::StreamingController::_Playing (381) Song queue is now 0


Here it is NOT working (same Listen Again program)
[09-03-27 10:34:01.5559] Slim::Player::Song::new (64) index 0 -> iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 10:34:01.5564] Slim::Player::StreamingController::_setStreamingState (1815) new streaming state TRACKWAIT
[09-03-27 10:34:01.5567] Slim::Player::Song::getNextSong (178) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 10:34:01.5570] Slim::Player::Song::getNextSong (200) scanning URL iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 10:34:01.5602] Slim::Player::Song::getNextSong (178) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 10:34:01.5606] Slim::Player::StreamingController::_nextTrackReady (639) 00:04:20:06:2a:33: nextTrack will be index 0
[09-03-27 10:34:01.5608] Slim::Player::StreamingController::_Stream (913) 00:04:20:06:2a:33: preparing to stream song index 0
[09-03-27 10:34:01.5611] Slim::Player::StreamingController::_Stream (928) Song queue is now 0
[09-03-27 10:34:01.5615] Slim::Player::Song::open (302) iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m
[09-03-27 10:34:01.5619] Slim::Player::Song::open (323) seek=false time=0 canSeek=1
[09-03-27 10:34:01.5626] Slim::Player::TranscodingHelper::getConvertCommand2 (454) Matched: mp3->mp3 via: -
[09-03-27 10:34:01.5629] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=mp3
[09-03-27 10:34:01.5633] Slim::Player::Song::open (354) URL supports direct streaming [iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m->iplayer://www.bbc.co.uk/mediaselector/3/auth/iplayer_streaming_http_mp4/b005h55m]
[09-03-27 10:34:01.5658] Slim::Player::StreamingController::_Stream (969) 00:04:20:06:2a:33: stream
[09-03-27 10:34:01.6665] Slim::Player::StreamingController::_Stream (998) Song queue is now 0
[09-03-27 10:34:01.6669] Slim::Player::StreamingController::_setPlayingState (1802) new playing state BUFFERING
[09-03-27 10:34:01.6671] Slim::Player::StreamingController::_setStreamingState (1815) new streaming state STREAMING
[09-03-27 10:34:01.7274] Slim::Player::Player::_buffering (1172) Buffering... 0 / 147456
[09-03-27 10:34:01.9271] Slim::Utils::Misc::msg (1122) Warning: [10:34:01.9180] Use of uninitialized value in gethostbyname at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Squeezebox.pm line 756.
[09-03-27 10:34:01.9290] Slim::Player::StreamingController::playerEndOfStream (1711) 00:04:20:06:2a:33
[09-03-27 10:34:01.9292] Slim::Player::StreamingController::_setStreamingState (1815) new streaming state STREAMOUT
[09-03-27 10:34:01.9296] Slim::Player::StreamingController::_setPlayingState (1802) new playing state PLAYING
[09-03-27 10:34:01.9298] Slim::Player::StreamingController::_Playing (352) Song 0 has now started playing
[09-03-27 10:34:01.9302] Slim::Player::StreamingController::_Playing (381) Song queue is now 0
[09-03-27 10:34:11.9421] Plugins::BBCiPlayer::iPlayer::handleDirectError (203) /iplayer/licence/error.shtml?statuscode=404 4 Connection timed out
[09-03-27 10:34:11.9424] Slim::Player::StreamingController::playerStreamingFailed (1685) 00:04:20:06:2a:33
[09-03-27 10:34:11.9431] Slim::Player::StreamingController::_playersMessage (684) iPlayer Failed to Direct Stream: Bogarde on Film, Breaking In
[09-03-27 10:34:11.9441] Slim::Player::StreamingController::_setStreamingState (1815) new streaming state IDLE
[09-03-27 10:34:11.9444] Slim::Player::StreamingController::nextsong (730) Giving up because of too many consecutive errors: 1
Comment 6 Adrian Smith 2009-03-27 14:15:25 UTC
Can't be fixed I'm afraid.