-- Logs begin at Tue 2026-03-31 18:30:42 UTC, end at Thu 2026-04-02 20:23:28 UTC. -- Apr 02 20:22:01 volumio volumio[1193]: info: CoreCommandRouter::volumioGetVisibleSources Apr 02 20:22:01 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 20:22:01 volumio volumio[1193]: info: CoreCommandRouter::volumioGetQueue Apr 02 20:22:01 volumio volumio[1193]: info: CoreStateMachine::getQueue Apr 02 20:22:01 volumio volumio[1193]: info: CorePlayQueue::getQueue Apr 02 20:22:02 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:07 volumio volumio[1193]: Searching all installed plugins Apr 02 20:22:07 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 20:22:07 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: , search Apr 02 20:22:07 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 02 20:22:07 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 02 20:22:07 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 02 20:22:07 volumio volumio[1193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 02 20:22:07 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 02 20:22:07 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:08 volumio volumio[1193]: info: All search sources collected, pushing search results Apr 02 20:22:12 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 02 20:22:12 volumio volumio[1193]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 02 20:22:12 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 02 20:22:12 volumio volumio[1193]: info: Received Get System Version Apr 02 20:22:12 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 02 20:22:12 volumio volumio[1193]: info: Received Get System Info Apr 02 20:22:12 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 02 20:22:12 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 02 20:22:12 volumio volumio[1193]: info: Discovery: Getting this device information Apr 02 20:22:12 volumio volumio[1193]: info: CoreCommandRouter::volumioGetState Apr 02 20:22:12 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 02 20:22:12 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:18 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:19 volumio volumio[1193]: Searching all installed plugins Apr 02 20:22:19 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 20:22:19 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: , search Apr 02 20:22:19 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 02 20:22:19 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 02 20:22:19 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 02 20:22:19 volumio volumio[1193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 02 20:22:19 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 02 20:22:20 volumio volumio[1193]: info: An error occurred while querying SHOUTCAST Apr 02 20:22:20 volumio volumio[1193]: Searching all installed plugins Apr 02 20:22:20 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 20:22:20 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: , search Apr 02 20:22:20 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 02 20:22:20 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 02 20:22:20 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 02 20:22:20 volumio volumio[1193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 02 20:22:20 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 02 20:22:20 volumio volumio[1193]: info: An error occurred while querying SHOUTCAST Apr 02 20:22:21 volumio volumio[1193]: Searching all installed plugins Apr 02 20:22:21 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 20:22:21 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: , search Apr 02 20:22:21 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 02 20:22:21 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 02 20:22:21 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 02 20:22:21 volumio volumio[1193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 02 20:22:21 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 02 20:22:23 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:24 volumio volumio[1193]: error: Search in plugin webradio timed out Apr 02 20:22:24 volumio volumio[1193]: info: All search sources collected, pushing search results Apr 02 20:22:25 volumio volumio[1193]: error: Search in plugin webradio timed out Apr 02 20:22:25 volumio volumio[1193]: info: All search sources collected, pushing search results Apr 02 20:22:26 volumio volumio[1193]: error: Search in plugin webradio timed out Apr 02 20:22:26 volumio volumio[1193]: info: All search sources collected, pushing search results Apr 02 20:22:28 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:32 volumio volumio[1193]: Searching all installed plugins Apr 02 20:22:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 20:22:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: , search Apr 02 20:22:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 02 20:22:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 02 20:22:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 02 20:22:32 volumio volumio[1193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 02 20:22:32 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 02 20:22:33 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:35 volumio volumio[1193]: Searching all installed plugins Apr 02 20:22:35 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 20:22:35 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: , search Apr 02 20:22:35 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 02 20:22:35 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 02 20:22:35 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 02 20:22:35 volumio volumio[1193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 02 20:22:35 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 02 20:22:36 volumio volumio[1193]: info: All search sources collected, pushing search results Apr 02 20:22:36 volumio volumio[1193]: Searching all installed plugins Apr 02 20:22:36 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 02 20:22:36 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: , search Apr 02 20:22:36 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 02 20:22:36 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 02 20:22:36 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 02 20:22:36 volumio volumio[1193]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 02 20:22:36 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 02 20:22:37 volumio volumio[1193]: error: Search in plugin webradio timed out Apr 02 20:22:37 volumio volumio[1193]: info: All search sources collected, pushing search results Apr 02 20:22:38 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:41 volumio volumio[1193]: error: Search in plugin webradio timed out Apr 02 20:22:41 volumio volumio[1193]: info: All search sources collected, pushing search results Apr 02 20:22:43 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 02 20:22:43 volumio volumio[1193]: info: CURURI: artists:// Apr 02 20:22:43 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:22:43 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:48 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:53 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:22:54 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 02 20:22:54 volumio volumio[1193]: info: CURURI: artists://Kula%20Shaker Apr 02 20:22:54 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:22:54 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:22:54 volumio volumio[1193]: info: Preloading song: music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F01%20Hey%20Dude.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F02%20Knight%20On%20The%20Town.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F03%20Temple%20Of%20Everlasting%20Light.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F04%20Govinda.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F05%20Smart%20Dogs.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F06%20Magic%20Theatre.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F07%20Into%20The%20Deep.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F08%20Sleeping%20Jiva.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F09%20Tattva.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F10%20Grateful%20When%20You're%20Dead%20-%20Jerry%20Was%20There.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F11%20303.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F12%20Start%20All%20Over.flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:22:54 volumio volumio[1193]: info: Exploding uri music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac in service mpd Apr 02 20:22:54 volumio volumio[1193]: info: ALBUMART /albumart?cacheid=237&web=Kula%20Shaker/K/extralarge&path=%2Fmnt%2FUSB%2FVolumio1%2FMusic2%2FKula%20Shaker%2FK%2F13%20Hollow%20Man%20(Parts%201%20%26%202).flac&metadata=false Apr 02 20:22:54 volumio volumio[1193]: info: URI /mnt/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:22:57 volumio volumio[1193]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ramsey%20Lewis/115a38fe-7113-45c9-9df7-ead85ef2b370.jpg' Apr 02 20:22:59 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:23:04 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:23:09 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:23:09 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::ClearQueue Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::stop Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::stPlaybackTimer Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::serviceStop Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::serviceStop Apr 02 20:23:09 volumio volumio[1193]: info: [1775161389360] ControllerWebradio::stop Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::clearPlayQueue Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::addQueueItems Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::addQueueItems Apr 02 20:23:09 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::volumioPlay Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::play index 0 Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::addQueueItems Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::addQueueItems Apr 02 20:23:09 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:23:09 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:23:09 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::stop Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::play index undefined Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::startPlaybackTimer Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::clearAddPlayTracks USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 20:23:09 volumio volumio[1193]: info: Apr 02 20:23:09 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand stop took 28 milliseconds Apr 02 20:23:09 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand stop took 6 milliseconds Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 20:23:09 volumio volumio[1193]: info: Apr 02 20:23:09 volumio volumio[1193]: ---------------------------- MPD announces system playlist update Apr 02 20:23:09 volumio volumio[1193]: info: Ignoring MPD Status Update Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand status took 1 milliseconds Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand clear took 1 milliseconds Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand add "USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac" Apr 02 20:23:09 volumio volumio[1193]: error: updateQueue error: null Apr 02 20:23:09 volumio volumio[1193]: info: ------------------------------ 5ms Apr 02 20:23:09 volumio volumio[1193]: info: Apr 02 20:23:09 volumio volumio[1193]: ---------------------------- MPD announces system playlist update Apr 02 20:23:09 volumio volumio[1193]: info: Ignoring MPD Status Update Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand add "USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac" took 5 milliseconds Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand play Apr 02 20:23:09 volumio volumio[1193]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 20:23:09 volumio volumio[1193]: info: ------------------------------ 13ms Apr 02 20:23:09 volumio volumio[1193]: info: ------------------------------ 3ms Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand play took 1 milliseconds Apr 02 20:23:09 volumio volumio[1193]: info: Apr 02 20:23:09 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:09 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:09 volumio volumio[1193]: info: Apr 02 20:23:09 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand status took 13 milliseconds Apr 02 20:23:09 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand status took 1 milliseconds Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:09 volumio volumio[1193]: info: ControllerMpd::pushState Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:09 volumio volumio[1193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hey Dude","artist":"Kula Shaker","album":"K","uri":"USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac","trackType":"flac"} Apr 02 20:23:09 volumio volumio[1193]: verbose: CURRENT POSITION 0 Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::syncState stateService play Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus stop Apr 02 20:23:09 volumio volumio[1193]: info: ------------------------------ 21ms Apr 02 20:23:09 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 02 20:23:09 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:09 volumio volumio[1193]: info: ControllerMpd::pushState Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:09 volumio volumio[1193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":591,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Hey Dude","artist":"Kula Shaker","album":"K","uri":"USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac","trackType":"flac"} Apr 02 20:23:09 volumio volumio[1193]: verbose: CURRENT POSITION 0 Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::syncState stateService play Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus play Apr 02 20:23:09 volumio volumio[1193]: info: Received an update from plugin. extracting info from payload Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:09 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:09 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:09 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:09 volumio volumio[1193]: info: ------------------------------ 14ms Apr 02 20:23:11 volumio volumio[1193]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Similou/9d3a2db7-fcb6-48fc-ad68-f18de1c44044.jpg' Apr 02 20:23:11 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::ClearQueue Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::stop Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::stPlaybackTimer Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::serviceStop Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrack 0 Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::serviceStop Apr 02 20:23:11 volumio volumio[1193]: info: ControllerMpd::stop Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::clearPlayQueue Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::addQueueItems Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::addQueueItems Apr 02 20:23:11 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::volumioPlay Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::play index 1 Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::addQueueItems Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::addQueueItems Apr 02 20:23:11 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:23:11 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:23:11 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::stop Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::play index undefined Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrack 1 Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::startPlaybackTimer Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrack 1 Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::clearAddPlayTracks USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 20:23:11 volumio volumio[1193]: info: Apr 02 20:23:11 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand stop took 149 milliseconds Apr 02 20:23:11 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand stop took 138 milliseconds Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 20:23:11 volumio volumio[1193]: info: Apr 02 20:23:11 volumio volumio[1193]: ---------------------------- MPD announces system playlist update Apr 02 20:23:11 volumio volumio[1193]: info: Ignoring MPD Status Update Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand status took 1 milliseconds Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand clear took 0 milliseconds Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand add "USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac" Apr 02 20:23:11 volumio volumio[1193]: error: updateQueue error: null Apr 02 20:23:11 volumio volumio[1193]: info: ------------------------------ 1ms Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:11 volumio volumio[1193]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 20:23:11 volumio volumio[1193]: info: ------------------------------ 2ms Apr 02 20:23:11 volumio volumio[1193]: info: Apr 02 20:23:11 volumio volumio[1193]: ---------------------------- MPD announces system playlist update Apr 02 20:23:11 volumio volumio[1193]: info: Ignoring MPD Status Update Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand add "USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac" took 1 milliseconds Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand play Apr 02 20:23:11 volumio volumio[1193]: info: ------------------------------ 0ms Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand play took 1 milliseconds Apr 02 20:23:11 volumio volumio[1193]: info: Apr 02 20:23:11 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:11 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand status took 8 milliseconds Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:11 volumio volumio[1193]: info: Apr 02 20:23:11 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:11 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:11 volumio volumio[1193]: info: ControllerMpd::pushState Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrack 1 Apr 02 20:23:11 volumio volumio[1193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Knight On The Town","artist":"Kula Shaker","album":"K","uri":"USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac","trackType":"flac"} Apr 02 20:23:11 volumio volumio[1193]: verbose: CURRENT POSITION 1 Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::syncState stateService play Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus stop Apr 02 20:23:11 volumio volumio[1193]: info: ------------------------------ 11ms Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand status took 2 milliseconds Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:11 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 02 20:23:11 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:11 volumio volumio[1193]: info: ControllerMpd::pushState Apr 02 20:23:11 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrack 1 Apr 02 20:23:11 volumio volumio[1193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"788 Kbps","isStreaming":false,"title":"Knight On The Town","artist":"Kula Shaker","album":"K","uri":"USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac","trackType":"flac"} Apr 02 20:23:11 volumio volumio[1193]: verbose: CURRENT POSITION 1 Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::syncState stateService play Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus play Apr 02 20:23:11 volumio volumio[1193]: info: Received an update from plugin. extracting info from payload Apr 02 20:23:11 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:11 volumio volumio[1193]: info: CorePlayQueue::getTrack 1 Apr 02 20:23:12 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 20:23:12 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:12 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:12 volumio volumio[1193]: info: CorePlayQueue::getTrack 1 Apr 02 20:23:12 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:12 volumio volumio[1193]: info: ------------------------------ 9ms Apr 02 20:23:12 volumio volumio[1193]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Harold%20Melvin%20%26%20The%20Blue%20Notes/e93ecfd3-8f71-42af-af5c-e6b29786ca35.jpg' Apr 02 20:23:14 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:23:14 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::ClearQueue Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::stop Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::stPlaybackTimer Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrack 1 Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::serviceStop Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrack 1 Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::serviceStop Apr 02 20:23:14 volumio volumio[1193]: info: ControllerMpd::stop Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::clearPlayQueue Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::addQueueItems Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::addQueueItems Apr 02 20:23:14 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::volumioPlay Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::play index 2 Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::addQueueItems Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::addQueueItems Apr 02 20:23:14 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:23:14 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:23:14 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::stop Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::play index undefined Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrack 2 Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::startPlaybackTimer Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrack 2 Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::clearAddPlayTracks USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 20:23:14 volumio volumio[1193]: info: Apr 02 20:23:14 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand stop took 134 milliseconds Apr 02 20:23:14 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand stop took 117 milliseconds Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 20:23:14 volumio volumio[1193]: info: Apr 02 20:23:14 volumio volumio[1193]: ---------------------------- MPD announces system playlist update Apr 02 20:23:14 volumio volumio[1193]: info: Ignoring MPD Status Update Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand status took 1 milliseconds Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand clear took 1 milliseconds Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand add "USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac" Apr 02 20:23:14 volumio volumio[1193]: error: updateQueue error: null Apr 02 20:23:14 volumio volumio[1193]: info: ------------------------------ 2ms Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:14 volumio volumio[1193]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 20:23:14 volumio volumio[1193]: info: ------------------------------ 4ms Apr 02 20:23:14 volumio volumio[1193]: info: Apr 02 20:23:14 volumio volumio[1193]: ---------------------------- MPD announces system playlist update Apr 02 20:23:14 volumio volumio[1193]: info: Ignoring MPD Status Update Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand add "USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac" took 3 milliseconds Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand play Apr 02 20:23:14 volumio volumio[1193]: info: ------------------------------ 1ms Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand play took 1 milliseconds Apr 02 20:23:14 volumio volumio[1193]: info: Apr 02 20:23:14 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:14 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:14 volumio volumio[1193]: info: Apr 02 20:23:14 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand status took 9 milliseconds Apr 02 20:23:14 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand status took 1 milliseconds Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:14 volumio volumio[1193]: info: ControllerMpd::pushState Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrack 2 Apr 02 20:23:14 volumio volumio[1193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"790 Kbps","isStreaming":false,"title":"Temple Of Everlasting Light","artist":"Kula Shaker","album":"K","uri":"USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac","trackType":"flac"} Apr 02 20:23:14 volumio volumio[1193]: verbose: CURRENT POSITION 2 Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::syncState stateService play Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus stop Apr 02 20:23:14 volumio volumio[1193]: info: ------------------------------ 12ms Apr 02 20:23:14 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 02 20:23:14 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:14 volumio volumio[1193]: info: ControllerMpd::pushState Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrack 2 Apr 02 20:23:14 volumio volumio[1193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Temple Of Everlasting Light","artist":"Kula Shaker","album":"K","uri":"USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac","trackType":"flac"} Apr 02 20:23:14 volumio volumio[1193]: verbose: CURRENT POSITION 2 Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::syncState stateService play Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus play Apr 02 20:23:14 volumio volumio[1193]: info: Received an update from plugin. extracting info from payload Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrack 2 Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:14 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:14 volumio volumio[1193]: info: CorePlayQueue::getTrack 2 Apr 02 20:23:14 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:14 volumio volumio[1193]: info: ------------------------------ 9ms Apr 02 20:23:16 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::ClearQueue Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::stop Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::stPlaybackTimer Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrack 2 Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::serviceStop Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrack 2 Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::serviceStop Apr 02 20:23:16 volumio volumio[1193]: info: ControllerMpd::stop Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::clearPlayQueue Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::addQueueItems Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::addQueueItems Apr 02 20:23:16 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/01 Hey Dude.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/02 Knight On The Town.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/03 Temple Of Everlasting Light.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::volumioPlay Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::play index 3 Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::addQueueItems Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::addQueueItems Apr 02 20:23:16 volumio volumio[1193]: info: Preload queue cleared Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/05 Smart Dogs.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/06 Magic Theatre.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/07 Into The Deep.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/08 Sleeping Jiva.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/09 Tattva.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/10 Grateful When You're Dead - Jerry Was There.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/11 303.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/12 Start All Over.flac Apr 02 20:23:16 volumio volumio[1193]: info: Adding Item to queue: music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:23:16 volumio volumio[1193]: info: Using cached record of: music-library/USB/Volumio1/Music2/Kula Shaker/K/13 Hollow Man (Parts 1 & 2).flac Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::stop Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::volumioPushQueue Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::saveQueue Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::play index undefined Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::updateTrackBlock Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrackBlock Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrack 3 Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::startPlaybackTimer Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrack 3 Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::clearAddPlayTracks USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand stop Apr 02 20:23:16 volumio volumio[1193]: info: Apr 02 20:23:16 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand stop took 145 milliseconds Apr 02 20:23:16 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand stop took 137 milliseconds Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand clear Apr 02 20:23:16 volumio volumio[1193]: info: Apr 02 20:23:16 volumio volumio[1193]: ---------------------------- MPD announces system playlist update Apr 02 20:23:16 volumio volumio[1193]: info: Ignoring MPD Status Update Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand status took 2 milliseconds Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand clear took 1 milliseconds Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand add "USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac" Apr 02 20:23:16 volumio volumio[1193]: error: updateQueue error: null Apr 02 20:23:16 volumio volumio[1193]: info: ------------------------------ 2ms Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:16 volumio volumio[1193]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 02 20:23:16 volumio volumio[1193]: info: ------------------------------ 6ms Apr 02 20:23:16 volumio volumio[1193]: info: Apr 02 20:23:16 volumio volumio[1193]: ---------------------------- MPD announces system playlist update Apr 02 20:23:16 volumio volumio[1193]: info: Ignoring MPD Status Update Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand add "USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac" took 2 milliseconds Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand play Apr 02 20:23:16 volumio volumio[1193]: info: ------------------------------ 1ms Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand play took 0 milliseconds Apr 02 20:23:16 volumio volumio[1193]: info: Apr 02 20:23:16 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:16 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:16 volumio volumio[1193]: info: Apr 02 20:23:16 volumio volumio[1193]: ---------------------------- MPD announces state update: player Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand status took 9 milliseconds Apr 02 20:23:16 volumio volumio[1193]: info: ControllerMpd::getState Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand status Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand status took 1 milliseconds Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::parseState Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:16 volumio volumio[1193]: info: ControllerMpd::pushState Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrack 3 Apr 02 20:23:16 volumio volumio[1193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"586 Kbps","isStreaming":false,"title":"Govinda","artist":"Kula Shaker","album":"K","uri":"USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac","trackType":"flac"} Apr 02 20:23:16 volumio volumio[1193]: verbose: CURRENT POSITION 3 Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::syncState stateService play Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus stop Apr 02 20:23:16 volumio volumio[1193]: info: ------------------------------ 12ms Apr 02 20:23:16 volumio volumio[1193]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 02 20:23:16 volumio volumio[1193]: verbose: ControllerMpd::parseTrackInfo Apr 02 20:23:16 volumio volumio[1193]: info: ControllerMpd::pushState Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::servicePushState Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrack 3 Apr 02 20:23:16 volumio volumio[1193]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"Govinda","artist":"Kula Shaker","album":"K","uri":"USB/Volumio1/Music2/Kula Shaker/K/04 Govinda.flac","trackType":"flac"} Apr 02 20:23:16 volumio volumio[1193]: verbose: CURRENT POSITION 3 Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::syncState stateService play Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::syncState currentStatus play Apr 02 20:23:16 volumio volumio[1193]: info: Received an update from plugin. extracting info from payload Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrack 3 Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:16 volumio volumio[1193]: info: CoreStateMachine::pushState Apr 02 20:23:16 volumio volumio[1193]: info: CorePlayQueue::getTrack 3 Apr 02 20:23:16 volumio volumio[1193]: info: CoreCommandRouter::volumioPushState Apr 02 20:23:16 volumio volumio[1193]: info: ------------------------------ 8ms Apr 02 20:23:19 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:23:24 volumio kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Apr 02 20:23:28 volumio volumio[1193]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 20:23:28 volumio volumio[1193]: TypeError: Cannot read property 'slice' of null Apr 02 20:23:28 volumio volumio[1193]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 02 20:23:28 volumio volumio[1193]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 02 20:23:28 volumio volumio[1193]: at Object.onceWrapper (events.js:520:26) Apr 02 20:23:28 volumio volumio[1193]: at ClientRequest.emit (events.js:400:28) Apr 02 20:23:28 volumio volumio[1193]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Apr 02 20:23:28 volumio volumio[1193]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Apr 02 20:23:28 volumio volumio[1193]: at TLSSocket.socketOnData (_http_client.js:515:22) Apr 02 20:23:28 volumio volumio[1193]: at TLSSocket.emit (events.js:400:28) Apr 02 20:23:28 volumio volumio[1193]: at addChunk (internal/streams/readable.js:293:12) Apr 02 20:23:28 volumio volumio[1193]: at readableAddChunk (internal/streams/readable.js:267:9) Apr 02 20:23:28 volumio volumio[1193]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Apr 02 20:23:28 volumio volumio[1193]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Apr 02 20:23:28 volumio volumio[1193]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 02 20:23:28 volumio sudo[31784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-02 20:22 Apr 02 20:23:28 volumio sudo[31784]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"