Hi,

Setup: LMS  7.9.2 - 1533559127 on Windows Server 2012R2
Players: PCP 3.5.0 players on Rpis with FFMPEG Squeezelite 1.9.0 

I this is an intermittent problem unfortunately, occurring every few
times I play the stream. It has also happened a few times the BBC radio
streams, but this is much rarer.

Stream:

http://gdansk1-1.radio.pionier.net.pl:8000/pl/tuba10-1.mp3

which is 44.1 kHz sometimes gets decoded as 32. It affects all players,
including when they are synched. It happens every few times I play the
stream, so it's not constant.


Today, the stream was playing OK, then there was some buffering issue
and it restarted at the wrong speed (around 13:01). I am attaching the
log around that event.

This is presumably not enough to point at the exact problem, but I would
appreciate some pointers as to how to debug this further.


Code:
--------------------
    
  13:01:14.0198] Slim::Player::StreamingController::playerOutputUnderrun (2219) 
02:0f:b5:10:4b:db: decoder: 1117 / output: 0
  [18-08-16 13:01:14.0202] Slim::Player::StreamingController::_setPlayingState 
(2357) new playing state BUFFERING
  [18-08-16 13:01:14.0248] 
Slim::Player::StreamingController::playerOutputUnderrun (2219) 
b8:27:eb:dd:bf:3e: decoder: 1117 / output: 0
  [18-08-16 13:01:14.1609] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:14.1619] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:14.4737] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:14.4746] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:14.7862] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:14.7872] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:15.0984] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:15.0994] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:15.4109] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:15.4118] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:15.7234] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:15.7243] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:16.0360] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:16.0368] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:16.3483] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:16.3492] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:16.6613] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:16.6622] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:16.9739] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:16.9749] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:17.2861] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:17.2871] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:17.5987] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:17.5996] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:17.9115] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:17.9124] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:18.2240] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:18.2250] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:18.5365] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:18.5375] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:18.8491] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:18.8500] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:19.1616] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:19.1625] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:19.4753] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:19.4763] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:19.7878] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:19.7887] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:20.1003] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:20.1013] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:20.4126] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:20.4137] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:20.7252] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:20.7262] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:21.0398] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  
  […]
  
  [18-08-16 13:01:24.4773] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:24.4783] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:24.7901] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:24.7912] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:25.1027] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:25.1037] Slim::Player::Player::_buffering (1140) Buffering... 
1117 / 80000
  [18-08-16 13:01:25.4008] Slim::Player::Player::_buffering (1140) Buffering... 
5317 / 80000
  [18-08-16 13:01:25.4017] Slim::Player::Player::_buffering (1140) Buffering... 
5317 / 80000
  [18-08-16 13:01:25.5681] Slim::Player::Source::_readNextChunk (373) end of 
file or error on socket, song pos: 248117693
  [18-08-16 13:01:25.5685] Slim::Player::Source::_readNextChunk (378) 
02:0f:b5:10:4b:db mark end of stream
  [18-08-16 13:01:25.5688] Slim::Player::Source::_readNextChunk (378) 
b8:27:eb:dd:bf:3e mark end of stream
  [18-08-16 13:01:25.5694] 
Slim::Player::StreamingController::_setStreamingState (2366) new streaming 
state STREAMOUT
  [18-08-16 13:01:25.5771] Slim::Player::StreamingController::playerEndOfStream 
(2266) 02:0f:b5:10:4b:db
  [18-08-16 13:01:25.5775] Slim::Player::StreamingController::_setPlayingState 
(2357) new playing state PLAYING
  [18-08-16 13:01:25.7071] 
Slim::Player::StreamingController::playerReadyToStream (2198) 02:0f:b5:10:4b:db
  [18-08-16 13:01:25.7079] 
Slim::Player::StreamingController::_setStreamingState (2366) new streaming 
state IDLE
  [18-08-16 13:01:25.7090] Slim::Player::StreamingController::_RetryOrNext 
(913) Attempting to re-stream 
http://gdansk1-1.radio.pionier.net.pl:8000/pl/tuba10-1.mp3 after time 
15500.8913919811
  [18-08-16 13:01:25.7094] Slim::Player::StreamingController::_Stream (1129) 
02:0f:b5:10:4b:db: got song from params, song index 1
  [18-08-16 13:01:25.7098] Slim::Player::StreamingController::_Stream (1203) 
Song queue is now 1
  [18-08-16 13:01:25.7101] Slim::Player::StreamingController::_Stream (1206) 
02:0f:b5:10:4b:db: preparing to stream song index 1
  [18-08-16 13:01:25.7105] Slim::Player::Song::open (364) 
http://gdansk1-1.radio.pionier.net.pl:8000/pl/tuba10-1.mp3
  [18-08-16 13:01:25.7110] Slim::Player::Song::open (388) seek=false time=0 
canSeek=0SEEK_ERROR_MP3_UNKNOWN_DURATION
  [18-08-16 13:01:25.7120] Slim::Player::TranscodingHelper::getConvertCommand2 
(446) Matched: mp3->mp3 via: -
  [18-08-16 13:01:25.7124] Slim::Player::Song::open (409) Transcoder: 
streamMode=I, streamformat=mp3
  [18-08-16 13:01:25.7129] Slim::Player::Song::open (462) Opening stream (no 
direct streaming) using Slim::Player::Protocols::HTTP 
[http://gdansk1-1.radio.pionier.net.pl:8000/pl/tuba10-1.mp3]
  [18-08-16 13:01:25.9579] Slim::Player::Song::open (483) URL is a song 
(audio): http://gdansk1-1.radio.pionier.net.pl:8000/pl/tuba10-1.mp3, type=mp3
  [18-08-16 13:01:25.9589] Slim::Player::StreamingController::_Stream (1265) 
02:0f:b5:10:4b:db: stream
  [18-08-16 13:01:25.9627] Slim::Player::StreamingController::_Stream (1265) 
b8:27:eb:dd:bf:3e: stream
  [18-08-16 13:01:25.9666] Slim::Player::StreamingController::_Stream (1302) 
Song queue is now 1
  [18-08-16 13:01:25.9671] 
Slim::Player::StreamingController::_setStreamingState (2366) new streaming 
state STREAMING
  [18-08-16 13:01:33.0047] 
Slim::Player::StreamingController::playerTrackStarted (2180) b8:27:eb:dd:bf:3e
  [18-08-16 13:01:33.0124] 
Slim::Player::StreamingController::playerTrackStarted (2180) 02:0f:b5:10:4b:db
  
--------------------


------------------------------------------------------------------------
Wigster's Profile: http://forums.slimdevices.com/member.php?userid=14899
View this thread: http://forums.slimdevices.com/showthread.php?t=109354

_______________________________________________
Squeezecenter mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/squeezecenter

Reply via email to