-- Logs begin at Tue 2024-04-30 01:26:18 CEST, end at Tue 2024-04-30 18:54:29 CEST. -- Apr 30 18:53:16 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:53:16 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:53:16 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:53:16 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:53:16 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:16 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:53:18 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:53:18 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:53:18 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:53:18 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:53:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:18 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:53:25 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:53:25 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:53:25 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:53:25 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:53:25 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:25 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:53:27 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:53:27 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:53:27 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:53:27 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:53:27 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:27 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:53:43 volumio volumio[28459]: info: CoreCommandRouter::volumioPause Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::pause Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::stPlaybackTimer Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::servicePause Apr 30 18:53:43 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:43 volumio volumio[28459]: info: CoreCommandRouter::servicePause Apr 30 18:53:43 volumio volumio[28459]: info: ControllerMpd::pause Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand pause Apr 30 18:53:43 volumio volumio[28459]: info: Apr 30 18:53:43 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:53:43 volumio volumio[28459]: info: sendMpdCommand pause took 3 milliseconds Apr 30 18:53:43 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:53:43 volumio volumio[28459]: info: Apr 30 18:53:43 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:53:43 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:53:43 volumio volumio[28459]: info: Apr 30 18:53:43 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:53:43 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:53:43 volumio volumio[28459]: info: sendMpdCommand status took 3 milliseconds Apr 30 18:53:43 volumio volumio[28459]: info: sendMpdCommand status took 2 milliseconds Apr 30 18:53:43 volumio volumio[28459]: info: sendMpdCommand status took 1 milliseconds Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:53:43 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 30 18:53:43 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 30 18:53:43 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:53:43 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:53:43 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:53:43 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:53:43 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:43 volumio volumio[28459]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":94356,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"997 Kbps","isStreaming":false,"title":"Legs (Part 1)","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 02 - Legs (Part 1).flac","trackType":"flac"} Apr 30 18:53:43 volumio volumio[28459]: verbose: CURRENT POSITION 21097 Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::syncState stateService pause Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus pause Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:53:43 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:43 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:53:43 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::stPlaybackTimer Apr 30 18:53:43 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:53:43 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:53:43 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:43 volumio volumio[28459]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":94356,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"997 Kbps","isStreaming":false,"title":"Legs (Part 1)","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 02 - Legs (Part 1).flac","trackType":"flac"} Apr 30 18:53:43 volumio volumio[28459]: verbose: CURRENT POSITION 21097 Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::syncState stateService pause Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus pause Apr 30 18:53:43 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:53:43 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:44 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:53:44 volumio volumio[28459]: info: CoreStateMachine::stPlaybackTimer Apr 30 18:53:44 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:53:44 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:53:44 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:44 volumio volumio[28459]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":94356,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"997 Kbps","isStreaming":false,"title":"Legs (Part 1)","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 02 - Legs (Part 1).flac","trackType":"flac"} Apr 30 18:53:44 volumio volumio[28459]: verbose: CURRENT POSITION 21097 Apr 30 18:53:44 volumio volumio[28459]: info: CoreStateMachine::syncState stateService pause Apr 30 18:53:44 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus pause Apr 30 18:53:44 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:53:44 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:44 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:53:44 volumio volumio[28459]: info: CoreStateMachine::stPlaybackTimer Apr 30 18:53:44 volumio volumio[28459]: info: ------------------------------ 34ms Apr 30 18:53:44 volumio volumio[28459]: info: ------------------------------ 33ms Apr 30 18:53:44 volumio volumio[28459]: info: ------------------------------ 32ms Apr 30 18:53:45 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:53:45 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:53:45 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:53:45 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:53:45 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:45 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:53:47 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:53:47 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:53:47 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:53:47 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:53:47 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:47 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:53:54 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:53:54 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:53:54 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:53:54 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:53:54 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:54 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:53:57 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:53:57 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:53:57 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:53:57 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:53:57 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:53:57 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:54:04 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:54:04 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:54:04 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:54:04 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:04 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:54:04 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:54:05 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:05 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:05 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:05 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:05 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:05 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:05 volumio volumio[28459]: info: Exploding uri music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac in service mpd Apr 30 18:54:05 volumio volumio[28459]: info: ALBUMART /albumart?cacheid=73&web=Darondo/Let%20My%20People%20Go/extralarge&path=%2Fmnt%2FUSB%2FMuziek%2FDarondo%2FDarondo%20-%20Let%20My%20People%20Go%20(2006)%2FDarondo%20-%20Let%20My%20People%20Go%20-%2003%20-%20Didn't%20I.flac&metadata=false Apr 30 18:54:05 volumio volumio[28459]: info: URI /mnt/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:05 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::stPlaybackTimer Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::serviceStop Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrack 21097 Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::serviceStop Apr 30 18:54:07 volumio volumio[28459]: info: ControllerMpd::stop Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:07 volumio volumio[28459]: info: Apr 30 18:54:07 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand stop took 383 milliseconds Apr 30 18:54:07 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:07 volumio volumio[28459]: info: Apr 30 18:54:07 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:07 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:07 volumio volumio[28459]: info: Apr 30 18:54:07 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:07 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand status took 48 milliseconds Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand stop took 44 milliseconds Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand status took 7 milliseconds Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand status took 4 milliseconds Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:07 volumio volumio[28459]: info: Apr 30 18:54:07 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:07 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand clear took 31 milliseconds Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 30 milliseconds Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:07 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:07 volumio volumio[28459]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Legs (Part 1)","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 02 - Legs (Part 1).flac","trackType":"flac"} Apr 30 18:54:07 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:07 volumio volumio[28459]: info: No code Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:07 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:07 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:07 volumio volumio[28459]: info: ------------------------------ 102ms Apr 30 18:54:07 volumio volumio[28459]: info: ------------------------------ 62ms Apr 30 18:54:07 volumio volumio[28459]: info: ------------------------------ 58ms Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:54:07 volumio volumio[28459]: info: Discovery: Getting this device information Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:54:07 volumio volumio[28459]: info: Apr 30 18:54:07 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:07 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:07 volumio volumio[28459]: info: Apr 30 18:54:07 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:07 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:07 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:07 volumio volumio[28459]: info: Apr 30 18:54:07 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:07 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:07 volumio volumio[28459]: info: ------------------------------ 53ms Apr 30 18:54:07 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 50 milliseconds Apr 30 18:54:07 volumio volumio[28459]: info: ------------------------------ 18ms Apr 30 18:54:07 volumio volumio[28459]: info: ------------------------------ 17ms Apr 30 18:54:07 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:07 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:07 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:07 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:07 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:07 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:07 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:07 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:08 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:08 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:08 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:08 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:08 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:08 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:08 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:08 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:08 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:08 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:08 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:08 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:08 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:08 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:08 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:08 volumio volumio[28459]: info: Apr 30 18:54:08 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:08 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:08 volumio volumio[28459]: info: Apr 30 18:54:08 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:08 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:08 volumio volumio[28459]: info: Apr 30 18:54:08 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:08 volumio volumio[28459]: info: Apr 30 18:54:08 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:08 volumio volumio[28459]: info: ------------------------------ 655ms Apr 30 18:54:08 volumio volumio[28459]: info: sendMpdCommand play took 650 milliseconds Apr 30 18:54:08 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:08 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:08 volumio volumio[28459]: info: sendMpdCommand stop took 14 milliseconds Apr 30 18:54:08 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:08 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:08 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:09 volumio volumio[28459]: info: Apr 30 18:54:09 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:09 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:09 volumio volumio[28459]: info: ------------------------------ 470ms Apr 30 18:54:09 volumio volumio[28459]: info: ------------------------------ 470ms Apr 30 18:54:09 volumio volumio[28459]: info: sendMpdCommand status took 466 milliseconds Apr 30 18:54:09 volumio volumio[28459]: info: sendMpdCommand status took 465 milliseconds Apr 30 18:54:09 volumio volumio[28459]: info: sendMpdCommand clear took 465 milliseconds Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:09 volumio volumio[28459]: info: Apr 30 18:54:09 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:09 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:09 volumio volumio[28459]: info: Apr 30 18:54:09 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:09 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:09 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:09 volumio volumio[28459]: info: Apr 30 18:54:09 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:09 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:09 volumio volumio[28459]: info: ------------------------------ 47ms Apr 30 18:54:09 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 43 milliseconds Apr 30 18:54:09 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 42 milliseconds Apr 30 18:54:09 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 42 milliseconds Apr 30 18:54:09 volumio volumio[28459]: info: sendMpdCommand status took 5 milliseconds Apr 30 18:54:09 volumio volumio[28459]: info: sendMpdCommand status took 3 milliseconds Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:09 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:09 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:09 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: 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} Apr 30 18:54:09 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:09 volumio volumio[28459]: info: No code Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:09 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: 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} Apr 30 18:54:09 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:09 volumio volumio[28459]: info: No code Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:09 volumio volumio[28459]: info: ------------------------------ 580ms Apr 30 18:54:09 volumio volumio[28459]: info: ------------------------------ 581ms Apr 30 18:54:09 volumio volumio[28459]: info: ------------------------------ 77ms Apr 30 18:54:09 volumio volumio[28459]: info: ------------------------------ 77ms Apr 30 18:54:09 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:09 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:09 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:09 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:09 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:09 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:09 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:09 volumio volumio[28459]: info: Apr 30 18:54:09 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:09 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:09 volumio volumio[28459]: info: Apr 30 18:54:09 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:09 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:10 volumio volumio[28459]: info: Apr 30 18:54:10 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:10 volumio volumio[28459]: info: Apr 30 18:54:10 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 1297ms Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand play took 1276 milliseconds Apr 30 18:54:10 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand stop took 553 milliseconds Apr 30 18:54:10 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 515ms Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 513ms Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:10 volumio volumio[28459]: info: Apr 30 18:54:10 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:10 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:10 volumio volumio[28459]: info: Apr 30 18:54:10 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:10 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:10 volumio volumio[28459]: info: Apr 30 18:54:10 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:10 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand status took 68 milliseconds Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand status took 67 milliseconds Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand clear took 66 milliseconds Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand status took 22 milliseconds Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand status took 20 milliseconds Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:10 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:10 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:10 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:10 volumio volumio[28459]: 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} Apr 30 18:54:10 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:10 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:10 volumio volumio[28459]: info: No code Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:10 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:10 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:10 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:10 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:10 volumio volumio[28459]: 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} Apr 30 18:54:10 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:10 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:10 volumio volumio[28459]: info: No code Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:10 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 53ms Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 52ms Apr 30 18:54:10 volumio volumio[28459]: info: Apr 30 18:54:10 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:10 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:10 volumio volumio[28459]: info: Apr 30 18:54:10 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:10 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:10 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:10 volumio volumio[28459]: info: Apr 30 18:54:10 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:10 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 53ms Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 51 milliseconds Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 51 milliseconds Apr 30 18:54:10 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 51 milliseconds Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 7ms Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 5ms Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:10 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:10 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:10 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 138ms Apr 30 18:54:10 volumio volumio[28459]: info: ------------------------------ 138ms Apr 30 18:54:10 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:10 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:10 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:10 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:10 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:10 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:10 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:11 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:11 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 1123ms Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand play took 1118 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand stop took 610 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 610ms Apr 30 18:54:11 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 609ms Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:11 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:11 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:11 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand status took 29 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand status took 29 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand clear took 28 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand status took 7 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand status took 6 milliseconds Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:11 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: 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} Apr 30 18:54:11 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:11 volumio volumio[28459]: info: No code Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:11 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: 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} Apr 30 18:54:11 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:11 volumio volumio[28459]: info: No code Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 48ms Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 49ms Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:11 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:11 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:11 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:11 volumio volumio[28459]: info: Apr 30 18:54:11 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:11 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 72ms Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 69 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 58 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 69 milliseconds Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 20ms Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 18ms Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:11 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:11 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:11 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:11 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 125ms Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 126ms Apr 30 18:54:11 volumio volumio[28459]: info: ------------------------------ 125ms Apr 30 18:54:11 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:11 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:11 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:11 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:11 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:11 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:11 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:12 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:12 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:12 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:12 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:12 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:12 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:12 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:12 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:12 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:12 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:12 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:12 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:12 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:12 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:12 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:12 volumio volumio[28459]: info: Apr 30 18:54:12 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:12 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:12 volumio volumio[28459]: info: Apr 30 18:54:12 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:12 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:12 volumio volumio[28459]: info: Apr 30 18:54:12 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:12 volumio volumio[28459]: info: Apr 30 18:54:12 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:12 volumio volumio[28459]: info: ------------------------------ 604ms Apr 30 18:54:12 volumio volumio[28459]: info: sendMpdCommand play took 587 milliseconds Apr 30 18:54:12 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:12 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:12 volumio volumio[28459]: info: sendMpdCommand stop took 9 milliseconds Apr 30 18:54:12 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:12 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:12 volumio volumio[28459]: info: ------------------------------ 5ms Apr 30 18:54:12 volumio volumio[28459]: info: ------------------------------ 4ms Apr 30 18:54:12 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:12 volumio volumio[28459]: info: Apr 30 18:54:12 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:12 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:12 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:12 volumio volumio[28459]: info: Apr 30 18:54:12 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:12 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:12 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:13 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand status took 653 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand status took 653 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand clear took 653 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand status took 650 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand status took 650 milliseconds Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: 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} Apr 30 18:54:13 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: No code Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: 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} Apr 30 18:54:13 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: No code Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 672ms Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 672ms Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:13 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:13 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:13 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:13 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 34ms Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 30 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 31 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 7ms Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 6ms Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:13 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 691ms Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:13 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:13 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 10ms Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand play took 6 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 5ms Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 4ms Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand status took 5 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand status took 3 milliseconds Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:13 volumio volumio[28459]: info: Apr 30 18:54:13 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand status took 7 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand status took 4 milliseconds Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Didn't I","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac","trackType":"flac"} Apr 30 18:54:13 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState stateService play Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"572 Kbps","isStreaming":false,"title":"Didn't I","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac","trackType":"flac"} Apr 30 18:54:13 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState stateService play Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus play Apr 30 18:54:13 volumio volumio[28459]: info: Received an update from plugin. extracting info from payload Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"578 Kbps","isStreaming":false,"title":"Didn't I","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac","trackType":"flac"} Apr 30 18:54:13 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState stateService play Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus play Apr 30 18:54:13 volumio volumio[28459]: info: Received an update from plugin. extracting info from payload Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 34ms Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 32ms Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 30 18:54:13 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"600 Kbps","isStreaming":false,"title":"Didn't I","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac","trackType":"flac"} Apr 30 18:54:13 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState stateService play Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus play Apr 30 18:54:13 volumio volumio[28459]: info: Received an update from plugin. extracting info from payload Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"600 Kbps","isStreaming":false,"title":"Didn't I","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac","trackType":"flac"} Apr 30 18:54:13 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState stateService play Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus play Apr 30 18:54:13 volumio volumio[28459]: info: Received an update from plugin. extracting info from payload Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":209,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"600 Kbps","isStreaming":false,"title":"Didn't I","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac","trackType":"flac"} Apr 30 18:54:13 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState stateService play Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus play Apr 30 18:54:13 volumio volumio[28459]: info: Received an update from plugin. extracting info from payload Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 50ms Apr 30 18:54:13 volumio volumio[28459]: info: ------------------------------ 46ms Apr 30 18:54:13 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:13 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:13 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:13 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::stPlaybackTimer Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:13 volumio volumio[28459]: info: CoreStateMachine::serviceStop Apr 30 18:54:13 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:13 volumio volumio[28459]: info: CoreCommandRouter::serviceStop Apr 30 18:54:13 volumio volumio[28459]: info: ControllerMpd::stop Apr 30 18:54:13 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand stop took 634 milliseconds Apr 30 18:54:14 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:14 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:14 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand status took 54 milliseconds Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand stop took 49 milliseconds Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand status took 8 milliseconds Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand status took 6 milliseconds Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:14 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand clear took 15 milliseconds Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:14 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:14 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:14 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:14 volumio volumio[28459]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Didn't I","artist":"Darondo","album":"Let My People Go","uri":"USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac","trackType":"flac"} Apr 30 18:54:14 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:14 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:14 volumio volumio[28459]: info: No code Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:14 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:14 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:14 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:14 volumio volumio[28459]: info: ------------------------------ 110ms Apr 30 18:54:14 volumio volumio[28459]: info: ------------------------------ 78ms Apr 30 18:54:14 volumio volumio[28459]: info: ------------------------------ 60ms Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:14 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:14 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:14 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:14 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:14 volumio volumio[28459]: info: ------------------------------ 43ms Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 39 milliseconds Apr 30 18:54:14 volumio volumio[28459]: info: ------------------------------ 4ms Apr 30 18:54:14 volumio volumio[28459]: info: ------------------------------ 4ms Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:14 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:14 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:14 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:14 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:14 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:14 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:14 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:14 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:14 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:14 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:14 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:14 volumio volumio[28459]: info: ------------------------------ 603ms Apr 30 18:54:14 volumio volumio[28459]: info: sendMpdCommand play took 601 milliseconds Apr 30 18:54:14 volumio volumio[28459]: info: ------------------------------ 600ms Apr 30 18:54:14 volumio volumio[28459]: info: ------------------------------ 599ms Apr 30 18:54:14 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:14 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:14 volumio volumio[28459]: info: Apr 30 18:54:14 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:14 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:14 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:15 volumio volumio[28459]: info: Apr 30 18:54:15 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand stop took 754 milliseconds Apr 30 18:54:15 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand status took 752 milliseconds Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand status took 751 milliseconds Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand status took 751 milliseconds Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:15 volumio volumio[28459]: info: Apr 30 18:54:15 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:15 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:15 volumio volumio[28459]: info: Apr 30 18:54:15 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:15 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:15 volumio volumio[28459]: info: Apr 30 18:54:15 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:15 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand status took 23 milliseconds Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand clear took 22 milliseconds Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand status took 19 milliseconds Apr 30 18:54:15 volumio volumio[28459]: info: sendMpdCommand status took 18 milliseconds Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:15 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:15 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:15 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:15 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:15 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:15 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:15 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:15 volumio volumio[28459]: 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} Apr 30 18:54:15 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:15 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:15 volumio volumio[28459]: info: No code Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:15 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:15 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:15 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:15 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:15 volumio volumio[28459]: 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} Apr 30 18:54:15 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:15 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:15 volumio volumio[28459]: info: No code Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:15 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:15 volumio volumio[28459]: info: ------------------------------ 825ms Apr 30 18:54:15 volumio volumio[28459]: info: ------------------------------ 823ms Apr 30 18:54:15 volumio volumio[28459]: info: ------------------------------ 823ms Apr 30 18:54:15 volumio volumio[28459]: info: ------------------------------ 68ms Apr 30 18:54:15 volumio volumio[28459]: info: ------------------------------ 68ms Apr 30 18:54:15 volumio volumio[28459]: info: Apr 30 18:54:15 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:15 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:15 volumio volumio[28459]: info: Apr 30 18:54:15 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:15 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:15 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:15 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:15 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:15 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:15 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:15 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:15 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:16 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:16 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:16 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:16 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:16 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:16 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:16 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:16 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:16 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:16 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:16 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:16 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:16 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:16 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:16 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:16 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:16 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:16 volumio volumio[28459]: info: ------------------------------ 542ms Apr 30 18:54:16 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 537 milliseconds Apr 30 18:54:16 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 538 milliseconds Apr 30 18:54:16 volumio volumio[28459]: info: ------------------------------ 489ms Apr 30 18:54:16 volumio volumio[28459]: info: ------------------------------ 489ms Apr 30 18:54:16 volumio volumio[28459]: info: sendMpdCommand stop took 25 milliseconds Apr 30 18:54:16 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:16 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:16 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:16 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:16 volumio volumio[28459]: info: ------------------------------ 571ms Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:16 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:16 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:16 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:16 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:16 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:16 volumio volumio[28459]: info: ------------------------------ 735ms Apr 30 18:54:16 volumio volumio[28459]: info: sendMpdCommand play took 729 milliseconds Apr 30 18:54:16 volumio volumio[28459]: info: sendMpdCommand clear took 729 milliseconds Apr 30 18:54:16 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:16 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:16 volumio volumio[28459]: info: ------------------------------ 727ms Apr 30 18:54:16 volumio volumio[28459]: info: ------------------------------ 725ms Apr 30 18:54:16 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:16 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:16 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:16 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:16 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:16 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:16 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:16 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:16 volumio volumio[28459]: info: Apr 30 18:54:16 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:17 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:17 volumio volumio[28459]: info: ------------------------------ 23ms Apr 30 18:54:17 volumio volumio[28459]: info: sendMpdCommand status took 21 milliseconds Apr 30 18:54:17 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 21 milliseconds Apr 30 18:54:17 volumio volumio[28459]: info: ------------------------------ 21ms Apr 30 18:54:17 volumio volumio[28459]: info: sendMpdCommand status took 20 milliseconds Apr 30 18:54:17 volumio volumio[28459]: info: ------------------------------ 21ms Apr 30 18:54:17 volumio volumio[28459]: info: sendMpdCommand status took 20 milliseconds Apr 30 18:54:17 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:17 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:17 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:17 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:17 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: 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} Apr 30 18:54:17 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:17 volumio volumio[28459]: info: No code Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:17 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: 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} Apr 30 18:54:17 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:17 volumio volumio[28459]: info: No code Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:17 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: 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} Apr 30 18:54:17 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:17 volumio volumio[28459]: info: No code Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:17 volumio volumio[28459]: info: ------------------------------ 72ms Apr 30 18:54:17 volumio volumio[28459]: info: ------------------------------ 69ms Apr 30 18:54:17 volumio volumio[28459]: info: ------------------------------ 68ms Apr 30 18:54:17 volumio volumio[28459]: info: Apr 30 18:54:17 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:17 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:17 volumio volumio[28459]: info: Apr 30 18:54:17 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:17 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:17 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:17 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:17 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:17 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:17 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:17 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:17 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:17 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 1296ms Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand play took 1288 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 1244ms Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 1243ms Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand stop took 684 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:18 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand status took 31 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand status took 42 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand clear took 42 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand status took 40 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand status took 39 milliseconds Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: 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} Apr 30 18:54:18 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: No code Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: 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} Apr 30 18:54:18 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: No code Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 79ms Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 78ms Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:18 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:18 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:18 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:18 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 201ms Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 182 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 181 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 180 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 146ms Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand status took 144 milliseconds Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 145ms Apr 30 18:54:18 volumio volumio[28459]: info: sendMpdCommand status took 143 milliseconds Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:18 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:18 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: 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} Apr 30 18:54:18 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: No code Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: 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} Apr 30 18:54:18 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: No code Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 272ms Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 271ms Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 180ms Apr 30 18:54:18 volumio volumio[28459]: info: ------------------------------ 179ms Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:18 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:18 volumio volumio[28459]: info: Apr 30 18:54:18 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:18 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:18 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:18 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:18 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:18 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:18 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:18 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:18 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 1141ms Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand play took 1133 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 1103ms Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 1102ms Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand stop took 718 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:19 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand status took 185 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand status took 183 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand clear took 183 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand status took 180 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand status took 178 milliseconds Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: 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} Apr 30 18:54:19 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: No code Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: 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} Apr 30 18:54:19 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: No code Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 207ms Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 205ms Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:19 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:19 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:19 volumio volumio[28459]: error: updateQueue error: null Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:19 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 85ms Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 61 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand playlistinfo took 60 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand add "USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac" took 59 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 38ms Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand status took 36 milliseconds Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 36ms Apr 30 18:54:19 volumio volumio[28459]: info: sendMpdCommand status took 34 milliseconds Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::parseTrackInfo Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand play Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:19 volumio volumio[28459]: verbose: ControllerMpd::parseState Apr 30 18:54:19 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:19 volumio volumio[28459]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: 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} Apr 30 18:54:19 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: No code Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: ControllerMpd::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::servicePushState Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: 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} Apr 30 18:54:19 volumio volumio[28459]: verbose: CURRENT POSITION 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::syncState stateService stop Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::syncState currentStatus stop Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: No code Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::pushState Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 299ms Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 298ms Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 82ms Apr 30 18:54:19 volumio volumio[28459]: info: ------------------------------ 81ms Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:19 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:19 volumio volumio[28459]: info: Apr 30 18:54:19 volumio volumio[28459]: ---------------------------- MPD announces system playlist update Apr 30 18:54:19 volumio volumio[28459]: info: Ignoring MPD Status Update Apr 30 18:54:19 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:19 volumio volumio[28459]: info: CoreStateMachine::addQueueItems Apr 30 18:54:19 volumio volumio[28459]: info: CorePlayQueue::addQueueItems Apr 30 18:54:19 volumio volumio[28459]: info: Preload queue cleared Apr 30 18:54:19 volumio volumio[28459]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:19 volumio volumio[28459]: info: Using cached record of: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:19 volumio volumio[28459]: info: CoreCommandRouter::volumioPushQueue Apr 30 18:54:20 volumio volumio[28459]: info: CorePlayQueue::saveQueue Apr 30 18:54:20 volumio volumio[28459]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:20 volumio volumio[28459]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:20 volumio volumio[28459]: info: CoreCommandRouter::volumioPlay Apr 30 18:54:20 volumio volumio[28459]: info: CoreStateMachine::play index 21098 Apr 30 18:54:20 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:20 volumio volumio[28459]: info: CoreStateMachine::stop Apr 30 18:54:20 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:20 volumio volumio[28459]: info: CoreStateMachine::play index undefined Apr 30 18:54:20 volumio volumio[28459]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 18:54:20 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:20 volumio volumio[28459]: info: CoreStateMachine::startPlaybackTimer Apr 30 18:54:20 volumio volumio[28459]: info: CorePlayQueue::getTrack 21098 Apr 30 18:54:20 volumio volumio[28459]: verbose: ControllerMpd::clearAddPlayTracks USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:20 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand stop Apr 30 18:54:20 volumio volumio[28459]: info: Apr 30 18:54:20 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:20 volumio volumio[28459]: info: Apr 30 18:54:20 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:20 volumio volumio[28459]: info: ------------------------------ 635ms Apr 30 18:54:20 volumio volumio[28459]: info: sendMpdCommand play took 617 milliseconds Apr 30 18:54:20 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:20 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:20 volumio volumio[28459]: info: ------------------------------ 573ms Apr 30 18:54:20 volumio volumio[28459]: info: ------------------------------ 572ms Apr 30 18:54:20 volumio volumio[28459]: info: sendMpdCommand stop took 37 milliseconds Apr 30 18:54:20 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:20 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:20 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand clear Apr 30 18:54:20 volumio volumio[28459]: info: Apr 30 18:54:20 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:20 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:20 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:20 volumio volumio[28459]: info: Apr 30 18:54:20 volumio volumio[28459]: ---------------------------- MPD announces state update: player Apr 30 18:54:20 volumio volumio[28459]: info: ControllerMpd::getState Apr 30 18:54:20 volumio volumio[28459]: verbose: ControllerMpd::sendMpdCommand status Apr 30 18:54:20 volumio volumio[28459]: <--- Last few GCs ---> Apr 30 18:54:20 volumio volumio[28459]: [28459:0x53ab008] 78068704 ms: Scavenge 459.6 (493.5) -> 459.2 (497.0) MB, 7.8 / 0.0 ms (average mu = 0.805, current mu = 0.777) allocation failure Apr 30 18:54:20 volumio volumio[28459]: [28459:0x53ab008] 78068737 ms: Scavenge 462.6 (497.0) -> 462.5 (497.5) MB, 5.5 / 0.0 ms (average mu = 0.805, current mu = 0.777) allocation failure Apr 30 18:54:20 volumio volumio[28459]: [28459:0x53ab008] 78068748 ms: Scavenge 463.0 (497.5) -> 462.6 (501.0) MB, 7.7 / 0.0 ms (average mu = 0.805, current mu = 0.777) allocation failure Apr 30 18:54:20 volumio volumio[28459]: <--- JS stacktrace ---> Apr 30 18:54:20 volumio volumio[28459]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Apr 30 18:54:20 volumio volumio-remote-updater[484]: [2024-04-30 18:54:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 30 18:54:20 volumio volumio-remote-updater[484]: [2024-04-30 18:54:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 30 18:54:20 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Apr 30 18:54:20 volumio systemd[1]: media-Muziek.mount: Succeeded. Apr 30 18:54:21 volumio systemd[1]: volumio.service: Failed with result 'signal'. Apr 30 18:54:21 volumio systemd[1]: Started dynamicswap service. Apr 30 18:54:21 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 30 18:54:21 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 30 18:54:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Apr 30 18:54:21 volumio systemd[1]: Started dynamicswap service. Apr 30 18:54:21 volumio systemd[1]: Stopped Volumio Backend Module. Apr 30 18:54:21 volumio systemd[1]: Started Volumio Backend Module. Apr 30 18:54:21 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 30 18:54:22 volumio volumio[22113]: info: ------------------------------------------- Apr 30 18:54:22 volumio volumio[22113]: info: ----- Volumio3 ---- Apr 30 18:54:22 volumio volumio[22113]: info: ------------------------------------------- Apr 30 18:54:22 volumio volumio[22113]: info: ----- System startup ---- Apr 30 18:54:22 volumio volumio[22113]: info: ------------------------------------------- Apr 30 18:54:23 volumio volumio[22113]: info: MYVOLUMIO Environment detected Apr 30 18:54:23 volumio volumio[22113]: info: Plugin folders cleanup Apr 30 18:54:23 volumio volumio[22113]: info: Scanning into folder /volumio/app/plugins/ Apr 30 18:54:23 volumio volumio[22113]: info: Scanning category audio_interface Apr 30 18:54:23 volumio volumio[22113]: info: Scanning category miscellanea Apr 30 18:54:23 volumio volumio[22113]: info: Scanning category music_service Apr 30 18:54:23 volumio volumio[22113]: info: Scanning category plugins.json Apr 30 18:54:23 volumio volumio[22113]: info: Scanning category system_controller Apr 30 18:54:23 volumio volumio[22113]: info: Scanning category user_interface Apr 30 18:54:23 volumio volumio[22113]: info: Scanning into folder /data/plugins/ Apr 30 18:54:23 volumio volumio[22113]: info: Scanning category music_service Apr 30 18:54:23 volumio volumio[22113]: info: Plugin folders cleanup completed Apr 30 18:54:23 volumio volumio[22113]: info: ------------------------------------------- Apr 30 18:54:23 volumio volumio[22113]: info: ----- Core plugins startup ---- Apr 30 18:54:23 volumio volumio[22113]: info: ------------------------------------------- Apr 30 18:54:23 volumio volumio[22113]: info: Loading plugins from folder /volumio/app/plugins/ Apr 30 18:54:23 volumio volumio[22113]: info: Adding plugin upnp to MyMusic Plugins Apr 30 18:54:23 volumio volumio[22113]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 30 18:54:23 volumio volumio[22113]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 30 18:54:23 volumio volumio[22113]: info: Loading plugins from folder /data/plugins/ Apr 30 18:54:23 volumio volumio[22113]: info: Loading plugin "system"... Apr 30 18:54:23 volumio volumio[22113]: info: Loading plugin "appearance"... Apr 30 18:54:23 volumio volumio[22113]: info: Loading plugin "network"... Apr 30 18:54:24 volumio volumio[22113]: info: Refreshing Cached IP Addresses Apr 30 18:54:24 volumio sudo[22139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "services"... Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "alsa_controller"... Apr 30 18:54:24 volumio sudo[22141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 30 18:54:24 volumio sudo[22139]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:24 volumio sudo[22141]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:24 volumio sudo[22139]: pam_unix(sudo:session): session closed for user root Apr 30 18:54:24 volumio sudo[22147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 30 18:54:24 volumio sudo[22141]: pam_unix(sudo:session): session closed for user root Apr 30 18:54:24 volumio sudo[22147]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:24 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "wizard"... Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "networkfs"... Apr 30 18:54:24 volumio volumio[22113]: info: Starting Udev Watcher for removable devices Apr 30 18:54:24 volumio volumio[22113]: info: Ignoring mount for partition: BOOT Apr 30 18:54:24 volumio volumio[22113]: info: Ignoring mount for partition: volumio Apr 30 18:54:24 volumio volumio[22113]: info: Ignoring mount for partition: volumio_data Apr 30 18:54:24 volumio volumio[22113]: info: Mounting Device Muziek Apr 30 18:54:24 volumio sudo[22182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Muziek -o noatime Apr 30 18:54:24 volumio sudo[22182]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:24 volumio sudo[22182]: pam_unix(sudo:session): session closed for user root Apr 30 18:54:24 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "volumio_command_line_client"... Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "upnp"... Apr 30 18:54:24 volumio volumio[22113]: info: [1714496064439] Starting Upmpd Daemon Apr 30 18:54:24 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "my_music"... Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "mpd"... Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "upnp_browser"... Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "alarm-clock"... Apr 30 18:54:24 volumio volumio[22113]: info: Plugin airplay_emulation is not enabled Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "last_100"... Apr 30 18:54:24 volumio volumio[22113]: info: Loading plugin "webradio"... Apr 30 18:54:25 volumio volumio[22113]: info: Loading plugin "i2s_dacs"... Apr 30 18:54:25 volumio volumio[22113]: info: I2S DAC not set, start Auto-detection Apr 30 18:54:25 volumio volumio[22113]: info: Loading plugin "volumiodiscovery"... Apr 30 18:54:25 volumio volumio[22113]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 30 18:54:25 volumio node[22113]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 30 18:54:25 volumio volumio[22113]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 30 18:54:25 volumio node[22113]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 30 18:54:25 volumio volumio[22113]: *** WARNING *** For more information see Apr 30 18:54:25 volumio node[22113]: *** WARNING *** For more information see Apr 30 18:54:25 volumio volumio[22113]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 30 18:54:25 volumio node[22113]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 30 18:54:25 volumio volumio[22113]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 30 18:54:25 volumio node[22113]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 30 18:54:25 volumio volumio[22113]: *** WARNING *** For more information see Apr 30 18:54:25 volumio node[22113]: *** WARNING *** For more information see Apr 30 18:54:25 volumio volumio[22113]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 30 18:54:25 volumio volumio[22113]: info: Discovery: Started advertising with name: Volumio Apr 30 18:54:25 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 30 18:54:25 volumio volumio[22113]: info: Loading plugin "bandcamp"... Apr 30 18:54:25 volumio sudo[22147]: pam_unix(sudo:session): session closed for user root Apr 30 18:54:25 volumio volumio[22113]: info: Loading plugin "soundcloud"... Apr 30 18:54:25 volumio volumio-remote-updater[484]: [2024-04-30 18:54:25] [connect] Successful connection Apr 30 18:54:26 volumio volumio[22113]: info: Loading plugin "outputs"... Apr 30 18:54:26 volumio volumio[22113]: info: Loading plugin "albumart"... Apr 30 18:54:26 volumio volumio[22113]: info: Plugin example_plugin is not enabled Apr 30 18:54:26 volumio volumio[22113]: info: Loading plugin "inputs"... Apr 30 18:54:26 volumio volumio[22113]: info: Loading plugin "updater_comm"... Apr 30 18:54:26 volumio volumio[22113]: info: Plugin mpdemulation is not enabled Apr 30 18:54:26 volumio volumio[22113]: info: Loading plugin "rest_api"... Apr 30 18:54:26 volumio volumio[22113]: info: Loading plugin "websocket"... Apr 30 18:54:26 volumio volumio[22113]: info: Loading plugin "lms"... Apr 30 18:54:26 volumio volumio[22113]: info: Loading plugin "minidlna"... Apr 30 18:54:26 volumio volumio[22113]: Forking 3 albumart workers Apr 30 18:54:26 volumio volumio[22113]: info: Applying required configuration parameters for plugin minidlna Apr 30 18:54:26 volumio volumio[22113]: info: Loading i18n strings for locale nl Apr 30 18:54:26 volumio volumio[22113]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Apr 30 18:54:26 volumio volumio[22113]: Updating browse sources language Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::initPlayerControls Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: Express server listening on port 3000 Apr 30 18:54:26 volumio volumio[22113]: [Metrics] WebUI: 4s 244.54ms Apr 30 18:54:26 volumio volumio[22113]: info: CoreStateMachine::resetVolumioState Apr 30 18:54:26 volumio volumio[22113]: info: CoreStateMachine::getcurrentVolume Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::volumioRetrievevolume Apr 30 18:54:26 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 18:54:26 volumio volumio[22113]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 30 18:54:26 volumio volumio[22113]: info: Completed loading Core Plugins Apr 30 18:54:26 volumio volumio[22113]: info: Preparing to generate the ALSA configuration file Apr 30 18:54:26 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 1 Apr 30 18:54:26 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 2 Apr 30 18:54:26 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 3 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 4 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 5 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 6 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 7 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 8 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 9 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 10 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 11 Apr 30 18:54:27 volumio volumio[22113]: Starting albumart workers Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 12 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 13 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 14 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 15 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 16 Apr 30 18:54:27 volumio volumio[22113]: Starting albumart workers Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 17 Apr 30 18:54:27 volumio volumio-remote-updater[484]: [2024-04-30 18:54:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714496065 101 Apr 30 18:54:27 volumio volumio[22113]: Starting albumart workers Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 18 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 19 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 20 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 21 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 22 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 23 Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:27 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 24 Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 25 Apr 30 18:54:27 volumio volumio[22113]: info: Scanning new location : "USB/Muziek" Apr 30 18:54:27 volumio volumio[22113]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.234 UA: okhttp/4.9.2 Total Clients: 26 Apr 30 18:54:27 volumio volumio[22113]: info: VolumeController:: Volume=25 Mute =false Apr 30 18:54:27 volumio volumio[22113]: info: CoreStateMachine::pushState Apr 30 18:54:27 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:27 volumio volumio[22113]: info: CoreStateMachine::updateTrackBlock Apr 30 18:54:27 volumio volumio[22113]: info: CorePlayQueue::getTrackBlock Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioRetrievevolume Apr 30 18:54:27 volumio volumio[22113]: info: Asound.conf file unchanged, so no further update is needed Apr 30 18:54:27 volumio volumio[22113]: info: Output device has changed, restarting MPD Apr 30 18:54:27 volumio volumio[22113]: info: ___________ START PLUGINS ___________ Apr 30 18:54:27 volumio sudo[22241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 18:54:27 volumio volumio[22113]: info: ControllerMpd::onStart: Initializing MPD Apr 30 18:54:27 volumio volumio[22113]: info: Creating MPD Configuration file Apr 30 18:54:27 volumio sudo[22241]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:27 volumio sudo[22249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 18:54:27 volumio sudo[22241]: pam_unix(sudo:session): session closed for user root Apr 30 18:54:27 volumio volumio[22113]: info: [1714496067624] CoreMusicLibrary::Adding element Media Servers Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 18:54:27 volumio sudo[22263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 18:54:27 volumio sudo[22249]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:27 volumio sudo[22271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 18:54:27 volumio sudo[22263]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:27 volumio sudo[22271]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 18:54:27 volumio volumio[22113]: info: [1714496067692] CoreMusicLibrary::Adding element Last_100 Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 18:54:27 volumio volumio[22113]: info: [1714496067696] CoreMusicLibrary::Adding element Webradio Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 18:54:27 volumio sudo[22263]: pam_unix(sudo:session): session closed for user root Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 30 18:54:27 volumio systemd[1]: Stopping Music Player Daemon... Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 18:54:27 volumio volumio[22113]: info: [1714496067717] CoreMusicLibrary::Adding element Bandcamp Discover Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 18:54:27 volumio volumio[22113]: Cannot find translation for source Bandcamp Discover Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 18:54:27 volumio volumio[22113]: info: [1714496067731] CoreMusicLibrary::Adding element SoundCloud Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 18:54:27 volumio volumio[22113]: Cannot find translation for source Bandcamp Discover Apr 30 18:54:27 volumio volumio[22113]: Cannot find translation for source SoundCloud Apr 30 18:54:27 volumio volumio[22113]: info: Loading i18n strings for locale nl Apr 30 18:54:27 volumio volumio[22113]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Apr 30 18:54:27 volumio sudo[22300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Apr 30 18:54:27 volumio volumio[22113]: info: Volumio Calling Home Apr 30 18:54:27 volumio sudo[22300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:27 volumio systemd[1]: Starting Logitech Media Server Daemon... Apr 30 18:54:27 volumio systemd[1]: Started Logitech Media Server Daemon. Apr 30 18:54:27 volumio sudo[22300]: pam_unix(sudo:session): session closed for user root Apr 30 18:54:27 volumio systemd[22312]: logitechmediaserver.service: Failed to execute command: No such file or directory Apr 30 18:54:27 volumio systemd[22312]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Apr 30 18:54:27 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Apr 30 18:54:27 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Apr 30 18:54:27 volumio volumio[22113]: info: Setting Device type: Tinkerboard Apr 30 18:54:27 volumio volumio[22113]: info: CoreStateMachine::setRepeat false single undefined Apr 30 18:54:27 volumio volumio[22113]: info: CoreStateMachine::pushState Apr 30 18:54:27 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:27 volumio volumio[22113]: info: CoreStateMachine::setRandom true Apr 30 18:54:27 volumio volumio[22113]: info: CoreStateMachine::pushState Apr 30 18:54:27 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 18:54:27 volumio volumio[22113]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 30 18:54:28 volumio volumio[22113]: info: Volumio called home Apr 30 18:54:28 volumio volumio[22113]: info: logitechmediaserver started Apr 30 18:54:28 volumio volumio[22113]: info: MPD Permissions set Apr 30 18:54:28 volumio volumio[22113]: info: MPD Permissions set Apr 30 18:54:28 volumio volumio[22113]: info: minidlna: Found miniDLNA version 1.2.1 Apr 30 18:54:28 volumio volumio[22113]: info: minidlna: Starting minidlna.service Apr 30 18:54:28 volumio sudo[22322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Apr 30 18:54:28 volumio sudo[22322]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 18:54:28 volumio sudo[22322]: pam_unix(sudo:session): session closed for user root Apr 30 18:54:28 volumio volumio[22113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 27 Apr 30 18:54:28 volumio volumio[22113]: info: VolumeController:: Volume=25 Mute =false Apr 30 18:54:28 volumio volumio[22113]: info: CoreStateMachine::pushState Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioPushState Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 30 18:54:28 volumio systemd[1]: mpd.service: Succeeded. Apr 30 18:54:28 volumio systemd[1]: Stopped Music Player Daemon. Apr 30 18:54:28 volumio systemd[1]: Starting Music Player Daemon... Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 18:54:28 volumio volumio[22113]: info: Discovery: adding 29f37aea-6a6c-40b3-bc9e-0c5144eff2cc Apr 30 18:54:28 volumio volumio[22113]: info: Discovery: Found device Volumio Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 30 18:54:28 volumio volumio[22113]: info: minidlna: systemctl start minidlna.service succeeded. Apr 30 18:54:28 volumio volumio[22113]: info: Discovery: this is already registered, 29f37aea-6a6c-40b3-bc9e-0c5144eff2cc Apr 30 18:54:28 volumio volumio[22113]: info: Discovery: Found device Volumio Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 30 18:54:28 volumio volumio[22113]: info: Received Get System Info Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:54:28 volumio volumio[22113]: info: Discovery: Getting this device information Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:28 volumio volumio[22113]: info: Listing playlists Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetQueue Apr 30 18:54:28 volumio volumio[22113]: info: CoreStateMachine::getQueue Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getQueue Apr 30 18:54:28 volumio volumio[22113]: info: Received Get System Info Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 18:54:28 volumio volumio[22113]: info: Discovery: Getting this device information Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioGetState Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::getTrack 0 Apr 30 18:54:28 volumio volumio[22113]: info: Preload queue cleared Apr 30 18:54:28 volumio volumio[22113]: info: CoreCommandRouter::volumioAddQueueItems Apr 30 18:54:28 volumio volumio[22113]: info: CoreStateMachine::addQueueItems Apr 30 18:54:28 volumio volumio[22113]: info: CorePlayQueue::addQueueItems Apr 30 18:54:28 volumio volumio[22113]: info: Preload queue cleared Apr 30 18:54:28 volumio volumio[22113]: info: Adding Item to queue: music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac Apr 30 18:54:28 volumio volumio[22113]: info: Exploding uri music-library/USB/Muziek/Darondo/Darondo - Let My People Go (2006)/Darondo - Let My People Go - 03 - Didn't I.flac in service mpd Apr 30 18:54:28 volumio volumio[22113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 18:54:28 volumio volumio[22113]: TypeError: Cannot read property 'then' of undefined Apr 30 18:54:28 volumio volumio[22113]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Apr 30 18:54:28 volumio volumio[22113]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Apr 30 18:54:28 volumio volumio[22113]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 30 18:54:28 volumio volumio[22113]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 30 18:54:28 volumio volumio[22113]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 30 18:54:28 volumio volumio[22113]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 30 18:54:28 volumio volumio[22113]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Apr 30 18:54:28 volumio volumio[22113]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:447:28) Apr 30 18:54:28 volumio volumio[22113]: at CoreCommandRouter.addPlay (/volumio/app/index.js:465:10) Apr 30 18:54:28 volumio volumio[22113]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:475:17) Apr 30 18:54:28 volumio volumio[22113]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Apr 30 18:54:28 volumio volumio[22113]: at Socket.emit (events.js:400:28) Apr 30 18:54:28 volumio volumio[22113]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 30 18:54:28 volumio volumio[22113]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 30 18:54:28 volumio volumio[22113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 18:54:29 volumio sudo[22353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-30 18:53 Apr 30 18:54:29 volumio sudo[22353]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:15:21 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="293a767fd1b71cabc5f5be4916f770a0"