-- Logs begin at Thu 2019-02-14 19:12:00 JST, end at Sat 2024-08-31 20:44:53 JST. -- Aug 31 20:43:01 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:05 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:06 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Aug 31 20:43:06 volumio volumio[7427]: info: Adding a new share Aug 31 20:43:06 volumio volumio[7427]: info: Checking key NasMounts.2e967554-ad4d-4f90-830a-293022ba945f Aug 31 20:43:06 volumio volumio[7427]: info: Found correspondence in configuration Aug 31 20:43:06 volumio volumio[7427]: info: Share RN526X has already been configured, with uuid 2e967554-ad4d-4f90-830a-293022ba945f Aug 31 20:43:07 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 20:43:09 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:13 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:17 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand rescan Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: info: Aug 31 20:43:19 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand rescan took 5 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: info: Aug 31 20:43:19 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: info: Aug 31 20:43:19 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 16 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 12 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 11 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 4 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 4 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 3 milliseconds Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: info: Command Router : Notfying DB Updatetrue Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: info: Command Router : Notfying DB Updatetrue Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: info: Command Router : Notfying DB Updatetrue Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 20:43:19 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: No code Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 20:43:19 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: No code Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 31 20:43:19 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: No code Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: ------------------------------ 494ms Aug 31 20:43:19 volumio volumio[7427]: info: ------------------------------ 489ms Aug 31 20:43:19 volumio volumio[7427]: info: ------------------------------ 482ms Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: info: Aug 31 20:43:19 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: info: Aug 31 20:43:19 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: info: Aug 31 20:43:19 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 14 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 13 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 13 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 13 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 12 milliseconds Aug 31 20:43:19 volumio volumio[7427]: info: sendMpdCommand status took 11 milliseconds Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: info: Command Router : Notfying DB Updatefalse Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: info: Command Router : Notfying DB Updatefalse Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: info: Command Router : Notfying DB Updatefalse Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:19 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: 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} Aug 31 20:43:19 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: No code Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: 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} Aug 31 20:43:19 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: No code Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: 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} Aug 31 20:43:19 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: No code Aug 31 20:43:19 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:19 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:19 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:19 volumio volumio[7427]: info: ------------------------------ 132ms Aug 31 20:43:19 volumio volumio[7427]: info: ------------------------------ 130ms Aug 31 20:43:19 volumio volumio[7427]: info: ------------------------------ 130ms Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand update Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: info: Aug 31 20:43:20 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand update took 2 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: info: Aug 31 20:43:20 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 3 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 2 milliseconds Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: info: Command Router : Notfying DB Updatefalse Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: 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} Aug 31 20:43:20 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: No code Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: ------------------------------ 44ms Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: info: Aug 31 20:43:20 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: info: Aug 31 20:43:20 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 46 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 46 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 3 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 2 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 3 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 2 milliseconds Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: info: Command Router : Notfying DB Updatefalse Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: info: Command Router : Notfying DB Updatefalse Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: info: Command Router : Notfying DB Updatefalse Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: 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} Aug 31 20:43:20 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: No code Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: 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} Aug 31 20:43:20 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: No code Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: 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} Aug 31 20:43:20 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: No code Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: ------------------------------ 164ms Aug 31 20:43:20 volumio volumio[7427]: info: ------------------------------ 122ms Aug 31 20:43:20 volumio volumio[7427]: info: ------------------------------ 122ms Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: info: Aug 31 20:43:20 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: info: Aug 31 20:43:20 volumio volumio[7427]: ---------------------------- MPD announces state update: update Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::getState Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 10 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 10 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 9 milliseconds Aug 31 20:43:20 volumio volumio[7427]: info: sendMpdCommand status took 8 milliseconds Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: info: Command Router : Notfying DB Updatefalse Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: info: Command Router : Notfying DB Updatefalse Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::Close All Modals sent Aug 31 20:43:20 volumio volumio[7427]: verbose: ControllerMpd::parseState Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: 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} Aug 31 20:43:20 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: No code Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: ControllerMpd::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::servicePushState Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: 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} Aug 31 20:43:20 volumio volumio[7427]: verbose: CURRENT POSITION 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState stateService stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: No code Aug 31 20:43:20 volumio volumio[7427]: info: CoreStateMachine::pushState Aug 31 20:43:20 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:20 volumio volumio[7427]: info: CoreCommandRouter::volumioPushState Aug 31 20:43:20 volumio volumio[7427]: info: ------------------------------ 90ms Aug 31 20:43:20 volumio volumio[7427]: info: ------------------------------ 90ms Aug 31 20:43:21 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:25 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:29 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:33 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:37 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:41 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:45 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:49 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:43:52 volumio volumio[7427]: info: CoreCommandRouter::volumioGetState Aug 31 20:43:52 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:43:59 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 31 20:43:59 volumio volumio[7427]: info: Preload queue cleared Aug 31 20:44:03 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 31 20:44:03 volumio volumio[7427]: info: Preload queue cleared Aug 31 20:44:05 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 31 20:44:06 volumio volumio[7427]: info: Preload queue cleared Aug 31 20:44:07 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 31 20:44:07 volumio volumio[7427]: info: Preload queue cleared Aug 31 20:44:09 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 31 20:44:09 volumio volumio[7427]: info: Preload queue cleared Aug 31 20:44:21 volumio volumio[7427]: info: CoreCommandRouter::volumioGetState Aug 31 20:44:21 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:44:27 volumio volumio[7427]: info: CoreCommandRouter::volumioGetState Aug 31 20:44:27 volumio volumio[7427]: info: CorePlayQueue::getTrack 0 Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 20:44:30 volumio volumio[7427]: info: Retrieving Cloud Streaming UI Aug 31 20:44:30 volumio volumio[7427]: info: Getting Tidal Cloud Configuration Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 20:44:30 volumio volumio[7427]: info: Getting Qobuz Cloud Configuration Aug 31 20:44:30 volumio volumio[7427]: info: Asking plugin for UI Config Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 20:44:30 volumio volumio[7427]: info: Getting Spotify Cloud Configuration Aug 31 20:44:30 volumio volumio[7427]: info: Asking plugin for UI Config Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 20:44:30 volumio volumio[7427]: info: Saving Spotify Acccount Aug 31 20:44:30 volumio volumio[7427]: info: Got Tidal Cloud Configuration Aug 31 20:44:30 volumio volumio[7427]: info: Got it Aug 31 20:44:30 volumio volumio[7427]: info: Got it Aug 31 20:44:30 volumio volumio[7427]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 20:44:30 volumio volumio[7427]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:44:30 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 20:44:34 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:44:35 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Aug 31 20:44:36 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 20:44:38 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:44:42 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:44:46 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:44:48 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 31 20:44:48 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 31 20:44:50 volumio volumio[7427]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 20:44:53 volumio volumio[7427]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 20:44:53 volumio volumio[7427]: Error: dns service error: unknown Aug 31 20:44:53 volumio volumio[7427]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Aug 31 20:44:53 volumio volumio[7427]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Aug 31 20:44:53 volumio volumio[7427]: errorCode: -65537 Aug 31 20:44:53 volumio volumio[7427]: } Aug 31 20:44:53 volumio volumio[7427]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 20:44:53 volumio sudo[8024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 20:43 Aug 31 20:44:53 volumio sudo[8024]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"