-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sat 2025-06-14 12:20:33 CST. -- Jun 14 12:19:04 volumio volumio[966]: info: Preload queue cleared Jun 14 12:19:04 volumio volumio[966]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::ClearQueue Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::stop Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::clearPlayQueue Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::saveQueue Jun 14 12:19:04 volumio volumio[966]: info: CoreCommandRouter::volumioPushQueue Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::addQueueItems Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::addQueueItems Jun 14 12:19:04 volumio volumio[966]: info: Preload queue cleared Jun 14 12:19:04 volumio volumio[966]: info: Adding Item to queue: albums://Black%20Sabbath/Paranoid Jun 14 12:19:04 volumio volumio[966]: info: Exploding uri albums://Black%20Sabbath/Paranoid in service mpd Jun 14 12:19:04 volumio volumio[966]: info: CoreCommandRouter::volumioPushQueue Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::saveQueue Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::updateTrackBlock Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::getTrackBlock Jun 14 12:19:04 volumio volumio[966]: info: CoreCommandRouter::volumioPlay Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::play index 0 Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::stop Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::play index undefined Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::startPlaybackTimer Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/1970 - Paranoid/01 War Pigs.m4a Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 12:19:04 volumio volumio[966]: info: sendMpdCommand stop took 1 milliseconds Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand clear Jun 14 12:19:04 volumio volumio[966]: info: Jun 14 12:19:04 volumio volumio[966]: ---------------------------- MPD announces system playlist update Jun 14 12:19:04 volumio volumio[966]: info: Ignoring MPD Status Update Jun 14 12:19:04 volumio volumio[966]: info: sendMpdCommand clear took 7 milliseconds Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/1970 - Paranoid/01 War Pigs.m4a" Jun 14 12:19:04 volumio volumio[966]: error: updateQueue error: null Jun 14 12:19:04 volumio volumio[966]: info: Jun 14 12:19:04 volumio volumio[966]: ---------------------------- MPD announces system playlist update Jun 14 12:19:04 volumio volumio[966]: info: Ignoring MPD Status Update Jun 14 12:19:04 volumio volumio[966]: info: ------------------------------ 3ms Jun 14 12:19:04 volumio volumio[966]: info: sendMpdCommand add "INTERNAL/1970 - Paranoid/01 War Pigs.m4a" took 1 milliseconds Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand play Jun 14 12:19:04 volumio volumio[966]: info: ------------------------------ 3ms Jun 14 12:19:04 volumio volumio[966]: info: sendMpdCommand play took 1 milliseconds Jun 14 12:19:04 volumio volumio[966]: info: Jun 14 12:19:04 volumio volumio[966]: ---------------------------- MPD announces state update: player Jun 14 12:19:04 volumio volumio[966]: info: ControllerMpd::getState Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Jun 14 12:19:04 volumio volumio[966]: info: Jun 14 12:19:04 volumio volumio[966]: ---------------------------- MPD announces state update: player Jun 14 12:19:04 volumio volumio[966]: info: sendMpdCommand status took 13 milliseconds Jun 14 12:19:04 volumio volumio[966]: info: ControllerMpd::getState Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::parseState Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 12:19:04 volumio volumio[966]: info: sendMpdCommand status took 2 milliseconds Jun 14 12:19:04 volumio volumio[966]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::parseState Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Jun 14 12:19:04 volumio volumio[966]: info: ControllerMpd::pushState Jun 14 12:19:04 volumio volumio[966]: info: CoreCommandRouter::servicePushState Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:19:04 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":475,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"260 Kbps","isStreaming":false,"title":"War Pigs","artist":"Black Sabbath","album":"Paranoid","uri":"INTERNAL/1970 - Paranoid/01 War Pigs.m4a","trackType":"m4a"} Jun 14 12:19:04 volumio volumio[966]: verbose: CURRENT POSITION 0 Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus stop Jun 14 12:19:04 volumio volumio[966]: info: ------------------------------ 22ms Jun 14 12:19:04 volumio volumio[966]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 14 12:19:04 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Jun 14 12:19:04 volumio volumio[966]: info: ControllerMpd::pushState Jun 14 12:19:04 volumio volumio[966]: info: CoreCommandRouter::servicePushState Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:19:04 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":856,"duration":475,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"260 Kbps","isStreaming":false,"title":"War Pigs","artist":"Black Sabbath","album":"Paranoid","uri":"INTERNAL/1970 - Paranoid/01 War Pigs.m4a","trackType":"m4a"} Jun 14 12:19:04 volumio volumio[966]: verbose: CURRENT POSITION 0 Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Jun 14 12:19:04 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::pushState Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:19:04 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 12:19:04 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Jun 14 12:19:04 volumio volumio[966]: info: CoreStateMachine::pushState Jun 14 12:19:04 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:19:04 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Jun 14 12:19:04 volumio volumio[966]: info: ------------------------------ 24ms Jun 14 12:19:33 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Jun 14 12:19:33 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:20:07 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Jun 14 12:20:07 volumio volumio[966]: info: Preload queue cleared Jun 14 12:20:07 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/1970 - Paranoid/01 War Pigs.m4a Jun 14 12:20:07 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/1970 - Paranoid/01 War Pigs.m4a in service mpd Jun 14 12:20:07 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Black%20Sabbath/Paranoid/extralarge&path=%2Fmnt%2FINTERNAL%2F1970%20-%20Paranoid%2F01%20War%20Pigs.m4a&metadata=false Jun 14 12:20:07 volumio volumio[966]: info: URI /mnt/INTERNAL/1970 - Paranoid/01 War Pigs.m4a Jun 14 12:20:10 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 14 12:20:10 volumio volumio[966]: info: CURURI: albums:// Jun 14 12:20:10 volumio volumio[966]: info: listAlbums - loading Albums from cache Jun 14 12:20:10 volumio volumio[966]: info: Preload queue cleared Jun 14 12:20:16 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 14 12:20:16 volumio volumio[966]: info: CURURI: albums://Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1 Jun 14 12:20:16 volumio volumio[966]: info: Preload queue cleared Jun 14 12:20:16 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a Jun 14 12:20:16 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Nova Era.m4a Jun 14 12:20:16 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Acid Rain.m4a Jun 14 12:20:16 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Angels Cry.m4a Jun 14 12:20:16 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Heroes Of Sand.m4a Jun 14 12:20:16 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Metal Icarus.m4a Jun 14 12:20:16 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Millennium Sun.m4a Jun 14 12:20:16 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Make Believe.m4a Jun 14 12:20:16 volumio volumio[966]: info: Preloading song: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Drums Solo.m4a Jun 14 12:20:16 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a in service mpd Jun 14 12:20:16 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1/extralarge&path=%2Fmnt%2FINTERNAL%2FAngra%20-%202003%20Rebirth%20World%20Tour-Live%20In%20Sao%20Paulo%2FIn%20Excelsis.m4a&metadata=false Jun 14 12:20:16 volumio volumio[966]: info: URI /mnt/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a Jun 14 12:20:16 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Nova Era.m4a in service mpd Jun 14 12:20:16 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1/extralarge&path=%2Fmnt%2FINTERNAL%2FAngra%20-%202003%20Rebirth%20World%20Tour-Live%20In%20Sao%20Paulo%2FNova%20Era.m4a&metadata=false Jun 14 12:20:16 volumio volumio[966]: info: URI /mnt/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Nova Era.m4a Jun 14 12:20:17 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Acid Rain.m4a in service mpd Jun 14 12:20:17 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1/extralarge&path=%2Fmnt%2FINTERNAL%2FAngra%20-%202003%20Rebirth%20World%20Tour-Live%20In%20Sao%20Paulo%2FAcid%20Rain.m4a&metadata=false Jun 14 12:20:17 volumio volumio[966]: info: URI /mnt/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Acid Rain.m4a Jun 14 12:20:17 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Angels Cry.m4a in service mpd Jun 14 12:20:17 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1/extralarge&path=%2Fmnt%2FINTERNAL%2FAngra%20-%202003%20Rebirth%20World%20Tour-Live%20In%20Sao%20Paulo%2FAngels%20Cry.m4a&metadata=false Jun 14 12:20:17 volumio volumio[966]: info: URI /mnt/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Angels Cry.m4a Jun 14 12:20:17 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Heroes Of Sand.m4a in service mpd Jun 14 12:20:17 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1/extralarge&path=%2Fmnt%2FINTERNAL%2FAngra%20-%202003%20Rebirth%20World%20Tour-Live%20In%20Sao%20Paulo%2FHeroes%20Of%20Sand.m4a&metadata=false Jun 14 12:20:17 volumio volumio[966]: info: URI /mnt/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Heroes Of Sand.m4a Jun 14 12:20:17 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Metal Icarus.m4a in service mpd Jun 14 12:20:17 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1/extralarge&path=%2Fmnt%2FINTERNAL%2FAngra%20-%202003%20Rebirth%20World%20Tour-Live%20In%20Sao%20Paulo%2FMetal%20Icarus.m4a&metadata=false Jun 14 12:20:17 volumio volumio[966]: info: URI /mnt/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Metal Icarus.m4a Jun 14 12:20:17 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Millennium Sun.m4a in service mpd Jun 14 12:20:17 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1/extralarge&path=%2Fmnt%2FINTERNAL%2FAngra%20-%202003%20Rebirth%20World%20Tour-Live%20In%20Sao%20Paulo%2FMillennium%20Sun.m4a&metadata=false Jun 14 12:20:17 volumio volumio[966]: info: URI /mnt/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Millennium Sun.m4a Jun 14 12:20:17 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Make Believe.m4a in service mpd Jun 14 12:20:17 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1/extralarge&path=%2Fmnt%2FINTERNAL%2FAngra%20-%202003%20Rebirth%20World%20Tour-Live%20In%20Sao%20Paulo%2FMake%20Believe.m4a&metadata=false Jun 14 12:20:17 volumio volumio[966]: info: URI /mnt/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Make Believe.m4a Jun 14 12:20:17 volumio volumio[966]: info: Exploding uri music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Drums Solo.m4a in service mpd Jun 14 12:20:17 volumio volumio[966]: info: ALBUMART /albumart?cacheid=764&web=Angra/Rebirth%20World%20Tour%20-%20Live%20In%20Sao%20Paulo%20CD1/extralarge&path=%2Fmnt%2FINTERNAL%2FAngra%20-%202003%20Rebirth%20World%20Tour-Live%20In%20Sao%20Paulo%2FDrums%20Solo.m4a&metadata=false Jun 14 12:20:17 volumio volumio[966]: info: URI /mnt/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Drums Solo.m4a Jun 14 12:20:18 volumio volumio[966]: info: Preload queue cleared Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::ClearQueue Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::stop Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::stPlaybackTimer Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::updateTrackBlock Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrackBlock Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::pushState Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::serviceStop Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::serviceStop Jun 14 12:20:18 volumio volumio[966]: info: ControllerMpd::stop Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::clearPlayQueue Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::saveQueue Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::volumioPushQueue Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::addQueueItems Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::addQueueItems Jun 14 12:20:18 volumio volumio[966]: info: Preload queue cleared Jun 14 12:20:18 volumio volumio[966]: info: Adding Item to queue: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a Jun 14 12:20:18 volumio volumio[966]: info: Using cached record of: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::volumioPushQueue Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::saveQueue Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::updateTrackBlock Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrackBlock Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::volumioPlay Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::play index 0 Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::addQueueItems Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::addQueueItems Jun 14 12:20:18 volumio volumio[966]: info: Preload queue cleared Jun 14 12:20:18 volumio volumio[966]: info: Adding Item to queue: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Nova Era.m4a Jun 14 12:20:18 volumio volumio[966]: info: Using cached record of: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Nova Era.m4a Jun 14 12:20:18 volumio volumio[966]: info: Adding Item to queue: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Acid Rain.m4a Jun 14 12:20:18 volumio volumio[966]: info: Using cached record of: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Acid Rain.m4a Jun 14 12:20:18 volumio volumio[966]: info: Adding Item to queue: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Angels Cry.m4a Jun 14 12:20:18 volumio volumio[966]: info: Using cached record of: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Angels Cry.m4a Jun 14 12:20:18 volumio volumio[966]: info: Adding Item to queue: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Heroes Of Sand.m4a Jun 14 12:20:18 volumio volumio[966]: info: Using cached record of: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Heroes Of Sand.m4a Jun 14 12:20:18 volumio volumio[966]: info: Adding Item to queue: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Metal Icarus.m4a Jun 14 12:20:18 volumio volumio[966]: info: Using cached record of: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Metal Icarus.m4a Jun 14 12:20:18 volumio volumio[966]: info: Adding Item to queue: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Millennium Sun.m4a Jun 14 12:20:18 volumio volumio[966]: info: Using cached record of: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Millennium Sun.m4a Jun 14 12:20:18 volumio volumio[966]: info: Adding Item to queue: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Make Believe.m4a Jun 14 12:20:18 volumio volumio[966]: info: Using cached record of: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Make Believe.m4a Jun 14 12:20:18 volumio volumio[966]: info: Adding Item to queue: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Drums Solo.m4a Jun 14 12:20:18 volumio volumio[966]: info: Using cached record of: music-library/INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/Drums Solo.m4a Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::stop Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::volumioPushQueue Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::saveQueue Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::play index undefined Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::updateTrackBlock Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrackBlock Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::startPlaybackTimer Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand stop took 22 milliseconds Jun 14 12:20:18 volumio volumio[966]: info: Jun 14 12:20:18 volumio volumio[966]: ---------------------------- MPD announces state update: player Jun 14 12:20:18 volumio volumio[966]: info: ControllerMpd::getState Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand stop took 6 milliseconds Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand clear Jun 14 12:20:18 volumio volumio[966]: info: Jun 14 12:20:18 volumio volumio[966]: ---------------------------- MPD announces system playlist update Jun 14 12:20:18 volumio volumio[966]: info: Ignoring MPD Status Update Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand status took 2 milliseconds Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand clear took 1 milliseconds Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::parseState Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a" Jun 14 12:20:18 volumio volumio[966]: error: updateQueue error: null Jun 14 12:20:18 volumio volumio[966]: info: Jun 14 12:20:18 volumio volumio[966]: ---------------------------- MPD announces system playlist update Jun 14 12:20:18 volumio volumio[966]: info: Ignoring MPD Status Update Jun 14 12:20:18 volumio volumio[966]: info: ------------------------------ 3ms Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand add "INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a" took 1 milliseconds Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand play Jun 14 12:20:18 volumio volumio[966]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 14 12:20:18 volumio volumio[966]: info: ------------------------------ 6ms Jun 14 12:20:18 volumio volumio[966]: info: ------------------------------ 3ms Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand play took 2 milliseconds Jun 14 12:20:18 volumio volumio[966]: info: Jun 14 12:20:18 volumio volumio[966]: ---------------------------- MPD announces state update: player Jun 14 12:20:18 volumio volumio[966]: info: ControllerMpd::getState Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Jun 14 12:20:18 volumio volumio[966]: info: Jun 14 12:20:18 volumio volumio[966]: ---------------------------- MPD announces state update: player Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand status took 1 milliseconds Jun 14 12:20:18 volumio volumio[966]: info: ControllerMpd::getState Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::parseState Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand status took 2 milliseconds Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::parseState Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Jun 14 12:20:18 volumio volumio[966]: info: ControllerMpd::pushState Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::servicePushState Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:20:18 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":114,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"In Excelsis","artist":"Angra","album":"Rebirth World Tour - Live In Sao Paulo CD1","uri":"INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a","trackType":"m4a"} Jun 14 12:20:18 volumio volumio[966]: verbose: CURRENT POSITION 0 Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus stop Jun 14 12:20:18 volumio volumio[966]: info: ------------------------------ 9ms Jun 14 12:20:18 volumio volumio[966]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 14 12:20:18 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Jun 14 12:20:18 volumio volumio[966]: info: ControllerMpd::pushState Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::servicePushState Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:20:18 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":114,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"316 Kbps","isStreaming":false,"title":"In Excelsis","artist":"Angra","album":"Rebirth World Tour - Live In Sao Paulo CD1","uri":"INTERNAL/Angra - 2003 Rebirth World Tour-Live In Sao Paulo/In Excelsis.m4a","trackType":"m4a"} Jun 14 12:20:18 volumio volumio[966]: verbose: CURRENT POSITION 0 Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Jun 14 12:20:18 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::pushState Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Jun 14 12:20:18 volumio volumio[966]: info: CoreStateMachine::pushState Jun 14 12:20:18 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Jun 14 12:20:18 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Jun 14 12:20:18 volumio volumio[966]: info: ------------------------------ 24ms Jun 14 12:20:19 volumio ntpd[787]: 193.182.111.12 local addr 192.168.1.123 -> Jun 14 12:20:32 volumio volumio[966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 12:20:32 volumio volumio[966]: Error: connect ETIMEDOUT 199.16.156.71:443 Jun 14 12:20:32 volumio volumio[966]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 14 12:20:32 volumio volumio[966]: errno: -110, Jun 14 12:20:32 volumio volumio[966]: code: 'ETIMEDOUT', Jun 14 12:20:32 volumio volumio[966]: syscall: 'connect', Jun 14 12:20:32 volumio volumio[966]: address: '199.16.156.71', Jun 14 12:20:32 volumio volumio[966]: port: 443 Jun 14 12:20:32 volumio volumio[966]: } Jun 14 12:20:32 volumio volumio[966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 12:20:33 volumio sudo[3179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 12:19 Jun 14 12:20:33 volumio sudo[3179]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"