-- Logs begin at Tue 2025-06-17 13:49:17 CEST, end at Tue 2025-06-17 18:36:51 CEST. -- Jun 17 18:35:36 volumio volumio[958]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.2 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::volumioGetVisibleSources Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jun 17 18:35:36 volumio volumio[958]: info: CorePlayQueue::getTrack 4 Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 17 18:35:36 volumio volumio[958]: info: Received Get System Info Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 17 18:35:36 volumio volumio[958]: info: Discovery: Getting this device information Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jun 17 18:35:36 volumio volumio[958]: info: CorePlayQueue::getTrack 4 Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::volumioGetState Jun 17 18:35:36 volumio volumio[958]: info: CorePlayQueue::getTrack 4 Jun 17 18:35:36 volumio volumio[958]: info: Listing playlists Jun 17 18:35:36 volumio volumio[958]: info: CoreCommandRouter::volumioGetQueue Jun 17 18:35:36 volumio volumio[958]: info: CoreStateMachine::getQueue Jun 17 18:35:36 volumio volumio[958]: info: CorePlayQueue::getQueue Jun 17 18:35:37 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 17 18:35:44 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 18:35:44 volumio volumio[958]: info: CURURI: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW] Jun 17 18:35:44 volumio volumio[958]: info: Preload queue cleared Jun 17 18:35:45 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 18:35:45 volumio volumio[958]: info: CURURI: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW] Jun 17 18:35:45 volumio volumio[958]: info: Preload queue cleared Jun 17 18:35:45 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac Jun 17 18:35:45 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/02. The Sceptred Isle.flac Jun 17 18:35:45 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/03. Holmgang.flac Jun 17 18:35:45 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/04. Kingdom of Wessex.flac Jun 17 18:35:45 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/05. The Tree of Life.flac Jun 17 18:35:45 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/06. The Guardian.flac Jun 17 18:35:45 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/07. Hrafnsmál - The Words of the Raven.flac Jun 17 18:35:45 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac in service mpd Jun 17 18:35:45 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Jesper%20Kyd/Assassin's%20Creed%20Valhalla%3A%20Out%20of%20the%20North%20Original%20Soundtrack/extralarge&path=%2Fmnt%2FINTERNAL%2FAssassin's%20Creed%20-%20Valhalla%20-%20Original%20Sountrack%20-%202CD%20%5BiDN_CreW%5D%2FAssassin's%20Creed%20Valhalla%20-%20Out%20of%20the%20North%20-%20Original%20Soundtrack%20%5BiDN_CreW%5D%2F01.%20Out%20of%20the%20North.flac&metadata=false Jun 17 18:35:45 volumio volumio[958]: info: URI /mnt/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac Jun 17 18:35:45 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/02. The Sceptred Isle.flac in service mpd Jun 17 18:35:45 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Sarah%20Schachner/Assassin's%20Creed%20Valhalla%3A%20Out%20of%20the%20North%20Original%20Soundtrack/extralarge&path=%2Fmnt%2FINTERNAL%2FAssassin's%20Creed%20-%20Valhalla%20-%20Original%20Sountrack%20-%202CD%20%5BiDN_CreW%5D%2FAssassin's%20Creed%20Valhalla%20-%20Out%20of%20the%20North%20-%20Original%20Soundtrack%20%5BiDN_CreW%5D%2F02.%20The%20Sceptred%20Isle.flac&metadata=false Jun 17 18:35:45 volumio volumio[958]: info: URI /mnt/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/02. The Sceptred Isle.flac Jun 17 18:35:45 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/03. Holmgang.flac in service mpd Jun 17 18:35:45 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Jesper%20Kyd/Assassin's%20Creed%20Valhalla%3A%20Out%20of%20the%20North%20Original%20Soundtrack/extralarge&path=%2Fmnt%2FINTERNAL%2FAssassin's%20Creed%20-%20Valhalla%20-%20Original%20Sountrack%20-%202CD%20%5BiDN_CreW%5D%2FAssassin's%20Creed%20Valhalla%20-%20Out%20of%20the%20North%20-%20Original%20Soundtrack%20%5BiDN_CreW%5D%2F03.%20Holmgang.flac&metadata=false Jun 17 18:35:45 volumio volumio[958]: info: URI /mnt/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/03. Holmgang.flac Jun 17 18:35:45 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/04. Kingdom of Wessex.flac in service mpd Jun 17 18:35:45 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Jesper%20Kyd/Assassin's%20Creed%20Valhalla%3A%20Out%20of%20the%20North%20Original%20Soundtrack/extralarge&path=%2Fmnt%2FINTERNAL%2FAssassin's%20Creed%20-%20Valhalla%20-%20Original%20Sountrack%20-%202CD%20%5BiDN_CreW%5D%2FAssassin's%20Creed%20Valhalla%20-%20Out%20of%20the%20North%20-%20Original%20Soundtrack%20%5BiDN_CreW%5D%2F04.%20Kingdom%20of%20Wessex.flac&metadata=false Jun 17 18:35:45 volumio volumio[958]: info: URI /mnt/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/04. Kingdom of Wessex.flac Jun 17 18:35:45 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/05. The Tree of Life.flac in service mpd Jun 17 18:35:45 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Sarah%20Schachner/Assassin's%20Creed%20Valhalla%3A%20Out%20of%20the%20North%20Original%20Soundtrack/extralarge&path=%2Fmnt%2FINTERNAL%2FAssassin's%20Creed%20-%20Valhalla%20-%20Original%20Sountrack%20-%202CD%20%5BiDN_CreW%5D%2FAssassin's%20Creed%20Valhalla%20-%20Out%20of%20the%20North%20-%20Original%20Soundtrack%20%5BiDN_CreW%5D%2F05.%20The%20Tree%20of%20Life.flac&metadata=false Jun 17 18:35:45 volumio volumio[958]: info: URI /mnt/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/05. The Tree of Life.flac Jun 17 18:35:45 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/06. The Guardian.flac in service mpd Jun 17 18:35:45 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Sarah%20Schachner/Assassin's%20Creed%20Valhalla%3A%20Out%20of%20the%20North%20Original%20Soundtrack/extralarge&path=%2Fmnt%2FINTERNAL%2FAssassin's%20Creed%20-%20Valhalla%20-%20Original%20Sountrack%20-%202CD%20%5BiDN_CreW%5D%2FAssassin's%20Creed%20Valhalla%20-%20Out%20of%20the%20North%20-%20Original%20Soundtrack%20%5BiDN_CreW%5D%2F06.%20The%20Guardian.flac&metadata=false Jun 17 18:35:45 volumio volumio[958]: info: URI /mnt/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/06. The Guardian.flac Jun 17 18:35:45 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/07. Hrafnsmál - The Words of the Raven.flac in service mpd Jun 17 18:35:45 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Einar%20Selvik/Assassin's%20Creed%20Valhalla%3A%20Out%20of%20the%20North%20Original%20Soundtrack/extralarge&path=%2Fmnt%2FINTERNAL%2FAssassin's%20Creed%20-%20Valhalla%20-%20Original%20Sountrack%20-%202CD%20%5BiDN_CreW%5D%2FAssassin's%20Creed%20Valhalla%20-%20Out%20of%20the%20North%20-%20Original%20Soundtrack%20%5BiDN_CreW%5D%2F07.%20Hrafnsm%C3%A1l%20-%20The%20Words%20of%20the%20Raven.flac&metadata=false Jun 17 18:35:45 volumio volumio[958]: info: URI /mnt/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/07. Hrafnsmál - The Words of the Raven.flac Jun 17 18:35:46 volumio volumio[958]: info: Preload queue cleared Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::ClearQueue Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 4 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::serviceStop Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 4 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::serviceStop Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::stop Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::clearPlayQueue Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:35:46 volumio volumio[958]: info: Preload queue cleared Jun 17 18:35:46 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac Jun 17 18:35:46 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::play index 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:35:46 volumio volumio[958]: info: Preload queue cleared Jun 17 18:35:46 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/02. The Sceptred Isle.flac Jun 17 18:35:46 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/02. The Sceptred Isle.flac Jun 17 18:35:46 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/03. Holmgang.flac Jun 17 18:35:46 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/03. Holmgang.flac Jun 17 18:35:46 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/04. Kingdom of Wessex.flac Jun 17 18:35:46 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/04. Kingdom of Wessex.flac Jun 17 18:35:46 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/05. The Tree of Life.flac Jun 17 18:35:46 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/05. The Tree of Life.flac Jun 17 18:35:46 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/06. The Guardian.flac Jun 17 18:35:46 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/06. The Guardian.flac Jun 17 18:35:46 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/07. Hrafnsmál - The Words of the Raven.flac Jun 17 18:35:46 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/07. Hrafnsmál - The Words of the Raven.flac Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::play index undefined Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand stop took 28 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand stop took 6 milliseconds Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:35:46 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand status took 10 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand clear took 10 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand status took 5 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand status took 3 milliseconds Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac" Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: 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} Jun 17 18:35:46 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: No code Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: 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} Jun 17 18:35:46 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: No code Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 38ms Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 35ms Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:35:46 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:35:46 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:35:46 volumio volumio[958]: error: updateQueue error: null Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:35:46 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 40ms Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand add "INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac" took 37 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 7ms Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 6ms Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand play Jun 17 18:35:46 volumio volumio[958]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 54ms Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:35:46 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:35:46 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 29ms Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand play took 26 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 26ms Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 24ms Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand status took 9 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand status took 9 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand status took 6 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand status took 5 milliseconds Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:35:46 volumio volumio[958]: info: Jun 17 18:35:46 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand status took 13 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand status took 11 milliseconds Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":421,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1180 Kbps","isStreaming":false,"title":"Out of the North","artist":"Jesper Kyd","album":"Assassin's Creed Valhalla: Out of the North Original Soundtrack","uri":"INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac","trackType":"flac"} Jun 17 18:35:46 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1180 Kbps","isStreaming":false,"title":"Out of the North","artist":"Jesper Kyd","album":"Assassin's Creed Valhalla: Out of the North Original Soundtrack","uri":"INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac","trackType":"flac"} Jun 17 18:35:46 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:35:46 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1214 Kbps","isStreaming":false,"title":"Out of the North","artist":"Jesper Kyd","album":"Assassin's Creed Valhalla: Out of the North Original Soundtrack","uri":"INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac","trackType":"flac"} Jun 17 18:35:46 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:35:46 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1213 Kbps","isStreaming":false,"title":"Out of the North","artist":"Jesper Kyd","album":"Assassin's Creed Valhalla: Out of the North Original Soundtrack","uri":"INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac","trackType":"flac"} Jun 17 18:35:46 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:35:46 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 67ms Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 79ms Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 74ms Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 73ms Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand playlistinfo took 55 milliseconds Jun 17 18:35:46 volumio volumio[958]: info: sendMpdCommand playlistinfo took 54 milliseconds Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:35:46 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1213 Kbps","isStreaming":false,"title":"Out of the North","artist":"Jesper Kyd","album":"Assassin's Creed Valhalla: Out of the North Original Soundtrack","uri":"INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac","trackType":"flac"} Jun 17 18:35:46 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:35:46 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1213 Kbps","isStreaming":false,"title":"Out of the North","artist":"Jesper Kyd","album":"Assassin's Creed Valhalla: Out of the North Original Soundtrack","uri":"INTERNAL/Assassin's Creed - Valhalla - Original Sountrack - 2CD [iDN_CreW]/Assassin's Creed Valhalla - Out of the North - Original Soundtrack [iDN_CreW]/01. Out of the North.flac","trackType":"flac"} Jun 17 18:35:46 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:35:46 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:35:46 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:35:46 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 99ms Jun 17 18:35:46 volumio volumio[958]: info: ------------------------------ 98ms Jun 17 18:35:59 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 18:35:59 volumio volumio[958]: info: CURURI: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS} Jun 17 18:35:59 volumio volumio[958]: info: Preload queue cleared Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Conciliation.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Sound of Hope.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Fire Tamer.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Forbidden Scents.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Enchantment.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Between the Sun and the Moon.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Barren Lands.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Sacred Earth.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 in service mpd Jun 17 18:35:59 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FThe%20Gates%20Of%20Reality.mp3&metadata=false Jun 17 18:35:59 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 in service mpd Jun 17 18:35:59 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FLifegiving.mp3&metadata=false Jun 17 18:35:59 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Conciliation.mp3 in service mpd Jun 17 18:35:59 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FConciliation.mp3&metadata=false Jun 17 18:35:59 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Conciliation.mp3 Jun 17 18:35:59 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 in service mpd Jun 17 18:35:59 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FTurning%20Point.mp3&metadata=false Jun 17 18:35:59 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Sound of Hope.mp3 in service mpd Jun 17 18:36:00 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FThe%20Sound%20of%20Hope.mp3&metadata=false Jun 17 18:36:00 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Sound of Hope.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Fire Tamer.mp3 in service mpd Jun 17 18:36:00 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FFire%20Tamer.mp3&metadata=false Jun 17 18:36:00 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Fire Tamer.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Forbidden Scents.mp3 in service mpd Jun 17 18:36:00 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FForbidden%20Scents.mp3&metadata=false Jun 17 18:36:00 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Forbidden Scents.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Enchantment.mp3 in service mpd Jun 17 18:36:00 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FThe%20Enchantment.mp3&metadata=false Jun 17 18:36:00 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Enchantment.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Between the Sun and the Moon.mp3 in service mpd Jun 17 18:36:00 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FBetween%20the%20Sun%20and%20the%20Moon.mp3&metadata=false Jun 17 18:36:00 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Between the Sun and the Moon.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Barren Lands.mp3 in service mpd Jun 17 18:36:00 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FBarren%20Lands.mp3&metadata=false Jun 17 18:36:00 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Barren Lands.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Sacred Earth.mp3 in service mpd Jun 17 18:36:00 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Australis/Lifegiving/extralarge&path=%2Fmnt%2FINTERNAL%2FAUSTRALIS-LIFEGIVING%7BBY%20SERGGIOS%7D%2FSacred%20Earth.mp3&metadata=false Jun 17 18:36:00 volumio volumio[958]: info: URI /mnt/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Sacred Earth.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::ClearQueue Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::serviceStop Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::serviceStop Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::stop Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::clearPlayQueue Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:00 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::play index 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:00 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Conciliation.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Conciliation.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Sound of Hope.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Sound of Hope.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Fire Tamer.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Fire Tamer.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Forbidden Scents.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Forbidden Scents.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Enchantment.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Enchantment.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Between the Sun and the Moon.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Between the Sun and the Moon.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Barren Lands.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Barren Lands.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Sacred Earth.mp3 Jun 17 18:36:00 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Sacred Earth.mp3 Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::play index undefined Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand stop took 34 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand stop took 5 milliseconds Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:00 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand status took 8 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand clear took 7 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand status took 6 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand status took 3 milliseconds Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3" Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: 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} Jun 17 18:36:00 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: No code Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: 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} Jun 17 18:36:00 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: No code Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 45ms Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 44ms Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:00 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:00 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:00 volumio volumio[958]: error: updateQueue error: null Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:00 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 50ms Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand playlistinfo took 47 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand add "INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3" took 47 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 8ms Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 7ms Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand play Jun 17 18:36:00 volumio volumio[958]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 64ms Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:00 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:00 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 21ms Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand play took 18 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 17ms Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 15ms Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand status took 11 milliseconds Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand status took 19 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand status took 16 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":328,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Gates Of Reality","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3","trackType":"mp3"} Jun 17 18:36:00 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 32ms Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:00 volumio volumio[958]: info: Jun 17 18:36:00 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand status took 31 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand playlistinfo took 28 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand status took 20 milliseconds Jun 17 18:36:00 volumio volumio[958]: info: sendMpdCommand status took 18 milliseconds Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:00 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":328,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Gates Of Reality","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3","trackType":"mp3"} Jun 17 18:36:00 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:00 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":328,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Gates Of Reality","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3","trackType":"mp3"} Jun 17 18:36:00 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:00 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:00 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:00 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 78ms Jun 17 18:36:00 volumio volumio[958]: info: ------------------------------ 77ms Jun 17 18:36:01 volumio volumio[958]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 17 18:36:01 volumio volumio[958]: info: sendMpdCommand playlistinfo took 28 milliseconds Jun 17 18:36:01 volumio volumio[958]: info: sendMpdCommand playlistinfo took 28 milliseconds Jun 17 18:36:01 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:01 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:01 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:01 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:01 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:01 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":328,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Gates Of Reality","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3","trackType":"mp3"} Jun 17 18:36:01 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:01 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:01 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:01 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:01 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:01 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:01 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":328,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Gates Of Reality","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3","trackType":"mp3"} Jun 17 18:36:01 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:01 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:01 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:01 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:01 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:01 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:01 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":328,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Gates Of Reality","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3","trackType":"mp3"} Jun 17 18:36:01 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:01 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:01 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:01 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:01 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:01 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:01 volumio volumio[958]: info: ------------------------------ 100ms Jun 17 18:36:01 volumio volumio[958]: info: ------------------------------ 88ms Jun 17 18:36:01 volumio volumio[958]: info: ------------------------------ 86ms Jun 17 18:36:08 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::ClearQueue Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::serviceStop Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::serviceStop Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::stop Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::clearPlayQueue Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:08 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::play index 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:08 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Conciliation.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Conciliation.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Sound of Hope.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Sound of Hope.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Fire Tamer.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Fire Tamer.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Forbidden Scents.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Forbidden Scents.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Enchantment.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Enchantment.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Between the Sun and the Moon.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Between the Sun and the Moon.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Barren Lands.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Barren Lands.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Sacred Earth.mp3 Jun 17 18:36:08 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Sacred Earth.mp3 Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::play index undefined Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand stop took 27 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand stop took 6 milliseconds Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:08 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand status took 2 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand clear took 2 milliseconds Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3" Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:08 volumio volumio[958]: error: updateQueue error: null Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:08 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 10ms Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand add "INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3" took 8 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand status took 7 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand status took 5 milliseconds Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand play Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:08 volumio volumio[958]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: 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} Jun 17 18:36:08 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: No code Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: 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} Jun 17 18:36:08 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: No code Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 38ms Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 43ms Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 44ms Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:08 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:08 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 50ms Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand play took 45 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 8ms Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 8ms Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:08 volumio volumio[958]: info: Jun 17 18:36:08 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand status took 14 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand status took 14 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand status took 11 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand status took 10 milliseconds Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 17 18:36:08 volumio volumio[958]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:08 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lifegiving","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3","trackType":"mp3"} Jun 17 18:36:08 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lifegiving","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3","trackType":"mp3"} Jun 17 18:36:08 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:08 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lifegiving","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3","trackType":"mp3"} Jun 17 18:36:08 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:08 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":243,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lifegiving","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3","trackType":"mp3"} Jun 17 18:36:08 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:08 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:08 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:08 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 61ms Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 71ms Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 68ms Jun 17 18:36:08 volumio volumio[958]: info: ------------------------------ 67ms Jun 17 18:36:26 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::ClearQueue Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::serviceStop Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::serviceStop Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::stop Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::clearPlayQueue Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:26 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Gates Of Reality.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Lifegiving.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Conciliation.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Conciliation.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::play index 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:26 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Sound of Hope.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Sound of Hope.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Fire Tamer.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Fire Tamer.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Forbidden Scents.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Forbidden Scents.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Enchantment.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/The Enchantment.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Between the Sun and the Moon.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Between the Sun and the Moon.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Barren Lands.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Barren Lands.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Sacred Earth.mp3 Jun 17 18:36:26 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Sacred Earth.mp3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::play index undefined Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3 Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand stop took 34 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand stop took 6 milliseconds Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:26 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand status took 7 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand clear took 7 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand status took 5 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand status took 4 milliseconds Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3" Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: 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} Jun 17 18:36:26 volumio volumio[958]: verbose: CURRENT POSITION 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: No code Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: 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} Jun 17 18:36:26 volumio volumio[958]: verbose: CURRENT POSITION 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: No code Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 52ms Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 52ms Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:26 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:26 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:26 volumio volumio[958]: error: updateQueue error: null Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:26 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 59ms Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand playlistinfo took 55 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand add "INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3" took 56 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 8ms Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 8ms Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand play Jun 17 18:36:26 volumio volumio[958]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 71ms Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:26 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:26 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 20ms Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand play took 17 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 15ms Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 14ms Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand status took 10 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand status took 10 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand status took 9 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand status took 7 milliseconds Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:26 volumio volumio[958]: info: Jun 17 18:36:26 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand playlistinfo took 17 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand playlistinfo took 17 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand status took 10 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand status took 9 milliseconds Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Turning Point","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3","trackType":"mp3"} Jun 17 18:36:26 volumio volumio[958]: verbose: CURRENT POSITION 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Turning Point","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3","trackType":"mp3"} Jun 17 18:36:26 volumio volumio[958]: verbose: CURRENT POSITION 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:26 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Turning Point","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3","trackType":"mp3"} Jun 17 18:36:26 volumio volumio[958]: verbose: CURRENT POSITION 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:26 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Turning Point","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3","trackType":"mp3"} Jun 17 18:36:26 volumio volumio[958]: verbose: CURRENT POSITION 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:26 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 69ms Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 79ms Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 77ms Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 76ms Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand playlistinfo took 44 milliseconds Jun 17 18:36:26 volumio volumio[958]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:26 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":329,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Turning Point","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3","trackType":"mp3"} Jun 17 18:36:26 volumio volumio[958]: verbose: CURRENT POSITION 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:26 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":329,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Turning Point","artist":"Australis","album":"Lifegiving","uri":"INTERNAL/AUSTRALIS-LIFEGIVING{BY SERGGIOS}/Turning Point.mp3","trackType":"mp3"} Jun 17 18:36:26 volumio volumio[958]: verbose: CURRENT POSITION 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:26 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:26 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:26 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 85ms Jun 17 18:36:26 volumio volumio[958]: info: ------------------------------ 84ms Jun 17 18:36:31 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 18:36:31 volumio volumio[958]: info: CURURI: music-library/INTERNAL/Autumn Jun 17 18:36:31 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 03 - falling leaves.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 04 - bonfires.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 05 - keeper's cottage.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 06 - pastel mists.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 07 - october trees.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 08 - twilight plough.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 09 - fading light.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 10 - rooks return.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3 in service mpd Jun 17 18:36:31 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2001%20-%20autumn.mp3&metadata=false Jun 17 18:36:31 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3 in service mpd Jun 17 18:36:31 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2002%20-%20golden%20fields.mp3&metadata=false Jun 17 18:36:31 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 03 - falling leaves.mp3 in service mpd Jun 17 18:36:31 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2003%20-%20falling%20leaves.mp3&metadata=false Jun 17 18:36:31 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 03 - falling leaves.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 04 - bonfires.mp3 in service mpd Jun 17 18:36:31 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2004%20-%20bonfires.mp3&metadata=false Jun 17 18:36:31 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 04 - bonfires.mp3 Jun 17 18:36:31 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 05 - keeper's cottage.mp3 in service mpd Jun 17 18:36:31 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2005%20-%20keeper's%20cottage.mp3&metadata=false Jun 17 18:36:31 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 05 - keeper's cottage.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 06 - pastel mists.mp3 in service mpd Jun 17 18:36:32 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2006%20-%20pastel%20mists.mp3&metadata=false Jun 17 18:36:32 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 06 - pastel mists.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 07 - october trees.mp3 in service mpd Jun 17 18:36:32 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2007%20-%20october%20trees.mp3&metadata=false Jun 17 18:36:32 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 07 - october trees.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 08 - twilight plough.mp3 in service mpd Jun 17 18:36:32 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2008%20-%20twilight%20plough.mp3&metadata=false Jun 17 18:36:32 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 08 - twilight plough.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 09 - fading light.mp3 in service mpd Jun 17 18:36:32 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2009%20-%20fading%20light.mp3&metadata=false Jun 17 18:36:32 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 09 - fading light.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 10 - rooks return.mp3 in service mpd Jun 17 18:36:32 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Kevin%20Kendle/Autumn/extralarge&path=%2Fmnt%2FINTERNAL%2FAutumn%2FKevin%20Kendle%20-%20Autumn%20-%2010%20-%20rooks%20return.mp3&metadata=false Jun 17 18:36:32 volumio volumio[958]: info: URI /mnt/INTERNAL/Autumn/Kevin Kendle - Autumn - 10 - rooks return.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::ClearQueue Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::serviceStop Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 3 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::serviceStop Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::stop Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::clearPlayQueue Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:32 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::play index 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:32 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 03 - falling leaves.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 03 - falling leaves.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 04 - bonfires.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 04 - bonfires.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 05 - keeper's cottage.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 05 - keeper's cottage.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 06 - pastel mists.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 06 - pastel mists.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 07 - october trees.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 07 - october trees.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 08 - twilight plough.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 08 - twilight plough.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 09 - fading light.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 09 - fading light.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 10 - rooks return.mp3 Jun 17 18:36:32 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 10 - rooks return.mp3 Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::play index undefined Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3 Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand stop took 35 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand stop took 6 milliseconds Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:32 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand status took 6 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand clear took 6 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand status took 4 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand status took 3 milliseconds Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3" Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: 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} Jun 17 18:36:32 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: No code Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: 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} Jun 17 18:36:32 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: No code Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: ------------------------------ 41ms Jun 17 18:36:32 volumio volumio[958]: info: ------------------------------ 41ms Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:32 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:32 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:32 volumio volumio[958]: error: updateQueue error: null Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:32 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:32 volumio volumio[958]: info: ------------------------------ 46ms Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand playlistinfo took 44 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand add "INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3" took 44 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: ------------------------------ 6ms Jun 17 18:36:32 volumio volumio[958]: info: ------------------------------ 6ms Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand play Jun 17 18:36:32 volumio volumio[958]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 18:36:32 volumio volumio[958]: info: ------------------------------ 57ms Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:32 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:32 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:32 volumio volumio[958]: info: ------------------------------ 25ms Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand play took 23 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: ------------------------------ 22ms Jun 17 18:36:32 volumio volumio[958]: info: ------------------------------ 20ms Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand status took 22 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand status took 21 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand status took 20 milliseconds Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:32 volumio volumio[958]: info: Jun 17 18:36:32 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand status took 11 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand status took 7 milliseconds Jun 17 18:36:32 volumio volumio[958]: info: sendMpdCommand status took 6 milliseconds Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:32 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Autumn","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3","trackType":"mp3"} Jun 17 18:36:32 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Autumn","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3","trackType":"mp3"} Jun 17 18:36:32 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:32 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Autumn","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3","trackType":"mp3"} Jun 17 18:36:32 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:32 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:32 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:32 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:32 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:33 volumio volumio[958]: info: ------------------------------ 60ms Jun 17 18:36:33 volumio volumio[958]: info: ------------------------------ 69ms Jun 17 18:36:33 volumio volumio[958]: info: ------------------------------ 68ms Jun 17 18:36:33 volumio volumio[958]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 17 18:36:33 volumio volumio[958]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 17 18:36:33 volumio volumio[958]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 17 18:36:33 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:33 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:33 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:33 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:33 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Autumn","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3","trackType":"mp3"} Jun 17 18:36:33 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:33 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:33 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:33 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Autumn","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3","trackType":"mp3"} Jun 17 18:36:33 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:33 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:33 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:33 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Autumn","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3","trackType":"mp3"} Jun 17 18:36:33 volumio volumio[958]: verbose: CURRENT POSITION 0 Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:33 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:33 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:33 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:33 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:33 volumio volumio[958]: info: ------------------------------ 103ms Jun 17 18:36:33 volumio volumio[958]: info: ------------------------------ 98ms Jun 17 18:36:33 volumio volumio[958]: info: ------------------------------ 98ms Jun 17 18:36:38 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::ClearQueue Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::serviceStop Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::serviceStop Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::stop Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::clearPlayQueue Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:38 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 01 - autumn.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::play index 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:38 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 03 - falling leaves.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 03 - falling leaves.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 04 - bonfires.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 04 - bonfires.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 05 - keeper's cottage.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 05 - keeper's cottage.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 06 - pastel mists.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 06 - pastel mists.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 07 - october trees.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 07 - october trees.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 08 - twilight plough.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 08 - twilight plough.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 09 - fading light.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 09 - fading light.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 10 - rooks return.mp3 Jun 17 18:36:38 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/Autumn/Kevin Kendle - Autumn - 10 - rooks return.mp3 Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::play index undefined Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3 Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand stop took 25 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand stop took 5 milliseconds Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand clear Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:38 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand status took 3 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand clear took 2 milliseconds Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3" Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:38 volumio volumio[958]: error: updateQueue error: null Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:38 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 7ms Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand add "INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3" took 6 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand status took 4 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand status took 3 milliseconds Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand play Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:38 volumio volumio[958]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: 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} Jun 17 18:36:38 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: No code Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: 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} Jun 17 18:36:38 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState stateService stop Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: No code Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 37ms Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 42ms Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 41ms Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:38 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces system playlist update Jun 17 18:36:38 volumio volumio[958]: info: Ignoring MPD Status Update Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 47ms Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand play took 44 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 7ms Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 7ms Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:38 volumio volumio[958]: info: Jun 17 18:36:38 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::getState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand status took 12 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand status took 12 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand status took 10 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand status took 8 milliseconds Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseState Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 17 18:36:38 volumio volumio[958]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:38 volumio volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Golden Fields","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3","trackType":"mp3"} Jun 17 18:36:38 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus stop Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Golden Fields","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3","trackType":"mp3"} Jun 17 18:36:38 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:38 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Golden Fields","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3","trackType":"mp3"} Jun 17 18:36:38 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:38 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: ControllerMpd::pushState Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::servicePushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Golden Fields","artist":"Kevin Kendle","album":"Autumn","uri":"INTERNAL/Autumn/Kevin Kendle - Autumn - 02 - golden fields.mp3","trackType":"mp3"} Jun 17 18:36:38 volumio volumio[958]: verbose: CURRENT POSITION 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState stateService play Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::syncState currentStatus play Jun 17 18:36:38 volumio volumio[958]: info: Received an update from plugin. extracting info from payload Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:38 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:38 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 65ms Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 74ms Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 71ms Jun 17 18:36:38 volumio volumio[958]: info: ------------------------------ 70ms Jun 17 18:36:48 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 18:36:48 volumio volumio[958]: info: CURURI: music-library/INTERNAL/bck scatolik-nuovo audio Jun 17 18:36:48 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:49 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 17 18:36:49 volumio volumio[958]: info: CURURI: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck Jun 17 18:36:49 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:49 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape Jun 17 18:36:49 volumio volumio[958]: info: Preloading song: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac Jun 17 18:36:50 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape in service mpd Jun 17 18:36:50 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FZPreisner10Pieces4Piano.ape&metadata=false Jun 17 18:36:50 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape Jun 17 18:36:50 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac in service mpd Jun 17 18:36:50 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Symatic%20Star/Transmissions/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FSymatic_Star_Transmissions.flac&metadata=false Jun 17 18:36:50 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac Jun 17 18:36:51 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:51 volumio volumio[958]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::ClearQueue Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::stPlaybackTimer Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::pushState Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:51 volumio volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 18:36:51 volumio volumio[958]: info: CoreCommandRouter::volumioPushState Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::serviceStop Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::getTrack 1 Jun 17 18:36:51 volumio volumio[958]: info: CoreCommandRouter::serviceStop Jun 17 18:36:51 volumio volumio[958]: info: ControllerMpd::stop Jun 17 18:36:51 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::clearPlayQueue Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:51 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:51 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape Jun 17 18:36:51 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape Jun 17 18:36:51 volumio volumio[958]: info: CoreCommandRouter::volumioPushQueue Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::saveQueue Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::getTrackBlock Jun 17 18:36:51 volumio volumio[958]: info: CoreCommandRouter::volumioPlay Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::play index 0 Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::addQueueItems Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::addQueueItems Jun 17 18:36:51 volumio volumio[958]: info: Preload queue cleared Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac Jun 17 18:36:51 volumio volumio[958]: info: Using cached record of: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com in service mpd Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[1970] - 05 Atom Heart Mother (Remastered 1994)[APE] Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[1970] - 05 Atom Heart Mother (Remastered 1994)[APE] in service mpd Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[Ecm NS 1798] MORTON FELDMAN (2008) The Viola in My Life (by.Barabbovich).zip Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[Ecm NS 1798] MORTON FELDMAN (2008) The Viola in My Life (by.Barabbovich).zip in service mpd Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/AAVV feat Rino Zurzolo, Antonio Onorato, Riccardo Zappa (1995) O' cuorp 'e Napule (by.Barabbovich).zip Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/AAVV feat Rino Zurzolo, Antonio Onorato, Riccardo Zappa (1995) O' cuorp 'e Napule (by.Barabbovich).zip in service mpd Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato in service mpd Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato - Jazz Napoletano (2004).zip Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato - Jazz Napoletano (2004).zip in service mpd Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Kevin Kendle - Tarot - The Mind Body And Soul Series.zip Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Kevin Kendle - Tarot - The Mind Body And Soul Series.zip in service mpd Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Michael Nyman And Motion Trio - Acoustic Accordions Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Michael Nyman And Motion Trio - Acoustic Accordions in service mpd Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Radiodervish - 1998 - Lingua Contro Lingua.zip Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Radiodervish - 1998 - Lingua Contro Lingua.zip in service mpd Jun 17 18:36:51 volumio volumio[958]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/VA - UKF Bass Culture Jun 17 18:36:51 volumio volumio[958]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/VA - UKF Bass Culture in service mpd Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::stop Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::play index undefined Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:51 volumio volumio[958]: info: CoreStateMachine::startPlaybackTimer Jun 17 18:36:51 volumio volumio[958]: info: CorePlayQueue::getTrack 0 Jun 17 18:36:51 volumio volumio[958]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape Jun 17 18:36:51 volumio volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 17 18:36:51 volumio volumio[958]: info: Jun 17 18:36:51 volumio volumio[958]: ---------------------------- MPD announces state update: player Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F01.%20A%20Promise%20Of%20Love.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/01. A Promise Of Love.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F02.%20Together%20As%20One.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/02. Together As One.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F03.%20Two%20Flames.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/03. Two Flames.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F04.%20My%20Valentine.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/04. My Valentine.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F05.%20The%20Yearning.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/05. The Yearning.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F06.%20All%20Of%20My%20Heart.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/06. All Of My Heart.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F07.%20Love%20Is%20All%20We%20Need.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/07. Love Is All We Need.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F08.%20Cupid's%20Arrow.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/08. Cupid's Arrow.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F%5B1970%5D%20-%2005%20Atom%20Heart%20Mother%20(Remastered%201994)%5BAPE%5D%2FAtom%20Heart%20Mother%20(CDImage).ape&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[1970] - 05 Atom Heart Mother (Remastered 1994)[APE]/Atom Heart Mother (CDImage).ape Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F01%20-%203_4%20e%20un%20po'.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/01 - 3_4 e un po'.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F02%20-%20Luandando.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/02 - Luandando.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F03%20-%20Neapolitan%20Minor%20Blues.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/03 - Neapolitan Minor Blues.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F04%20-%20Palummella.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/04 - Palummella.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F05%20-%20Tammurriata%20Nera.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/05 - Tammurriata Nera.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F06%20-%20Morte%20di%20un%20fiore.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/06 - Morte di un fiore.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F07%20-%20Napulegna.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/07 - Napulegna.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F08%20-%20Manha%20de%20Carnaval.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/08 - Manha de Carnaval.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F09%20-%20Assaje.mp3&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/09 - Assaje.mp3 Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%20-%20Jazz%20Napoletano%20(2004).zip&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato - Jazz Napoletano (2004).zip Jun 17 18:36:51 volumio volumio[958]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%20-%20Jazz%20Napoletano%20(2004).zip&metadata=false Jun 17 18:36:51 volumio volumio[958]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato - Jazz Napoletano (2004).zip Jun 17 18:36:51 volumio volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 18:36:51 volumio volumio[958]: Error: Unable to resolve or reject the same promise twice Jun 17 18:36:51 volumio volumio[958]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 17 18:36:51 volumio volumio[958]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jun 17 18:36:51 volumio volumio[958]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jun 17 18:36:51 volumio volumio[958]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jun 17 18:36:51 volumio volumio[958]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jun 17 18:36:51 volumio volumio[958]: at Socket.emit (events.js:400:28) Jun 17 18:36:51 volumio volumio[958]: at addChunk (internal/streams/readable.js:293:12) Jun 17 18:36:51 volumio volumio[958]: at readableAddChunk (internal/streams/readable.js:263:11) Jun 17 18:36:51 volumio volumio[958]: at Socket.Readable.push (internal/streams/readable.js:206:10) Jun 17 18:36:51 volumio volumio[958]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jun 17 18:36:51 volumio volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 18:36:51 volumio sudo[8393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 18:35 Jun 17 18:36:51 volumio sudo[8393]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"