-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Sun 2026-01-25 18:41:07 +07. -- Jan 25 18:40:03 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand rescan Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:04 chot volumio[879]: info: Jan 25 18:40:04 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:04 chot volumio[879]: info: sendMpdCommand rescan took 3 milliseconds Jan 25 18:40:04 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:04 chot volumio[879]: info: Jan 25 18:40:04 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:04 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:04 chot volumio[879]: info: Jan 25 18:40:04 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:04 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:04 chot volumio[879]: info: sendMpdCommand status took 8 milliseconds Jan 25 18:40:04 chot volumio[879]: info: sendMpdCommand status took 7 milliseconds Jan 25 18:40:04 chot volumio[879]: info: sendMpdCommand status took 6 milliseconds Jan 25 18:40:04 chot volumio[879]: info: sendMpdCommand status took 4 milliseconds Jan 25 18:40:04 chot volumio[879]: info: sendMpdCommand status took 5 milliseconds Jan 25 18:40:04 chot volumio[879]: info: sendMpdCommand status took 3 milliseconds Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:04 chot volumio[879]: info: Command Router : Notfying DB Updatetrue Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:04 chot volumio[879]: info: Command Router : Notfying DB Updatetrue Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:04 chot volumio[879]: info: Command Router : Notfying DB Updatetrue Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:04 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:04 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: 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 25 18:40:04 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:04 chot volumio[879]: info: No code Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:04 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: 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 25 18:40:04 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:04 chot volumio[879]: info: No code Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:04 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: 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 25 18:40:04 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:04 chot volumio[879]: info: No code Jan 25 18:40:04 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:04 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:04 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:04 chot volumio[879]: info: ------------------------------ 177ms Jan 25 18:40:04 chot volumio[879]: info: ------------------------------ 175ms Jan 25 18:40:04 chot volumio[879]: info: ------------------------------ 174ms Jan 25 18:40:07 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 18:40:09 chot volumio[879]: info: MPD Database updated - AlbumList cache refreshed Jan 25 18:40:09 chot volumio[879]: info: Jan 25 18:40:09 chot volumio[879]: ---------------------------- MPD announces state update: database Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:09 chot volumio[879]: info: Jan 25 18:40:09 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:09 chot volumio[879]: info: MPD Database updated - AlbumList cache refreshed Jan 25 18:40:09 chot volumio[879]: info: Jan 25 18:40:09 chot volumio[879]: ---------------------------- MPD announces state update: database Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:09 chot volumio[879]: info: Jan 25 18:40:09 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:09 chot volumio[879]: info: MPD Database updated - AlbumList cache refreshed Jan 25 18:40:09 chot volumio[879]: info: Jan 25 18:40:09 chot volumio[879]: ---------------------------- MPD announces state update: database Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:09 chot volumio[879]: info: Jan 25 18:40:09 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:09 chot volumio[879]: info: sendMpdCommand status took 43 milliseconds Jan 25 18:40:09 chot volumio[879]: info: sendMpdCommand status took 43 milliseconds Jan 25 18:40:09 chot volumio[879]: info: sendMpdCommand status took 42 milliseconds Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:09 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: 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 25 18:40:09 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: No code Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: 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 25 18:40:09 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: No code Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: ------------------------------ 101ms Jan 25 18:40:09 chot volumio[879]: info: ------------------------------ 103ms Jan 25 18:40:09 chot volumio[879]: info: sendMpdCommand status took 125 milliseconds Jan 25 18:40:09 chot volumio[879]: info: sendMpdCommand status took 125 milliseconds Jan 25 18:40:09 chot volumio[879]: info: sendMpdCommand status took 124 milliseconds Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:09 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:09 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: 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 25 18:40:09 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: No code Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: 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 25 18:40:09 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: No code Jan 25 18:40:09 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:09 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:09 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:09 chot volumio[879]: info: ------------------------------ 175ms Jan 25 18:40:09 chot volumio[879]: info: ------------------------------ 175ms Jan 25 18:40:10 chot volumio[879]: info: sendMpdCommand status took 239 milliseconds Jan 25 18:40:10 chot volumio[879]: info: sendMpdCommand status took 239 milliseconds Jan 25 18:40:10 chot volumio[879]: info: sendMpdCommand status took 238 milliseconds Jan 25 18:40:10 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:10 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:10 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:10 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:10 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:10 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:10 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:10 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:10 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:10 chot volumio[879]: 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 25 18:40:10 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:10 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:10 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:10 chot volumio[879]: info: No code Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:10 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:10 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:10 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:10 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:10 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:10 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:10 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:10 chot volumio[879]: 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 25 18:40:10 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:10 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:10 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:10 chot volumio[879]: info: No code Jan 25 18:40:10 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:10 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:10 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:10 chot volumio[879]: info: ------------------------------ 292ms Jan 25 18:40:10 chot volumio[879]: info: ------------------------------ 292ms Jan 25 18:40:11 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 18:40:15 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 18:40:17 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 25 18:40:17 chot sudo[2186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Asus Jan 25 18:40:17 chot sudo[2186]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:40:17 chot systemd[1]: mnt-NAS-Asus.mount: Succeeded. Jan 25 18:40:17 chot sudo[2186]: pam_unix(sudo:session): session closed for user root Jan 25 18:40:17 chot volumio[879]: info: Share Asus successfully unmounted Jan 25 18:40:18 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 25 18:40:19 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: Jan 25 18:40:20 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: Jan 25 18:40:20 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 5 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 4 milliseconds Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:20 chot volumio[879]: info: Command Router : Notfying DB Updatetrue Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:20 chot volumio[879]: info: Command Router : Notfying DB Updatetrue Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: 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 25 18:40:20 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: No code Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: 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 25 18:40:20 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: No code Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: ------------------------------ 98ms Jan 25 18:40:20 chot volumio[879]: info: ------------------------------ 98ms Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: Jan 25 18:40:20 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: MPD Database updated - AlbumList cache refreshed Jan 25 18:40:20 chot volumio[879]: info: Jan 25 18:40:20 chot volumio[879]: ---------------------------- MPD announces state update: database Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: Jan 25 18:40:20 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 6 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 5 milliseconds Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:20 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: 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 25 18:40:20 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: No code Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: ------------------------------ 68ms Jan 25 18:40:20 chot volumio[879]: info: MPD Database updated - AlbumList cache refreshed Jan 25 18:40:20 chot volumio[879]: info: Jan 25 18:40:20 chot volumio[879]: ---------------------------- MPD announces state update: database Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: Jan 25 18:40:20 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: MPD Database updated - AlbumList cache refreshed Jan 25 18:40:20 chot volumio[879]: info: Jan 25 18:40:20 chot volumio[879]: ---------------------------- MPD announces state update: database Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: Jan 25 18:40:20 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:20 chot volumio[879]: info: [1769341220673] Database update started Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 85 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 84 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 85 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 20 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 19 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 19 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 17 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 16 milliseconds Jan 25 18:40:20 chot volumio[879]: info: sendMpdCommand status took 16 milliseconds Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:20 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:20 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:20 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:20 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: 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 25 18:40:20 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: No code Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: 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 25 18:40:20 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: No code Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: 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 25 18:40:20 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: No code Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: 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 25 18:40:20 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: No code Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: 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 25 18:40:20 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: No code Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: 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 25 18:40:20 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: No code Jan 25 18:40:20 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:20 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:20 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:20 chot volumio[879]: info: ------------------------------ 242ms Jan 25 18:40:20 chot volumio[879]: info: ------------------------------ 242ms Jan 25 18:40:20 chot volumio[879]: info: ------------------------------ 178ms Jan 25 18:40:20 chot volumio[879]: info: ------------------------------ 178ms Jan 25 18:40:20 chot volumio[879]: info: ------------------------------ 175ms Jan 25 18:40:20 chot volumio[879]: info: ------------------------------ 175ms Jan 25 18:40:23 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand rescan Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: info: Jan 25 18:40:24 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand rescan took 3 milliseconds Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: info: Jan 25 18:40:24 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: info: Jan 25 18:40:24 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: info: Jan 25 18:40:24 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 8 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 6 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 6 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 5 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 4 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 4 milliseconds Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:24 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:24 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:24 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: 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 25 18:40:24 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: No code Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: 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 25 18:40:24 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: No code Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: 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 25 18:40:24 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: No code Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: ------------------------------ 153ms Jan 25 18:40:24 chot volumio[879]: info: ------------------------------ 162ms Jan 25 18:40:24 chot volumio[879]: info: ------------------------------ 161ms Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: info: Jan 25 18:40:24 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: info: Jan 25 18:40:24 chot volumio[879]: ---------------------------- MPD announces state update: update Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::getState Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::sendMpdCommand status Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 165 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 164 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 6 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 4 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 4 milliseconds Jan 25 18:40:24 chot volumio[879]: info: sendMpdCommand status took 3 milliseconds Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:24 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:24 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:24 chot volumio[879]: info: Command Router : Notfying DB Updatefalse Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::Close All Modals sent Jan 25 18:40:24 chot volumio[879]: verbose: ControllerMpd::parseState Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: 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 25 18:40:24 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: No code Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: 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 25 18:40:24 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: No code Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: ControllerMpd::pushState Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::servicePushState Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: 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 25 18:40:24 chot volumio[879]: verbose: CURRENT POSITION 0 Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState stateService stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: No code Jan 25 18:40:24 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:24 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:24 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:24 chot volumio[879]: info: ------------------------------ 273ms Jan 25 18:40:24 chot volumio[879]: info: ------------------------------ 114ms Jan 25 18:40:24 chot volumio[879]: info: ------------------------------ 112ms Jan 25 18:40:27 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 18:40:31 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 18:40:33 chot volumio[879]: info: CoreCommandRouter::volumioGetState Jan 25 18:40:33 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:36 chot volumio[879]: info: CoreCommandRouter::volumioClearQueue Jan 25 18:40:36 chot volumio[879]: info: CoreStateMachine::ClearQueue Jan 25 18:40:36 chot volumio[879]: info: CoreStateMachine::stop Jan 25 18:40:36 chot volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 18:40:36 chot volumio[879]: info: CorePlayQueue::clearPlayQueue Jan 25 18:40:36 chot volumio[879]: info: CorePlayQueue::saveQueue Jan 25 18:40:36 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:36 chot volumio[879]: info: CoreCommandRouter::volumioPushQueue Jan 25 18:40:38 chot volumio[879]: info: CoreCommandRouter::volumioGetState Jan 25 18:40:38 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:45 chot volumio[879]: info: VolumeController::SetAlsaVolume+ Jan 25 18:40:45 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:45 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:45 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:45 chot volumio[879]: info: VolumeController::SetAlsaVolume+ Jan 25 18:40:46 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:46 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:46 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:46 chot volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 18:40:46 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 18:40:46 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:40:46 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:40:46 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:40:46 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 25 18:40:46 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 25 18:40:58 chot volumio[879]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 25 18:40:58 chot volumio[879]: info: Preparing to save Alsa Options, stopping services first Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::volumioGetState Jan 25 18:40:58 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::volumioPause Jan 25 18:40:58 chot volumio[879]: info: CoreStateMachine::pause Jan 25 18:40:58 chot volumio[879]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"D90"}} Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:40:58 chot volumio[879]: info: Setting mixer D90 for card D90 Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 25 18:40:58 chot volumio[879]: info: Updating Volume Controller Parameters: Device: 5 Name: D90 Mixer: D90 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 25 18:40:58 chot volumio[879]: info: Disabling external Volume Control Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 18:40:58 chot volumio[879]: info: Preparing to generate the ALSA configuration file Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 25 18:40:58 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 25 18:40:58 chot volumio[879]: info: Asound.conf file unchanged, so no further update is needed Jan 25 18:40:58 chot volumio[879]: info: Output device has changed, restarting MPD Jan 25 18:40:59 chot volumio[879]: info: VolumeController:: Volume=100 Mute =false Jan 25 18:40:59 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:40:59 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:40:59 chot sudo[2348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:40:59 chot sudo[2348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:40:59 chot sudo[2349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 18:40:59 chot sudo[2348]: pam_unix(sudo:session): session closed for user root Jan 25 18:40:59 chot sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 25 18:40:59 chot systemd[1]: musicservicesshield.service: Succeeded. Jan 25 18:40:59 chot systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jan 25 18:40:59 chot systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jan 25 18:40:59 chot systemd[1]: Stopping Music Player Daemon... Jan 25 18:40:59 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:40:59 chot volumio[879]: info: MPD Permissions set Jan 25 18:40:59 chot systemd[1]: mpd.service: Succeeded. Jan 25 18:40:59 chot systemd[1]: Stopped Music Player Daemon. Jan 25 18:40:59 chot systemd[1]: Starting Music Player Daemon... Jan 25 18:40:59 chot sudo[2369]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 18:40:59 chot sudo[2369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:40:59 chot sudo[2369]: pam_unix(sudo:session): session closed for user root Jan 25 18:41:00 chot mpd[2378]: Jan 25 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 18:41:00 chot systemd[1]: Started Music Player Daemon. Jan 25 18:41:00 chot systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jan 25 18:41:00 chot sudo[2349]: pam_unix(sudo:session): session closed for user root Jan 25 18:41:00 chot volumio[879]: error: updateQueue error: null Jan 25 18:41:00 chot volumio[2382]: cset: --> shielding system active with Jan 25 18:41:00 chot volumio[2382]: cset: "system" cpuset of CPUSPEC(0) with 105 tasks running Jan 25 18:41:00 chot volumio[2382]: cset: "user" cpuset of CPUSPEC(1-3) with 3 tasks running Jan 25 18:41:00 chot systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jan 25 18:41:02 chot volumio[879]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 25 18:41:02 chot volumio[879]: info: CoreStateMachine::getcurrentVolume Jan 25 18:41:02 chot volumio[879]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 18:41:02 chot volumio[879]: info: VolumeController:: Volume=100 Mute =false Jan 25 18:41:02 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:41:02 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:41:02 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:41:02 chot volumio[879]: info: CoreStateMachine::updateTrackBlock Jan 25 18:41:02 chot volumio[879]: info: CorePlayQueue::getTrackBlock Jan 25 18:41:02 chot volumio[879]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 18:41:02 chot volumio[879]: info: VolumeController:: Volume=100 Mute =false Jan 25 18:41:02 chot volumio[879]: info: CoreStateMachine::pushState Jan 25 18:41:02 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:41:02 chot volumio[879]: info: CoreCommandRouter::volumioPushState Jan 25 18:41:03 chot sudo[2405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 25 18:41:03 chot sudo[2405]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:41:03 chot systemd[1]: Stopping MPD Monitor Service... Jan 25 18:41:03 chot systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 25 18:41:03 chot systemd[1]: mpd_monitor.service: Succeeded. Jan 25 18:41:03 chot systemd[1]: Stopped MPD Monitor Service. Jan 25 18:41:03 chot systemd[1]: Started MPD Monitor Service. Jan 25 18:41:03 chot sudo[2405]: pam_unix(sudo:session): session closed for user root Jan 25 18:41:03 chot volumio[879]: info: Successfully started MPD Monitor Jan 25 18:41:03 chot mpd_monitor.sh[2420]: MPD Monitor Service: Starting MPD Monitor Service Jan 25 18:41:03 chot volumio[879]: info: CoreCommandRouter::volumioGetState Jan 25 18:41:03 chot volumio[879]: info: CorePlayQueue::getTrack 0 Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 18:41:05 chot volumio[879]: info: Retrieving Cloud Streaming UI Jan 25 18:41:05 chot volumio[879]: info: Getting Tidal Cloud Configuration Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 18:41:05 chot volumio[879]: info: Getting Qobuz Cloud Configuration Jan 25 18:41:05 chot volumio[879]: info: Asking plugin for UI Config Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 18:41:05 chot volumio[879]: info: Getting Spotify Cloud Configuration Jan 25 18:41:05 chot volumio[879]: info: Asking plugin for UI Config Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 18:41:05 chot volumio[879]: info: Saving Spotify Acccount Jan 25 18:41:05 chot volumio[879]: info: Got Tidal Cloud Configuration Jan 25 18:41:05 chot volumio[879]: info: Got it Jan 25 18:41:05 chot volumio[879]: info: Got it Jan 25 18:41:05 chot volumio[879]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 18:41:05 chot volumio[879]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::volumioGetBrowseSources Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::volumioGetBrowseSources Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::volumioGetBrowseSources Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 18:41:05 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 25 18:41:07 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 25 18:41:07 chot volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 25 18:41:07 chot volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 18:41:07 chot volumio[879]: Error: getaddrinfo -3007 Jan 25 18:41:07 chot volumio[879]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jan 25 18:41:07 chot volumio[879]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jan 25 18:41:07 chot volumio[879]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jan 25 18:41:07 chot volumio[879]: code: -3007, Jan 25 18:41:07 chot volumio[879]: errno: -3007, Jan 25 18:41:07 chot volumio[879]: syscall: 'getaddrinfo' Jan 25 18:41:07 chot volumio[879]: } Jan 25 18:41:07 chot volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 18:41:07 chot sudo[2443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-25 18:40 Jan 25 18:41:07 chot sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"