-- Logs begin at Thu 2019-02-14 18:11:59 +08, end at Mon 2026-01-26 16:05:07 +08. -- Jan 26 16:04:01 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:05 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:09 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:13 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:17 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:21 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:25 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:26 eddypie sudo[1805]: pam_unix(sudo:session): session closed for user root Jan 26 16:04:26 eddypie volumio[968]: info: An error occurred mounting the new share. Rolling back configuration Jan 26 16:04:27 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 26 16:04:29 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:33 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:37 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:41 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:45 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:47 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 26 16:04:48 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 26 16:04:49 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:53 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:04:57 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand update Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: info: Jan 26 16:05:00 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand update took 6 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 7 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 3 milliseconds Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: info: Command Router : Notfying DB Updatetrue Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: 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} Jan 26 16:05:00 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: No code Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: ------------------------------ 65ms Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: info: Jan 26 16:05:00 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: info: Jan 26 16:05:00 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: info: Jan 26 16:05:00 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 7 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 9 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 9 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 8 milliseconds Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: 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} Jan 26 16:05:00 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: No code Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: 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} Jan 26 16:05:00 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: No code Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: ------------------------------ 110ms Jan 26 16:05:00 eddypie volumio[968]: info: ------------------------------ 109ms Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: info: Jan 26 16:05:00 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: info: Jan 26 16:05:00 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 125 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 124 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 18 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 17 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 16 milliseconds Jan 26 16:05:00 eddypie volumio[968]: info: sendMpdCommand status took 15 milliseconds Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:00 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: 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} Jan 26 16:05:00 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: No code Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: 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} Jan 26 16:05:00 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: No code Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: 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} Jan 26 16:05:00 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: No code Jan 26 16:05:00 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:00 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:00 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:00 eddypie volumio[968]: info: ------------------------------ 275ms Jan 26 16:05:00 eddypie volumio[968]: info: ------------------------------ 168ms Jan 26 16:05:00 eddypie volumio[968]: info: ------------------------------ 167ms Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand update Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: info: Jan 26 16:05:01 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand update took 4 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: info: Jan 26 16:05:01 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 4 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 3 milliseconds Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: 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} Jan 26 16:05:01 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: No code Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: ------------------------------ 53ms Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: info: Jan 26 16:05:01 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: info: Jan 26 16:05:01 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 57 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 56 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 6 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 5 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 4 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 4 milliseconds Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: 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} Jan 26 16:05:01 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: No code Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: 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} Jan 26 16:05:01 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: No code Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: 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} Jan 26 16:05:01 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: No code Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: ------------------------------ 198ms Jan 26 16:05:01 eddypie volumio[968]: info: ------------------------------ 147ms Jan 26 16:05:01 eddypie volumio[968]: info: ------------------------------ 146ms Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: info: Jan 26 16:05:01 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: info: Jan 26 16:05:01 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 33 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 32 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 27 milliseconds Jan 26 16:05:01 eddypie volumio[968]: info: sendMpdCommand status took 25 milliseconds Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:01 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: 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} Jan 26 16:05:01 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: No code Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: 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} Jan 26 16:05:01 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: No code Jan 26 16:05:01 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:01 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:01 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:01 eddypie volumio[968]: info: ------------------------------ 141ms Jan 26 16:05:01 eddypie volumio[968]: info: ------------------------------ 135ms Jan 26 16:05:02 eddypie sudo[1870]: pam_unix(sudo:session): session closed for user root Jan 26 16:05:02 eddypie volumio[968]: info: Cannot mount NAS Songs at system boot, trial number 3 ,retrying in 5 seconds Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand rescan Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand rescan took 4 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 6 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 4 milliseconds Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: 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} Jan 26 16:05:02 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: No code Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: ------------------------------ 57ms Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 60 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 57 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 6 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 5 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 4 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 4 milliseconds Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: 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} Jan 26 16:05:02 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: No code Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: 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} Jan 26 16:05:02 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: No code Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: 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} Jan 26 16:05:02 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: No code Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: ------------------------------ 216ms Jan 26 16:05:02 eddypie volumio[968]: info: ------------------------------ 162ms Jan 26 16:05:02 eddypie volumio[968]: info: ------------------------------ 161ms Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 21 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 20 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 19 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 19 milliseconds Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: 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} Jan 26 16:05:02 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: No code Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: 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} Jan 26 16:05:02 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: No code Jan 26 16:05:02 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:02 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:02 eddypie volumio[968]: info: ------------------------------ 119ms Jan 26 16:05:02 eddypie volumio[968]: info: ------------------------------ 118ms Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand rescan Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand rescan took 3 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: Jan 26 16:05:02 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:02 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 8 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 7 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 7 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 6 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 5 milliseconds Jan 26 16:05:02 eddypie volumio[968]: info: sendMpdCommand status took 5 milliseconds Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:02 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:02 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 211ms Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 210ms Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 209ms Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: Jan 26 16:05:03 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: Jan 26 16:05:03 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 213 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 212 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 7 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 5 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 5 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 4 milliseconds Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 350ms Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 144ms Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 142ms Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand rescan Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: Jan 26 16:05:03 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand rescan took 5 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: Jan 26 16:05:03 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: Jan 26 16:05:03 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: Jan 26 16:05:03 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 10 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 8 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 8 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 7 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 6 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 6 milliseconds Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 164ms Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 162ms Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 161ms Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: Jan 26 16:05:03 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: Jan 26 16:05:03 eddypie volumio[968]: ---------------------------- MPD announces state update: update Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::getState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::sendMpdCommand status Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 164 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 164 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 6 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 5 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 5 milliseconds Jan 26 16:05:03 eddypie volumio[968]: info: sendMpdCommand status took 4 milliseconds Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: Command Router : Notfying DB Updatefalse Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::Close All Modals sent Jan 26 16:05:03 eddypie volumio[968]: verbose: ControllerMpd::parseState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ControllerMpd::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::servicePushState Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: 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} Jan 26 16:05:03 eddypie volumio[968]: verbose: CURRENT POSITION 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState stateService stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::syncState currentStatus stop Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: No code Jan 26 16:05:03 eddypie volumio[968]: info: CoreStateMachine::pushState Jan 26 16:05:03 eddypie volumio[968]: info: CorePlayQueue::getTrack 0 Jan 26 16:05:03 eddypie volumio[968]: info: CoreCommandRouter::volumioPushState Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 308ms Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 150ms Jan 26 16:05:03 eddypie volumio[968]: info: ------------------------------ 149ms Jan 26 16:05:05 eddypie volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 16:05:07 eddypie volumio[968]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 16:05:07 eddypie volumio[968]: TypeError: Cannot read property 'replace' of undefined Jan 26 16:05:07 eddypie volumio[968]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jan 26 16:05:07 eddypie volumio[968]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jan 26 16:05:07 eddypie volumio[968]: at listOnTimeout (internal/timers.js:554:17) Jan 26 16:05:07 eddypie volumio[968]: at processTimers (internal/timers.js:497:7) Jan 26 16:05:07 eddypie volumio[968]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 16:05:07 eddypie sudo[2257]: volumio : unable to resolve host eddypie Jan 26 16:05:07 eddypie sudo[2257]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jan 26 16:05:07 eddypie sudo[2257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-26 16:04 Jan 26 16:05:07 eddypie sudo[2257]: 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="49c352e1d55e9b76c3bd7b0e3940507619bf455a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="1d32690fc900ac8c739e7eabd35ed0f570899eb8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 19 Dec 2025 02:07:26 PM CET" VOLUMIO_VERSION="3.887" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f8c1d964af93dfdda0f02ac4140eec2"