-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2023-09-05 12:58:36 UTC. -- Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioNext Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::next Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::stPlaybackTimer Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 9 Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::serviceStop Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 9 Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreCommandRouter::serviceStop Sep 05 12:57:10 eshoxmusic volumio[975]: info: ControllerMpd::stop Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand stop took 11 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::play index undefined Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 10 Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::startPlaybackTimer Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 10 Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::clearAddPlayTracks USB/Z-MUSIC-X/alternativeROCKS/09.-My Plague (New Abuse Mix).flac Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:10 eshoxmusic volumio[975]: info: Sep 05 12:57:10 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:10 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand stop took 2 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 12:57:10 eshoxmusic volumio[975]: info: Sep 05 12:57:10 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:10 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand status took 4 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand clear took 2 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/09.-My Plague (New Abuse Mix).flac" Sep 05 12:57:10 eshoxmusic volumio[975]: error: updateQueue error: null Sep 05 12:57:10 eshoxmusic volumio[975]: info: Sep 05 12:57:10 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:10 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:10 eshoxmusic volumio[975]: info: ------------------------------ 4ms Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/09.-My Plague (New Abuse Mix).flac" took 3 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand play Sep 05 12:57:10 eshoxmusic volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 12:57:10 eshoxmusic volumio[975]: info: ------------------------------ 10ms Sep 05 12:57:10 eshoxmusic volumio[975]: info: ------------------------------ 4ms Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand play took 2 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: info: Sep 05 12:57:10 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:10 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:10 eshoxmusic volumio[975]: info: Sep 05 12:57:10 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand status took 25 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand status took 3 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:10 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 10 Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Plague (New Abuse Mix)","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/09.-My Plague (New Abuse Mix).flac","trackType":"flac"} Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: CURRENT POSITION 10 Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:57:10 eshoxmusic volumio[975]: info: ------------------------------ 37ms Sep 05 12:57:10 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:10 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 10 Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"712 Kbps","isStreaming":false,"title":"My Plague (New Abuse Mix)","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/09.-My Plague (New Abuse Mix).flac","trackType":"flac"} Sep 05 12:57:10 eshoxmusic volumio[975]: verbose: CURRENT POSITION 10 Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus play Sep 05 12:57:10 eshoxmusic volumio[975]: info: Received an update from plugin. extracting info from payload Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 10 Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 10 Sep 05 12:57:10 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:10 eshoxmusic volumio[975]: info: ------------------------------ 35ms Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioNext Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreStateMachine::next Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreStateMachine::stPlaybackTimer Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:11 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:11 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 10 Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreStateMachine::serviceStop Sep 05 12:57:11 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 10 Sep 05 12:57:11 eshoxmusic volumio[975]: info: CoreCommandRouter::serviceStop Sep 05 12:57:11 eshoxmusic volumio[975]: info: ControllerMpd::stop Sep 05 12:57:11 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:12 eshoxmusic volumio[975]: info: Sep 05 12:57:12 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand stop took 126 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::play index undefined Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::startPlaybackTimer Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::clearAddPlayTracks USB/Z-MUSIC-X/alternativeROCKS/10.-The Heretic Anthem (Live).flac Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand status took 5 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand stop took 2 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 12:57:12 eshoxmusic volumio[975]: info: Sep 05 12:57:12 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:12 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand clear took 3 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/10.-The Heretic Anthem (Live).flac" Sep 05 12:57:12 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Plague (New Abuse Mix)","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/09.-My Plague (New Abuse Mix).flac","trackType":"flac"} Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: CURRENT POSITION 11 Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService stop Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: No code Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: ------------------------------ 31ms Sep 05 12:57:12 eshoxmusic volumio[975]: error: updateQueue error: null Sep 05 12:57:12 eshoxmusic volumio[975]: info: Sep 05 12:57:12 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:12 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:12 eshoxmusic volumio[975]: info: ------------------------------ 25ms Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/10.-The Heretic Anthem (Live).flac" took 22 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand play Sep 05 12:57:12 eshoxmusic volumio[975]: info: ------------------------------ 11ms Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand play took 9 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: info: Sep 05 12:57:12 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:12 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:12 eshoxmusic volumio[975]: info: Sep 05 12:57:12 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand status took 27 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand status took 2 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:12 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Heretic Anthem (Live)","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/10.-The Heretic Anthem (Live).flac","trackType":"flac"} Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: CURRENT POSITION 11 Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:57:12 eshoxmusic volumio[975]: info: ------------------------------ 41ms Sep 05 12:57:12 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:12 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"887 Kbps","isStreaming":false,"title":"The Heretic Anthem (Live)","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/10.-The Heretic Anthem (Live).flac","trackType":"flac"} Sep 05 12:57:12 eshoxmusic volumio[975]: verbose: CURRENT POSITION 11 Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus play Sep 05 12:57:12 eshoxmusic volumio[975]: info: Received an update from plugin. extracting info from payload Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:12 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:12 eshoxmusic volumio[975]: info: ------------------------------ 29ms Sep 05 12:57:13 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 12:57:13 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 12:57:13 eshoxmusic volumio[975]: info: Discovery: Getting this device information Sep 05 12:57:13 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioGetState Sep 05 12:57:13 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:13 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 12:57:13 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 12:57:13 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 12:57:13 eshoxmusic volumio[975]: info: Discovery: Getting this device information Sep 05 12:57:13 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioGetState Sep 05 12:57:13 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:13 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 12:57:14 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume30 Sep 05 12:57:14 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:14 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:14 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:14 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:14 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume30 Sep 05 12:57:14 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:14 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:14 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:14 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:14 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume40 Sep 05 12:57:14 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:14 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:14 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:14 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:15 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume40 Sep 05 12:57:15 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:15 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:15 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:15 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioNext Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::next Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::stPlaybackTimer Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::serviceStop Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 11 Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreCommandRouter::serviceStop Sep 05 12:57:22 eshoxmusic volumio[975]: info: ControllerMpd::stop Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand stop took 12 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::play index undefined Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 12 Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::startPlaybackTimer Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 12 Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::clearAddPlayTracks USB/Z-MUSIC-X/alternativeROCKS/13.-Duality.flac Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:22 eshoxmusic volumio[975]: info: Sep 05 12:57:22 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:22 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand stop took 3 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 12:57:22 eshoxmusic volumio[975]: info: Sep 05 12:57:22 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:22 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand status took 3 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand clear took 3 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/13.-Duality.flac" Sep 05 12:57:22 eshoxmusic volumio[975]: error: updateQueue error: null Sep 05 12:57:22 eshoxmusic volumio[975]: info: Sep 05 12:57:22 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:22 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:22 eshoxmusic volumio[975]: info: ------------------------------ 7ms Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/13.-Duality.flac" took 4 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand play Sep 05 12:57:22 eshoxmusic volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 12:57:22 eshoxmusic volumio[975]: info: ------------------------------ 15ms Sep 05 12:57:22 eshoxmusic volumio[975]: info: ------------------------------ 6ms Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand play took 3 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: info: Sep 05 12:57:22 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:22 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:22 eshoxmusic volumio[975]: info: Sep 05 12:57:22 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand status took 24 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand status took 2 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:22 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 12 Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Duality","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/13.-Duality.flac","trackType":"flac"} Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: CURRENT POSITION 12 Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:57:22 eshoxmusic volumio[975]: info: ------------------------------ 47ms Sep 05 12:57:22 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:22 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 12 Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"534 Kbps","isStreaming":false,"title":"Duality","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/13.-Duality.flac","trackType":"flac"} Sep 05 12:57:22 eshoxmusic volumio[975]: verbose: CURRENT POSITION 12 Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus play Sep 05 12:57:22 eshoxmusic volumio[975]: info: Received an update from plugin. extracting info from payload Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 12 Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 12 Sep 05 12:57:22 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:22 eshoxmusic volumio[975]: info: ------------------------------ 42ms Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioNext Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::next Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::stPlaybackTimer Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 12 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::serviceStop Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 12 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::serviceStop Sep 05 12:57:23 eshoxmusic volumio[975]: info: ControllerMpd::stop Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:23 eshoxmusic volumio[975]: info: Sep 05 12:57:23 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand stop took 102 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::play index undefined Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::startPlaybackTimer Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::clearAddPlayTracks USB/Z-MUSIC-X/alternativeROCKS/15.-Vermilion (Terry Date Mix).flac Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand status took 5 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand stop took 2 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 12:57:23 eshoxmusic volumio[975]: info: Sep 05 12:57:23 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:23 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand clear took 2 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/15.-Vermilion (Terry Date Mix).flac" Sep 05 12:57:23 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Duality","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/13.-Duality.flac","trackType":"flac"} Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: CURRENT POSITION 13 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService stop Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: No code Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: ------------------------------ 31ms Sep 05 12:57:23 eshoxmusic volumio[975]: error: updateQueue error: null Sep 05 12:57:23 eshoxmusic volumio[975]: info: Sep 05 12:57:23 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:23 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:23 eshoxmusic volumio[975]: info: ------------------------------ 20ms Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/15.-Vermilion (Terry Date Mix).flac" took 18 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand play Sep 05 12:57:23 eshoxmusic volumio[975]: info: ------------------------------ 5ms Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand play took 4 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: info: Sep 05 12:57:23 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:23 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand status took 22 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:23 eshoxmusic volumio[975]: info: Sep 05 12:57:23 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:23 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:23 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Vermilion (Terry Date Mix)","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/15.-Vermilion (Terry Date Mix).flac","trackType":"flac"} Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: CURRENT POSITION 13 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:57:23 eshoxmusic volumio[975]: info: ------------------------------ 32ms Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand status took 3 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:23 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:23 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"749 Kbps","isStreaming":false,"title":"Vermilion (Terry Date Mix)","artist":"Slipknot","album":"Antennas To Hell: The Best Of Slipknot (Special Edition)","uri":"USB/Z-MUSIC-X/alternativeROCKS/15.-Vermilion (Terry Date Mix).flac","trackType":"flac"} Sep 05 12:57:23 eshoxmusic volumio[975]: verbose: CURRENT POSITION 13 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus play Sep 05 12:57:23 eshoxmusic volumio[975]: info: Received an update from plugin. extracting info from payload Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:23 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:23 eshoxmusic volumio[975]: info: ------------------------------ 21ms Sep 05 12:57:27 eshoxmusic ntpd[659]: Soliciting pool server 222.127.1.27 Sep 05 12:57:29 eshoxmusic ntpd[659]: Soliciting pool server 222.127.1.25 Sep 05 12:57:33 eshoxmusic volumio[975]: info: Preload queue cleared Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::ClearQueue Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::stPlaybackTimer Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::serviceStop Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 13 Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreCommandRouter::serviceStop Sep 05 12:57:33 eshoxmusic volumio[975]: info: ControllerMpd::stop Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::clearPlayQueue Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::saveQueue Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushQueue Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::addQueueItems Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::addQueueItems Sep 05 12:57:33 eshoxmusic volumio[975]: info: Preload queue cleared Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/Korn - Blind.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/Korn - Blind.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/04.-The Devil In I.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/04.-The Devil In I.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/04.-Psychosocial.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/04.-Psychosocial.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/11.-Snuff.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/11.-Snuff.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/14.-Vermilion Pt. 2 (Bloodstone Mix).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/14.-Vermilion Pt. 2 (Bloodstone Mix).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/03.-Wait And Bleed.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/03.-Wait And Bleed.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/04.-Spit It Out.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/04.-Spit It Out.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/05.-Surfacing.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/05.-Surfacing.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/06.-People = Shit.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/06.-People = Shit.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/08.-Left Behind.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/08.-Left Behind.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/09.-My Plague (New Abuse Mix).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/09.-My Plague (New Abuse Mix).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/10.-The Heretic Anthem (Live).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/10.-The Heretic Anthem (Live).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/13.-Duality.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/13.-Duality.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/15.-Vermilion (Terry Date Mix).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/15.-Vermilion (Terry Date Mix).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/17.-Psychosocial (2012 Remaster).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/17.-Psychosocial (2012 Remaster).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/19.-Snuff (Remastered).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/19.-Snuff (Remastered).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/07 - Never Gonna Be Alone.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/07 - Never Gonna Be Alone.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/Scorpions - Send Me An Angel.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/Scorpions - Send Me An Angel.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/01-under_the_bridge.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/01-under_the_bridge.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/02-give_it_away.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/02-give_it_away.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/03-californication.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/03-californication.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/04-scar_tissue.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/04-scar_tissue.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/06-otherside.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/06-otherside.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/07-suck_my_kiss.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/07-suck_my_kiss.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/08-by_the_way.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/08-by_the_way.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/05. My Hero.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/05. My Hero.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/06. Learn to Fly.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/06. Learn to Fly.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/10. Breakout.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/10. Breakout.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/04-stone_sour-hydrograd.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/04-stone_sour-hydrograd.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/06-stone_sour-fabuless.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/06-stone_sour-fabuless.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/11-stone_sour-mercy.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/11-stone_sour-mercy.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/01-disturbed-the_eye_of_the_storm.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/01-disturbed-the_eye_of_the_storm.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/02-disturbed-immortalized.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/02-disturbed-immortalized.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/03-disturbed-the_vengeful_one.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/03-disturbed-the_vengeful_one.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/04-disturbed-open_your_eyes.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/04-disturbed-open_your_eyes.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/05-disturbed-the_light.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/05-disturbed-the_light.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/11-disturbed-the_sound_of_silence.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/11-disturbed-the_sound_of_silence.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/01.-(515).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/01.-(515).flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/02.-People = Shit.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/02.-People = Shit.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/04.-My Plague.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/04.-My Plague.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/06.-The Heretic Anthem.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/06.-The Heretic Anthem.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/Metallica - Enter Sandman.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/Metallica - Enter Sandman.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/Metallica - The Unforgiven.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/Metallica - The Unforgiven.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/Metallica - Nothing Else Matters.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/Metallica - Nothing Else Matters.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/A2 B.Y.O.B..flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/A2 B.Y.O.B..flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/B1 Violent Pornography.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/B1 Violent Pornography.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/03 - Stupify.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/03 - Stupify.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/05 - Down With The Sickness.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/05 - Down With The Sickness.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/alternativeROCKS/02. Unsainted.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/alternativeROCKS/02. Unsainted.flac Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushQueue Sep 05 12:57:33 eshoxmusic ntpd[659]: Soliciting pool server 2a02:2a50:6::123 Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::saveQueue Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPlay Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::play index 46 Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::play index undefined Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:33 eshoxmusic volumio[975]: info: CoreStateMachine::startPlaybackTimer Sep 05 12:57:33 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::clearAddPlayTracks USB/Z-MUSIC-X/alternativeROCKS/03 - Stupify.flac Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:57:33 eshoxmusic volumio[975]: info: Sep 05 12:57:33 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand stop took 79 milliseconds Sep 05 12:57:33 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand stop took 11 milliseconds Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 12:57:33 eshoxmusic volumio[975]: info: Sep 05 12:57:33 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:33 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand status took 6 milliseconds Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand clear took 6 milliseconds Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/03 - Stupify.flac" Sep 05 12:57:33 eshoxmusic volumio[975]: error: updateQueue error: null Sep 05 12:57:33 eshoxmusic volumio[975]: info: Sep 05 12:57:33 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:57:33 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:57:33 eshoxmusic volumio[975]: info: ------------------------------ 9ms Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/03 - Stupify.flac" took 5 milliseconds Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand play Sep 05 12:57:33 eshoxmusic volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 12:57:33 eshoxmusic volumio[975]: info: ------------------------------ 20ms Sep 05 12:57:33 eshoxmusic volumio[975]: info: ------------------------------ 9ms Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand play took 6 milliseconds Sep 05 12:57:33 eshoxmusic volumio[975]: info: Sep 05 12:57:33 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:33 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:33 eshoxmusic volumio[975]: info: Sep 05 12:57:33 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand status took 19 milliseconds Sep 05 12:57:33 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:33 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand status took 3 milliseconds Sep 05 12:57:33 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 12:57:34 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:57:34 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:57:34 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:34 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:34 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:34 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03 - Stupify","artist":"Disturbed","album":"The Sickness","uri":"USB/Z-MUSIC-X/alternativeROCKS/03 - Stupify.flac","trackType":"flac"} Sep 05 12:57:34 eshoxmusic volumio[975]: verbose: CURRENT POSITION 46 Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:57:34 eshoxmusic volumio[975]: info: ------------------------------ 33ms Sep 05 12:57:34 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 05 12:57:34 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:57:34 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:57:34 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:34 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03 - Stupify","artist":"Disturbed","album":"The Sickness","uri":"USB/Z-MUSIC-X/alternativeROCKS/03 - Stupify.flac","trackType":"flac"} Sep 05 12:57:34 eshoxmusic volumio[975]: verbose: CURRENT POSITION 46 Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus play Sep 05 12:57:34 eshoxmusic volumio[975]: info: Received an update from plugin. extracting info from payload Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:34 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:34 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:34 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:34 eshoxmusic volumio[975]: info: ------------------------------ 29ms Sep 05 12:57:36 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume50 Sep 05 12:57:36 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:36 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:36 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:36 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:36 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume50 Sep 05 12:57:36 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:36 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:36 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:36 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:37 eshoxmusic ntpd[659]: Soliciting pool server 222.127.1.20 Sep 05 12:57:38 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume40 Sep 05 12:57:38 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:38 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:38 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:38 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:38 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume40 Sep 05 12:57:38 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:38 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:38 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:38 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:41 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume50 Sep 05 12:57:41 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:41 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:41 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:41 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:57:41 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume50 Sep 05 12:57:41 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:57:41 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:57:41 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:57:41 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioNext Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::next Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::stPlaybackTimer Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::serviceStop Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 46 Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreCommandRouter::serviceStop Sep 05 12:58:00 eshoxmusic volumio[975]: info: ControllerMpd::stop Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand stop took 42 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::play index undefined Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 47 Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::startPlaybackTimer Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 47 Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::clearAddPlayTracks USB/Z-MUSIC-X/alternativeROCKS/05 - Down With The Sickness.flac Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:58:00 eshoxmusic volumio[975]: info: Sep 05 12:58:00 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:00 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand stop took 2 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 12:58:00 eshoxmusic volumio[975]: info: Sep 05 12:58:00 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:58:00 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand status took 2 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand clear took 2 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/05 - Down With The Sickness.flac" Sep 05 12:58:00 eshoxmusic volumio[975]: error: updateQueue error: null Sep 05 12:58:00 eshoxmusic volumio[975]: info: Sep 05 12:58:00 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:58:00 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:58:00 eshoxmusic volumio[975]: info: ------------------------------ 3ms Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/05 - Down With The Sickness.flac" took 3 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand play Sep 05 12:58:00 eshoxmusic volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 12:58:00 eshoxmusic volumio[975]: info: ------------------------------ 8ms Sep 05 12:58:00 eshoxmusic volumio[975]: info: ------------------------------ 4ms Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand play took 2 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: info: Sep 05 12:58:00 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:00 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand status took 22 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:00 eshoxmusic volumio[975]: info: Sep 05 12:58:00 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:00 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:00 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 47 Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 - Down With The Sickness","artist":"Disturbed","album":"The Sickness","uri":"USB/Z-MUSIC-X/alternativeROCKS/05 - Down With The Sickness.flac","trackType":"flac"} Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: CURRENT POSITION 47 Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:58:00 eshoxmusic volumio[975]: info: ------------------------------ 34ms Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand status took 6 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:00 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:00 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 47 Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 - Down With The Sickness","artist":"Disturbed","album":"The Sickness","uri":"USB/Z-MUSIC-X/alternativeROCKS/05 - Down With The Sickness.flac","trackType":"flac"} Sep 05 12:58:00 eshoxmusic volumio[975]: verbose: CURRENT POSITION 47 Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus play Sep 05 12:58:00 eshoxmusic volumio[975]: info: Received an update from plugin. extracting info from payload Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 47 Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 47 Sep 05 12:58:00 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:00 eshoxmusic volumio[975]: info: ------------------------------ 25ms Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioNext Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::next Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::stPlaybackTimer Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 47 Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::serviceStop Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 47 Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreCommandRouter::serviceStop Sep 05 12:58:04 eshoxmusic volumio[975]: info: ControllerMpd::stop Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand stop took 11 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::play index undefined Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::startPlaybackTimer Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::clearAddPlayTracks USB/Z-MUSIC-X/alternativeROCKS/02. Unsainted.flac Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:58:04 eshoxmusic volumio[975]: info: Sep 05 12:58:04 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:04 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand stop took 3 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 12:58:04 eshoxmusic volumio[975]: info: Sep 05 12:58:04 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:58:04 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand status took 4 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand clear took 3 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/02. Unsainted.flac" Sep 05 12:58:04 eshoxmusic volumio[975]: error: updateQueue error: null Sep 05 12:58:04 eshoxmusic volumio[975]: info: Sep 05 12:58:04 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:58:04 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:58:04 eshoxmusic volumio[975]: info: ------------------------------ 6ms Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand add "USB/Z-MUSIC-X/alternativeROCKS/02. Unsainted.flac" took 4 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand play Sep 05 12:58:04 eshoxmusic volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 12:58:04 eshoxmusic volumio[975]: info: ------------------------------ 14ms Sep 05 12:58:04 eshoxmusic volumio[975]: info: ------------------------------ 7ms Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand play took 4 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: info: Sep 05 12:58:04 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:04 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:04 eshoxmusic volumio[975]: info: Sep 05 12:58:04 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand status took 24 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand status took 3 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:04 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unsainted","artist":"Slipknot","album":"We Are Not Your Kind","uri":"USB/Z-MUSIC-X/alternativeROCKS/02. Unsainted.flac","trackType":"flac"} Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: CURRENT POSITION 48 Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:58:04 eshoxmusic volumio[975]: info: ------------------------------ 38ms Sep 05 12:58:04 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:04 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unsainted","artist":"Slipknot","album":"We Are Not Your Kind","uri":"USB/Z-MUSIC-X/alternativeROCKS/02. Unsainted.flac","trackType":"flac"} Sep 05 12:58:04 eshoxmusic volumio[975]: verbose: CURRENT POSITION 48 Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus play Sep 05 12:58:04 eshoxmusic volumio[975]: info: Received an update from plugin. extracting info from payload Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:04 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:04 eshoxmusic volumio[975]: info: ------------------------------ 33ms Sep 05 12:58:05 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume40 Sep 05 12:58:05 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:05 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:05 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:05 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:06 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume40 Sep 05 12:58:06 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:06 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:06 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:06 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:14 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 05 12:58:14 eshoxmusic volumio[975]: info: CURURI: music-library/USB/Z-MUSIC-X/AudioPhile Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preload queue cleared Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD1.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD2.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/01 Don't Know Why.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 04 - Caribbean Blue.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/01-Insensatez.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/02-The First Time.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/03-Ain't No Sunshine.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/04-Zombie.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/05-Something So Right.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/06-Baker Street.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/07-Maria Mulata.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/08-Spooky.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/09-Someone To Watch Over Me.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/10-Cherokee.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/11-Fly Me to the Moon.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/12-Can't Help Falling.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/13-Have You Ever Seen The Rain.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/14-That's What Friends Are For.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Preloading song: music-library/USB/Z-MUSIC-X/AudioPhile/15-Full Moon in Paris.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD1.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=//extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2FSnow%20Rose%20-%20The%20Best%20of%20Snow%20Rose%20CD1.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD1.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD2.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=//extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2FSnow%20Rose%20-%20The%20Best%20of%20Snow%20Rose%20CD2.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD2.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/01 Don't Know Why.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Norah%20Jones/Come%20Away%20With%20Me/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F01%20Don't%20Know%20Why.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/01 Don't Know Why.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Norah%20Jones/Come%20Away%20With%20Me/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F05%20Come%20Away%20With%20Me.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 04 - Caribbean Blue.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=ENYA/The%20Very%20Best%20Of%20Enya/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2FENYA%20-%20The%20Very%20Best%20Of%20Enya%20-%2004%20-%20Caribbean%20Blue.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 04 - Caribbean Blue.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=ENYA/The%20Very%20Best%20Of%20Enya/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2FENYA%20-%20The%20Very%20Best%20Of%20Enya%20-%2007%20-%20Only%20Time.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/01-Insensatez.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Rosa%20Passos%20and%20Ron%20Carter/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F01-Insensatez.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/01-Insensatez.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/02-The First Time.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Macy%20Gray/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F02-The%20First%20Time.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/02-The First Time.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/03-Ain't No Sunshine.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=The%20Persuasions/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F03-Ain't%20No%20Sunshine.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/03-Ain't No Sunshine.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/04-Zombie.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Meiko/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F04-Zombie.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/04-Zombie.flac Sep 05 12:58:14 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/05-Something So Right.flac in service mpd Sep 05 12:58:14 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Alexis%20Cole/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F05-Something%20So%20Right.flac&metadata=false Sep 05 12:58:14 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/05-Something So Right.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/06-Baker Street.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Livingston%20Taylor/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F06-Baker%20Street.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/06-Baker Street.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/07-Maria Mulata.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Marta%20Gomez/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F07-Maria%20Mulata.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/07-Maria Mulata.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/08-Spooky.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Christy%20Baron/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F08-Spooky.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/08-Spooky.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/09-Someone To Watch Over Me.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Kenny%20Rankin/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F09-Someone%20To%20Watch%20Over%20Me.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/09-Someone To Watch Over Me.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/10-Cherokee.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Camille%20Thurman/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F10-Cherokee.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/10-Cherokee.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/11-Fly Me to the Moon.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Ana%20Caram/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F11-Fly%20Me%20to%20the%20Moon.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/11-Fly Me to the Moon.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/12-Can't Help Falling.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Melissa%20Menago/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F12-Can't%20Help%20Falling.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/12-Can't Help Falling.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/13-Have You Ever Seen The Rain.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Casey%20Abrams/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F13-Have%20You%20Ever%20Seen%20The%20Rain.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/13-Have You Ever Seen The Rain.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/14-That's What Friends Are For.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Valerie%20Joyce/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F14-That's%20What%20Friends%20Are%20For.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/14-That's What Friends Are For.flac Sep 05 12:58:15 eshoxmusic volumio[975]: info: Exploding uri music-library/USB/Z-MUSIC-X/AudioPhile/15-Full Moon in Paris.flac in service mpd Sep 05 12:58:15 eshoxmusic volumio[975]: info: ALBUMART /albumart?cacheid=713&web=Amber%20Rubarth/The%20World's%20Greatest%20Audiophile%20Vocal%20Recordings%20Vol.%20II/extralarge&path=%2Fmnt%2FUSB%2FZ-MUSIC-X%2FAudioPhile%2F15-Full%20Moon%20in%20Paris.flac&metadata=false Sep 05 12:58:15 eshoxmusic volumio[975]: info: URI /mnt/USB/Z-MUSIC-X/AudioPhile/15-Full Moon in Paris.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Preload queue cleared Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::ClearQueue Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::stPlaybackTimer Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::serviceStop Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 48 Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::serviceStop Sep 05 12:58:17 eshoxmusic volumio[975]: info: ControllerMpd::stop Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::clearPlayQueue Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::saveQueue Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushQueue Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::addQueueItems Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::addQueueItems Sep 05 12:58:17 eshoxmusic volumio[975]: info: Preload queue cleared Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD1.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD1.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD2.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD2.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/01 Don't Know Why.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/01 Don't Know Why.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 04 - Caribbean Blue.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 04 - Caribbean Blue.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/01-Insensatez.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/01-Insensatez.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/02-The First Time.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/02-The First Time.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/03-Ain't No Sunshine.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/03-Ain't No Sunshine.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/04-Zombie.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/04-Zombie.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/05-Something So Right.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/05-Something So Right.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/06-Baker Street.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/06-Baker Street.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/07-Maria Mulata.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/07-Maria Mulata.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/08-Spooky.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/08-Spooky.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/09-Someone To Watch Over Me.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/09-Someone To Watch Over Me.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/10-Cherokee.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/10-Cherokee.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/11-Fly Me to the Moon.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/11-Fly Me to the Moon.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/12-Can't Help Falling.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/12-Can't Help Falling.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/13-Have You Ever Seen The Rain.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/13-Have You Ever Seen The Rain.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/14-That's What Friends Are For.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/14-That's What Friends Are For.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/15-Full Moon in Paris.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/15-Full Moon in Paris.flac Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushQueue Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::saveQueue Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPlay Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::play index 3 Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::play index undefined Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::startPlaybackTimer Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::clearAddPlayTracks USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand stop took 45 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: info: Sep 05 12:58:17 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:17 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand stop took 21 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 12:58:17 eshoxmusic volumio[975]: info: Sep 05 12:58:17 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:58:17 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand status took 3 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand clear took 3 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand add "USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac" Sep 05 12:58:17 eshoxmusic volumio[975]: error: updateQueue error: null Sep 05 12:58:17 eshoxmusic volumio[975]: info: Sep 05 12:58:17 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:58:17 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:58:17 eshoxmusic volumio[975]: info: ------------------------------ 5ms Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand add "USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac" took 4 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand play Sep 05 12:58:17 eshoxmusic volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 12:58:17 eshoxmusic volumio[975]: info: ------------------------------ 10ms Sep 05 12:58:17 eshoxmusic volumio[975]: info: ------------------------------ 5ms Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand play took 5 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: info: Sep 05 12:58:17 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:17 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:17 eshoxmusic volumio[975]: info: Sep 05 12:58:17 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:17 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand status took 16 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand status took 4 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:17 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":198,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"Come Away With Me","artist":"Norah Jones","album":"Come Away With Me","uri":"USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac","trackType":"flac"} Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: CURRENT POSITION 3 Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:58:17 eshoxmusic volumio[975]: info: ------------------------------ 23ms Sep 05 12:58:17 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:17 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":561,"duration":198,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"Come Away With Me","artist":"Norah Jones","album":"Come Away With Me","uri":"USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac","trackType":"flac"} Sep 05 12:58:17 eshoxmusic volumio[975]: verbose: CURRENT POSITION 3 Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus play Sep 05 12:58:17 eshoxmusic volumio[975]: info: Received an update from plugin. extracting info from payload Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:17 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:17 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:18 eshoxmusic volumio[975]: info: ------------------------------ 25ms Sep 05 12:58:26 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume50 Sep 05 12:58:26 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:26 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:26 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:26 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:26 eshoxmusic volumio[975]: info: VolumeController::SetAlsaVolume50 Sep 05 12:58:26 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:26 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:26 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:26 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:34 eshoxmusic volumio[975]: info: Preload queue cleared Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreStateMachine::ClearQueue Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreStateMachine::stPlaybackTimer Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:58:34 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:34 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreStateMachine::serviceStop Sep 05 12:58:34 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 3 Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreCommandRouter::serviceStop Sep 05 12:58:34 eshoxmusic volumio[975]: info: ControllerMpd::stop Sep 05 12:58:34 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:58:34 eshoxmusic volumio[975]: info: CorePlayQueue::clearPlayQueue Sep 05 12:58:34 eshoxmusic volumio[975]: info: CorePlayQueue::saveQueue Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushQueue Sep 05 12:58:34 eshoxmusic volumio[975]: info: CoreStateMachine::addQueueItems Sep 05 12:58:34 eshoxmusic volumio[975]: info: CorePlayQueue::addQueueItems Sep 05 12:58:34 eshoxmusic volumio[975]: info: Preload queue cleared Sep 05 12:58:34 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD1.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD1.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD2.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/Snow Rose - The Best of Snow Rose CD2.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/01 Don't Know Why.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/01 Don't Know Why.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/05 Come Away With Me.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 04 - Caribbean Blue.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 04 - Caribbean Blue.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/01-Insensatez.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/01-Insensatez.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/02-The First Time.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/02-The First Time.flac Sep 05 12:58:34 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/03-Ain't No Sunshine.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/03-Ain't No Sunshine.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/04-Zombie.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/04-Zombie.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/05-Something So Right.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/05-Something So Right.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/06-Baker Street.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/06-Baker Street.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/07-Maria Mulata.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/07-Maria Mulata.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/08-Spooky.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/08-Spooky.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/09-Someone To Watch Over Me.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/09-Someone To Watch Over Me.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/10-Cherokee.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/10-Cherokee.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/11-Fly Me to the Moon.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/11-Fly Me to the Moon.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/12-Can't Help Falling.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/12-Can't Help Falling.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/13-Have You Ever Seen The Rain.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/13-Have You Ever Seen The Rain.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/14-That's What Friends Are For.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/14-That's What Friends Are For.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Adding Item to queue: music-library/USB/Z-MUSIC-X/AudioPhile/15-Full Moon in Paris.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: Using cached record of: music-library/USB/Z-MUSIC-X/AudioPhile/15-Full Moon in Paris.flac Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushQueue Sep 05 12:58:35 eshoxmusic volumio[975]: info: CorePlayQueue::saveQueue Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::updateTrackBlock Sep 05 12:58:35 eshoxmusic volumio[975]: info: CorePlayQueue::getTrackBlock Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPlay Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::play index 5 Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::stop Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::play index undefined Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 12:58:35 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 5 Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::startPlaybackTimer Sep 05 12:58:35 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 5 Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::clearAddPlayTracks USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 12:58:35 eshoxmusic ntpd[659]: Soliciting pool server 222.127.1.26 Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand stop took 119 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: info: Sep 05 12:58:35 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:35 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand stop took 93 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 12:58:35 eshoxmusic volumio[975]: info: Sep 05 12:58:35 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:58:35 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand status took 2 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand clear took 1 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand add "USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac" Sep 05 12:58:35 eshoxmusic volumio[975]: error: updateQueue error: null Sep 05 12:58:35 eshoxmusic volumio[975]: info: Sep 05 12:58:35 eshoxmusic volumio[975]: ---------------------------- MPD announces system playlist update Sep 05 12:58:35 eshoxmusic volumio[975]: info: Ignoring MPD Status Update Sep 05 12:58:35 eshoxmusic volumio[975]: info: ------------------------------ 3ms Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand add "USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac" took 3 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand play Sep 05 12:58:35 eshoxmusic volumio[975]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 12:58:35 eshoxmusic volumio[975]: info: ------------------------------ 10ms Sep 05 12:58:35 eshoxmusic volumio[975]: info: ------------------------------ 5ms Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand play took 4 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: info: Sep 05 12:58:35 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:35 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:35 eshoxmusic volumio[975]: info: Sep 05 12:58:35 eshoxmusic volumio[975]: ---------------------------- MPD announces state update: player Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand status took 11 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: info: ControllerMpd::getState Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand status Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand status took 3 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::parseState Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:35 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:58:35 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 5 Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Only Time","artist":"ENYA","album":"The Very Best Of Enya","uri":"USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac","trackType":"flac"} Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: CURRENT POSITION 5 Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus stop Sep 05 12:58:35 eshoxmusic volumio[975]: info: ------------------------------ 20ms Sep 05 12:58:35 eshoxmusic volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: ControllerMpd::parseTrackInfo Sep 05 12:58:35 eshoxmusic volumio[975]: info: ControllerMpd::pushState Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreCommandRouter::servicePushState Sep 05 12:58:35 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 5 Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"566 Kbps","isStreaming":false,"title":"Only Time","artist":"ENYA","album":"The Very Best Of Enya","uri":"USB/Z-MUSIC-X/AudioPhile/ENYA - The Very Best Of Enya - 07 - Only Time.flac","trackType":"flac"} Sep 05 12:58:35 eshoxmusic volumio[975]: verbose: CURRENT POSITION 5 Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::syncState stateService play Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::syncState currentStatus play Sep 05 12:58:35 eshoxmusic volumio[975]: info: Received an update from plugin. extracting info from payload Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:35 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 5 Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreStateMachine::pushState Sep 05 12:58:35 eshoxmusic volumio[975]: info: CorePlayQueue::getTrack 5 Sep 05 12:58:35 eshoxmusic volumio[975]: info: CoreCommandRouter::volumioPushState Sep 05 12:58:35 eshoxmusic volumio[975]: info: ------------------------------ 24ms Sep 05 12:58:35 eshoxmusic ntpd[659]: Soliciting pool server 222.127.1.24 Sep 05 12:58:35 eshoxmusic volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 12:58:35 eshoxmusic volumio[975]: Error: certificate is not yet valid Sep 05 12:58:35 eshoxmusic volumio[975]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Sep 05 12:58:35 eshoxmusic volumio[975]: at TLSSocket.emit (events.js:315:20) Sep 05 12:58:35 eshoxmusic volumio[975]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Sep 05 12:58:35 eshoxmusic volumio[975]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Sep 05 12:58:35 eshoxmusic volumio[975]: code: 'CERT_NOT_YET_VALID' Sep 05 12:58:35 eshoxmusic volumio[975]: } Sep 05 12:58:35 eshoxmusic volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 12:58:36 eshoxmusic sudo[3946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-09-05 12:57 Sep 05 12:58:36 eshoxmusic sudo[3946]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"