Jun 06 10:16:01 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 10:16:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 10:16:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 10:16:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 06 10:16:09 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand update Jun 06 10:16:09 volumio volumio[977]: info: sendMpdCommand update took 2 milliseconds Jun 06 10:16:13 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 10:16:17 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 10:16:21 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 10:16:25 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 10:16:29 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 10:16:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 06 10:16:33 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 06 10:16:33 volumio dbus-daemon[609]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.32' (uid=0 pid=2188 comm="timedatectl show --property=NTPSynchronized --valu") Jun 06 10:16:33 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 06 10:16:33 volumio dbus-daemon[609]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 06 10:16:33 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 06 10:16:33 volumio setdatetime-helper.sh[2187]: Time is already synchronized. Jun 06 10:16:33 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 06 10:16:33 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 06 10:16:35 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Jun 06 10:16:35 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:16:38 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 06 10:16:38 volumio volumio[977]: info: CURURI: music-library Jun 06 10:16:38 volumio volumio[977]: info: Preload queue cleared Jun 06 10:16:39 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 06 10:16:39 volumio volumio[977]: info: CURURI: music-library/USB Jun 06 10:16:39 volumio volumio[977]: info: Preload queue cleared Jun 06 10:16:47 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 06 10:16:47 volumio volumio[977]: info: CURURI: music-library/USB/KINGSTON Jun 06 10:16:47 volumio volumio[977]: info: Preload queue cleared Jun 06 10:16:48 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 06 10:16:48 volumio volumio[977]: info: CURURI: music-library/USB/KINGSTON/Kerkmuziek Jun 06 10:16:48 volumio volumio[977]: info: Preload queue cleared Jun 06 10:17:03 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 06 10:17:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 06 10:17:03 volumio volumio[977]: info: CURURI: music-library/USB/Volumio_hdd_Dick Jun 06 10:17:03 volumio volumio[977]: error: Failed LSINFO: null Jun 06 10:17:04 volumio volumio[977]: info: Preload queue cleared Jun 06 10:17:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 06 10:17:10 volumio volumio[977]: info: CURURI: music-library Jun 06 10:17:10 volumio volumio[977]: info: Preload queue cleared Jun 06 10:17:11 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 06 10:17:11 volumio volumio[977]: info: CURURI: music-library/USB Jun 06 10:17:11 volumio volumio[977]: info: Preload queue cleared Jun 06 10:17:12 volumio volumio[977]: info: MPD Database updated - AlbumList cache refreshed Jun 06 10:17:12 volumio volumio[977]: info: Jun 06 10:17:12 volumio volumio[977]: ---------------------------- MPD announces state update: database Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: Jun 06 10:17:12 volumio volumio[977]: ---------------------------- MPD announces state update: update Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::getState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::getState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: MPD Database updated - AlbumList cache refreshed Jun 06 10:17:12 volumio volumio[977]: info: Jun 06 10:17:12 volumio volumio[977]: ---------------------------- MPD announces state update: database Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: Jun 06 10:17:12 volumio volumio[977]: ---------------------------- MPD announces state update: update Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::getState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::getState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: MPD Database updated - AlbumList cache refreshed Jun 06 10:17:12 volumio volumio[977]: info: Jun 06 10:17:12 volumio volumio[977]: ---------------------------- MPD announces state update: database Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: Jun 06 10:17:12 volumio volumio[977]: ---------------------------- MPD announces state update: update Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::getState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::getState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: MPD Database updated - AlbumList cache refreshed Jun 06 10:17:12 volumio volumio[977]: info: Jun 06 10:17:12 volumio volumio[977]: ---------------------------- MPD announces state update: database Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: Jun 06 10:17:12 volumio volumio[977]: ---------------------------- MPD announces state update: update Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::getState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::getState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 69 milliseconds Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 67 milliseconds Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 67 milliseconds Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: info: Command Router : Notfying DB Updatetrue Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::pushState Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::servicePushState Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 06 10:17:12 volumio volumio[977]: verbose: CURRENT POSITION 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: No code Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::pushState Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::servicePushState Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 06 10:17:12 volumio volumio[977]: verbose: CURRENT POSITION 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: No code Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: ------------------------------ 160ms Jun 06 10:17:12 volumio volumio[977]: info: ------------------------------ 161ms Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 182 milliseconds Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 181 milliseconds Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 181 milliseconds Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: info: Command Router : Notfying DB Updatetrue Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::pushState Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::servicePushState Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 06 10:17:12 volumio volumio[977]: verbose: CURRENT POSITION 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: No code Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::pushState Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::servicePushState Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 06 10:17:12 volumio volumio[977]: verbose: CURRENT POSITION 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: No code Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: ------------------------------ 236ms Jun 06 10:17:12 volumio volumio[977]: info: ------------------------------ 237ms Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 258 milliseconds Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 258 milliseconds Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 258 milliseconds Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: info: Command Router : Notfying DB Updatetrue Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::pushState Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::servicePushState Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 06 10:17:12 volumio volumio[977]: verbose: CURRENT POSITION 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: No code Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::pushState Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::servicePushState Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 06 10:17:12 volumio volumio[977]: verbose: CURRENT POSITION 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: No code Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: ------------------------------ 312ms Jun 06 10:17:12 volumio volumio[977]: info: ------------------------------ 313ms Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 375 milliseconds Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 374 milliseconds Jun 06 10:17:12 volumio volumio[977]: info: sendMpdCommand status took 374 milliseconds Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: info: Command Router : Notfying DB Updatetrue Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: verbose: ControllerMpd::parseState Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::pushState Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::servicePushState Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 06 10:17:12 volumio volumio[977]: verbose: CURRENT POSITION 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: No code Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: ControllerMpd::pushState Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::servicePushState Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 06 10:17:12 volumio volumio[977]: verbose: CURRENT POSITION 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: No code Jun 06 10:17:12 volumio volumio[977]: info: CoreStateMachine::pushState Jun 06 10:17:12 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jun 06 10:17:12 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Jun 06 10:17:12 volumio volumio[977]: info: ------------------------------ 433ms Jun 06 10:17:12 volumio volumio[977]: info: ------------------------------ 434ms Jun 06 10:17:14 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 06 10:17:14 volumio volumio[977]: info: CURURI: music-library/USB/KINGSTON Jun 06 10:17:14 volumio volumio[977]: info: Preload queue cleared Jun 06 10:17:18 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 06 10:17:18 volumio volumio[977]: info: CURURI: music-library/USB/KINGSTON/Test Jun 06 10:17:18 volumio volumio[977]: info: Preload queue cleared Jun 06 10:17:18 volumio volumio[977]: info: Preloading song: music-library/USB/KINGSTON/Test/audiocheck.net_pink_96k_-3dBFS.wav Jun 06 10:17:18 volumio volumio[977]: info: Preloading song: music-library/USB/KINGSTON/Test/audiocheck.net_sweep_20Hz_20000Hz_-3dBFS_10s.wav Jun 06 10:17:18 volumio volumio[977]: info: Exploding uri music-library/USB/KINGSTON/Test/audiocheck.net_pink_96k_-3dBFS.wav in service mpd Jun 06 10:17:18 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=//extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2Faudiocheck.net_pink_96k_-3dBFS.wav&metadata=false Jun 06 10:17:18 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/audiocheck.net_pink_96k_-3dBFS.wav Jun 06 10:17:18 volumio volumio[977]: info: Exploding uri music-library/USB/KINGSTON/Test/audiocheck.net_sweep_20Hz_20000Hz_-3dBFS_10s.wav in service mpd Jun 06 10:17:18 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=//extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2Faudiocheck.net_sweep_20Hz_20000Hz_-3dBFS_10s.wav&metadata=false Jun 06 10:17:18 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/audiocheck.net_sweep_20Hz_20000Hz_-3dBFS_10s.wav Jun 06 10:17:42 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 06 10:17:42 volumio dbus-daemon[609]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.34' (uid=0 pid=2318 comm="timedatectl show --property=NTPSynchronized --valu") Jun 06 10:17:42 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 06 10:17:42 volumio dbus-daemon[609]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 06 10:17:42 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 06 10:17:42 volumio setdatetime-helper.sh[2317]: Time is already synchronized. Jun 06 10:17:42 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 06 10:17:42 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 06 10:17:49 volumio volumio[977]: info: Preload queue cleared Jun 06 10:17:49 volumio volumio[977]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::ClearQueue Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::stop Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 10:17:49 volumio volumio[977]: info: CorePlayQueue::clearPlayQueue Jun 06 10:17:49 volumio volumio[977]: info: CorePlayQueue::saveQueue Jun 06 10:17:49 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::addQueueItems Jun 06 10:17:49 volumio volumio[977]: info: CorePlayQueue::addQueueItems Jun 06 10:17:49 volumio volumio[977]: info: Preload queue cleared Jun 06 10:17:49 volumio volumio[977]: info: Adding Item to queue: music-library/USB/KINGSTON/Test/audiocheck.net_pink_96k_-3dBFS.wav Jun 06 10:17:49 volumio volumio[977]: info: Using cached record of: music-library/USB/KINGSTON/Test/audiocheck.net_pink_96k_-3dBFS.wav Jun 06 10:17:49 volumio volumio[977]: info: Adding Item to queue: music-library/USB/KINGSTON/Test/audiocheck.net_sweep_20Hz_20000Hz_-3dBFS_10s.wav Jun 06 10:17:49 volumio volumio[977]: info: Using cached record of: music-library/USB/KINGSTON/Test/audiocheck.net_sweep_20Hz_20000Hz_-3dBFS_10s.wav Jun 06 10:17:49 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Jun 06 10:17:49 volumio volumio[977]: info: CorePlayQueue::saveQueue Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Jun 06 10:17:49 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Jun 06 10:17:49 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::play index 1 Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::addQueueItems Jun 06 10:17:49 volumio volumio[977]: info: CorePlayQueue::addQueueItems Jun 06 10:17:49 volumio volumio[977]: info: Preload queue cleared Jun 06 10:17:49 volumio volumio[977]: info: Adding Item to queue: music-library/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD} Jun 06 10:17:49 volumio volumio[977]: info: Exploding uri music-library/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD} in service mpd Jun 06 10:17:49 volumio volumio[977]: info: Adding Item to queue: music-library/USB/KINGSTON/Test/An America in Paris, Geert Bierling, Grote kerk Rotterdam Jun 06 10:17:49 volumio volumio[977]: info: Exploding uri music-library/USB/KINGSTON/Test/An America in Paris, Geert Bierling, Grote kerk Rotterdam in service mpd Jun 06 10:17:49 volumio volumio[977]: info: Adding Item to queue: music-library/USB/KINGSTON/Test/High-End testcd Jun 06 10:17:49 volumio volumio[977]: info: Exploding uri music-library/USB/KINGSTON/Test/High-End testcd in service mpd Jun 06 10:17:49 volumio volumio[977]: info: Adding Item to queue: music-library/USB/KINGSTON/Test/muziektests Jun 06 10:17:49 volumio volumio[977]: info: Exploding uri music-library/USB/KINGSTON/Test/muziektests in service mpd Jun 06 10:17:49 volumio volumio[977]: info: Adding Item to queue: music-library/USB/KINGSTON/Test/Roots half a mile Jun 06 10:17:49 volumio volumio[977]: info: Exploding uri music-library/USB/KINGSTON/Test/Roots half a mile in service mpd Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::stop Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::play index undefined Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 10:17:49 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Jun 06 10:17:49 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Jun 06 10:17:49 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Jun 06 10:17:49 volumio volumio[977]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/Test/audiocheck.net_sweep_20Hz_20000Hz_-3dBFS_10s.wav Jun 06 10:17:49 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 10:17:49 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=Patricia%20Barber/Companion%20%7B24kt%20Gold%2C%20HDCD%7D/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2F1999.%20Patricia%20Barber%20-%20Companion%20%7B24kt%20Gold%2C%20HDCD%7D%2F01%20-%20The%20Beat%20Goes%20On.flac&metadata=false Jun 06 10:17:49 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD}/01 - The Beat Goes On.flac Jun 06 10:17:49 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=Patricia%20Barber/Companion%20%7B24kt%20Gold%2C%20HDCD%7D/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2F1999.%20Patricia%20Barber%20-%20Companion%20%7B24kt%20Gold%2C%20HDCD%7D%2F02%20-%20Use%20Me.flac&metadata=false Jun 06 10:17:49 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD}/02 - Use Me.flac Jun 06 10:17:49 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=Patricia%20Barber/Companion%20%7B24kt%20Gold%2C%20HDCD%7D/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2F1999.%20Patricia%20Barber%20-%20Companion%20%7B24kt%20Gold%2C%20HDCD%7D%2F03%20-%20Like%20JT.flac&metadata=false Jun 06 10:17:49 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD}/03 - Like JT.flac Jun 06 10:17:49 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=Patricia%20Barber/Companion%20%7B24kt%20Gold%2C%20HDCD%7D/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2F1999.%20Patricia%20Barber%20-%20Companion%20%7B24kt%20Gold%2C%20HDCD%7D%2F04%20-%20Let%20It%20Rain.flac&metadata=false Jun 06 10:17:49 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD}/04 - Let It Rain.flac Jun 06 10:17:49 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=Patricia%20Barber/Companion%20%7B24kt%20Gold%2C%20HDCD%7D/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2F1999.%20Patricia%20Barber%20-%20Companion%20%7B24kt%20Gold%2C%20HDCD%7D%2F05%20-%20Touch%20Of%20Trash.flac&metadata=false Jun 06 10:17:49 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD}/05 - Touch Of Trash.flac Jun 06 10:17:49 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=Patricia%20Barber/Companion%20%7B24kt%20Gold%2C%20HDCD%7D/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2F1999.%20Patricia%20Barber%20-%20Companion%20%7B24kt%20Gold%2C%20HDCD%7D%2F06%20-%20If%20This%20Isn't%20Jazz.flac&metadata=false Jun 06 10:17:49 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD}/06 - If This Isn't Jazz.flac Jun 06 10:17:49 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=Patricia%20Barber/Companion%20%7B24kt%20Gold%2C%20HDCD%7D/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2F1999.%20Patricia%20Barber%20-%20Companion%20%7B24kt%20Gold%2C%20HDCD%7D%2F07%20-%20Black%20Magic%20Woman.flac&metadata=false Jun 06 10:17:49 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD}/07 - Black Magic Woman.flac Jun 06 10:17:49 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=Patricia%20Barber/Companion%20%20%2024kt%20Gold%20hdcd/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2F1999.%20Patricia%20Barber%20-%20Companion%20%7B24kt%20Gold%2C%20HDCD%7D%2FPatricia%20Barber%20-%20Companion%20%20%2024kt%20Gold%20hdcd.cue&metadata=false Jun 06 10:17:49 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD}/Patricia Barber - Companion 24kt Gold hdcd.cue Jun 06 10:17:49 volumio volumio[977]: info: ALBUMART /albumart?cacheid=383&web=Patricia%20Barber/Companion%20%20%2024kt%20Gold%20hdcd/extralarge&path=%2Fmnt%2FUSB%2FKINGSTON%2FTest%2F1999.%20Patricia%20Barber%20-%20Companion%20%7B24kt%20Gold%2C%20HDCD%7D%2FPatricia%20Barber%20-%20Companion%20%20%2024kt%20Gold%20hdcd.cue&metadata=false Jun 06 10:17:49 volumio volumio[977]: info: URI /mnt/USB/KINGSTON/Test/1999. Patricia Barber - Companion {24kt Gold, HDCD}/Patricia Barber - Companion 24kt Gold hdcd.cue Jun 06 10:17:49 volumio volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 10:17:49 volumio volumio[977]: Error: Unable to resolve or reject the same promise twice Jun 06 10:17:49 volumio volumio[977]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 06 10:17:49 volumio volumio[977]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jun 06 10:17:49 volumio volumio[977]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jun 06 10:17:49 volumio volumio[977]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jun 06 10:17:49 volumio volumio[977]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jun 06 10:17:49 volumio volumio[977]: at Socket.emit (node:events:514:28) Jun 06 10:17:49 volumio volumio[977]: at addChunk (node:internal/streams/readable:343:12) Jun 06 10:17:49 volumio volumio[977]: at readableAddChunk (node:internal/streams/readable:312:11) Jun 06 10:17:49 volumio volumio[977]: at Readable.push (node:internal/streams/readable:253:10) Jun 06 10:17:49 volumio volumio[977]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 06 10:17:49 volumio volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 10:17:49 volumio sudo[2350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-06 10:16' Jun 06 10:17:49 volumio sudo[2350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"