-- Logs begin at Thu 2019-02-14 18:11:59 HKT, end at Thu 2026-02-12 17:38:36 HKT. -- Feb 12 17:37:09 minidsp-shd-d volumio[707]: Searching all installed plugins Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: , search Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: [youtube2] InnertubeLoader: creating Innertube instance... Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: inputs , search Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Feb 12 17:37:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: Searching all installed plugins Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: inputs , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: tidal , search Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: searchTIDALUri took 1606 milliseconds Feb 12 17:37:10 minidsp-shd-d volumio[707]: info: search took 1623 milliseconds Feb 12 17:37:11 minidsp-shd-d volumio[707]: info: searchTIDALUri took 877 milliseconds Feb 12 17:37:11 minidsp-shd-d volumio[707]: info: search took 881 milliseconds Feb 12 17:37:11 minidsp-shd-d volumio[707]: PlayerError: Failed to extract signature decipher algorithm Feb 12 17:37:11 minidsp-shd-d volumio[707]: at Function.extractSigSourceCode (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:21905:20) Feb 12 17:37:11 minidsp-shd-d volumio[707]: at Function. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:21798:27) Feb 12 17:37:11 minidsp-shd-d volumio[707]: at Generator.next () Feb 12 17:37:11 minidsp-shd-d volumio[707]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Feb 12 17:37:11 minidsp-shd-d volumio[707]: at runMicrotasks () Feb 12 17:37:11 minidsp-shd-d volumio[707]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 12 17:37:11 minidsp-shd-d volumio[707]: info: [youtube2] InnertubeLoader: creating Auth instance... Feb 12 17:37:11 minidsp-shd-d volumio[707]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 17:37:11 minidsp-shd-d volumio[707]: PlayerError: Failed to extract signature decipher algorithm Feb 12 17:37:11 minidsp-shd-d volumio[707]: at Function.extractSigSourceCode (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:21905:20) Feb 12 17:37:11 minidsp-shd-d volumio[707]: at Function. (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:21798:27) Feb 12 17:37:11 minidsp-shd-d volumio[707]: at Generator.next () Feb 12 17:37:11 minidsp-shd-d volumio[707]: at fulfilled (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Feb 12 17:37:11 minidsp-shd-d volumio[707]: at runMicrotasks () Feb 12 17:37:11 minidsp-shd-d volumio[707]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 12 17:37:11 minidsp-shd-d volumio[707]: info: [ytmusic] InnertubeLoader: creating Auth instance... Feb 12 17:37:11 minidsp-shd-d volumio[707]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 17:37:11 minidsp-shd-d volumio[707]: info: searchQOBUZUri took 2377 milliseconds Feb 12 17:37:12 minidsp-shd-d volumio[707]: info: searchQOBUZUri took 1771 milliseconds Feb 12 17:37:14 minidsp-shd-d volumio[707]: error: Search in plugin youtube2 timed out Feb 12 17:37:14 minidsp-shd-d volumio[707]: error: Search in plugin ytmusic timed out Feb 12 17:37:14 minidsp-shd-d volumio[707]: error: Search in plugin inputs timed out Feb 12 17:37:14 minidsp-shd-d volumio[707]: info: All search sources collected, pushing search results Feb 12 17:37:14 minidsp-shd-d volumio[707]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Feb 12 17:37:15 minidsp-shd-d volumio[707]: error: Search in plugin youtube2 timed out Feb 12 17:37:15 minidsp-shd-d volumio[707]: error: Search in plugin ytmusic timed out Feb 12 17:37:15 minidsp-shd-d volumio[707]: error: Search in plugin inputs timed out Feb 12 17:37:15 minidsp-shd-d volumio[707]: info: All search sources collected, pushing search results Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: Preload queue cleared Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::ClearQueue Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::stPlaybackTimer Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::updateTrackBlock Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrackBlock Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 17 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 17 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::serviceStop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 17 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::serviceStop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: [1770889057607] ControllerQobuz::stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: ControllerMpd::stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::clearPlayQueue Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::saveQueue Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushQueue Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::addQueueItems Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::addQueueItems Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: Preload queue cleared Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: Adding Item to queue: music-library/USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: Exploding uri music-library/USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3 in service mpd Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: Feb 12 17:37:37 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: ALBUMART /albumart?cacheid=62&web=%E9%99%88%E5%A5%95%E8%BF%85/What's%20Going%20On....%3F/extralarge&path=%2Fmnt%2FUSB%2FSandisk256%2FOTHERS%2FPopular%2FHong%20Kong%2F%E9%99%B3%E5%A5%95%E8%BF%85%2F%E9%99%B3%E5%A5%95%E8%BF%85-%20%E5%AF%8C%E5%A3%AB%E5%B1%B1%E4%B8%8B.mp3&metadata=false Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: URI /mnt/USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: sendMpdCommand stop took 77 milliseconds Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushQueue Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::saveQueue Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::updateTrackBlock Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrackBlock Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPlay Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::play index 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::play index undefined Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::startPlaybackTimer Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::clearAddPlayTracks USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3 Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 44 milliseconds Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: sendMpdCommand stop took 9 milliseconds Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: Feb 12 17:37:37 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: Feb 12 17:37:37 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: Feb 12 17:37:37 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: sendMpdCommand clear took 15 milliseconds Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 13 milliseconds Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 10 milliseconds Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand add "USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3" Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"307331727","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/307331727","trackType":"qobuz"} Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: No code Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: No code Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 12 17:37:37 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus stop Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: No code Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:37 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 331ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 275ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 273ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:37:38 minidsp-shd-d volumio[707]: error: updateQueue error: null Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 294ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand add "USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3" took 287 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 27ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 24ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 103ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand play took 98 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 97ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 94ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 22 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 21 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 16 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 12 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Feb 12 17:37:38 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 40 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 27 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 24 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":217,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus stop Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":217,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Received an update from plugin. extracting info from payload Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Received an update from plugin. extracting info from payload Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Received an update from plugin. extracting info from payload Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 163ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 283ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 275ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 272ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 247 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 247 milliseconds Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":262,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Received an update from plugin. extracting info from payload Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":262,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:37:38 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus play Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: Received an update from plugin. extracting info from payload Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 411ms Feb 12 17:37:38 minidsp-shd-d volumio[707]: info: ------------------------------ 408ms Feb 12 17:37:42 minidsp-shd-d volumiologrotate[520]: ls: cannot access '/var/log/samba/log.wb-MINIDSP': No such file or directory Feb 12 17:37:42 minidsp-shd-d volumiologrotate[520]: ls: cannot access 'SHD': No such file or directory Feb 12 17:37:42 minidsp-shd-d volumiologrotate[520]: ls: cannot access 'D': No such file or directory Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: CURURI: albums://%E9%99%88%E5%A5%95%E8%BF%85/What's%20Going%20On....%3F Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: Preload queue cleared Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: No valid Plugin REST Endpoint: getSimilarAlbums Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: No valid Plugin REST Endpoint: getSimilarAlbums Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: No valid Plugin REST Endpoint: getSimilarAlbums Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: No valid Plugin REST Endpoint: getSimilarAlbums Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: No valid Plugin REST Endpoint: getSimilarAlbums Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: No valid Plugin REST Endpoint: getSimilarAlbums Feb 12 17:38:06 minidsp-shd-d volumio[707]: info: No valid Plugin REST Endpoint: getSimilarAlbums Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Preload queue cleared Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::ClearQueue Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::stPlaybackTimer Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::updateTrackBlock Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrackBlock Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::serviceStop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::serviceStop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::clearPlayQueue Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::saveQueue Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushQueue Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::addQueueItems Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::addQueueItems Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Preload queue cleared Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Adding Item to queue: music-library/USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Using cached record of: music-library/USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushQueue Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::saveQueue Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::updateTrackBlock Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrackBlock Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPlay Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::play index 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::play index undefined Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::startPlaybackTimer Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::clearAddPlayTracks USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3 Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand stop took 83 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand stop took 19 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 22 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand clear took 20 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 18 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 15 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand add "USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3" Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: No code Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: No code Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 205ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 203ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:38:09 minidsp-shd-d volumio[707]: error: updateQueue error: null Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 211ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 205 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand add "USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3" took 206 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 20ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 17ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand play Feb 12 17:38:09 minidsp-shd-d volumio[707]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 243ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces system playlist update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Ignoring MPD Status Update Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 76ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand play took 68 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 67ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 62ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 18 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 18 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 13 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 8 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Feb 12 17:38:09 minidsp-shd-d volumio[707]: ---------------------------- MPD announces state update: player Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::getState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand status Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand status took 12 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseState Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus stop Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus play Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Received an update from plugin. extracting info from payload Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus play Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Received an update from plugin. extracting info from payload Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":217,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus play Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Received an update from plugin. extracting info from payload Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 141ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 282ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 274ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ------------------------------ 271ms Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: sendMpdCommand playlistinfo took 229 milliseconds Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: ControllerMpd::parseTrackInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: ControllerMpd::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::servicePushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240,"duration":260,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On....?","uri":"USB/Sandisk256/OTHERS/Popular/Hong Kong/陳奕迅/陳奕迅- 富士山下.mp3","trackType":"mp3"} Feb 12 17:38:09 minidsp-shd-d volumio[707]: verbose: CURRENT POSITION 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState stateService play Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::syncState currentStatus play Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: Received an update from plugin. extracting info from payload Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:38:09 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:10 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:10 minidsp-shd-d volumio[707]: info: CoreStateMachine::pushState Feb 12 17:38:10 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioPushState Feb 12 17:38:10 minidsp-shd-d volumio[707]: info: CoreCommandRouter::volumioGetState Feb 12 17:38:10 minidsp-shd-d volumio[707]: info: CorePlayQueue::getTrack 0 Feb 12 17:38:10 minidsp-shd-d volumio[707]: info: ------------------------------ 324ms Feb 12 17:38:20 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 12 17:38:20 minidsp-shd-d volumio[707]: info: handleBrowseUri took 536 milliseconds Feb 12 17:38:20 minidsp-shd-d volumio[707]: info: Preload queue cleared Feb 12 17:38:20 minidsp-shd-d volumio[707]: info: Preload queue cleared Feb 12 17:38:21 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Feb 12 17:38:23 minidsp-shd-d volumio[707]: info: handleBrowseUri took 1707 milliseconds Feb 12 17:38:23 minidsp-shd-d volumio[707]: info: Preload queue cleared Feb 12 17:38:23 minidsp-shd-d volumio[707]: info: Preloading song: qobuz://song/292406883 Feb 12 17:38:23 minidsp-shd-d volumio[707]: info: Exploding uri qobuz://song/292406883 in service qobuz Feb 12 17:38:23 minidsp-shd-d volumio[707]: https://prod.vlmapi.io/v2/qobuz/explodeUri Feb 12 17:38:24 minidsp-shd-d volumio[707]: info: explodeUri took 942 milliseconds Feb 12 17:38:24 minidsp-shd-d volumio[707]: error: QOBUZ Browse Error: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Feb 12 17:38:24 minidsp-shd-d volumio[707]: error: Commandrouter: Cannot explode uri qobuz://song/292406883 from service qobuz: failed to get track: got 404: {"status":"error","code":404,"message":"No result matching given argument"} Feb 12 17:38:35 minidsp-shd-d volumio[707]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Feb 12 17:38:35 minidsp-shd-d volumio[707]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 17:38:35 minidsp-shd-d volumio[707]: TypeError: Cannot read property 'then' of undefined Feb 12 17:38:35 minidsp-shd-d volumio[707]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Feb 12 17:38:35 minidsp-shd-d volumio[707]: at Socket.emit (events.js:400:28) Feb 12 17:38:35 minidsp-shd-d volumio[707]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 12 17:38:35 minidsp-shd-d volumio[707]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Feb 12 17:38:35 minidsp-shd-d volumio[707]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 17:38:36 minidsp-shd-d sudo[4689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-12 17:37 Feb 12 17:38:36 minidsp-shd-d sudo[4689]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 18 Nov 2025 04:27:40 PM CET" VOLUMIO_VERSION="3.877" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="861d146b0ca5e28d2e157bf87e6b15ea"