Finally had one of these rebuffering episodes (not synched, direct
playback to player, etc.). Was fine for several hours through 17:11
time. Then at 19:18 time (excerpt below), it stopped and then
rebuffered before starting back playing. Note the "playerOutputUnderrun"
message when this started. (full log at end of message).
Code:
--------------------
*[22-03-22 19:18:22.4027]
Slim::Player::StreamingController::playerOutputUnderrun (2239)
00:04:20:10:12:97: decoder: 150 / output: 0*
[22-03-22 19:18:22.4034] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state BUFFERING
[22-03-22 19:18:22.5346] Slim::Player::Player::_buffering (1144) Buffering...
150 / 80000
[22-03-22 19:18:22.8377] Slim::Player::Player::_buffering (1144) Buffering...
150 / 80000
[...] buffering lines removed
[22-03-22 19:18:51.7637] Slim::Player::Player::_buffering (1144) Buffering...
3069 / 80000
[22-03-22 19:18:52.0678] Slim::Player::Player::_buffering (1144) Buffering...
3069 / 80000
[22-03-22 19:18:52.3718] Slim::Player::Player::_buffering (1144) Buffering...
3069 / 80000
[22-03-22 19:18:52.6753] Slim::Player::Player::_buffering (1144) Buffering...
3069 / 80000
[22-03-22 19:18:52.9792] Slim::Player::Player::_buffering (1144) Buffering...
3069 / 80000
[22-03-22 19:18:53.2837] Slim::Player::StreamingController::jumpToTime (2166)
00:04:20:10:12:97
[22-03-22 19:18:53.2860] Slim::Player::StreamingController::_Stop (610) Song
queue is now 0
[22-03-22 19:18:53.2867] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state STOPPED
[22-03-22 19:18:53.2871]
Slim::Player::StreamingController::_setStreamingState (2386) new streaming
state IDLE
[22-03-22 19:18:53.2877] Slim::Player::StreamingController::_Stream (1136)
00:04:20:10:12:97: got song from params, song index 0
[22-03-22 19:18:53.2882] Slim::Player::StreamingController::_Stream (1210)
Song queue is now 0
[22-03-22 19:18:53.2888] Slim::Player::StreamingController::_Stream (1213)
00:04:20:10:12:97: preparing to stream song index 0
[22-03-22 19:18:53.2893] Slim::Player::Song::open (362)
http://wumb.streamguys1.com/wumb919fast
[22-03-22 19:18:53.2928] Slim::Player::TranscodingHelper::getConvertCommand2
(490) Error: Didn't find any command matches for type: mp3
[22-03-22 19:18:53.2934] Slim::Player::Song::open (386) seek=false time=0
canSeek=0SEEK_ERROR_MP3_UNKNOWN_DURATION
[22-03-22 19:18:53.2946] Slim::Player::TranscodingHelper::getConvertCommand2
(493) Matched: mp3->mp3 via: -
[22-03-22 19:18:53.2951] Slim::Player::Song::open (416) Transcoder:
streamMode=I, streamformat=mp3
[22-03-22 19:18:53.2955] Slim::Player::Song::open (462) URL supports direct
streaming
[http://wumb.streamguys1.com/wumb919fast->http://wumb.streamguys1.com/wumb919fast]
[22-03-22 19:18:53.2981] Slim::Player::StreamingController::_Stream (1282)
00:04:20:10:12:97: stream
[22-03-22 19:18:53.2987] Slim::Player::Transporter::setDigitalInput (188)
Switching to digital input 0
[22-03-22 19:18:53.3036] Slim::Player::StreamingController::_Stream (1319)
Song queue is now 0
[22-03-22 19:18:53.3039] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state BUFFERING
[22-03-22 19:18:53.3042]
Slim::Player::StreamingController::_setStreamingState (2386) new streaming
state STREAMING
[22-03-22 19:18:53.4267] Slim::Player::Player::_buffering (1144) Buffering...
0 / 245760
[22-03-22 19:18:53.7302] Slim::Player::Player::_buffering (1144) Buffering...
1255 / 245760
[22-03-22 19:18:54.0343] Slim::Player::Player::_buffering (1144) Buffering...
3344 / 245760
[22-03-22 19:18:54.3387] Slim::Player::Player::_buffering (1144) Buffering...
3344 / 245760
[22-03-22 19:18:54.6428] Slim::Player::Player::_buffering (1144) Buffering...
11279 / 245760
[22-03-22 19:18:54.7329] Slim::Player::TranscodingHelper::getConvertCommand2
(490) Error: Didn't find any command matches for type: mp3
[22-03-22 19:18:54.9473] Slim::Player::Player::_buffering (1144) Buffering...
64000 / 245760
[22-03-22 19:18:55.2519] Slim::Player::Player::_buffering (1144) Buffering...
87771 / 245760
[22-03-22 19:18:55.5560] Slim::Player::Player::_buffering (1144) Buffering...
91951 / 245760
[....] buffering lines removed
22 19:19:04.4251] Slim::Player::Player::_buffering (1144) Buffering... 234057
/ 245760
[22-03-22 19:19:04.7281] Slim::Player::Player::_buffering (1144) Buffering...
242870 / 245760
[22-03-22 19:19:04.7288] Slim::Player::Player::_buffering (1145) +output...
4984 / 5292000
[22-03-22 19:19:04.7482]
Slim::Player::StreamingController::playerTrackStarted (2200) 00:04:20:10:12:97
[22-03-22 19:19:04.7490] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state PLAYING
[22-03-22 19:19:04.7496] Slim::Player::StreamingController::_Playing (368)
Song 0 has now started playing
[22-03-22 19:19:04.7510] Slim::Player::StreamingController::_Playing (397)
Song queue is now 0
--------------------
full log:
+-------------------------------------------------------------------+
|Filename: server.log |
|Download: http://forums.slimdevices.com/attachment.php?attachmentid=37541|
+-------------------------------------------------------------------+
*Home:* Pi4B-8GB/pCP8.x/4TB>LMS 8.2.x>Transporter, Touch, Boom, Radio
(all ethernet)
*Cottage:* rPi4B-4GB/pCP8.x/4TB>LMS 8.2.x>Touch>Benchmark DAC I, Boom,
Radio w/Battery (Radio WIFI)
*Office:* Win10(64)>foobar2000
*The Wild: *rPi3B+/pCP7.x/4TB>LMS 8.1.x>hifiberry Dac+Pro (LMS &
Squeezelite)
*Controllers:* iPhone11 & iPadAir3 (iPeng), CONTROLLER, Material Skin,
or SqueezePlay 7.8 on Win10(64)
*Files:* -Ripping-: dBpoweramp > FLAC; -Post-rip-: mp3tag, PerfectTunes,
TuneFusion; -Streaming:- Spotify
------------------------------------------------------------------------
garym's Profile: http://forums.slimdevices.com/member.php?userid=17325
View this thread: http://forums.slimdevices.com/showthread.php?t=116117
_______________________________________________
Squeezecenter mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/squeezecenter
