SW: 2009-06-11 17:45:35.561 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:35.561 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:35.7385] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:35.748 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:35.748 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:35.748 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:35.748 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:35.748 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:35.748 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:35.748 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:35.748 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:35.9390] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:35.936 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:35.936 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:35.936 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:35.936 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:35.936 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:35.936 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:35.936 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:35.936 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:36.1117] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:36.108 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.108 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.108 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.108 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.108 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.108 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.108 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.108 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:36.2173] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:36.3035] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:36.295 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.295 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.295 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.295 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.295 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.295 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.311 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.311 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:36.4831] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:36.483 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.498 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.498 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.498 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.498 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.498 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.498 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.498 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:36.6516] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:36.655 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.655 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.655 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.655 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.670 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.670 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.670 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.670 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:36.8435] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:36.858 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.858 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.858 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.858 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.858 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.858 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:36.858 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:36.858 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:36.9095] Slim::Player::Source::songTime (104) songtime=1.04967120181406 from byterate=176400, duration=233.346, byte
sReceived=411720, fullness=226558, startStream=0
[09-06-11 17:45:36.9177] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:37.0456] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:37.045 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.061 MoveDataThreadProc for step 0 about to call ReadFile.
[09-06-11 17:45:37.2135] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:37.2345] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:37.233 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
[09-06-11 17:45:37.2363] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
[09-06-11 17:45:37.2387] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
[09-06-11 17:45:37.2403] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
[09-06-11 17:45:37.2420] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
[09-06-11 17:45:37.2435] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
[09-06-11 17:45:37.2451] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:37.248 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.248 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.248 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.248 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.248 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.248 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.248 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.248 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:37.2934] Slim::Player::Source::songTime (104) songtime=2.04959183673469 from byterate=176400, duration=233.346, byte
sReceived=586920, fullness=225372, startStream=0
[09-06-11 17:45:37.4062] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:37.405 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.405 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.405 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.405 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.405 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.405 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.405 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.405 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:37.5925] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:37.592 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.592 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.592 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.592 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.608 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.608 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.608 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.608 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:37.7850] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:37.795 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.795 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.795 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.795 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.795 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.795 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.795 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.795 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:37.9728] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:37.983 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.983 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.983 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.983 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.983 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.983 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:37.983 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:37.983 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:38.0806] Slim::Player::Source::songTime (104) songtime=2.04959183673469 from byterate=176400, duration=233.346, byte
sReceived=586920, fullness=225372, startStream=0
[09-06-11 17:45:38.0853] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-00:04:20:05:7a:ec
[09-06-11 17:45:38.0876] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-00:04:20:05:7a:ec
[09-06-11 17:45:38.0898] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-*
[09-06-11 17:45:38.0922] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-*
[09-06-11 17:45:38.0930] Slim::Player::TranscodingHelper::enabledFormat (199) Checking to see if ogg-aif-*-* is enabled
[09-06-11 17:45:38.0954] Slim::Player::TranscodingHelper::enabledFormat (207) There are 1 disabled formats...
[09-06-11 17:45:38.0971] Slim::Player::TranscodingHelper::enabledFormat (213) Testing ogg-mp3-*-* vs ogg-aif-*-*
[09-06-11 17:45:38.0986] Slim::Player::TranscodingHelper::checkBin (240)    enabled
[09-06-11 17:45:38.1001] Slim::Player::TranscodingHelper::checkBin (242)   Found command: [sox] -q -t ogg $FILE$ -t raw -r 44100 -c
2 -2 -s $-x$ -
[09-06-11 17:45:38.1025] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: ogg->aif via: [sox] -q -t ogg $FILE$ -t
raw -r 44100 -c 2 -2 -s $-x$ -
[09-06-11 17:45:38.1054] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-00:04:20:05:7a:ec
[09-06-11 17:45:38.1069] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-00:04:20:05:7a:ec
[09-06-11 17:45:38.1085] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-*
[09-06-11 17:45:38.1100] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-*
[09-06-11 17:45:38.1115] Slim::Player::TranscodingHelper::enabledFormat (199) Checking to see if ogg-aif-*-* is enabled
[09-06-11 17:45:38.1130] Slim::Player::TranscodingHelper::enabledFormat (207) There are 1 disabled formats...
[09-06-11 17:45:38.1145] Slim::Player::TranscodingHelper::enabledFormat (213) Testing ogg-mp3-*-* vs ogg-aif-*-*
[09-06-11 17:45:38.1160] Slim::Player::TranscodingHelper::checkBin (240)    enabled
[09-06-11 17:45:38.1175] Slim::Player::TranscodingHelper::checkBin (242)   Found command: [sox] -q -t ogg $FILE$ -t raw -r 44100 -c
2 -2 -s $-x$ -
[09-06-11 17:45:38.1199] Slim::Player::TranscodingHelper::getConvertCommand2 (375) Rejecting [sox] -q -t ogg $FILE$ -t raw -r 44100
-c 2 -2 -s $-x$ - because required capability T not supported:
[09-06-11 17:45:38.1222] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-pcm-squeezebox-00:04:20:05:7a:ec
[09-06-11 17:45:38.1237] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-pcm-*-00:04:20:05:7a:ec
[09-06-11 17:45:38.1252] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-pcm-squeezebox-*
[09-06-11 17:45:38.1267] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-pcm-*-*
[09-06-11 17:45:38.1284] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-mp3-squeezebox-00:04:20:05:7a:ec
[09-06-11 17:45:38.1299] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-mp3-*-00:04:20:05:7a:ec
[09-06-11 17:45:38.1314] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-mp3-squeezebox-*
[09-06-11 17:45:38.1328] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-mp3-*-*
[09-06-11 17:45:38.1343] Slim::Player::TranscodingHelper::enabledFormat (199) Checking to see if ogg-mp3-*-* is enabled
[09-06-11 17:45:38.1358] Slim::Player::TranscodingHelper::enabledFormat (207) There are 1 disabled formats...
[09-06-11 17:45:38.1373] Slim::Player::TranscodingHelper::enabledFormat (213) Testing ogg-mp3-*-* vs ogg-mp3-*-*
[09-06-11 17:45:38.1388] Slim::Player::TranscodingHelper::enabledFormat (217) ** ogg-mp3-*-* Disabled **
[09-06-11 17:45:38.1404] Slim::Player::TranscodingHelper::getConvertCommand2 (430) Error: Didn't find any command matches for type:
ogg
[09-06-11 17:45:38.1443] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:38.155 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.155 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.155 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.155 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.155 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.155 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.155 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.155 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:38.1826] Slim::Player::Source::songTime (104) songtime=2.04959183673469 from byterate=176400, duration=233.346, byte
sReceived=586920, fullness=225372, startStream=0
[09-06-11 17:45:38.2440] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:38.2861] Slim::Player::Source::songTime (104) songtime=3.04946712018141 from byterate=176400, duration=233.346, byte
sReceived=763580, fullness=225654, startStream=0
[09-06-11 17:45:38.4180] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:38.420 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.420 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.420 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.420 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.436 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.436 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.436 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.436 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:38.5235] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:38.530 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.530 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.530 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.530 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.530 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.530 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.530 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.530 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:38.7175] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:38.717 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.717 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.717 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.717 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.717 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.717 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.717 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.717 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:38.8891] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:38.889 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.889 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.889 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.889 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.889 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.889 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:38.889 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:38.889 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.0827] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.076 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.076 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.076 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.076 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.092 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.108 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.108 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.108 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.108 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.108 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.108 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.2127] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:39.2643] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.280 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
[09-06-11 17:45:39.2663] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
[09-06-11 17:45:39.2685] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
[09-06-11 17:45:39.2701] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
[09-06-11 17:45:39.2719] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
[09-06-11 17:45:39.2734] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
[09-06-11 17:45:39.2749] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.280 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.280 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.280 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.280 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.280 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.295 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.295 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.295 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.2864] Slim::Player::Source::songTime (104) songtime=4.0493537414966 from byterate=176400, duration=233.346, bytes
Received=941700, fullness=227394, startStream=0
[09-06-11 17:45:39.4228] Slim::Player::StreamingController::skip (1714) 00:04:20:05:7a:ec
[09-06-11 17:45:39.4244] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: Skip in PLAYING-STREAMING -> Slim:
:Player::StreamingController::_Skip
[09-06-11 17:45:39.4272] Slim::Player::StreamingController::_Stop (595) Song queue is now 3
SW: 2009-06-11 17:45:39.436 MoveDataThreadProc for step 2 failed Send writing with error 10054.
SW: 2009-06-11 17:45:39.436 MoveDataThreadProc for step 2 ending.
[09-06-11 17:45:39.4300] Slim::Player::SongStreamController::DESTROY (45) DESTROY(Slim::Player::SongStreamController=HASH(0x88534d4)
) live=0
SW: 2009-06-11 17:45:39.436 Timeout Process/Thread for step 2 died.
[09-06-11 17:45:39.4338] Slim::Player::StreamingController::_setPlayingState (1969) new playing state STOPPED
SW: 2009-06-11 17:45:39.436 Tidying up
SW: 2009-06-11 17:45:39.436  Process/thread 2 stopped
SW: 2009-06-11 17:45:39.436 Watchdog expired
[09-06-11 17:45:39.4384] Slim::Player::StreamingController::_setStreamingState (1982) new streaming state IDLE
[09-06-11 17:45:39.4402] Slim::Player::StreamingController::nextsong (823) The next song is number 4, was 3
[09-06-11 17:45:39.4460] Slim::Player::Song::new (72) index 4 -> file:///G:/ogg/Johnny%20A/Get%20Inside/05%20Get%20Inside.ogg
[09-06-11 17:45:39.4478] Slim::Player::Song::new (109) live=2
[09-06-11 17:45:39.4493] Slim::Player::StreamingController::_setStreamingState (1982) new streaming state TRACKWAIT
[09-06-11 17:45:39.4509] Slim::Player::Song::getNextSong (186) file:///G:/ogg/Johnny%20A/Get%20Inside/05%20Get%20Inside.ogg
[09-06-11 17:45:39.4526] Slim::Player::StreamingController::_nextTrackReady (715) 00:04:20:05:7a:ec: nextTrack will be index 4
[09-06-11 17:45:39.4542] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: NextTrackReady in STOPPED-TRACKWAI
T -> Slim::Player::StreamingController::_Stream
[09-06-11 17:45:39.4565] Slim::Player::StreamingController::_Stream (989) 00:04:20:05:7a:ec: preparing to stream song index 4
[09-06-11 17:45:39.4583] Slim::Player::StreamingController::_Stream (1005) Song queue is now 4
[09-06-11 17:45:39.4600] Slim::Player::Song::open (310) file:///G:/ogg/Johnny%20A/Get%20Inside/05%20Get%20Inside.ogg
[09-06-11 17:45:39.4626] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-00:04:20:05:7a:ec
[09-06-11 17:45:39.4641] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-00:04:20:05:7a:ec
[09-06-11 17:45:39.4657] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-*
[09-06-11 17:45:39.4672] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-*
[09-06-11 17:45:39.4688] Slim::Player::TranscodingHelper::enabledFormat (199) Checking to see if ogg-aif-*-* is enabled
[09-06-11 17:45:39.4703] Slim::Player::TranscodingHelper::enabledFormat (207) There are 1 disabled formats...
[09-06-11 17:45:39.4718] Slim::Player::TranscodingHelper::enabledFormat (213) Testing ogg-mp3-*-* vs ogg-aif-*-*
[09-06-11 17:45:39.4733] Slim::Player::TranscodingHelper::checkBin (240)    enabled
[09-06-11 17:45:39.4748] Slim::Player::TranscodingHelper::checkBin (242)   Found command: [sox] -q -t ogg $FILE$ -t raw -r 44100 -c
2 -2 -s $-x$ -
[09-06-11 17:45:39.4772] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: ogg->aif via: [sox] -q -t ogg $FILE$ -t
raw -r 44100 -c 2 -2 -s $-x$ -
[09-06-11 17:45:39.4802] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-00:04:20:05:7a:ec
[09-06-11 17:45:39.4817] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-00:04:20:05:7a:ec
[09-06-11 17:45:39.4832] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-*
[09-06-11 17:45:39.4850] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-*
[09-06-11 17:45:39.4865] Slim::Player::TranscodingHelper::enabledFormat (199) Checking to see if ogg-aif-*-* is enabled
[09-06-11 17:45:39.4880] Slim::Player::TranscodingHelper::enabledFormat (207) There are 1 disabled formats...
[09-06-11 17:45:39.4895] Slim::Player::TranscodingHelper::enabledFormat (213) Testing ogg-mp3-*-* vs ogg-aif-*-*
[09-06-11 17:45:39.4910] Slim::Player::TranscodingHelper::checkBin (240)    enabled
[09-06-11 17:45:39.4925] Slim::Player::TranscodingHelper::checkBin (242)   Found command: [sox] -q -t ogg $FILE$ -t raw -r 44100 -c
2 -2 -s $-x$ -
[09-06-11 17:45:39.4949] Slim::Player::TranscodingHelper::getConvertCommand2 (375) Rejecting [sox] -q -t ogg $FILE$ -t raw -r 44100
-c 2 -2 -s $-x$ - because required capability T not supported:
[09-06-11 17:45:39.4972] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-pcm-squeezebox-00:04:20:05:7a:ec
[09-06-11 17:45:39.4987] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-pcm-*-00:04:20:05:7a:ec
[09-06-11 17:45:39.5002] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-pcm-squeezebox-*
[09-06-11 17:45:39.5018] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-pcm-*-*
[09-06-11 17:45:39.5033] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-mp3-squeezebox-00:04:20:05:7a:ec
[09-06-11 17:45:39.5048] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-mp3-*-00:04:20:05:7a:ec
[09-06-11 17:45:39.5063] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-mp3-squeezebox-*
[09-06-11 17:45:39.5078] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-mp3-*-*
[09-06-11 17:45:39.5093] Slim::Player::TranscodingHelper::enabledFormat (199) Checking to see if ogg-mp3-*-* is enabled
[09-06-11 17:45:39.5108] Slim::Player::TranscodingHelper::enabledFormat (207) There are 1 disabled formats...
[09-06-11 17:45:39.5123] Slim::Player::TranscodingHelper::enabledFormat (213) Testing ogg-mp3-*-* vs ogg-mp3-*-*
[09-06-11 17:45:39.5138] Slim::Player::TranscodingHelper::enabledFormat (217) ** ogg-mp3-*-* Disabled **
[09-06-11 17:45:39.5153] Slim::Player::TranscodingHelper::getConvertCommand2 (430) Error: Didn't find any command matches for type:
ogg
[09-06-11 17:45:39.5177] Slim::Player::Song::open (330) seek=false time=0 canSeek=1
[09-06-11 17:45:39.5196] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-00:04:20:05:7a:ec
[09-06-11 17:45:39.5211] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-00:04:20:05:7a:ec
[09-06-11 17:45:39.5226] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-squeezebox-*
[09-06-11 17:45:39.5241] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: ogg-aif-*-*
[09-06-11 17:45:39.5256] Slim::Player::TranscodingHelper::enabledFormat (199) Checking to see if ogg-aif-*-* is enabled
[09-06-11 17:45:39.5272] Slim::Player::TranscodingHelper::enabledFormat (207) There are 1 disabled formats...
[09-06-11 17:45:39.5287] Slim::Player::TranscodingHelper::enabledFormat (213) Testing ogg-mp3-*-* vs ogg-aif-*-*
[09-06-11 17:45:39.5302] Slim::Player::TranscodingHelper::checkBin (240)    enabled
[09-06-11 17:45:39.5321] Slim::Player::TranscodingHelper::checkBin (242)   Found command: [sox] -q -t ogg $FILE$ -t raw -r 44100 -c
2 -2 -s $-x$ -
[09-06-11 17:45:39.5345] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: ogg->aif via: [sox] -q -t ogg $FILE$ -t
raw -r 44100 -c 2 -2 -s $-x$ -
[09-06-11 17:45:39.5369] Slim::Player::Song::open (351) Transcoder: streamMode=I, streamformat=aif
[09-06-11 17:45:39.5384] Slim::Player::Song::open (399) Opening stream (no direct streaming) using Slim::Player::Protocols::File [fi
le:///G:/ogg/Johnny%20A/Get%20Inside/05%20Get%20Inside.ogg]
[09-06-11 17:45:39.5415] Slim::Player::Protocols::File::open (78) duration: [378.613] size: [10844317] endian [] offset: [0] for fil
e:///G:/ogg/Johnny%20A/Get%20Inside/05%20Get%20Inside.ogg
[09-06-11 17:45:39.5439] Slim::Player::Protocols::File::open (95) Opening file G:\ogg\Johnny A\Get Inside\05 Get Inside.ogg
[09-06-11 17:45:39.5460] Slim::Player::Song::open (420) URL is a song (audio): file:///G:/ogg/Johnny%20A/Get%20Inside/05%20Get%20Ins
ide.ogg, type=ogg
[09-06-11 17:45:39.5493] Slim::Player::TranscodingHelper::tokenizeConvertCommand2 (536) Using command for conversion: "C:\Program Fi
les\SqueezeCenter 7.4 SQLite\server\Bin\MSWin32-x86-multi-thread\sox.exe" -q -t ogg "-" -t raw -r 44100 -c 2 -2 -s -x -
[09-06-11 17:45:39.5517] Slim::Player::Song::open (496) Tokenized command "C:\Program Files\SqueezeCenter 7.4 SQLite\server\Bin\MSWi
n32-x86-multi-thread\sox.exe" -q -t ogg "-" -t raw -r 44100 -c 2 -2 -s -x -
[09-06-11 17:45:39.5566] Slim::Player::Pipeline::new (93) Launching process with command: "C:\Program Files\SqueezeCenter 7.4 SQLite
\server\Bin\MSWin32-x86-multi-thread\socketwrapper.exe"  -D -i 2635 -o 2634 -c "\"C:\Program Files\SqueezeCenter 7.4 SQLite\server\B
in\MSWin32-x86-multi-thread\sox.exe\" -q -t ogg \"-\" -t raw -r 44100 -c 2 -2 -s -x -"
SW: 2009-06-11 17:45:39.576 Socketwrapper 1.10
SW: 2009-06-11 17:45:39.576 -i 2635 -o 2634 -c "C:\Program Files\SqueezeCenter 7.4 SQLite\server\Bin\MSWin32-x86-multi-thread\sox.ex
e" -q -t ogg "-" -t raw -r 44100 -c 2 -2 -s -x -
SW: 2009-06-11 17:45:39.576 Input from socket ...
SW: 2009-06-11 17:45:39.592 Input socket connected OK.
SW: 2009-06-11 17:45:39.592 Input socket pipe created OK.
SW: 2009-06-11 17:45:39.592 Init complete.
SW: 2009-06-11 17:45:39.592 # =input== =output= ==type== ===details===
SW: 2009-06-11 17:45:39.592 0 00000080 00000094  THREAD
SW: 2009-06-11 17:45:39.592 1 00000090 0000009c  PROCESS "C:\Program Files\SqueezeCenter 7.4 SQLite\server\Bin\MSWin32-x86-multi-thr
ead\sox.exe" -q -t ogg "-" -t raw -r 44100 -c 2 -2 -s -x -
SW: 2009-06-11 17:45:39.592 2 00000098 000000a0  THREAD  Output Socket
SW: 2009-06-11 17:45:39.592 MoveDataThreadProc for step 0 started.
SW: 2009-06-11 17:45:39.592 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.592 MoveDataThreadProc for step 2 started.
SW: 2009-06-11 17:45:39.592 MoveDataThreadProc for step 2 about to call ReadFile.
[09-06-11 17:45:39.6178] Slim::Player::SongStreamController::new (32) live=1
[09-06-11 17:45:39.6201] Slim::Player::StreamingController::_Stream (1058) 00:04:20:05:7a:ec: stream
[09-06-11 17:45:39.6240] Slim::Player::StreamingController::_Stream (1088) Song queue is now 4
[09-06-11 17:45:39.6256] Slim::Player::StreamingController::_setPlayingState (1969) new playing state BUFFERING
[09-06-11 17:45:39.6271] Slim::Player::StreamingController::_setStreamingState (1982) new streaming state STREAMING
[09-06-11 17:45:39.6287] Slim::Player::StreamingController::_eventAction (303) 00:04:20:05:7a:ec: NextTrackReady - new state BUFFERI
NG-STREAMING
[09-06-11 17:45:39.6313] Slim::Player::Song::DESTROY (119) DESTROY(Slim::Player::Song=HASH(0x876b4d4)) live=1: index=3, url=file:///
G:/ogg/Johnny%20A/Get%20Inside/04%20Sing%20Singin%27.ogg
[09-06-11 17:45:39.6337] Slim::Player::StreamingController::_eventAction (303) 00:04:20:05:7a:ec: Skip - new state BUFFERING-STREAMI
NG
[09-06-11 17:45:39.6381] Slim::Player::Source::songTime (104) songtime=0.0719089508056641 from byterate=176400, duration=378.613, by
tesReceived=0, fullness=0, startStream=0
[09-06-11 17:45:39.6437] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
[09-06-11 17:45:39.6464] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
[09-06-11 17:45:39.6490] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
[09-06-11 17:45:39.6516] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
[09-06-11 17:45:39.6542] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
[09-06-11 17:45:39.6570] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
[09-06-11 17:45:39.6596] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
[09-06-11 17:45:39.6622] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
[09-06-11 17:45:39.6650] Slim::Player::Pipeline::acceptReader (199) Pipeline reader connected
[09-06-11 17:45:39.6668] Slim::Player::Pipeline::acceptWriter (230) Pipeline writer connected
[09-06-11 17:45:39.6737] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
[09-06-11 17:45:39.6754] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.686 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
[09-06-11 17:45:39.6775] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
SW: 2009-06-11 17:45:39.686 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.686 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
[09-06-11 17:45:39.6810] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
SW: 2009-06-11 17:45:39.686 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.686 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
[09-06-11 17:45:39.6834] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.686 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:39.686 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.701 MoveDataThreadProc for step 0 about to call ReadFile.
[09-06-11 17:45:39.6884] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
SW: 2009-06-11 17:45:39.701 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
[09-06-11 17:45:39.6907] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
SW: 2009-06-11 17:45:39.701 MoveDataThreadProc for step 0 about to call ReadFile.
[09-06-11 17:45:39.6930] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.701 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.701 MoveDataThreadProc for step 0 about to call ReadFile.
[09-06-11 17:45:39.6952] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
SW: 2009-06-11 17:45:39.701 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.701 MoveDataThreadProc for step 0 about to call ReadFile.
[09-06-11 17:45:39.6992] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
SW: 2009-06-11 17:45:39.701 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.701 MoveDataThreadProc for step 0 about to call ReadFile.
[09-06-11 17:45:39.7016] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
[09-06-11 17:45:39.7061] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.7082] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.717 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.7276] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.733 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.733 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.7315] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
SW: 2009-06-11 17:45:39.733 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.748 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.7356] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in BUFFERING-STREA
MING -> Slim::Player::StreamingController::_NoOp
SW: 2009-06-11 17:45:39.748 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.748 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.7396] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.748 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.748 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.7440] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.748 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.748 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.748 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.748 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.7588] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.780 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.780 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.780 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.7884] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.795 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.795 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.795 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.795 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.795 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.795 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.795 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.811 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.8042] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.811 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.811 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.811 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.811 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.811 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.811 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.826 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.826 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.8557] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.858 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.858 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.873 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.873 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.873 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.873 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.873 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.873 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.8845] Slim::Player::StreamingController::playerTrackStarted (1787) 00:04:20:05:7a:ec
[09-06-11 17:45:39.8861] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: Started in BUFFERING-STREAMING ->
Slim::Player::StreamingController::_Playing
[09-06-11 17:45:39.8886] Slim::Player::StreamingController::_setPlayingState (1969) new playing state PLAYING
[09-06-11 17:45:39.8901] Slim::Player::StreamingController::_Playing (361) Song 4 has now started playing
[09-06-11 17:45:39.8922] Slim::Player::StreamingController::_Playing (390) Song queue is now 4
[09-06-11 17:45:39.8938] Slim::Player::StreamingController::_eventAction (303) 00:04:20:05:7a:ec: Started - new state PLAYING-STREAM
ING
[09-06-11 17:45:39.8971] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.905 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.905 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.905 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.905 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.905 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.905 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.905 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.920 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.9144] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.920 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.920 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.920 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.920 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.920 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.920 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.936 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.936 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:39.9356] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:39.9437] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:39.951 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.951 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.951 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.951 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.951 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.951 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:39.951 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:39.951 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:40.0900] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:40.092 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.092 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.108 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.108 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.108 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.108 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.108 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.108 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:40.2616] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:40.264 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.264 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:40.280 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.295 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:40.295 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.295 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:40.295 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.295 MoveDataThreadProc for step 0 about to call ReadFile.
[09-06-11 17:45:40.4613] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:40.467 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
[09-06-11 17:45:40.4631] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
[09-06-11 17:45:40.4655] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
[09-06-11 17:45:40.4762] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
[09-06-11 17:45:40.4780] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
[09-06-11 17:45:40.4795] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
[09-06-11 17:45:40.4811] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:40.483 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.483 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.483 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.483 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.483 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.483 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.483 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.483 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:40.6532] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:40.655 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.655 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.655 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.655 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.655 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.655 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.655 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.655 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:40.8419] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:40.842 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.842 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.842 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.842 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.842 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.842 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:40.842 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:40.858 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:40.9437] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:41.0210] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:41.014 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.014 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.014 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.014 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.014 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.014 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.014 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.014 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:41.2147] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:41.201 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.217 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.217 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.217 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.217 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.217 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.217 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.217 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:41.4055] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:41.405 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.405 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.405 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.405 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.405 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.405 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.405 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.405 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:41.5925] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:41.592 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.592 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.592 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.592 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.592 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.592 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.592 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.592 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:41.6560] Slim::Player::Source::songTime (104) songtime=1.04971655328798 from byterate=176400, duration=378.613, byte
sReceived=410260, fullness=225090, startStream=0
[09-06-11 17:45:41.6610] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:41.7639] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:41.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.764 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.764 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.764 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.764 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.764 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:41.9425] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:41.9560] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:41.951 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.951 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.951 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.951 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.951 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.951 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:41.951 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:41.951 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:41.9997] Slim::Player::Source::songTime (104) songtime=2.04959183673469 from byterate=176400, duration=378.613, byte
sReceived=588380, fullness=226832, startStream=0
[09-06-11 17:45:42.1393] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:42.139 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.139 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.139 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.155 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.155 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.155 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.155 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.155 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:42.3069] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:42.311 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.311 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.311 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.311 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.311 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.311 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.311 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.311 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:42.4978] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:42.498 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.498 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.498 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.498 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.514 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.514 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.514 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.514 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:42.6862] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:42.701 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.701 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.701 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.701 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.701 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.701 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.701 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.701 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:42.8786] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.889 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 0 about to call ReadFile.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:42.905 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:42.9288] Slim::Player::StreamingController::_eventAction (271) 00:04:20:05:7a:ec: StatusHeartbeat in PLAYING-STREAMI
NG -> Slim::Player::StreamingController::_CheckSync
[09-06-11 17:45:42.9860] Slim::Player::Source::songTime (104) songtime=3.04960317460317 from byterate=176400, duration=378.613, byte
sReceived=766500, fullness=228550, startStream=0
[09-06-11 17:45:43.0663] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:43.076 MoveDataThreadProc for step 0 got 8192 bytes, about to write data.
[09-06-11 17:45:43.0682] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
[09-06-11 17:45:43.0706] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
[09-06-11 17:45:43.0723] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
[09-06-11 17:45:43.0741] Slim::Player::Pipeline::sysread (303) Wrote 32768 bytes to pipeline writer
[09-06-11 17:45:43.0756] Slim::Player::Pipeline::sysread (275) Pipeline doesn't have pending bytes - trying to get some from source
[09-06-11 17:45:43.0773] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:43.092 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.092 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.092 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.092 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.092 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.092 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.092 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.092 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:43.2526] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:43.248 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.248 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.248 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.248 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.248 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.248 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.248 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.248 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:43.4445] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:43.436 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.436 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.436 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.436 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.436 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.451 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.451 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.451 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
[09-06-11 17:45:43.6340] Slim::Player::Pipeline::sysread (297) Attempting to write to pipeline writer
SW: 2009-06-11 17:45:43.623 MoveDataThreadProc for step 2 about to call ReadFile.
SW: 2009-06-11 17:45:43.623 MoveDataThreadProc for step 2 got 8192 bytes, about to write data.
SW: 2009-06-11 17:45:43.639 MoveDataThreadProc for step 2 about to call ReadFile.