-- Logs begin at Sun 2025-03-09 11:36:00 UTC, end at Sun 2025-03-09 11:45:02 UTC. -- Mar 09 11:44:00 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:04 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:08 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:08 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 09 11:44:08 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand rescan Mar 09 11:44:08 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:08 volumio16g volumio[1055]: info: Mar 09 11:44:08 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:08 volumio16g volumio[1055]: info: sendMpdCommand rescan took 12 milliseconds Mar 09 11:44:08 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:08 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:08 volumio16g volumio[1055]: info: Mar 09 11:44:08 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:08 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:08 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:08 volumio16g volumio[1055]: info: Mar 09 11:44:08 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:08 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:08 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:08 volumio16g volumio[1055]: info: sendMpdCommand status took 13 milliseconds Mar 09 11:44:08 volumio16g volumio[1055]: info: sendMpdCommand status took 12 milliseconds Mar 09 11:44:08 volumio16g volumio[1055]: info: sendMpdCommand status took 11 milliseconds Mar 09 11:44:08 volumio16g volumio[1055]: info: sendMpdCommand status took 8 milliseconds Mar 09 11:44:08 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:09 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatetrue Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:09 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:09 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:09 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatetrue Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:09 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:09 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:09 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatetrue Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:09 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:09 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: 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} Mar 09 11:44:09 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:09 volumio16g volumio[1055]: info: No code Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:09 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: 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} Mar 09 11:44:09 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:09 volumio16g volumio[1055]: info: No code Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:09 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: 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} Mar 09 11:44:09 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:09 volumio16g volumio[1055]: info: No code Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:09 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:09 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:09 volumio16g volumio[1055]: info: ------------------------------ 405ms Mar 09 11:44:09 volumio16g volumio[1055]: info: ------------------------------ 404ms Mar 09 11:44:09 volumio16g volumio[1055]: info: ------------------------------ 403ms Mar 09 11:44:10 volumio16g volumio[1055]: info: MPD Database updated - AlbumList cache refreshed Mar 09 11:44:10 volumio16g volumio[1055]: info: Mar 09 11:44:10 volumio16g volumio[1055]: ---------------------------- MPD announces state update: database Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:10 volumio16g volumio[1055]: info: Mar 09 11:44:10 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:10 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:10 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:10 volumio16g volumio[1055]: info: MPD Database updated - AlbumList cache refreshed Mar 09 11:44:10 volumio16g volumio[1055]: info: Mar 09 11:44:10 volumio16g volumio[1055]: ---------------------------- MPD announces state update: database Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:10 volumio16g volumio[1055]: info: Mar 09 11:44:10 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:10 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:10 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:10 volumio16g volumio[1055]: info: MPD Database updated - AlbumList cache refreshed Mar 09 11:44:10 volumio16g volumio[1055]: info: Mar 09 11:44:10 volumio16g volumio[1055]: ---------------------------- MPD announces state update: database Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:10 volumio16g volumio[1055]: info: Mar 09 11:44:10 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:10 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:10 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:10 volumio16g volumio[1055]: info: sendMpdCommand status took 75 milliseconds Mar 09 11:44:10 volumio16g volumio[1055]: info: sendMpdCommand status took 75 milliseconds Mar 09 11:44:10 volumio16g volumio[1055]: info: sendMpdCommand status took 76 milliseconds Mar 09 11:44:10 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:11 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatefalse Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:11 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:11 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:11 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: 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} Mar 09 11:44:11 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: No code Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: 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} Mar 09 11:44:11 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: No code Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: ------------------------------ 407ms Mar 09 11:44:11 volumio16g volumio[1055]: info: ------------------------------ 408ms Mar 09 11:44:11 volumio16g volumio[1055]: info: sendMpdCommand status took 437 milliseconds Mar 09 11:44:11 volumio16g volumio[1055]: info: sendMpdCommand status took 438 milliseconds Mar 09 11:44:11 volumio16g volumio[1055]: info: sendMpdCommand status took 437 milliseconds Mar 09 11:44:11 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:11 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatefalse Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:11 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:11 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:11 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: 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} Mar 09 11:44:11 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: No code Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: 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} Mar 09 11:44:11 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: No code Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:11 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:11 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:11 volumio16g volumio[1055]: info: ------------------------------ 953ms Mar 09 11:44:11 volumio16g volumio[1055]: info: ------------------------------ 956ms Mar 09 11:44:11 volumio16g volumio[1055]: info: sendMpdCommand status took 1003 milliseconds Mar 09 11:44:11 volumio16g volumio[1055]: info: sendMpdCommand status took 1002 milliseconds Mar 09 11:44:11 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:12 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatefalse Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:12 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:12 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:12 volumio16g volumio[1055]: 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} Mar 09 11:44:12 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:12 volumio16g volumio[1055]: info: No code Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:12 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:12 volumio16g volumio[1055]: 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} Mar 09 11:44:12 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:12 volumio16g volumio[1055]: info: No code Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:12 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:12 volumio16g volumio[1055]: info: ------------------------------ 1513ms Mar 09 11:44:12 volumio16g volumio[1055]: info: ------------------------------ 1515ms Mar 09 11:44:12 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:16 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:20 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:24 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:28 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:32 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:36 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:40 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:44 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:48 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 09 11:44:48 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand update Mar 09 11:44:48 volumio16g volumio[1055]: info: sendMpdCommand update took 5 milliseconds Mar 09 11:44:48 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:48 volumio16g volumio[1055]: info: Mar 09 11:44:48 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:48 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:48 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:48 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:48 volumio16g volumio[1055]: info: Mar 09 11:44:48 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:48 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:48 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:48 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:48 volumio16g volumio[1055]: info: Mar 09 11:44:48 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:48 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:48 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:48 volumio16g volumio[1055]: info: sendMpdCommand status took 12 milliseconds Mar 09 11:44:48 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatetrue Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:49 volumio16g volumio[1055]: info: Mar 09 11:44:49 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 1281 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 1271 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 1270 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 1269 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 1270 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatetrue Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatetrue Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: 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} Mar 09 11:44:49 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: No code Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: 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} Mar 09 11:44:49 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: No code Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: 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} Mar 09 11:44:49 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: No code Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: ------------------------------ 1467ms Mar 09 11:44:49 volumio16g volumio[1055]: info: ------------------------------ 1513ms Mar 09 11:44:49 volumio16g volumio[1055]: info: ------------------------------ 1513ms Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:49 volumio16g volumio[1055]: info: Mar 09 11:44:49 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:49 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:49 volumio16g volumio[1055]: info: Mar 09 11:44:49 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:49 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 269 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 268 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 20 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 19 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 19 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: info: sendMpdCommand status took 17 milliseconds Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatefalse Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatefalse Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatefalse Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:44:49 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:44:49 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: 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} Mar 09 11:44:49 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: No code Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: 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} Mar 09 11:44:49 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: No code Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: 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} Mar 09 11:44:49 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: No code Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:44:49 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:44:49 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:44:49 volumio16g volumio[1055]: info: ------------------------------ 511ms Mar 09 11:44:49 volumio16g volumio[1055]: info: ------------------------------ 261ms Mar 09 11:44:49 volumio16g volumio[1055]: info: ------------------------------ 260ms Mar 09 11:44:52 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:56 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:44:59 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 09 11:44:59 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand update Mar 09 11:44:59 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:59 volumio16g volumio[1055]: info: Mar 09 11:44:59 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:59 volumio16g volumio[1055]: info: sendMpdCommand update took 6 milliseconds Mar 09 11:44:59 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:59 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:59 volumio16g volumio[1055]: info: Mar 09 11:44:59 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:59 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:59 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:59 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:59 volumio16g volumio[1055]: info: Mar 09 11:44:59 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:44:59 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:44:59 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:44:59 volumio16g volumio[1055]: info: sendMpdCommand status took 11 milliseconds Mar 09 11:44:59 volumio16g volumio[1055]: info: sendMpdCommand status took 9 milliseconds Mar 09 11:44:59 volumio16g volumio[1055]: info: sendMpdCommand status took 9 milliseconds Mar 09 11:44:59 volumio16g volumio[1055]: info: sendMpdCommand status took 17 milliseconds Mar 09 11:44:59 volumio16g volumio[1055]: info: sendMpdCommand status took 15 milliseconds Mar 09 11:44:59 volumio16g volumio[1055]: info: sendMpdCommand status took 14 milliseconds Mar 09 11:44:59 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:45:01 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatetrue Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:45:01 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:45:01 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatetrue Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:45:01 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:45:01 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:45:01 volumio16g volumio[1055]: info: Command Router : Notfying DB Updatetrue Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::Close All Modals sent Mar 09 11:45:01 volumio16g volumio[1055]: verbose: ControllerMpd::parseState Mar 09 11:45:01 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: 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} Mar 09 11:45:01 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:45:01 volumio16g volumio[1055]: info: No code Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:45:01 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: 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} Mar 09 11:45:01 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:45:01 volumio16g volumio[1055]: info: No code Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:45:01 volumio16g volumio[1055]: info: ControllerMpd::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::servicePushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: 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} Mar 09 11:45:01 volumio16g volumio[1055]: verbose: CURRENT POSITION 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::syncState stateService stop Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::syncState currentStatus stop Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:45:01 volumio16g volumio[1055]: info: No code Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreStateMachine::pushState Mar 09 11:45:01 volumio16g volumio[1055]: info: CorePlayQueue::getTrack 0 Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::volumioPushState Mar 09 11:45:01 volumio16g volumio[1055]: info: ------------------------------ 2259ms Mar 09 11:45:01 volumio16g volumio[1055]: info: ------------------------------ 2257ms Mar 09 11:45:01 volumio16g volumio[1055]: info: ------------------------------ 2253ms Mar 09 11:45:01 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:45:01 volumio16g volumio[1055]: info: Mar 09 11:45:01 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:45:01 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:45:01 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:45:01 volumio16g volumio[1055]: info: Mar 09 11:45:01 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:45:01 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:45:01 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:45:01 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:45:01 volumio16g volumio[1055]: info: Mar 09 11:45:01 volumio16g volumio[1055]: ---------------------------- MPD announces state update: update Mar 09 11:45:01 volumio16g volumio[1055]: info: ControllerMpd::getState Mar 09 11:45:01 volumio16g volumio[1055]: verbose: ControllerMpd::sendMpdCommand status Mar 09 11:45:01 volumio16g volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 09 11:45:01 volumio16g volumio[1055]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 11:45:01 volumio16g volumio[1055]: Error: connect ETIMEDOUT 128.242.240.221:80 Mar 09 11:45:01 volumio16g volumio[1055]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 09 11:45:01 volumio16g volumio[1055]: errno: -110, Mar 09 11:45:01 volumio16g volumio[1055]: code: 'ETIMEDOUT', Mar 09 11:45:01 volumio16g volumio[1055]: syscall: 'connect', Mar 09 11:45:01 volumio16g volumio[1055]: address: '128.242.240.221', Mar 09 11:45:01 volumio16g volumio[1055]: port: 80 Mar 09 11:45:01 volumio16g volumio[1055]: } Mar 09 11:45:01 volumio16g volumio[1055]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 11:45:02 volumio16g sudo[3153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-09 11:44 Mar 09 11:45:02 volumio16g sudo[3153]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"