===== Tuesday, April 21, 2009 8:18:25 AM US/Pacific ===== [09-04-21 08:18:36.9052] main::init (277) Starting SqueezeCenter (v7.4, r26132, Tue Apr 21 01:07:07 PDT 2009) [09-04-21 08:18:38.9371] main::init (277) Starting SqueezeCenter (v7.4, r26132, Tue Apr 21 01:07:07 PDT 2009) [09-04-21 08:18:39.6216] Slim::Player::TranscodingHelper::loadConversionTables (46) Loading conversion config files... [09-04-21 08:18:40.0921] Slim::Utils::PluginManager::enablePlugins (450) Couldn't load Plugins::OtherPlayers::Plugin. Error: Plugin is incompatible with this version of SqueezeCenter. Please update. [09-04-21 08:18:40.0925] Slim::Utils::PluginManager::enablePlugins (450) Couldn't load Plugins::SuperDateTime::Plugin. Error: Plugin is incompatible with this version of SqueezeCenter. Please update. [09-04-21 08:18:40.1350] Slim::Utils::PluginManager::enablePlugins (450) Couldn't load Plugins::XMRadio::Plugin. Error: Plugin is incompatible with this version of SqueezeCenter. Please update. [09-04-21 08:18:43.7618] Slim::Player::Source::streamingSongIndex (310) Adding song index 1 to song queue [09-04-21 08:18:43.7621] Slim::Player::Source::streamingSongIndex (314) Clearing out song queue first [09-04-21 08:18:43.7672] Slim::Player::Song::new (64) index 1 -> file:///Volumes/Music/Rhonda%20Smith/RS2/02%20To%20Get%20With%20You.m4a [09-04-21 08:18:43.7745] Slim::Player::Source::streamingSongIndex (330) Song queue is now 1 [09-04-21 08:18:43.9147] Slim::Player::Squeezebox::reconnect (119) 00:04:20:10:02:d7 forcing stop on pseudo-reconnect [09-04-21 08:18:43.9197] Slim::Player::Transporter::setDigitalInput (204) Switching to digital input 0 [09-04-21 08:18:44.0789] Slim::Player::Squeezebox::reconnect (119) 00:04:20:06:d1:7f forcing stop on pseudo-reconnect [09-04-21 08:18:44.0818] Slim::Player::StreamingController::playerInactive (1593) 00:04:20:06:d1:7f sync group now has: 00:04:20:06:d1:7f [09-04-21 08:18:44.0821] Slim::Player::StreamingController::playerInactive (1594) 00:04:20:06:d1:7f active players are: [09-04-21 08:18:46.6080] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: mp4 [09-04-21 08:18:48.3329] Slim::Utils::Strings::string (376) Error: missing string PLUGIN_WEATHERTIME_WIND_NORTH [09-04-21 08:18:48.3334] Slim::Utils::Strings::string (376) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Strings.pm line 376) frame 1: Slim::Utils::Strings::string (Plugins/WeatherTime/Plugin.pm line 2473) frame 2: Plugins::WeatherTime::Plugin::retrieveWeather (Plugins/WeatherTime/Plugin.pm line 2024) frame 3: Plugins::WeatherTime::Plugin::tictac (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Timers.pm line 211) frame 4: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Timers.pm line 211) frame 5: Slim::Utils::Timers::checkTimers (./slimserver.pl line 538) frame 6: main::idle (./slimserver.pl line 478) frame 7: main::main (./slimserver.pl line 1001) [09-04-21 08:18:48.3553] Slim::Utils::Strings::string (376) Error: missing string PLUGIN_WEATHERTIME_WIND_NORTH [09-04-21 08:18:48.3557] Slim::Utils::Strings::string (376) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Strings.pm line 376) frame 1: Slim::Utils::Strings::string (Plugins/WeatherTime/Plugin.pm line 2520) frame 2: Plugins::WeatherTime::Plugin::getDayInfos (Plugins/WeatherTime/Plugin.pm line 2492) frame 3: Plugins::WeatherTime::Plugin::retrieveWeather (Plugins/WeatherTime/Plugin.pm line 2024) frame 4: Plugins::WeatherTime::Plugin::tictac (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Timers.pm line 211) frame 5: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Timers.pm line 211) frame 6: Slim::Utils::Timers::checkTimers (./slimserver.pl line 538) frame 7: main::idle (./slimserver.pl line 478) frame 8: main::main (./slimserver.pl line 1001) [09-04-21 08:19:13.0050] Slim::Utils::Update::checkVersion (50) Checking version in 48072.4688539505 seconds [09-04-21 08:19:35.4160] Slim::Player::StreamingController::stop (1625) 00:04:20:10:02:d7 [09-04-21 08:19:35.4168] Slim::Player::Source::playmode (172) 00:04:20:10:02:d7: Current playmode: stop [09-04-21 08:19:35.5201] Slim::Player::StreamingController::playerActive (1551) 00:04:20:10:02:d7 sync group now has: 00:04:20:10:02:d7 [09-04-21 08:19:35.5204] Slim::Player::StreamingController::playerActive (1552) 00:04:20:10:02:d7 active players are: 00:04:20:10:02:d7 [09-04-21 08:19:35.5226] Slim::Player::StreamingController::play (1628) 00:04:20:10:02:d7 [09-04-21 08:19:35.5240] Slim::Player::StreamingController::_Stop (548) Song queue is now 1 [09-04-21 08:19:35.5244] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:19:35.5246] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:19:35.5288] Slim::Player::Song::new (64) index 0 -> file:///Volumes/Music/Return%20To%20Forever/The%20Anthology/1-01%20Hymn%20Of%20The%20Seventh%20Galaxy.m4a [09-04-21 08:19:35.5293] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state TRACKWAIT [09-04-21 08:19:35.5298] Slim::Player::Song::getNextSong (178) file:///Volumes/Music/Return%20To%20Forever/The%20Anthology/1-01%20Hymn%20Of%20The%20Seventh%20Galaxy.m4a [09-04-21 08:19:35.5302] Slim::Player::StreamingController::_nextTrackReady (668) 00:04:20:10:02:d7: nextTrack will be index 0 [09-04-21 08:19:35.5306] Slim::Player::StreamingController::_Stream (942) 00:04:20:10:02:d7: preparing to stream song index 0 [09-04-21 08:19:35.5309] Slim::Player::StreamingController::_Stream (958) Song queue is now 0 [09-04-21 08:19:35.5313] Slim::Player::Song::open (302) file:///Volumes/Music/Return%20To%20Forever/The%20Anthology/1-01%20Hymn%20Of%20The%20Seventh%20Galaxy.m4a [09-04-21 08:19:35.5355] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:19:35.5359] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDalc [09-04-21 08:19:35.5368] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: alc->pcm via: [alac] -r $FILE$ [09-04-21 08:19:35.5371] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=pcm [09-04-21 08:19:35.5380] Slim::Player::Song::open (460) Tokenized command "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/alac" -r "/Volumes/Music/Return To Forever/The Anthology/1-01 Hymn Of The Seventh Galaxy.m4a" & | [09-04-21 08:19:35.6217] Slim::Player::StreamingController::_Stream (1011) 00:04:20:10:02:d7: stream [09-04-21 08:19:35.6240] Slim::Player::Transporter::play (124) Setting DigitalInput to 0 for [file:///Volumes/Music/Return%20To%20Forever/The%20Anthology/1-01%20Hymn%20Of%20The%20Seventh%20Galaxy.m4a] [09-04-21 08:19:35.6244] Slim::Player::Transporter::setDigitalInput (204) Switching to digital input 0 [09-04-21 08:19:35.6372] Slim::Player::StreamingController::_Stream (1041) Song queue is now 0 [09-04-21 08:19:35.6384] Slim::Player::StreamingController::_setPlayingState (1882) new playing state BUFFERING [09-04-21 08:19:35.6388] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMING [09-04-21 08:19:36.2454] Slim::Player::StreamingController::playerTrackStarted (1701) 00:04:20:10:02:d7 [09-04-21 08:19:36.2457] Slim::Player::StreamingController::_setPlayingState (1882) new playing state PLAYING [09-04-21 08:19:36.2460] Slim::Player::StreamingController::_Playing (359) Song 0 has now started playing [09-04-21 08:19:36.2471] Slim::Player::StreamingController::_Playing (388) Song queue is now 0 [09-04-21 08:19:37.4412] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:20:02.9552] Slim::Player::StreamingController::stop (1625) 00:04:20:10:02:d7 [09-04-21 08:20:02.9569] Slim::Player::StreamingController::_Stop (548) Song queue is now 0 [09-04-21 08:20:02.9632] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:20:02.9636] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:20:02.9645] Slim::Player::Source::playmode (172) 00:04:20:10:02:d7: Current playmode: stop [09-04-21 08:20:03.0565] Slim::Player::StreamingController::play (1628) 00:04:20:10:02:d7 [09-04-21 08:20:03.0589] Slim::Player::StreamingController::_Stop (548) Song queue is now 0 [09-04-21 08:20:03.0592] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:20:03.0595] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:20:03.0640] Slim::Player::Song::new (64) index 0 -> file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a [09-04-21 08:20:03.0645] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state TRACKWAIT [09-04-21 08:20:03.0653] Slim::Player::Song::getNextSong (178) file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a [09-04-21 08:20:03.0657] Slim::Player::StreamingController::_nextTrackReady (668) 00:04:20:10:02:d7: nextTrack will be index 0 [09-04-21 08:20:03.0664] Slim::Player::StreamingController::_Stream (942) 00:04:20:10:02:d7: preparing to stream song index 0 [09-04-21 08:20:03.0667] Slim::Player::StreamingController::_Stream (958) Song queue is now 0 [09-04-21 08:20:03.0671] Slim::Player::Song::open (302) file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a [09-04-21 08:20:03.0690] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: mp4 [09-04-21 08:20:03.0693] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDmp4 [09-04-21 08:20:03.0702] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: mp4->flc via: [faad] -q -w -f 1 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - [09-04-21 08:20:03.0705] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=flc [09-04-21 08:20:03.0715] Slim::Player::Song::open (460) Tokenized command "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/faad" -q -w -f 1 "/Volumes/Music/Rhonda Smith/Intellipop/01 ITP.m4a" | "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/flac" -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - & | [09-04-21 08:20:03.1188] Slim::Player::StreamingController::_Stream (1011) 00:04:20:10:02:d7: stream [09-04-21 08:20:03.1213] Slim::Player::Transporter::play (124) Setting DigitalInput to 0 for [file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a] [09-04-21 08:20:03.1216] Slim::Player::Transporter::setDigitalInput (204) Switching to digital input 0 [09-04-21 08:20:03.1630] Slim::Player::StreamingController::_Stream (1041) Song queue is now 0 [09-04-21 08:20:03.1642] Slim::Player::StreamingController::_setPlayingState (1882) new playing state BUFFERING [09-04-21 08:20:03.1645] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMING [09-04-21 08:20:03.4022] Slim::Player::Source::_readNextChunk (499) end of file or error on socket, song pos: 9633792 [09-04-21 08:20:03.4026] Slim::Player::Source::_readNextChunk (506) Didn't stream any bytes for this song; mark it as failed [09-04-21 08:20:03.4029] Slim::Player::StreamingController::playerStreamingFailed (1759) 00:04:20:10:02:d7 [09-04-21 08:20:03.4034] Slim::Player::StreamingController::_playersMessage (711) Problem: Can't open file for:: file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a [09-04-21 08:20:03.4062] Slim::Player::StreamingController::_Stop (548) Song queue is now 0 [09-04-21 08:20:03.4067] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:20:03.4070] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:20:03.4076] Slim::Player::StreamingController::nextsong (776) The next song is number 1, was 0 [09-04-21 08:20:03.4125] Slim::Player::Song::new (64) index 1 -> file:///Volumes/Music/Rhonda%20Smith/Intellipop/02%20Say.m4a [09-04-21 08:20:03.4130] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state TRACKWAIT [09-04-21 08:20:03.4134] Slim::Player::Song::getNextSong (178) file:///Volumes/Music/Rhonda%20Smith/Intellipop/02%20Say.m4a [09-04-21 08:20:03.4138] Slim::Player::StreamingController::_nextTrackReady (668) 00:04:20:10:02:d7: nextTrack will be index 1 [09-04-21 08:20:03.4141] Slim::Player::StreamingController::_Stream (942) 00:04:20:10:02:d7: preparing to stream song index 1 [09-04-21 08:20:03.4144] Slim::Player::StreamingController::_Stream (958) Song queue is now 1 [09-04-21 08:20:03.4148] Slim::Player::Song::open (302) file:///Volumes/Music/Rhonda%20Smith/Intellipop/02%20Say.m4a [09-04-21 08:20:03.4170] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:20:03.4173] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDalc [09-04-21 08:20:03.4182] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: alc->pcm via: [alac] -r $FILE$ [09-04-21 08:20:03.4185] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=pcm [09-04-21 08:20:03.4195] Slim::Player::Song::open (460) Tokenized command "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/alac" -r "/Volumes/Music/Rhonda Smith/Intellipop/02 Say.m4a" & | [09-04-21 08:20:03.4546] Slim::Player::StreamingController::_Stream (1011) 00:04:20:10:02:d7: stream [09-04-21 08:20:03.4570] Slim::Player::Transporter::play (124) Setting DigitalInput to 0 for [file:///Volumes/Music/Rhonda%20Smith/Intellipop/02%20Say.m4a] [09-04-21 08:20:03.4574] Slim::Player::Transporter::setDigitalInput (204) Switching to digital input 0 [09-04-21 08:20:03.4610] Slim::Player::StreamingController::_Stream (1041) Song queue is now 1 [09-04-21 08:20:03.4627] Slim::Player::StreamingController::_setPlayingState (1882) new playing state BUFFERING [09-04-21 08:20:03.4633] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMING [09-04-21 08:20:03.7924] Slim::Player::StreamingController::playerTrackStarted (1701) 00:04:20:10:02:d7 [09-04-21 08:20:03.7928] Slim::Player::StreamingController::_setPlayingState (1882) new playing state PLAYING [09-04-21 08:20:03.7931] Slim::Player::StreamingController::_Playing (359) Song 1 has now started playing [09-04-21 08:20:03.7941] Slim::Player::StreamingController::_Playing (388) Song queue is now 1 [09-04-21 08:20:12.6613] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:21:01.0113] Slim::Player::StreamingController::stop (1625) 00:04:20:10:02:d7 [09-04-21 08:21:01.0128] Slim::Player::StreamingController::_Stop (548) Song queue is now 1 [09-04-21 08:21:01.0133] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:21:01.0136] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:21:01.0144] Slim::Player::Source::playmode (172) 00:04:20:10:02:d7: Current playmode: stop [09-04-21 08:21:01.3291] Slim::Player::StreamingController::play (1628) 00:04:20:10:02:d7 [09-04-21 08:21:01.3305] Slim::Player::StreamingController::_Stop (548) Song queue is now 1 [09-04-21 08:21:01.3308] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:21:01.3311] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:21:01.3365] Slim::Player::Song::new (64) index 0 -> file:///Volumes/Music/Rhonda%20Smith/RS2/01%20What%20Do%20They%20Think%20Music%20Is_.m4a [09-04-21 08:21:01.3370] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state TRACKWAIT [09-04-21 08:21:01.3375] Slim::Player::Song::getNextSong (178) file:///Volumes/Music/Rhonda%20Smith/RS2/01%20What%20Do%20They%20Think%20Music%20Is_.m4a [09-04-21 08:21:01.3379] Slim::Player::StreamingController::_nextTrackReady (668) 00:04:20:10:02:d7: nextTrack will be index 0 [09-04-21 08:21:01.3382] Slim::Player::StreamingController::_Stream (942) 00:04:20:10:02:d7: preparing to stream song index 0 [09-04-21 08:21:01.3385] Slim::Player::StreamingController::_Stream (958) Song queue is now 0 [09-04-21 08:21:01.3389] Slim::Player::Song::open (302) file:///Volumes/Music/Rhonda%20Smith/RS2/01%20What%20Do%20They%20Think%20Music%20Is_.m4a [09-04-21 08:21:01.3408] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:21:01.3411] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDalc [09-04-21 08:21:01.3420] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: alc->pcm via: [alac] -r $FILE$ [09-04-21 08:21:01.3423] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=pcm [09-04-21 08:21:01.3432] Slim::Player::Song::open (460) Tokenized command "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/alac" -r "/Volumes/Music/Rhonda Smith/RS2/01 What Do They Think Music Is_.m4a" & | [09-04-21 08:21:01.3725] Slim::Player::StreamingController::_Stream (1011) 00:04:20:10:02:d7: stream [09-04-21 08:21:01.3749] Slim::Player::Transporter::play (124) Setting DigitalInput to 0 for [file:///Volumes/Music/Rhonda%20Smith/RS2/01%20What%20Do%20They%20Think%20Music%20Is_.m4a] [09-04-21 08:21:01.3752] Slim::Player::Transporter::setDigitalInput (204) Switching to digital input 0 [09-04-21 08:21:01.3834] Slim::Player::StreamingController::_Stream (1041) Song queue is now 0 [09-04-21 08:21:01.3847] Slim::Player::StreamingController::_setPlayingState (1882) new playing state BUFFERING [09-04-21 08:21:01.3850] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMING [09-04-21 08:21:01.6215] Slim::Player::StreamingController::playerTrackStarted (1701) 00:04:20:10:02:d7 [09-04-21 08:21:01.6219] Slim::Player::StreamingController::_setPlayingState (1882) new playing state PLAYING [09-04-21 08:21:01.6222] Slim::Player::StreamingController::_Playing (359) Song 0 has now started playing [09-04-21 08:21:01.6232] Slim::Player::StreamingController::_Playing (388) Song queue is now 0 [09-04-21 08:21:01.7183] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:21:03.1753] Slim::Player::Source::_readNextChunk (499) end of file or error on socket, song pos: 29507504 [09-04-21 08:21:03.1757] Slim::Player::Source::_readNextChunk (513) 00:04:20:10:02:d7 mark end of stream [09-04-21 08:21:03.1763] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMOUT [09-04-21 08:21:03.5791] Slim::Player::StreamingController::playerEndOfStream (1791) 00:04:20:10:02:d7 [09-04-21 08:21:03.5795] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMOUT [09-04-21 08:21:13.4762] Slim::Player::StreamingController::stop (1625) 00:04:20:10:02:d7 [09-04-21 08:21:13.4778] Slim::Player::StreamingController::_Stop (548) Song queue is now 0 [09-04-21 08:21:13.4781] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:21:13.4784] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:21:13.4791] Slim::Player::Source::playmode (172) 00:04:20:10:02:d7: Current playmode: stop [09-04-21 08:21:13.4931] Slim::Player::StreamingController::play (1628) 00:04:20:10:02:d7 [09-04-21 08:21:13.4956] Slim::Player::StreamingController::_Stop (548) Song queue is now 0 [09-04-21 08:21:13.4959] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:21:13.4961] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:21:13.4998] Slim::Player::Song::new (64) index 0 -> file:///Volumes/Music/Richard%20Marx/Richard%20Marx/01%20Should%27ve%20Known%20Better.m4a [09-04-21 08:21:13.5003] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state TRACKWAIT [09-04-21 08:21:13.5008] Slim::Player::Song::getNextSong (178) file:///Volumes/Music/Richard%20Marx/Richard%20Marx/01%20Should%27ve%20Known%20Better.m4a [09-04-21 08:21:13.5012] Slim::Player::StreamingController::_nextTrackReady (668) 00:04:20:10:02:d7: nextTrack will be index 0 [09-04-21 08:21:13.5015] Slim::Player::StreamingController::_Stream (942) 00:04:20:10:02:d7: preparing to stream song index 0 [09-04-21 08:21:13.5018] Slim::Player::StreamingController::_Stream (958) Song queue is now 0 [09-04-21 08:21:13.5022] Slim::Player::Song::open (302) file:///Volumes/Music/Richard%20Marx/Richard%20Marx/01%20Should%27ve%20Known%20Better.m4a [09-04-21 08:21:13.5041] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: mp4 [09-04-21 08:21:13.5044] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDmp4 [09-04-21 08:21:13.5052] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: mp4->flc via: [faad] -q -w -f 1 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - [09-04-21 08:21:13.5055] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=flc [09-04-21 08:21:13.5065] Slim::Player::Song::open (460) Tokenized command "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/faad" -q -w -f 1 "/Volumes/Music/Richard Marx/Richard Marx/01 Should've Known Better.m4a" | "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/flac" -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - & | [09-04-21 08:21:13.5358] Slim::Player::StreamingController::_Stream (1011) 00:04:20:10:02:d7: stream [09-04-21 08:21:13.5383] Slim::Player::Transporter::play (124) Setting DigitalInput to 0 for [file:///Volumes/Music/Richard%20Marx/Richard%20Marx/01%20Should%27ve%20Known%20Better.m4a] [09-04-21 08:21:13.5387] Slim::Player::Transporter::setDigitalInput (204) Switching to digital input 0 [09-04-21 08:21:13.5474] Slim::Player::StreamingController::_Stream (1041) Song queue is now 0 [09-04-21 08:21:13.5486] Slim::Player::StreamingController::_setPlayingState (1882) new playing state BUFFERING [09-04-21 08:21:13.5489] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMING [09-04-21 08:21:13.7165] Slim::Player::Source::_readNextChunk (499) end of file or error on socket, song pos: 29507504 [09-04-21 08:21:13.7168] Slim::Player::Source::_readNextChunk (506) Didn't stream any bytes for this song; mark it as failed [09-04-21 08:21:13.7171] Slim::Player::StreamingController::playerStreamingFailed (1759) 00:04:20:10:02:d7 [09-04-21 08:21:13.7176] Slim::Player::StreamingController::_playersMessage (711) Problem: Can't open file for:: file:///Volumes/Music/Richard%20Marx/Richard%20Marx/01%20Should%27ve%20Known%20Better.m4a [09-04-21 08:21:13.7205] Slim::Player::StreamingController::_Stop (548) Song queue is now 0 [09-04-21 08:21:13.7210] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:21:13.7213] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:21:13.7218] Slim::Player::StreamingController::nextsong (776) The next song is number 1, was 0 [09-04-21 08:21:13.7265] Slim::Player::Song::new (64) index 1 -> file:///Volumes/Music/Richard%20Marx/Richard%20Marx/02%20Don%27t%20Mean%20Nothing.m4a [09-04-21 08:21:13.7270] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state TRACKWAIT [09-04-21 08:21:13.7274] Slim::Player::Song::getNextSong (178) file:///Volumes/Music/Richard%20Marx/Richard%20Marx/02%20Don%27t%20Mean%20Nothing.m4a [09-04-21 08:21:13.7278] Slim::Player::StreamingController::_nextTrackReady (668) 00:04:20:10:02:d7: nextTrack will be index 1 [09-04-21 08:21:13.7281] Slim::Player::StreamingController::_Stream (942) 00:04:20:10:02:d7: preparing to stream song index 1 [09-04-21 08:21:13.7284] Slim::Player::StreamingController::_Stream (958) Song queue is now 1 [09-04-21 08:21:13.7288] Slim::Player::Song::open (302) file:///Volumes/Music/Richard%20Marx/Richard%20Marx/02%20Don%27t%20Mean%20Nothing.m4a [09-04-21 08:21:13.7310] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:21:13.7314] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDalc [09-04-21 08:21:13.7322] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: alc->pcm via: [alac] -r $FILE$ [09-04-21 08:21:13.7325] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=pcm [09-04-21 08:21:13.7335] Slim::Player::Song::open (460) Tokenized command "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/alac" -r "/Volumes/Music/Richard Marx/Richard Marx/02 Don't Mean Nothing.m4a" & | [09-04-21 08:21:13.7628] Slim::Player::StreamingController::_Stream (1011) 00:04:20:10:02:d7: stream [09-04-21 08:21:13.7653] Slim::Player::Transporter::play (124) Setting DigitalInput to 0 for [file:///Volumes/Music/Richard%20Marx/Richard%20Marx/02%20Don%27t%20Mean%20Nothing.m4a] [09-04-21 08:21:13.7657] Slim::Player::Transporter::setDigitalInput (204) Switching to digital input 0 [09-04-21 08:21:13.7693] Slim::Player::StreamingController::_Stream (1041) Song queue is now 1 [09-04-21 08:21:13.7704] Slim::Player::StreamingController::_setPlayingState (1882) new playing state BUFFERING [09-04-21 08:21:13.7708] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMING [09-04-21 08:21:14.2166] Slim::Player::StreamingController::playerTrackStarted (1701) 00:04:20:10:02:d7 [09-04-21 08:21:14.2170] Slim::Player::StreamingController::_setPlayingState (1882) new playing state PLAYING [09-04-21 08:21:14.2173] Slim::Player::StreamingController::_Playing (359) Song 1 has now started playing [09-04-21 08:21:14.2186] Slim::Player::StreamingController::_Playing (388) Song queue is now 1 [09-04-21 08:21:18.5826] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:21:29.4930] Slim::Player::StreamingController::stop (1625) 00:04:20:10:02:d7 [09-04-21 08:21:29.4947] Slim::Player::StreamingController::_Stop (548) Song queue is now 1 [09-04-21 08:21:29.4953] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:21:29.4956] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:21:29.4965] Slim::Player::Source::playmode (172) 00:04:20:10:02:d7: Current playmode: stop [09-04-21 08:21:29.5100] Slim::Player::StreamingController::play (1628) 00:04:20:10:02:d7 [09-04-21 08:21:29.5118] Slim::Player::StreamingController::_Stop (548) Song queue is now 1 [09-04-21 08:21:29.5121] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:21:29.5124] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:21:29.5170] Slim::Player::Song::new (64) index 0 -> file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a [09-04-21 08:21:29.5175] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state TRACKWAIT [09-04-21 08:21:29.5180] Slim::Player::Song::getNextSong (178) file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a [09-04-21 08:21:29.5184] Slim::Player::StreamingController::_nextTrackReady (668) 00:04:20:10:02:d7: nextTrack will be index 0 [09-04-21 08:21:29.5187] Slim::Player::StreamingController::_Stream (942) 00:04:20:10:02:d7: preparing to stream song index 0 [09-04-21 08:21:29.5190] Slim::Player::StreamingController::_Stream (958) Song queue is now 0 [09-04-21 08:21:29.5194] Slim::Player::Song::open (302) file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a [09-04-21 08:21:29.5213] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: mp4 [09-04-21 08:21:29.5216] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDmp4 [09-04-21 08:21:29.5225] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: mp4->flc via: [faad] -q -w -f 1 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - [09-04-21 08:21:29.5228] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=flc [09-04-21 08:21:29.5239] Slim::Player::Song::open (460) Tokenized command "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/faad" -q -w -f 1 "/Volumes/Music/Rhonda Smith/Intellipop/01 ITP.m4a" | "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/flac" -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - & | [09-04-21 08:21:29.5685] Slim::Player::StreamingController::_Stream (1011) 00:04:20:10:02:d7: stream [09-04-21 08:21:29.5709] Slim::Player::Transporter::play (124) Setting DigitalInput to 0 for [file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a] [09-04-21 08:21:29.5713] Slim::Player::Transporter::setDigitalInput (204) Switching to digital input 0 [09-04-21 08:21:29.5802] Slim::Player::StreamingController::_Stream (1041) Song queue is now 0 [09-04-21 08:21:29.5814] Slim::Player::StreamingController::_setPlayingState (1882) new playing state BUFFERING [09-04-21 08:21:29.5819] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMING [09-04-21 08:21:29.7731] Slim::Player::Source::_readNextChunk (499) end of file or error on socket, song pos: 37175216 [09-04-21 08:21:29.7735] Slim::Player::Source::_readNextChunk (506) Didn't stream any bytes for this song; mark it as failed [09-04-21 08:21:29.7738] Slim::Player::StreamingController::playerStreamingFailed (1759) 00:04:20:10:02:d7 [09-04-21 08:21:29.7743] Slim::Player::StreamingController::_playersMessage (711) Problem: Can't open file for:: file:///Volumes/Music/Rhonda%20Smith/Intellipop/01%20ITP.m4a [09-04-21 08:21:29.7759] Slim::Player::StreamingController::_Stop (548) Song queue is now 0 [09-04-21 08:21:29.7765] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:21:29.7768] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:21:29.7773] Slim::Player::StreamingController::nextsong (776) The next song is number 1, was 0 [09-04-21 08:21:29.7817] Slim::Player::Song::new (64) index 1 -> file:///Volumes/Music/Rhonda%20Smith/Intellipop/02%20Say.m4a [09-04-21 08:21:29.7822] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state TRACKWAIT [09-04-21 08:21:29.7826] Slim::Player::Song::getNextSong (178) file:///Volumes/Music/Rhonda%20Smith/Intellipop/02%20Say.m4a [09-04-21 08:21:29.7830] Slim::Player::StreamingController::_nextTrackReady (668) 00:04:20:10:02:d7: nextTrack will be index 1 [09-04-21 08:21:29.7833] Slim::Player::StreamingController::_Stream (942) 00:04:20:10:02:d7: preparing to stream song index 1 [09-04-21 08:21:29.7836] Slim::Player::StreamingController::_Stream (958) Song queue is now 1 [09-04-21 08:21:29.7840] Slim::Player::Song::open (302) file:///Volumes/Music/Rhonda%20Smith/Intellipop/02%20Say.m4a [09-04-21 08:21:29.7862] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:21:29.7865] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDalc [09-04-21 08:21:29.7874] Slim::Player::TranscodingHelper::getConvertCommand2 (458) Matched: alc->pcm via: [alac] -r $FILE$ [09-04-21 08:21:29.7877] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=pcm [09-04-21 08:21:29.7886] Slim::Player::Song::open (460) Tokenized command "/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Bin/darwin/alac" -r "/Volumes/Music/Rhonda Smith/Intellipop/02 Say.m4a" & | [09-04-21 08:21:29.8234] Slim::Player::StreamingController::_Stream (1011) 00:04:20:10:02:d7: stream [09-04-21 08:21:29.8259] Slim::Player::Transporter::play (124) Setting DigitalInput to 0 for [file:///Volumes/Music/Rhonda%20Smith/Intellipop/02%20Say.m4a] [09-04-21 08:21:29.8263] Slim::Player::Transporter::setDigitalInput (204) Switching to digital input 0 [09-04-21 08:21:29.8299] Slim::Player::StreamingController::_Stream (1041) Song queue is now 1 [09-04-21 08:21:29.8310] Slim::Player::StreamingController::_setPlayingState (1882) new playing state BUFFERING [09-04-21 08:21:29.8313] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state STREAMING [09-04-21 08:21:30.1201] Slim::Player::StreamingController::playerTrackStarted (1701) 00:04:20:10:02:d7 [09-04-21 08:21:30.1206] Slim::Player::StreamingController::_setPlayingState (1882) new playing state PLAYING [09-04-21 08:21:30.1209] Slim::Player::StreamingController::_Playing (359) Song 1 has now started playing [09-04-21 08:21:30.1225] Slim::Player::StreamingController::_Playing (388) Song queue is now 1 [09-04-21 08:21:36.9527] Slim::Player::StreamingController::stop (1625) 00:04:20:10:02:d7 [09-04-21 08:21:36.9542] Slim::Player::StreamingController::_Stop (548) Song queue is now 1 [09-04-21 08:21:36.9548] Slim::Player::StreamingController::_setPlayingState (1882) new playing state STOPPED [09-04-21 08:21:36.9592] Slim::Player::StreamingController::_setStreamingState (1895) new streaming state IDLE [09-04-21 08:21:36.9602] Slim::Player::Source::playmode (172) 00:04:20:10:02:d7: Current playmode: stop [09-04-21 08:21:37.2169] Slim::Player::TranscodingHelper::getConvertCommand2 (455) Error: Didn't find any command matches for type: alc [09-04-21 08:21:41.4456] Slim::Utils::Strings::string (376) Error: missing string PLUGIN_WEATHERTIME_WIND_NORTH [09-04-21 08:21:41.4461] Slim::Utils::Strings::string (376) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Strings.pm line 376) frame 1: Slim::Utils::Strings::string (Plugins/WeatherTime/Plugin.pm line 2473) frame 2: Plugins::WeatherTime::Plugin::retrieveWeather (Plugins/WeatherTime/Plugin.pm line 2024) frame 3: Plugins::WeatherTime::Plugin::tictac (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Timers.pm line 211) frame 4: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Timers.pm line 211) frame 5: Slim::Utils::Timers::checkTimers (./slimserver.pl line 538) frame 6: main::idle (./slimserver.pl line 478) frame 7: main::main (./slimserver.pl line 1001) [09-04-21 08:21:41.4674] Slim::Utils::Strings::string (376) Error: missing string PLUGIN_WEATHERTIME_WIND_NORTH [09-04-21 08:21:41.4678] Slim::Utils::Strings::string (376) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Strings.pm line 376) frame 1: Slim::Utils::Strings::string (Plugins/WeatherTime/Plugin.pm line 2520) frame 2: Plugins::WeatherTime::Plugin::getDayInfos (Plugins/WeatherTime/Plugin.pm line 2492) frame 3: Plugins::WeatherTime::Plugin::retrieveWeather (Plugins/WeatherTime/Plugin.pm line 2024) frame 4: Plugins::WeatherTime::Plugin::tictac (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Timers.pm line 211) frame 5: (eval) (/Library/PreferencePanes/SqueezeCenter.prefPane/Contents/server/Slim/Utils/Timers.pm line 211) frame 6: Slim::Utils::Timers::checkTimers (./slimserver.pl line 538) frame 7: main::idle (./slimserver.pl line 478) frame 8: main::main (./slimserver.pl line 1001)