-- Logs begin at Thu 2019-02-14 07:11:59 -03, end at Wed 2024-10-30 18:17:30 -03. -- Oct 30 18:16:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 18:16:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 18:16:00 voldi volumio[1045]: info: Discovery: Getting this device information Oct 30 18:16:00 voldi volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 30 18:16:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 1 Oct 30 18:16:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 18:16:21 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 30 18:16:21 voldi volumio[1045]: info: CURURI: music-library/NAS/DiNas/Crystal Castles Oct 30 18:16:21 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:16:23 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 30 18:16:23 voldi volumio[1045]: info: CURURI: music-library/NAS/DiNas/Crystal Castles/Albums Oct 30 18:16:23 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:16:26 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::ClearQueue Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::stop Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::stPlaybackTimer Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 1 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::serviceStop Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 1 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::serviceStop Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::stop Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::clearPlayQueue Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::saveQueue Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::addQueueItems Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::addQueueItems Oct 30 18:16:26 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:16:26 voldi volumio[1045]: info: Adding Item to queue: music-library/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps] Oct 30 18:16:26 voldi volumio[1045]: info: Exploding uri music-library/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps] in service mpd Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F01%20-%20Untrust%20Us.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F02%20-%20Alice%20Practice.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/02 - Alice Practice.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F03%20-%20Crimewave%20(Crystal%20Castles%20vs.%20Health).mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/03 - Crimewave (Crystal Castles vs. Health).mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F04%20-%20Magic%20Spells.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/04 - Magic Spells.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F05%20-%20Xxzxcuzx%20Me.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/05 - Xxzxcuzx Me.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F06%20-%20Air%20War.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/06 - Air War.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F07%20-%20Courtship%20Dating.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/07 - Courtship Dating.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F08%20-%20Good%20Time.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/08 - Good Time.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F09%20-%201991.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/09 - 1991.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F10%20-%20Vanished.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/10 - Vanished.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F11%20-%20Knights.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/11 - Knights.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F12%20-%20Love%20And%20Caring.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/12 - Love And Caring.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F13%20-%20Through%20The%20Hosiery.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/13 - Through The Hosiery.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F14%20-%20Reckless.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/14 - Reckless.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F15%20-%20Black%20Panther.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/15 - Black Panther.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Crystal%20Castles/Crystal%20Castles/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FCrystal%20Castles%2FAlbums%2F2008%20-%20Crystal%20Castles%20%5B320%20kbps%5D%2F16%20-%20Tell%20Me%20What%20To%20Swallow.mp3&metadata=false Oct 30 18:16:26 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/16 - Tell Me What To Swallow.mp3 Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand stop took 128 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::saveQueue Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::play index 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::stop Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::play index undefined Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::clearAddPlayTracks NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3 Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand status took 19 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand stop took 4 milliseconds Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:26 voldi volumio[1045]: info: touch_display: Setting screensaver timeout to 30 seconds. Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:26 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand clear took 6 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand status took 4 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand status took 4 milliseconds Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3" Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: 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":"Feldspar","artist":"Yvat","album":"Kunzite [BOLTLP007]","uri":"NAS/DiNas/[BOLTLP007] Yvat - Kunzite-2009-320kbps/02-yvat-feldspar-ulc.mp3","trackType":"mp3"} Oct 30 18:16:26 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState stateService stop Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: No code Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 30 18:16:26 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState stateService stop Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: No code Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 30 18:16:26 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState stateService stop Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: No code Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 59ms Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 31ms Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 30ms Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:26 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:26 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:26 voldi volumio[1045]: error: updateQueue error: null Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:26 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 30ms Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand add "NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3" took 28 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 2ms Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 1ms Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:26 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:26 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 18ms Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand play took 17 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 16ms Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 30 18:16:26 voldi volumio[1045]: info: touch_display: Setting screensaver timeout to 30 seconds. Oct 30 18:16:26 voldi kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand status took 20 milliseconds Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:26 voldi volumio[1045]: info: Oct 30 18:16:26 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand status took 23 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand status took 23 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Untrust Us","artist":"Crystal Castles","album":"Crystal Castles","uri":"NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3","trackType":"mp3"} Oct 30 18:16:26 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 27ms Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand status took 7 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand status took 7 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand status took 7 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Untrust Us","artist":"Crystal Castles","album":"Crystal Castles","uri":"NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3","trackType":"mp3"} Oct 30 18:16:26 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:26 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Untrust Us","artist":"Crystal Castles","album":"Crystal Castles","uri":"NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3","trackType":"mp3"} Oct 30 18:16:26 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:26 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 73ms Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 74ms Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 30 18:16:26 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:26 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Untrust Us","artist":"Crystal Castles","album":"Crystal Castles","uri":"NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3","trackType":"mp3"} Oct 30 18:16:26 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:26 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Untrust Us","artist":"Crystal Castles","album":"Crystal Castles","uri":"NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3","trackType":"mp3"} Oct 30 18:16:26 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:26 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":188,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Untrust Us","artist":"Crystal Castles","album":"Crystal Castles","uri":"NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3","trackType":"mp3"} Oct 30 18:16:26 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:26 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:26 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:26 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 98ms Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 98ms Oct 30 18:16:26 voldi volumio[1045]: info: ------------------------------ 98ms Oct 30 18:16:26 voldi volumio[1045]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 30 18:16:30 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 18:16:30 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 18:16:30 voldi volumio[1045]: info: Discovery: Getting this device information Oct 30 18:16:30 voldi volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 30 18:16:30 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:30 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 18:16:51 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::ClearQueue Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::stop Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::stPlaybackTimer Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::serviceStop Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::serviceStop Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::stop Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::clearPlayQueue Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::saveQueue Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::addQueueItems Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::addQueueItems Oct 30 18:16:52 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:16:52 voldi volumio[1045]: info: Adding Item to queue: music-library/NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC Oct 30 18:16:52 voldi volumio[1045]: info: Exploding uri music-library/NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC in service mpd Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:52 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Deadbeat/Eastward%20On%20To%20Mecca%20Vinyl/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FDeadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC%2F01-deadbeat-mecca.mp3&metadata=false Oct 30 18:16:52 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3 Oct 30 18:16:52 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Deadbeat/Eastward%20On%20To%20Mecca%20Vinyl/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FDeadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC%2F02-deadbeat-mecca_(dub).mp3&metadata=false Oct 30 18:16:52 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/02-deadbeat-mecca_(dub).mp3 Oct 30 18:16:52 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Deadbeat/Eastward%20On%20To%20Mecca%20Vinyl/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FDeadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC%2F03-deadbeat-mecca_(drum_jack).mp3&metadata=false Oct 30 18:16:52 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/03-deadbeat-mecca_(drum_jack).mp3 Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand stop took 53 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::saveQueue Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::play index 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::stop Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::play index undefined Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::clearAddPlayTracks NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3 Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand status took 9 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand stop took 4 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand status took 2 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand status took 1 milliseconds Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:52 voldi volumio[1045]: info: touch_display: Setting screensaver timeout to 30 seconds. Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:52 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand clear took 4 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3" Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: 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":"Untrust Us","artist":"Crystal Castles","album":"Crystal Castles","uri":"NAS/DiNas/Crystal Castles/Albums/2008 - Crystal Castles [320 kbps]/01 - Untrust Us.mp3","trackType":"mp3"} Oct 30 18:16:52 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState stateService stop Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: No code Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 18:16:52 voldi volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 30ms Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 20ms Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 19ms Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:52 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:52 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:52 voldi volumio[1045]: error: updateQueue error: null Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:52 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 16ms Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand add "NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3" took 15 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 2ms Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:52 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:16:52 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 6ms Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand play took 8 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 9ms Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 8ms Oct 30 18:16:52 voldi kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand status took 27 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand status took 28 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand status took 27 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand status took 3 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":653,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Mecca","artist":"Deadbeat","album":"Eastward On To Mecca Vinyl","uri":"NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3","trackType":"mp3"} Oct 30 18:16:52 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 32ms Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:52 voldi volumio[1045]: info: Oct 30 18:16:52 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand status took 6 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand status took 5 milliseconds Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":653,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mecca","artist":"Deadbeat","album":"Eastward On To Mecca Vinyl","uri":"NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3","trackType":"mp3"} Oct 30 18:16:52 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:52 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":653,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Mecca","artist":"Deadbeat","album":"Eastward On To Mecca Vinyl","uri":"NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3","trackType":"mp3"} Oct 30 18:16:52 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:52 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":653,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Mecca","artist":"Deadbeat","album":"Eastward On To Mecca Vinyl","uri":"NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3","trackType":"mp3"} Oct 30 18:16:52 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:52 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 83ms Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 84ms Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 64ms Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 52 milliseconds Oct 30 18:16:52 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 52 milliseconds Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:52 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":653,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mecca","artist":"Deadbeat","album":"Eastward On To Mecca Vinyl","uri":"NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3","trackType":"mp3"} Oct 30 18:16:52 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:52 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":653,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Mecca","artist":"Deadbeat","album":"Eastward On To Mecca Vinyl","uri":"NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3","trackType":"mp3"} Oct 30 18:16:52 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:16:52 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:16:52 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:16:52 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 78ms Oct 30 18:16:52 voldi volumio[1045]: info: ------------------------------ 79ms Oct 30 18:16:52 voldi volumio[1045]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 30 18:17:00 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::ClearQueue Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::stop Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::stPlaybackTimer Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::serviceStop Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::serviceStop Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::stop Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::clearPlayQueue Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::saveQueue Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::addQueueItems Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::addQueueItems Oct 30 18:17:00 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:17:00 voldi volumio[1045]: info: Adding Item to queue: music-library/NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK Oct 30 18:17:00 voldi volumio[1045]: info: Exploding uri music-library/NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK in service mpd Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:00 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Deep%20Dive%20Corp/Some%20Funky%20Fish/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FDeep_Dive_Corp_-_Some_Funky_Fish-2006-JFK%2F01-deep_dive_corp_-_some_funky_fish.mp3&metadata=false Oct 30 18:17:00 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3 Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand stop took 42 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::saveQueue Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPlay Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::play index 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::stop Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::play index undefined Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::startPlaybackTimer Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::clearAddPlayTracks NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3 Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand status took 12 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand stop took 7 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand status took 6 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand status took 4 milliseconds Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:17:00 voldi volumio[1045]: info: touch_display: Setting screensaver timeout to 30 seconds. Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:17:00 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand clear took 6 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3" Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: 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":"Mecca","artist":"Deadbeat","album":"Eastward On To Mecca Vinyl","uri":"NAS/DiNas/Deadbeat-Eastward_on_to_Mecca-Vinyl-2008-BCC/01-deadbeat-mecca.mp3","trackType":"mp3"} Oct 30 18:17:00 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState stateService stop Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: No code Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 18:17:00 voldi volumio[1045]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 35ms Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 25ms Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 24ms Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:17:00 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:17:00 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:17:00 voldi volumio[1045]: error: updateQueue error: null Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:17:00 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 18ms Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand add "NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3" took 16 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 3ms Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 3ms Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:17:00 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces system playlist update Oct 30 18:17:00 voldi volumio[1045]: info: Ignoring MPD Status Update Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 8ms Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand play took 6 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 6ms Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 6ms Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:17:00 voldi kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand status took 27 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand status took 28 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand status took 27 milliseconds Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:17:00 voldi volumio[1045]: info: Oct 30 18:17:00 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::getState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand status took 5 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4078,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Some Funky Fish","artist":"Deep Dive Corp","album":"Some Funky Fish","uri":"NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3","trackType":"mp3"} Oct 30 18:17:00 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 33ms Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand status took 4 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand status took 4 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseState Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":4078,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Some Funky Fish","artist":"Deep Dive Corp","album":"Some Funky Fish","uri":"NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3","trackType":"mp3"} Oct 30 18:17:00 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:17:00 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":4078,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Some Funky Fish","artist":"Deep Dive Corp","album":"Some Funky Fish","uri":"NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3","trackType":"mp3"} Oct 30 18:17:00 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:17:00 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":4078,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Some Funky Fish","artist":"Deep Dive Corp","album":"Some Funky Fish","uri":"NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3","trackType":"mp3"} Oct 30 18:17:00 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:17:00 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 56ms Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 57ms Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 33ms Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 30 18:17:00 voldi volumio[1045]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:17:00 voldi volumio[1045]: verbose: ControllerMpd::parseTrackInfo Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":4078,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Some Funky Fish","artist":"Deep Dive Corp","album":"Some Funky Fish","uri":"NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3","trackType":"mp3"} Oct 30 18:17:00 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:17:00 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: ControllerMpd::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::servicePushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":4078,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Some Funky Fish","artist":"Deep Dive Corp","album":"Some Funky Fish","uri":"NAS/DiNas/Deep_Dive_Corp_-_Some_Funky_Fish-2006-JFK/01-deep_dive_corp_-_some_funky_fish.mp3","trackType":"mp3"} Oct 30 18:17:00 voldi volumio[1045]: verbose: CURRENT POSITION 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState stateService play Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::syncState currentStatus play Oct 30 18:17:00 voldi volumio[1045]: info: Received an update from plugin. extracting info from payload Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 44ms Oct 30 18:17:00 voldi volumio[1045]: info: ------------------------------ 44ms Oct 30 18:17:00 voldi volumio[1045]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 18:17:00 voldi volumio[1045]: info: Discovery: Getting this device information Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 30 18:17:00 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:00 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 18:17:29 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:17:29 voldi volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 18:17:29 voldi volumio[1045]: info: CoreStateMachine::ClearQueue Oct 30 18:17:29 voldi volumio[1045]: info: CoreStateMachine::stop Oct 30 18:17:29 voldi volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 18:17:29 voldi volumio[1045]: info: CoreStateMachine::stPlaybackTimer Oct 30 18:17:29 voldi volumio[1045]: info: CoreStateMachine::updateTrackBlock Oct 30 18:17:29 voldi volumio[1045]: info: CorePlayQueue::getTrackBlock Oct 30 18:17:29 voldi volumio[1045]: info: CoreStateMachine::pushState Oct 30 18:17:29 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:29 voldi volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 18:17:29 voldi volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 30 18:17:29 voldi volumio[1045]: info: CoreStateMachine::serviceStop Oct 30 18:17:29 voldi volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 30 18:17:29 voldi volumio[1045]: info: CoreCommandRouter::serviceStop Oct 30 18:17:29 voldi volumio[1045]: info: ControllerMpd::stop Oct 30 18:17:29 voldi volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 18:17:29 voldi volumio[1045]: info: CorePlayQueue::clearPlayQueue Oct 30 18:17:29 voldi volumio[1045]: info: CorePlayQueue::saveQueue Oct 30 18:17:29 voldi volumio[1045]: info: CoreCommandRouter::volumioPushQueue Oct 30 18:17:29 voldi volumio[1045]: info: CoreStateMachine::addQueueItems Oct 30 18:17:29 voldi volumio[1045]: info: CorePlayQueue::addQueueItems Oct 30 18:17:29 voldi volumio[1045]: info: Preload queue cleared Oct 30 18:17:29 voldi volumio[1045]: info: Adding Item to queue: music-library/NAS/DiNas/EELS Discography lossless Oct 30 18:17:29 voldi volumio[1045]: info: Exploding uri music-library/NAS/DiNas/EELS Discography lossless in service mpd Oct 30 18:17:30 voldi volumio[1045]: info: Oct 30 18:17:30 voldi volumio[1045]: ---------------------------- MPD announces state update: player Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F01%20-%20Hello%20Cruel%20World.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/01 - Hello Cruel World.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F02%20-%20Fitting%20In%20With%20the%20Misfits.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/02 - Fitting In With the Misfits.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F03%20-%20Are%20You%20%26%20Me%20Gonna%20Happen.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/03 - Are You & Me Gonna Happen.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F04%20-%20Looking%20Out%20the%20Window%20With%20a%20Blue%20Hat%20On.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/04 - Looking Out the Window With a Blue Hat On.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F05%20-%20Nowheresville.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/05 - Nowheresville.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F06%20-%20Symphony%20for%20Toy%20Piano%20in%20G%20minor.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/06 - Symphony for Toy Piano in G minor.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F07%20-%20Mockingbird%20Franklin.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/07 - Mockingbird Franklin.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F08%20-%20I've%20Been%20Kicked%20Around.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/08 - I've Been Kicked Around.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F09%20-%20Pray.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/09 - Pray.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F10%20-%20E's%20Tune.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/10 - E's Tune.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/A%20Man%20Called%20E/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1992%20-%20A%20Man%20Called%20E%2F11%20-%20You'll%20Be%20the%20Scarecrow.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1992 - A Man Called E/11 - You'll Be the Scarecrow.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F01%20-%20Shine%20It%20All%20On.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/01 - Shine It All On.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F02%20-%20Standing%20At%20The%20Gate.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/02 - Standing At The Gate.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F03%20-%20The%20Only%20Thing%20I%20Care%20About.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/03 - The Only Thing I Care About.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F04%20-%20Manchester%20Girl.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/04 - Manchester Girl.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F05%20-%20L.a.%20River.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/05 - L.a. River.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F06%20-%20A%20Most%20Unpleasant%20Man.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/06 - A Most Unpleasant Man.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F07%20-%20Mass.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/07 - Mass.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F08%20-%20Tomorrow%20I'll%20Be%20Nine.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/08 - Tomorrow I'll Be Nine.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F09%20-%20The%20Day%20I%20Wrote%20You%20Off.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/09 - The Day I Wrote You Off.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F10%20-%20Someone%20To%20Break%20The%20Spell.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/10 - Someone To Break The Spell.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F11%20-%20She%20Loves%20A%20Puppet.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/11 - She Loves A Puppet.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F12%20-%20My%20Old%20Raincoat.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/12 - My Old Raincoat.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F13%20-%20Permanent%20Broken%20Heart.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/13 - Permanent Broken Heart.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Broken%20Toy%20Shop/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Broken%20Toy%20Shop%2F14%20-%20Eight%20Lives%20Left.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Broken Toy Shop/14 - Eight Lives Left.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Not%20For%20Airplay/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Not%20For%20Airplay%20EP%2F01%20-%20E%20-%20Shine%20It%20All%20On.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Not For Airplay EP/01 - E - Shine It All On.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Not%20For%20Airplay/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Not%20For%20Airplay%20EP%2F02%20-%20E%20-%20L.A.%20River.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Not For Airplay EP/02 - E - L.A. River.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Not%20For%20Airplay/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Not%20For%20Airplay%20EP%2F03%20-%20E%20-%20Tomorrow%20I'll%20Be%20Nine.flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Not For Airplay EP/03 - E - Tomorrow I'll Be Nine.flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=E/Not%20For%20Airplay/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FE%2F1993%20-%20Not%20For%20Airplay%20EP%2F04%20-%20E%20-%20Everything's%20Gonna%20Be%20Cool%20This%20Christmas%20(Non-Album%20Track).flac&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/E/1993 - Not For Airplay EP/04 - E - Everything's Gonna Be Cool This Christmas (Non-Album Track).flac Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=//extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FEELS%2F1996%20-%20Beautiful%20Freak%2FEels%20-%20Beautiful%20Freak.ape&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/EELS/1996 - Beautiful Freak/Eels - Beautiful Freak.ape Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Eels/Beautiful%20Freak/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FEELS%2F1996%20-%20Beautiful%20Freak%2FEels%20-%20Beautiful%20Freak.cue&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/EELS/1996 - Beautiful Freak/Eels - Beautiful Freak.cue Oct 30 18:17:30 voldi volumio[1045]: info: ALBUMART /albumart?cacheid=94&web=Eels/Beautiful%20Freak/extralarge&path=%2Fmnt%2FNAS%2FDiNas%2FEELS%20Discography%20lossless%2FEELS%2F1996%20-%20Beautiful%20Freak%2FEels%20-%20Beautiful%20Freak.cue&metadata=false Oct 30 18:17:30 voldi volumio[1045]: info: URI /mnt/NAS/DiNas/EELS Discography lossless/EELS/1996 - Beautiful Freak/Eels - Beautiful Freak.cue Oct 30 18:17:30 voldi volumio[1045]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 18:17:30 voldi volumio[1045]: Error: Unable to resolve or reject the same promise twice Oct 30 18:17:30 voldi volumio[1045]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 30 18:17:30 voldi volumio[1045]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 30 18:17:30 voldi volumio[1045]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 30 18:17:30 voldi volumio[1045]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 30 18:17:30 voldi volumio[1045]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 30 18:17:30 voldi volumio[1045]: at Socket.emit (events.js:315:20) Oct 30 18:17:30 voldi volumio[1045]: at addChunk (internal/streams/readable.js:309:12) Oct 30 18:17:30 voldi volumio[1045]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 30 18:17:30 voldi volumio[1045]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 30 18:17:30 voldi volumio[1045]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 30 18:17:30 voldi volumio[1045]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 18:17:30 voldi sudo[20546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-30 18:16 Oct 30 18:17:30 voldi sudo[20546]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"