Oct 14 23:29:00 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:00 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:00 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:00 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:00 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:00 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:00 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:00 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:00 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:02 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:02 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:02 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:02 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:04 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::ClearQueue Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::clearPlayQueue Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Oct 14 23:29:04 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:04 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::play index 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Oct 14 23:29:04 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:04 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/07.雨溅海棠花.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/07.雨溅海棠花.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/02.渔舟唱晚.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/02.渔舟唱晚.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/01.平湖秋月.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/01.平湖秋月.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/04.汉宫秋月.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/04.汉宫秋月.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/03.出水莲.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/03.出水莲.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/06.陈杏元和番.dsf Oct 14 23:29:04 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/06.陈杏元和番.dsf Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::play index undefined Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::startPlaybackTimer Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::clearAddPlayTracks USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand stop took 5 milliseconds Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:04 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand clear took 1 milliseconds Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand add "USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf" Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:04 volumio volumio[1187]: error: updateQueue error: null Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:04 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand status took 4 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 4ms Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand add "USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf" took 3 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand status took 2 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand status took 3 milliseconds Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand play Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: 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} Oct 14 23:29:04 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: No code Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: 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} Oct 14 23:29:04 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: No code Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: 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} Oct 14 23:29:04 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: No code Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 114ms Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 113ms Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 112ms Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:04 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:04 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 118ms Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand play took 117 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 7ms Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 6ms Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:04 volumio volumio[1187]: info: Oct 14 23:29:04 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:04 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand status took 51 milliseconds Oct 14 23:29:04 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand status took 52 milliseconds Oct 14 23:29:04 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand status took 55 milliseconds Oct 14 23:29:04 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand status took 55 milliseconds Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand clearerror took 44 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand clearerror took 43 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand clearerror took 39 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand clearerror took 38 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 14 23:29:04 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:04 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":562,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"月儿高","artist":"袁莎,高晓棠","album":"中国古筝名曲","uri":"USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf","trackType":"dsf"} Oct 14 23:29:04 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":562,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"月儿高","artist":"袁莎,高晓棠","album":"中国古筝名曲","uri":"USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf","trackType":"dsf"} Oct 14 23:29:04 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":562,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"月儿高","artist":"袁莎,高晓棠","album":"中国古筝名曲","uri":"USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf","trackType":"dsf"} Oct 14 23:29:04 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":562,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"月儿高","artist":"袁莎,高晓棠","album":"中国古筝名曲","uri":"USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf","trackType":"dsf"} Oct 14 23:29:04 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:04 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:04 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:04 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 155ms Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 155ms Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 154ms Oct 14 23:29:04 volumio volumio[1187]: info: ------------------------------ 154ms Oct 14 23:29:05 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:05 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:05 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::ClearQueue Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::clearPlayQueue Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Oct 14 23:29:05 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:05 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::play index 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Oct 14 23:29:05 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:05 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/07.雨溅海棠花.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/07.雨溅海棠花.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/02.渔舟唱晚.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/02.渔舟唱晚.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/01.平湖秋月.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/01.平湖秋月.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/04.汉宫秋月.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/04.汉宫秋月.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/03.出水莲.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/03.出水莲.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/06.陈杏元和番.dsf Oct 14 23:29:05 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/06.陈杏元和番.dsf Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::play index undefined Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::startPlaybackTimer Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::clearAddPlayTracks USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand stop took 5 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:05 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand status took 4 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand clear took 3 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand status took 3 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand status took 2 milliseconds Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand add "USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf" Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: 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} Oct 14 23:29:05 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: No code Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: 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} Oct 14 23:29:05 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: No code Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 107ms Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 109ms Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:05 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:05 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:05 volumio volumio[1187]: error: updateQueue error: null Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:05 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 118ms Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 116 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand add "USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf" took 116 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 10ms Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 9ms Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand play Oct 14 23:29:05 volumio volumio[1187]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 145ms Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:05 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:05 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 63ms Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand play took 41 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 38ms Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 29ms Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:05 volumio volumio[1187]: info: Oct 14 23:29:05 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:05 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand status took 16 milliseconds Oct 14 23:29:05 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand status took 19 milliseconds Oct 14 23:29:05 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand status took 17 milliseconds Oct 14 23:29:05 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand status took 17 milliseconds Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand clearerror took 21 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand clearerror took 19 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand clearerror took 19 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand clearerror took 18 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 14 23:29:05 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:05 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":562,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"月儿高","artist":"袁莎,高晓棠","album":"中国古筝名曲","uri":"USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf","trackType":"dsf"} Oct 14 23:29:05 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":562,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"月儿高","artist":"袁莎,高晓棠","album":"中国古筝名曲","uri":"USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf","trackType":"dsf"} Oct 14 23:29:05 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":562,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"月儿高","artist":"袁莎,高晓棠","album":"中国古筝名曲","uri":"USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf","trackType":"dsf"} Oct 14 23:29:05 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":562,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"月儿高","artist":"袁莎,高晓棠","album":"中国古筝名曲","uri":"USB/系统/【SONY DSD】袁莎：中国古筝名曲 (2.8MHz DSD) 2018.10.22/05.月儿高.dsf","trackType":"dsf"} Oct 14 23:29:05 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:05 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:05 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 88ms Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 88ms Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 86ms Oct 14 23:29:05 volumio volumio[1187]: info: ------------------------------ 87ms Oct 14 23:29:08 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 55. Oct 14 23:29:08 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:29:08 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:29:08 volumio upmpdcli[4273]: Could not open config: /tmp/upmpdcli.conf Oct 14 23:29:08 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 23:29:08 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 23:29:10 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:10 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:10 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:10 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:12 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:12 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:12 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:12 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:14 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:14 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:14 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:14 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:14 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:14 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:14 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:14 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:14 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:14 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:29:14 volumio volumio[1187]: info: CURURI: albums://UNAMAS%20Piazzolla%20Septet/UNAMAS%20Selection Oct 14 23:29:14 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:14 volumio volumio[1187]: info: Preloading song: music-library/USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf Oct 14 23:29:14 volumio volumio[1187]: info: Exploding uri music-library/USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf in service mpd Oct 14 23:29:14 volumio volumio[1187]: info: ALBUMART /albumart?cacheid=422&web=UNAMAS%20Piazzolla%20Septet/UNAMAS%20Selection/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E3%80%90SONY%20DSD%E3%80%91%E8%B4%A8%E6%84%9F%E9%9F%B3%E6%95%88%20%E5%BD%95%E9%9F%B3%E5%A4%A7%E5%B8%88%E6%B3%BD%E5%8F%A3%E7%9C%9F%E7%94%9F%E7%B2%BE%E9%80%89%E9%9B%86Mick%20Sawaguchi%20-%20MASTER%20SELECTION%202021%20%5BDSD256%5D%2F01.Liber%20Tango.dsf&metadata=false Oct 14 23:29:14 volumio volumio[1187]: info: URI /mnt/USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf Oct 14 23:29:15 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::ClearQueue Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::clearPlayQueue Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Oct 14 23:29:15 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:15 volumio volumio[1187]: info: Adding Item to queue: albums://UNAMAS%20Piazzolla%20Septet/UNAMAS%20Selection Oct 14 23:29:15 volumio volumio[1187]: info: Exploding uri albums://UNAMAS%20Piazzolla%20Septet/UNAMAS%20Selection in service mpd Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::play index 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::play index undefined Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::startPlaybackTimer Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::clearAddPlayTracks USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 23:29:15 volumio volumio[1187]: info: Oct 14 23:29:15 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:15 volumio volumio[1187]: info: sendMpdCommand stop took 8 milliseconds Oct 14 23:29:15 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 23:29:15 volumio volumio[1187]: info: Oct 14 23:29:15 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:15 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:15 volumio volumio[1187]: info: Oct 14 23:29:15 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:15 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:15 volumio volumio[1187]: info: Oct 14 23:29:15 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:15 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:15 volumio volumio[1187]: info: sendMpdCommand status took 4 milliseconds Oct 14 23:29:15 volumio volumio[1187]: info: sendMpdCommand clear took 4 milliseconds Oct 14 23:29:15 volumio volumio[1187]: info: sendMpdCommand status took 3 milliseconds Oct 14 23:29:15 volumio volumio[1187]: info: sendMpdCommand status took 3 milliseconds Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand add "USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf" Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:15 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: 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} Oct 14 23:29:15 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:15 volumio volumio[1187]: info: No code Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:15 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: 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} Oct 14 23:29:15 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:15 volumio volumio[1187]: info: No code Oct 14 23:29:15 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:15 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:15 volumio volumio[1187]: info: ------------------------------ 108ms Oct 14 23:29:15 volumio volumio[1187]: info: ------------------------------ 107ms Oct 14 23:29:15 volumio volumio[1187]: info: Oct 14 23:29:15 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:15 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:15 volumio volumio[1187]: info: Oct 14 23:29:15 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:15 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:15 volumio volumio[1187]: error: updateQueue error: null Oct 14 23:29:15 volumio volumio[1187]: info: Oct 14 23:29:15 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:15 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:15 volumio volumio[1187]: info: ------------------------------ 113ms Oct 14 23:29:15 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 111 milliseconds Oct 14 23:29:15 volumio volumio[1187]: info: sendMpdCommand add "USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf" took 111 milliseconds Oct 14 23:29:15 volumio volumio[1187]: info: ------------------------------ 6ms Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:15 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand play Oct 14 23:29:15 volumio volumio[1187]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 23:29:15 volumio volumio[1187]: info: ------------------------------ 118ms Oct 14 23:29:15 volumio volumio[1187]: info: Oct 14 23:29:15 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:15 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:15 volumio volumio[1187]: info: Oct 14 23:29:15 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:15 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:15 volumio volumio[1187]: info: ------------------------------ 18ms Oct 14 23:29:15 volumio volumio[1187]: info: ------------------------------ 17ms Oct 14 23:29:15 volumio volumio[1187]: info: sendMpdCommand play took 12 milliseconds Oct 14 23:29:15 volumio volumio[1187]: info: ------------------------------ 10ms Oct 14 23:29:15 volumio volumio[1187]: info: ------------------------------ 9ms Oct 14 23:29:16 volumio volumio[1187]: info: Oct 14 23:29:16 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:16 volumio volumio[1187]: info: Oct 14 23:29:16 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:16 volumio volumio[1187]: info: Oct 14 23:29:16 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:16 volumio volumio[1187]: info: Oct 14 23:29:16 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:16 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand status took 18 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:16 volumio volumio[1187]: info: Oct 14 23:29:16 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:16 volumio volumio[1187]: info: Oct 14 23:29:16 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:16 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand status took 47 milliseconds Oct 14 23:29:16 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand status took 46 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand clearerror took 33 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand status took 33 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand status took 28 milliseconds Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":250,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liber Tango","artist":"UNAMAS Piazzolla Septet","album":"UNAMAS Selection","uri":"USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf","trackType":"dsf"} Oct 14 23:29:16 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:16 volumio volumio[1187]: info: ------------------------------ 86ms Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand status took 76 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand clearerror took 51 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand clearerror took 50 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 41 milliseconds Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 41 milliseconds Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":250,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liber Tango","artist":"UNAMAS Piazzolla Septet","album":"UNAMAS Selection","uri":"USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf","trackType":"dsf"} Oct 14 23:29:16 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":250,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liber Tango","artist":"UNAMAS Piazzolla Septet","album":"UNAMAS Selection","uri":"USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf","trackType":"dsf"} Oct 14 23:29:16 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":250,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liber Tango","artist":"UNAMAS Piazzolla Septet","album":"UNAMAS Selection","uri":"USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf","trackType":"dsf"} Oct 14 23:29:16 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":250,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liber Tango","artist":"UNAMAS Piazzolla Septet","album":"UNAMAS Selection","uri":"USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf","trackType":"dsf"} Oct 14 23:29:16 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:16 volumio volumio[1187]: info: ------------------------------ 192ms Oct 14 23:29:16 volumio volumio[1187]: info: ------------------------------ 190ms Oct 14 23:29:16 volumio volumio[1187]: info: ------------------------------ 191ms Oct 14 23:29:16 volumio volumio[1187]: info: ------------------------------ 172ms Oct 14 23:29:16 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 101 milliseconds Oct 14 23:29:16 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:16 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":250,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liber Tango","artist":"UNAMAS Piazzolla Septet","album":"UNAMAS Selection","uri":"USB/系统/【SONY DSD】质感音效 录音大师泽口真生精选集Mick Sawaguchi - MASTER SELECTION 2021 [DSD256]/01.Liber Tango.dsf","trackType":"dsf"} Oct 14 23:29:16 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:16 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:16 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:16 volumio volumio[1187]: info: ------------------------------ 210ms Oct 14 23:29:20 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:20 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:20 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:20 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:20 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:20 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:20 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:20 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:20 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:22 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:22 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:22 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:22 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:23 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 56. Oct 14 23:29:23 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:29:23 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:29:23 volumio upmpdcli[4318]: Could not open config: /tmp/upmpdcli.conf Oct 14 23:29:23 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 23:29:23 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 23:29:25 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:25 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:25 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:25 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:25 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:25 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:25 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:25 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:25 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:28 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:29:28 volumio volumio[1187]: info: CURURI: albums://Wiener%20Philharmoniker/50%20Classical%20Masterworks Oct 14 23:29:28 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:28 volumio volumio[1187]: info: Preloading song: music-library/USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac Oct 14 23:29:28 volumio volumio[1187]: info: Exploding uri music-library/USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac in service mpd Oct 14 23:29:28 volumio volumio[1187]: info: ALBUMART /albumart?cacheid=422&web=Wiener%20Philharmoniker/50%20Classical%20Masterworks/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E4%BA%94%E5%8D%81%E9%A6%96%E5%8F%A4%E5%85%B8%E5%90%8D%E4%BD%9C%E9%9B%86%20(50%20Classical%20Masterworks)%2F02.Peer%20Gynt%20Suite%20No.%201%2C%20Op.%2046%EF%BC%9AI.%20Morning%20Mood.flac&metadata=false Oct 14 23:29:28 volumio volumio[1187]: info: URI /mnt/USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac Oct 14 23:29:30 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::ClearQueue Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::clearPlayQueue Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Oct 14 23:29:30 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:29:30 volumio volumio[1187]: info: Adding Item to queue: albums://Wiener%20Philharmoniker/50%20Classical%20Masterworks Oct 14 23:29:30 volumio volumio[1187]: info: Exploding uri albums://Wiener%20Philharmoniker/50%20Classical%20Masterworks in service mpd Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::play index 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::play index undefined Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::startPlaybackTimer Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::clearAddPlayTracks USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand stop took 3 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:30 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand status took 5 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand clear took 5 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand status took 3 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand status took 2 milliseconds Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand add "USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac" Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: 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} Oct 14 23:29:30 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:30 volumio volumio[1187]: info: No code Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: 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} Oct 14 23:29:30 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:30 volumio volumio[1187]: info: No code Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 79ms Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 78ms Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:30 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:30 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:30 volumio volumio[1187]: error: updateQueue error: null Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:30 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 82ms Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 82 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand add "USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac" took 82 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 5ms Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 4ms Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand play Oct 14 23:29:30 volumio volumio[1187]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 91ms Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:30 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:29:30 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 44ms Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand play took 39 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 37ms Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 35ms Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:30 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand status took 17 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:30 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand status took 12 milliseconds Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:30 volumio volumio[1187]: info: Oct 14 23:29:30 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:29:30 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand status took 54 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand clearerror took 45 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand status took 45 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand clearerror took 44 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand status took 14 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand status took 12 milliseconds Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Peer Gynt Suite No. 1, Op. 46: I. Morning Mood","artist":"Wiener Philharmoniker","album":"50 Classical Masterworks","uri":"USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac","trackType":"flac"} Oct 14 23:29:30 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Peer Gynt Suite No. 1, Op. 46: I. Morning Mood","artist":"Wiener Philharmoniker","album":"50 Classical Masterworks","uri":"USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac","trackType":"flac"} Oct 14 23:29:30 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 102ms Oct 14 23:29:30 volumio volumio[1187]: info: ------------------------------ 97ms Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand clearerror took 50 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 14 23:29:30 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 50 milliseconds Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:30 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:29:30 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Peer Gynt Suite No. 1, Op. 46: I. Morning Mood","artist":"Wiener Philharmoniker","album":"50 Classical Masterworks","uri":"USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac","trackType":"flac"} Oct 14 23:29:30 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:30 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:30 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:30 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:31 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:31 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:31 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:31 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Peer Gynt Suite No. 1, Op. 46: I. Morning Mood","artist":"Wiener Philharmoniker","album":"50 Classical Masterworks","uri":"USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac","trackType":"flac"} Oct 14 23:29:31 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:31 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:31 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:31 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:31 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:31 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:31 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:31 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:31 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:31 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Peer Gynt Suite No. 1, Op. 46: I. Morning Mood","artist":"Wiener Philharmoniker","album":"50 Classical Masterworks","uri":"USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac","trackType":"flac"} Oct 14 23:29:31 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:31 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:31 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:31 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:31 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:31 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:31 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:29:31 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:29:31 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:31 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Peer Gynt Suite No. 1, Op. 46: I. Morning Mood","artist":"Wiener Philharmoniker","album":"50 Classical Masterworks","uri":"USB/系统/五十首古典名作集 (50 Classical Masterworks)/02.Peer Gynt Suite No. 1, Op. 46：I. Morning Mood.flac","trackType":"flac"} Oct 14 23:29:31 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:29:31 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:29:31 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:29:31 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:29:31 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:31 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:29:31 volumio volumio[1187]: info: ------------------------------ 223ms Oct 14 23:29:31 volumio volumio[1187]: info: ------------------------------ 225ms Oct 14 23:29:31 volumio volumio[1187]: info: ------------------------------ 187ms Oct 14 23:29:31 volumio volumio[1187]: info: ------------------------------ 186ms Oct 14 23:29:35 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:35 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:35 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:35 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:37 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:37 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:37 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:39 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 57. Oct 14 23:29:39 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:29:39 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:29:39 volumio upmpdcli[4350]: Could not open config: /tmp/upmpdcli.conf Oct 14 23:29:39 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 23:29:39 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 23:29:39 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Oct 14 23:29:39 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Oct 14 23:29:39 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Oct 14 23:29:39 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Oct 14 23:29:40 volumio ntpd[927]: PROTO: 84.16.73.33 unlink local addr 192.168.1.141 -> Oct 14 23:29:40 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:40 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:40 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:40 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:42 volumio sudo[4373]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 23:29:42 volumio sudo[4374]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 23:29:42 volumio sudo[4374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:29:42 volumio sudo[4373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:29:42 volumio sudo[4374]: pam_unix(sudo:session): session closed for user root Oct 14 23:29:42 volumio sudo[4373]: pam_unix(sudo:session): session closed for user root Oct 14 23:29:42 volumio volumio[1187]: verbose: New Socket.io Connection to 192.168.1.141 from 192.168.1.105 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Oct 14 23:29:42 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:42 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:42 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 23:29:42 volumio volumio[1187]: info: Listing playlists Oct 14 23:29:42 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 14 23:29:42 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:42 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:42 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 23:29:44 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 23:29:44 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:44 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:44 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:44 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:44 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:44 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:44 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:49 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:49 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:49 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:49 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:49 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:49 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:49 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:49 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:49 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:51 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:51 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:51 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:51 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:51 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:54 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:54 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:54 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:54 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:54 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:54 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:54 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:54 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:54 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:54 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 58. Oct 14 23:29:54 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:29:54 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:29:54 volumio upmpdcli[4405]: Could not open config: /tmp/upmpdcli.conf Oct 14 23:29:54 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 23:29:54 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 23:29:56 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:56 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:56 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:56 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:56 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:56 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:56 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:56 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:56 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:29:58 volumio volumio[1187]: info: Received Get System Info Oct 14 23:29:58 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:29:58 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:29:58 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:29:58 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:29:58 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:29:58 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:29:58 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:29:58 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:01 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:01 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:01 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:01 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:01 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:01 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:01 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:01 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:01 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:03 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:03 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:03 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:03 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:03 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:03 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:03 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:03 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:03 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:05 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:05 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:05 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:05 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:05 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:08 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:08 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:08 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:08 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:08 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:08 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:08 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:08 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:08 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:09 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 59. Oct 14 23:30:09 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:30:09 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:30:09 volumio upmpdcli[4436]: Could not open config: /tmp/upmpdcli.conf Oct 14 23:30:09 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 23:30:09 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 23:30:10 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:10 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:10 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:10 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:12 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:12 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:12 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:12 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:12 volumio volumio[1187]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 23:30:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 23:30:12 volumio volumio[1187]: info: CoreCommandRouter::volumioGetQueue Oct 14 23:30:12 volumio volumio[1187]: info: CoreStateMachine::getQueue Oct 14 23:30:12 volumio volumio[1187]: info: CorePlayQueue::getQueue Oct 14 23:30:14 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:30:14 volumio volumio[1187]: info: CURURI: playlists Oct 14 23:30:14 volumio volumio[1187]: info: Listing playlists Oct 14 23:30:14 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:15 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:15 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:15 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:15 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:15 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:15 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:15 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:15 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:15 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:17 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:17 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:17 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:17 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:17 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:17 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:17 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:17 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:17 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:19 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:19 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:19 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:19 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:19 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:19 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:19 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:19 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:19 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:19 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:30:19 volumio volumio[1187]: info: CURURI: music-library Oct 14 23:30:19 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:20 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:30:20 volumio volumio[1187]: info: CURURI: music-library/USB Oct 14 23:30:20 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:22 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:22 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:22 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:22 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:30:22 volumio volumio[1187]: info: CURURI: music-library/USB/老毛桃U盘 Oct 14 23:30:22 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:24 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:24 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:24 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:24 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:24 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:24 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:24 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:24 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:24 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:24 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 60. Oct 14 23:30:24 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:30:24 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:30:24 volumio upmpdcli[4481]: Could not open config: /tmp/upmpdcli.conf Oct 14 23:30:24 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 23:30:24 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 23:30:26 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:26 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:26 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:26 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:26 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:26 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:26 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:26 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:26 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:28 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:30:28 volumio volumio[1187]: info: CURURI: music-library Oct 14 23:30:28 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:29 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:29 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:29 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:29 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:29 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:30:29 volumio volumio[1187]: info: CURURI: music-library/USB Oct 14 23:30:29 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:30 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:30:30 volumio volumio[1187]: info: CURURI: music-library/USB/系统 Oct 14 23:30:30 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:35 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:35 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:35 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:35 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:35 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:37 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:37 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:37 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:37 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:37 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:39 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 61. Oct 14 23:30:39 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:30:39 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 14 23:30:39 volumio upmpdcli[4512]: Could not open config: /tmp/upmpdcli.conf Oct 14 23:30:39 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 14 23:30:39 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 14 23:30:40 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:40 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:40 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:40 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:40 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 23:30:40 volumio volumio[1187]: info: CURURI: music-library/USB/系统/風舞（风舞） Oct 14 23:30:40 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:40 volumio volumio[1187]: info: Preloading song: music-library/USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac Oct 14 23:30:40 volumio volumio[1187]: info: Preloading song: music-library/USB/系统/風舞（风舞）/02.風の住む街（风居住的街道）.flac Oct 14 23:30:40 volumio volumio[1187]: info: Exploding uri music-library/USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac in service mpd Oct 14 23:30:40 volumio volumio[1187]: info: ALBUMART /albumart?cacheid=422&web=%E7%9F%B6%E6%9D%91%E7%94%B1%E7%BA%AA%E5%AD%90%2C%E5%B0%8F%E6%BB%A8%E6%98%8E%E4%BA%BA%2C%E5%92%8C%E8%8C%B6%2C%E4%B8%B8%E7%94%B0%E7%BE%8E%E7%BA%AA/%E9%A2%A8%E8%88%9E%EF%BC%88%E9%A3%8E%E8%88%9E%EF%BC%89/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%A2%A8%E8%88%9E%EF%BC%88%E9%A3%8E%E8%88%9E%EF%BC%89%2F01.Hungarian%20Rhapsody%E3%80%90%E5%8C%88%E7%89%99%E5%88%A9%E7%8B%82%E6%83%B3%E6%9B%B2NO.2%EF%BC%88%E8%8A%82%E9%80%89%EF%BC%89%E3%80%91.flac&metadata=false Oct 14 23:30:40 volumio volumio[1187]: info: URI /mnt/USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac Oct 14 23:30:40 volumio volumio[1187]: info: Exploding uri music-library/USB/系统/風舞（风舞）/02.風の住む街（风居住的街道）.flac in service mpd Oct 14 23:30:40 volumio volumio[1187]: info: ALBUMART /albumart?cacheid=422&web=%E7%9F%B6%E6%9D%91%E7%94%B1%E7%BA%AA%E5%AD%90%2C%E5%B0%8F%E6%BB%A8%E6%98%8E%E4%BA%BA%2C%E5%92%8C%E8%8C%B6%2C%E4%B8%B8%E7%94%B0%E7%BE%8E%E7%BA%AA/%E9%A2%A8%E8%88%9E%EF%BC%88%E9%A3%8E%E8%88%9E%EF%BC%89/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%A2%A8%E8%88%9E%EF%BC%88%E9%A3%8E%E8%88%9E%EF%BC%89%2F02.%E9%A2%A8%E3%81%AE%E4%BD%8F%E3%82%80%E8%A1%97%EF%BC%88%E9%A3%8E%E5%B1%85%E4%BD%8F%E7%9A%84%E8%A1%97%E9%81%93%EF%BC%89.flac&metadata=false Oct 14 23:30:40 volumio volumio[1187]: info: URI /mnt/USB/系统/風舞（风舞）/02.風の住む街（风居住的街道）.flac Oct 14 23:30:45 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:45 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:45 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:45 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:45 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:45 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:45 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:45 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:45 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:47 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:47 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:47 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::ClearQueue Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::clearPlayQueue Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Oct 14 23:30:47 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:47 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac Oct 14 23:30:47 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::play index 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Oct 14 23:30:47 volumio volumio[1187]: info: Preload queue cleared Oct 14 23:30:47 volumio volumio[1187]: info: Adding Item to queue: music-library/USB/系统/風舞（风舞）/02.風の住む街（风居住的街道）.flac Oct 14 23:30:47 volumio volumio[1187]: info: Using cached record of: music-library/USB/系统/風舞（风舞）/02.風の住む街（风居住的街道）.flac Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::stop Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::saveQueue Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::play index undefined Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::startPlaybackTimer Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::clearAddPlayTracks USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 23:30:47 volumio volumio[1187]: info: Oct 14 23:30:47 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:30:47 volumio volumio[1187]: info: sendMpdCommand stop took 6 milliseconds Oct 14 23:30:47 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 23:30:47 volumio volumio[1187]: info: Oct 14 23:30:47 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:30:47 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:30:47 volumio volumio[1187]: info: Oct 14 23:30:47 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:30:47 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:30:47 volumio volumio[1187]: info: Oct 14 23:30:47 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:30:47 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:30:47 volumio volumio[1187]: info: sendMpdCommand status took 5 milliseconds Oct 14 23:30:47 volumio volumio[1187]: info: sendMpdCommand clear took 5 milliseconds Oct 14 23:30:47 volumio volumio[1187]: info: sendMpdCommand status took 4 milliseconds Oct 14 23:30:47 volumio volumio[1187]: info: sendMpdCommand status took 3 milliseconds Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand add "USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac" Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:30:47 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: 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} Oct 14 23:30:47 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:47 volumio volumio[1187]: info: No code Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:47 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: 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} Oct 14 23:30:47 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:47 volumio volumio[1187]: info: No code Oct 14 23:30:47 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:47 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:47 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:47 volumio volumio[1187]: info: ------------------------------ 73ms Oct 14 23:30:47 volumio volumio[1187]: info: ------------------------------ 74ms Oct 14 23:30:47 volumio volumio[1187]: info: Oct 14 23:30:47 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:30:47 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:30:47 volumio volumio[1187]: info: Oct 14 23:30:47 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:30:47 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:30:47 volumio volumio[1187]: error: updateQueue error: null Oct 14 23:30:47 volumio volumio[1187]: info: Oct 14 23:30:47 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:30:47 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:30:47 volumio volumio[1187]: info: ------------------------------ 81ms Oct 14 23:30:47 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 80 milliseconds Oct 14 23:30:47 volumio volumio[1187]: info: sendMpdCommand add "USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac" took 80 milliseconds Oct 14 23:30:47 volumio volumio[1187]: info: ------------------------------ 11ms Oct 14 23:30:47 volumio volumio[1187]: info: ------------------------------ 11ms Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:30:47 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand play Oct 14 23:30:47 volumio volumio[1187]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 23:30:47 volumio volumio[1187]: info: ------------------------------ 91ms Oct 14 23:30:47 volumio volumio[1187]: info: Oct 14 23:30:47 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:30:47 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:30:47 volumio volumio[1187]: info: Oct 14 23:30:47 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 14 23:30:47 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 14 23:30:48 volumio volumio[1187]: info: Oct 14 23:30:48 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:30:48 volumio volumio[1187]: info: Oct 14 23:30:48 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:30:48 volumio volumio[1187]: info: ------------------------------ 57ms Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand play took 53 milliseconds Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:30:48 volumio volumio[1187]: info: ------------------------------ 50ms Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:30:48 volumio volumio[1187]: info: ------------------------------ 38ms Oct 14 23:30:48 volumio volumio[1187]: info: Oct 14 23:30:48 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:30:48 volumio volumio[1187]: info: Oct 14 23:30:48 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:30:48 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand status took 28 milliseconds Oct 14 23:30:48 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand status took 29 milliseconds Oct 14 23:30:48 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand status took 26 milliseconds Oct 14 23:30:48 volumio volumio[1187]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand status took 22 milliseconds Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:30:48 volumio volumio[1187]: info: Oct 14 23:30:48 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::getState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand clearerror took 37 milliseconds Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand clearerror took 41 milliseconds Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand clearerror took 42 milliseconds Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand clearerror took 41 milliseconds Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand status took 21 milliseconds Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":292,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】","artist":"矶村由纪子,小滨明人,和茶,丸田美纪","album":"風舞（风舞）","uri":"USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac","trackType":"flac"} Oct 14 23:30:48 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":292,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】","artist":"矶村由纪子,小滨明人,和茶,丸田美纪","album":"風舞（风舞）","uri":"USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac","trackType":"flac"} Oct 14 23:30:48 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":292,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】","artist":"矶村由纪子,小滨明人,和茶,丸田美纪","album":"風舞（风舞）","uri":"USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac","trackType":"flac"} Oct 14 23:30:48 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":292,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】","artist":"矶村由纪子,小滨明人,和茶,丸田美纪","album":"風舞（风舞）","uri":"USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac","trackType":"flac"} Oct 14 23:30:48 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:48 volumio volumio[1187]: info: ------------------------------ 145ms Oct 14 23:30:48 volumio volumio[1187]: info: ------------------------------ 144ms Oct 14 23:30:48 volumio volumio[1187]: info: ------------------------------ 140ms Oct 14 23:30:48 volumio volumio[1187]: info: ------------------------------ 138ms Oct 14 23:30:48 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 267 milliseconds Oct 14 23:30:48 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 14 23:30:48 volumio volumio[1187]: info: ControllerMpd::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":292,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】","artist":"矶村由纪子,小滨明人,和茶,丸田美纪","album":"風舞（风舞）","uri":"USB/系统/風舞（风舞）/01.Hungarian Rhapsody【匈牙利狂想曲NO.2（节选）】.flac","trackType":"flac"} Oct 14 23:30:48 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 14 23:30:48 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 14 23:30:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 23:30:48 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 14 23:30:48 volumio volumio[1187]: info: ------------------------------ 321ms Oct 14 23:30:50 volumio volumio[1187]: info: Received Get System Info Oct 14 23:30:50 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:30:50 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:30:50 volumio volumio[1187]: info: Discovery: Getting this device information Oct 14 23:30:50 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 14 23:30:50 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 14 23:30:50 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:30:50 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:30:50 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:30:53 volumio volumio[1187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 23:30:53 volumio volumio[1187]: Error: connect ETIMEDOUT 199.59.150.43:443 Oct 14 23:30:53 volumio volumio[1187]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 14 23:30:53 volumio volumio[1187]: errno: -110, Oct 14 23:30:53 volumio volumio[1187]: code: 'ETIMEDOUT', Oct 14 23:30:53 volumio volumio[1187]: syscall: 'connect', Oct 14 23:30:53 volumio volumio[1187]: address: '199.59.150.43', Oct 14 23:30:53 volumio volumio[1187]: port: 443 Oct 14 23:30:53 volumio volumio[1187]: } Oct 14 23:30:53 volumio volumio[1187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 23:30:53 volumio sudo[4572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 23:29' Oct 14 23:30:53 volumio sudo[4572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"