-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2024-10-21 10:27:27 UTC. -- Oct 21 10:26:05 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 21 10:26:11 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 10:26:11 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 10:26:11 motivo volumio[1001]: info: Discovery: Getting this device information Oct 21 10:26:11 motivo volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 21 10:26:11 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 10:26:14 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 21 10:26:14 motivo volumio[1001]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 21 10:26:14 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 21 10:26:14 motivo volumio[1001]: info: Received Get System Version Oct 21 10:26:14 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 21 10:26:14 motivo volumio[1001]: info: Received Get System Info Oct 21 10:26:14 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 10:26:14 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 10:26:14 motivo volumio[1001]: info: Discovery: Getting this device information Oct 21 10:26:14 motivo volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 21 10:26:14 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 10:26:19 motivo volumio[1001]: info: CoreCommandRouter::volumioGetQueue Oct 21 10:26:19 motivo volumio[1001]: info: CoreStateMachine::getQueue Oct 21 10:26:19 motivo volumio[1001]: info: CorePlayQueue::getQueue Oct 21 10:26:23 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 10:26:23 motivo volumio[1001]: info: CURURI: music-library Oct 21 10:26:23 motivo volumio[1001]: error: Failed LSINFO: null Oct 21 10:26:23 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:26:27 motivo volumio[1001]: info: CoreCommandRouter::volumioGetQueue Oct 21 10:26:27 motivo volumio[1001]: info: CoreStateMachine::getQueue Oct 21 10:26:27 motivo volumio[1001]: info: CorePlayQueue::getQueue Oct 21 10:26:29 motivo volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 21 10:26:31 motivo volumio[1001]: info: Executing endpoint metavolumio Oct 21 10:26:31 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 21 10:26:31 motivo volumio[1001]: info: Executing endpoint metavolumio Oct 21 10:26:31 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 21 10:26:31 motivo volumio[1001]: info: Executing endpoint metavolumio Oct 21 10:26:31 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 21 10:26:33 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 10:26:33 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:26:35 motivo volumio[1001]: error: Failed request for metavolumio API Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 10:26:37 motivo volumio[1001]: info: Retrieving Cloud Streaming UI Oct 21 10:26:37 motivo volumio[1001]: info: Getting Tidal Cloud Configuration Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 10:26:37 motivo volumio[1001]: info: Getting Qobuz Cloud Configuration Oct 21 10:26:37 motivo volumio[1001]: info: Asking plugin for UI Config Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 10:26:37 motivo volumio[1001]: info: Getting Spotify Cloud Configuration Oct 21 10:26:37 motivo volumio[1001]: info: Asking plugin for UI Config Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 10:26:37 motivo volumio[1001]: info: Saving Spotify Acccount Oct 21 10:26:37 motivo volumio[1001]: info: Got it Oct 21 10:26:37 motivo volumio[1001]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 21 10:26:37 motivo volumio[1001]: info: Got it Oct 21 10:26:37 motivo volumio[1001]: info: Got Tidal Cloud Configuration Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 21 10:26:37 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand rescan Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:39 motivo volumio[1001]: info: Oct 21 10:26:39 motivo volumio[1001]: ---------------------------- MPD announces state update: update Oct 21 10:26:39 motivo volumio[1001]: info: sendMpdCommand rescan took 9 milliseconds Oct 21 10:26:39 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:39 motivo volumio[1001]: info: sendMpdCommand status took 4 milliseconds Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:39 motivo volumio[1001]: info: Command Router : Notfying DB Updatetrue Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:39 motivo volumio[1001]: info: Oct 21 10:26:39 motivo volumio[1001]: ---------------------------- MPD announces state update: update Oct 21 10:26:39 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:39 motivo volumio[1001]: info: Oct 21 10:26:39 motivo volumio[1001]: ---------------------------- MPD announces state update: update Oct 21 10:26:39 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:39 motivo volumio[1001]: info: sendMpdCommand status took 64 milliseconds Oct 21 10:26:39 motivo volumio[1001]: info: sendMpdCommand status took 6 milliseconds Oct 21 10:26:39 motivo volumio[1001]: info: sendMpdCommand status took 5 milliseconds Oct 21 10:26:39 motivo volumio[1001]: info: sendMpdCommand status took 4 milliseconds Oct 21 10:26:39 motivo volumio[1001]: info: sendMpdCommand status took 3 milliseconds Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:39 motivo volumio[1001]: info: Command Router : Notfying DB Updatetrue Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:39 motivo volumio[1001]: info: Command Router : Notfying DB Updatetrue Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 21 10:26:39 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:39 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:39 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:26:39 motivo volumio[1001]: 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} Oct 21 10:26:39 motivo volumio[1001]: verbose: CURRENT POSITION 3 Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus pause Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:39 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:39 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:26:39 motivo volumio[1001]: 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} Oct 21 10:26:39 motivo volumio[1001]: verbose: CURRENT POSITION 3 Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus pause Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:39 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:39 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:26:39 motivo volumio[1001]: 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} Oct 21 10:26:39 motivo volumio[1001]: verbose: CURRENT POSITION 3 Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus pause Oct 21 10:26:39 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:39 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:39 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:39 motivo volumio[1001]: info: ------------------------------ 221ms Oct 21 10:26:39 motivo volumio[1001]: info: ------------------------------ 179ms Oct 21 10:26:39 motivo volumio[1001]: info: ------------------------------ 177ms Oct 21 10:26:39 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:39 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:39 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:39 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:39 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:39 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:39 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:39 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:41 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 10:26:41 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 10:26:41 motivo volumio[1001]: info: Discovery: Getting this device information Oct 21 10:26:41 motivo volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 21 10:26:41 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 10:26:41 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 21 10:26:45 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 21 10:26:51 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 10:26:51 motivo volumio[1001]: info: CURURI: music-library Oct 21 10:26:51 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:26:54 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 10:26:54 motivo volumio[1001]: info: CURURI: music-library/INTERNAL Oct 21 10:26:54 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:26:55 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 10:26:55 motivo volumio[1001]: info: CURURI: music-library/USB Oct 21 10:26:56 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:26:57 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 10:26:57 motivo volumio[1001]: info: CURURI: music-library/USB/D0CE-8773 Oct 21 10:26:57 motivo volumio[1001]: error: Failed LSINFO: null Oct 21 10:26:57 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:26:58 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 10:26:58 motivo volumio[1001]: info: CURURI: music-library/USB/A6FD-25A9 Oct 21 10:26:58 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:26:59 motivo volumio[1001]: info: MPD Database updated - AlbumList cache refreshed Oct 21 10:26:59 motivo volumio[1001]: info: Oct 21 10:26:59 motivo volumio[1001]: ---------------------------- MPD announces state update: database Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:59 motivo volumio[1001]: info: Oct 21 10:26:59 motivo volumio[1001]: ---------------------------- MPD announces state update: update Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:59 motivo volumio[1001]: info: MPD Database updated - AlbumList cache refreshed Oct 21 10:26:59 motivo volumio[1001]: info: Oct 21 10:26:59 motivo volumio[1001]: ---------------------------- MPD announces state update: database Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:59 motivo volumio[1001]: info: Oct 21 10:26:59 motivo volumio[1001]: ---------------------------- MPD announces state update: update Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:59 motivo volumio[1001]: info: MPD Database updated - AlbumList cache refreshed Oct 21 10:26:59 motivo volumio[1001]: info: Oct 21 10:26:59 motivo volumio[1001]: ---------------------------- MPD announces state update: database Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:59 motivo volumio[1001]: info: Oct 21 10:26:59 motivo volumio[1001]: ---------------------------- MPD announces state update: update Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:26:59 motivo volumio[1001]: info: sendMpdCommand status took 55 milliseconds Oct 21 10:26:59 motivo volumio[1001]: info: sendMpdCommand status took 55 milliseconds Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:59 motivo volumio[1001]: info: Command Router : Notfying DB Updatefalse Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:26:59 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:26:59 motivo volumio[1001]: verbose: CURRENT POSITION 3 Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus pause Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:26:59 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:26:59 motivo volumio[1001]: verbose: CURRENT POSITION 3 Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus pause Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: ------------------------------ 128ms Oct 21 10:26:59 motivo volumio[1001]: info: ------------------------------ 128ms Oct 21 10:26:59 motivo volumio[1001]: info: sendMpdCommand status took 150 milliseconds Oct 21 10:26:59 motivo volumio[1001]: info: sendMpdCommand status took 150 milliseconds Oct 21 10:26:59 motivo volumio[1001]: info: sendMpdCommand status took 143 milliseconds Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:59 motivo volumio[1001]: info: Command Router : Notfying DB Updatefalse Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:26:59 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:26:59 motivo volumio[1001]: verbose: CURRENT POSITION 3 Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus pause Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:26:59 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:26:59 motivo volumio[1001]: verbose: CURRENT POSITION 3 Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus pause Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: ------------------------------ 246ms Oct 21 10:26:59 motivo volumio[1001]: info: ------------------------------ 247ms Oct 21 10:26:59 motivo volumio[1001]: info: sendMpdCommand status took 281 milliseconds Oct 21 10:26:59 motivo volumio[1001]: info: sendMpdCommand status took 280 milliseconds Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:59 motivo volumio[1001]: info: Command Router : Notfying DB Updatefalse Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::Close All Modals sent Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:59 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:26:59 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:26:59 motivo volumio[1001]: verbose: CURRENT POSITION 3 Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus pause Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:26:59 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:26:59 motivo volumio[1001]: verbose: CURRENT POSITION 3 Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus pause Oct 21 10:26:59 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:26:59 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:26:59 motivo volumio[1001]: info: ------------------------------ 453ms Oct 21 10:26:59 motivo volumio[1001]: info: ------------------------------ 446ms Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:26:59 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 10:26:59 motivo volumio[1001]: info: CURURI: music-library/USB/A6FD-25A9/AC_DC Oct 21 10:26:59 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:27:00 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 10:27:00 motivo volumio[1001]: info: CURURI: music-library/USB/A6FD-25A9/AC_DC/Back In Black Oct 21 10:27:00 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/02 Shoot To Thrill.wav Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/03 What Do You Do For Money Honey.wav Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/04 Given The Dog A Bone.wav Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/05 Let Me Put My Love Into You.wav Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/06 Back In Black.wav Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/07 You Shook Me All Night Long.wav Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/08 Have A Drink On Me.wav Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/09 Shake A Leg.wav Oct 21 10:27:00 motivo volumio[1001]: info: Preloading song: music-library/USB/A6FD-25A9/AC_DC/Back In Black/10 Rock And Roll Ain't Noise Pollution.wav Oct 21 10:27:00 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav in service mpd Oct 21 10:27:00 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F01%20Hells%20Bells.wav&metadata=false Oct 21 10:27:00 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav Oct 21 10:27:00 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/02 Shoot To Thrill.wav in service mpd Oct 21 10:27:00 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F02%20Shoot%20To%20Thrill.wav&metadata=false Oct 21 10:27:00 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/02 Shoot To Thrill.wav Oct 21 10:27:00 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/03 What Do You Do For Money Honey.wav in service mpd Oct 21 10:27:00 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F03%20What%20Do%20You%20Do%20For%20Money%20Honey.wav&metadata=false Oct 21 10:27:00 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/03 What Do You Do For Money Honey.wav Oct 21 10:27:00 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/04 Given The Dog A Bone.wav in service mpd Oct 21 10:27:00 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F04%20Given%20The%20Dog%20A%20Bone.wav&metadata=false Oct 21 10:27:00 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/04 Given The Dog A Bone.wav Oct 21 10:27:01 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/05 Let Me Put My Love Into You.wav in service mpd Oct 21 10:27:01 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F05%20Let%20Me%20Put%20My%20Love%20Into%20You.wav&metadata=false Oct 21 10:27:01 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/05 Let Me Put My Love Into You.wav Oct 21 10:27:01 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/06 Back In Black.wav in service mpd Oct 21 10:27:01 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F06%20Back%20In%20Black.wav&metadata=false Oct 21 10:27:01 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/06 Back In Black.wav Oct 21 10:27:01 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/07 You Shook Me All Night Long.wav in service mpd Oct 21 10:27:01 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F07%20You%20Shook%20Me%20All%20Night%20Long.wav&metadata=false Oct 21 10:27:01 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/07 You Shook Me All Night Long.wav Oct 21 10:27:01 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/08 Have A Drink On Me.wav in service mpd Oct 21 10:27:01 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F08%20Have%20A%20Drink%20On%20Me.wav&metadata=false Oct 21 10:27:01 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/08 Have A Drink On Me.wav Oct 21 10:27:01 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/09 Shake A Leg.wav in service mpd Oct 21 10:27:01 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F09%20Shake%20A%20Leg.wav&metadata=false Oct 21 10:27:01 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/09 Shake A Leg.wav Oct 21 10:27:01 motivo volumio[1001]: info: Exploding uri music-library/USB/A6FD-25A9/AC_DC/Back In Black/10 Rock And Roll Ain't Noise Pollution.wav in service mpd Oct 21 10:27:01 motivo volumio[1001]: info: ALBUMART /albumart?cacheid=238&web=//mega&path=%2Fmnt%2FUSB%2FA6FD-25A9%2FAC_DC%2FBack%20In%20Black%2F10%20Rock%20And%20Roll%20Ain't%20Noise%20Pollution.wav&metadata=false Oct 21 10:27:01 motivo volumio[1001]: info: URI /mnt/USB/A6FD-25A9/AC_DC/Back In Black/10 Rock And Roll Ain't Noise Pollution.wav Oct 21 10:27:01 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:27:01 motivo volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::ClearQueue Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::stop Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::serviceStop Oct 21 10:27:01 motivo volumio[1001]: info: Received STOP, but no service to execute it Oct 21 10:27:01 motivo volumio[1001]: info: CorePlayQueue::clearPlayQueue Oct 21 10:27:01 motivo volumio[1001]: info: CorePlayQueue::saveQueue Oct 21 10:27:01 motivo volumio[1001]: info: CoreCommandRouter::volumioPushQueue Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::addQueueItems Oct 21 10:27:01 motivo volumio[1001]: info: CorePlayQueue::addQueueItems Oct 21 10:27:01 motivo volumio[1001]: info: Preload queue cleared Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/02 Shoot To Thrill.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/02 Shoot To Thrill.wav Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/03 What Do You Do For Money Honey.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/03 What Do You Do For Money Honey.wav Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/04 Given The Dog A Bone.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/04 Given The Dog A Bone.wav Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/05 Let Me Put My Love Into You.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/05 Let Me Put My Love Into You.wav Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/06 Back In Black.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/06 Back In Black.wav Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/07 You Shook Me All Night Long.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/07 You Shook Me All Night Long.wav Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/08 Have A Drink On Me.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/08 Have A Drink On Me.wav Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/09 Shake A Leg.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/09 Shake A Leg.wav Oct 21 10:27:01 motivo volumio[1001]: info: Adding Item to queue: music-library/USB/A6FD-25A9/AC_DC/Back In Black/10 Rock And Roll Ain't Noise Pollution.wav Oct 21 10:27:01 motivo volumio[1001]: info: Using cached record of: music-library/USB/A6FD-25A9/AC_DC/Back In Black/10 Rock And Roll Ain't Noise Pollution.wav Oct 21 10:27:01 motivo volumio[1001]: info: CoreCommandRouter::volumioPushQueue Oct 21 10:27:01 motivo volumio[1001]: info: CorePlayQueue::saveQueue Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::updateTrackBlock Oct 21 10:27:01 motivo volumio[1001]: info: CorePlayQueue::getTrackBlock Oct 21 10:27:01 motivo volumio[1001]: info: CoreCommandRouter::volumioPlay Oct 21 10:27:01 motivo volumio[1001]: verbose: UNSET VOLATILE: Service: undefined Oct 21 10:27:01 motivo volumio[1001]: info: unSetVolatile called Oct 21 10:27:01 motivo volumio[1001]: info: Relinquishing Volumio State to another service Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::play index 0 Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::stop Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::updateTrackBlock Oct 21 10:27:01 motivo volumio[1001]: info: CorePlayQueue::getTrackBlock Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::stPlaybackTimer Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:01 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:27:01 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 10:27:01 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:01 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:01 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:01 motivo volumio[1001]: info: CoreStateMachine::serviceStop Oct 21 10:27:01 motivo volumio[1001]: info: CorePlayQueue::getTrack 3 Oct 21 10:27:01 motivo volumio[1001]: info: CoreCommandRouter::serviceStop Oct 21 10:27:01 motivo volumio[1001]: info: ControllerMpd::stop Oct 21 10:27:01 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Oct 21 10:27:01 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:01 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:02 motivo volumio[1001]: info: PlaybackInactive Oct 21 10:27:02 motivo volumio[1001]: info: Device Session is_active: true Oct 21 10:27:02 motivo volumio[1001]: info: DeviceInactive Oct 21 10:27:02 motivo volumio[1001]: info: Relinquishing Volumio State Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand stop took 24 milliseconds Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::play index undefined Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::startPlaybackTimer Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::clearAddPlayTracks USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand stop took 4 milliseconds Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:02 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand clear took 5 milliseconds Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav" Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:02 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:02 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:02 motivo volumio[1001]: error: updateQueue error: null Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:02 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 27ms Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand add "USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav" took 24 milliseconds Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 7ms Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 6ms Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:02 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:02 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:02 motivo volumio[1001]: error: Upnp client error: Error: This socket has been ended by the other party Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 19ms Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand play took 18 milliseconds Oct 21 10:27:02 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 4ms Oct 21 10:27:02 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 4ms Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:02 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:02 motivo volumio[1001]: info: Oct 21 10:27:02 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:02 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand status took 17 milliseconds Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand status took 17 milliseconds Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand status took 15 milliseconds Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand status took 14 milliseconds Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand clearerror took 30 milliseconds Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand clearerror took 30 milliseconds Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand clearerror took 29 milliseconds Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand clearerror took 27 milliseconds Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 21 10:27:02 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:02 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:02 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:02 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:02 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::syncState stateService pause Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 10:27:02 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:02 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:02 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:02 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:02 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:02 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::syncState stateService pause Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:02 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:02 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:02 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:02 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:02 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::syncState stateService pause Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:02 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:02 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:02 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:02 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:02 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::syncState stateService pause Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:02 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:02 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:02 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:02 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:02 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 97ms Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 98ms Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 95ms Oct 21 10:27:02 motivo volumio[1001]: info: ------------------------------ 93ms Oct 21 10:27:02 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:02 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:02 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:02 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:02 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:02 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:02 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:02 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPlay Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::play index undefined Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::startPlaybackTimer Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::clearAddPlayTracks USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:10 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand clear took 0 milliseconds Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav" Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:10 motivo volumio[1001]: error: updateQueue error: null Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:10 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand status took 3 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 3ms Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand add "USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav" took 2 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand status took 1 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand status took 1 milliseconds Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:27:10 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: No code Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:27:10 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: No code Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:27:10 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: No code Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 75ms Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 74ms Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:10 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:10 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 80ms Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand play took 79 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:10 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:10 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:10 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:10 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:10 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 47ms Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 47ms Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand status took 45 milliseconds Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:10 motivo volumio[1001]: info: Oct 21 10:27:10 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand status took 10 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand clearerror took 8 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand status took 7 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand status took 6 milliseconds Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:10 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState stateService pause Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 71ms Oct 21 10:27:10 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand clearerror took 26 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 21 10:27:10 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:10 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:10 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState stateService pause Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:10 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState stateService pause Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:10 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState stateService pause Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:10 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:10 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:10 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:10 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 59ms Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 55ms Oct 21 10:27:10 motivo volumio[1001]: info: ------------------------------ 54ms Oct 21 10:27:10 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:10 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:10 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:10 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:11 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 10:27:11 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 10:27:11 motivo volumio[1001]: info: Discovery: Getting this device information Oct 21 10:27:11 motivo volumio[1001]: info: CoreCommandRouter::volumioGetState Oct 21 10:27:11 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:11 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 10:27:13 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 21 10:27:13 motivo volumio[1001]: info: MRS: Starting browser stream Oct 21 10:27:13 motivo volumio[1001]: info: MRS: Setting this device as Streaming Server Oct 21 10:27:13 motivo volumio[1001]: info: Oct 21 10:27:13 motivo volumio[1001]: [1729506433971] ---------------------------- MRS: Setting Streaming Server Oct 21 10:27:13 motivo volumio[1001]: info: Enabled audio output: browserPlayback Oct 21 10:27:13 motivo volumio[1001]: info: MRS: enable multiroom server output Oct 21 10:27:13 motivo volumio[1001]: info: MRS: Set multiroom target PCM to volumioMultiRoom Oct 21 10:27:14 motivo volumio[1001]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Oct 21 10:27:14 motivo volumio[1001]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 21 10:27:14 motivo volumio[1001]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Oct 21 10:27:14 motivo volumio[1001]: info: MRS: STARTING volumioStreaming Oct 21 10:27:14 motivo sudo[27119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 21 10:27:14 motivo sudo[27119]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 10:27:14 motivo sudo[27119]: pam_unix(sudo:session): session closed for user root Oct 21 10:27:14 motivo sudo[27122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Oct 21 10:27:14 motivo sudo[27122]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 10:27:14 motivo systemd[1]: Started VolumioStreamingService. Oct 21 10:27:14 motivo sudo[27122]: pam_unix(sudo:session): session closed for user root Oct 21 10:27:14 motivo volumio[1001]: info: MRS: volumioStreaming STARTED Oct 21 10:27:14 motivo sudo[27125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 21 10:27:14 motivo sudo[27125]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 10:27:14 motivo sudo[27125]: pam_unix(sudo:session): session closed for user root Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPlay Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::play index undefined Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::startPlaybackTimer Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::clearAddPlayTracks USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand stop took 4 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:18 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand status took 3 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand clear took 3 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand status took 1 milliseconds Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav" Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:27:18 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: No code Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 21 10:27:18 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState stateService stop Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: No code Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 71ms Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 71ms Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:18 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:18 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:18 motivo volumio[1001]: error: updateQueue error: null Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:18 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 81ms Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 82 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand add "USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav" took 80 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 7ms Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 6ms Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Oct 21 10:27:18 motivo volumio[1001]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 93ms Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:18 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces system playlist update Oct 21 10:27:18 motivo volumio[1001]: info: Ignoring MPD Status Update Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 38ms Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand play took 35 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 29ms Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 28ms Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand status took 4 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand status took 4 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand status took 2 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand status took 1 milliseconds Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:18 motivo volumio[1001]: info: Oct 21 10:27:18 motivo volumio[1001]: ---------------------------- MPD announces state update: player Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::getState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand status took 3 milliseconds Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:18 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:18 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 21 10:27:18 motivo volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:18 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 21 10:27:18 motivo volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:18 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 21 10:27:18 motivo volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 33ms Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 61ms Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 60ms Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 60ms Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand status took 54 milliseconds Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseState Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:18 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 21 10:27:18 motivo volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 72ms Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: sendMpdCommand playlistinfo took 54 milliseconds Oct 21 10:27:18 motivo volumio[1001]: verbose: ControllerMpd::parseTrackInfo Oct 21 10:27:18 motivo volumio[1001]: info: ControllerMpd::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::servicePushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 Hells Bells.wav","artist":null,"album":null,"uri":"USB/A6FD-25A9/AC_DC/Back In Black/01 Hells Bells.wav","trackType":"wav"} Oct 21 10:27:18 motivo volumio[1001]: verbose: CURRENT POSITION 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState stateService play Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::syncState currentStatus play Oct 21 10:27:18 motivo volumio[1001]: info: Received an update from plugin. extracting info from payload Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: CoreStateMachine::pushState Oct 21 10:27:18 motivo volumio[1001]: info: CorePlayQueue::getTrack 0 Oct 21 10:27:18 motivo volumio[1001]: info: CoreCommandRouter::volumioPushState Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Oct 21 10:27:18 motivo volumio[1001]: info: MRS: Pushing multiroomSync output Oct 21 10:27:18 motivo volumio[1001]: info: ------------------------------ 122ms Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Updating RAAT Signal Path Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: Signalling Playback active due to playback status change Oct 21 10:27:18 motivo volumio[1001]: info: MCU Signalled Playback Active Oct 21 10:27:23 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Oct 21 10:27:23 motivo volumio[1001]: info: MRS: Starting browser stream Oct 21 10:27:23 motivo volumio[1001]: info: MRS: Setting this device as single Oct 21 10:27:23 motivo volumio[1001]: info: Oct 21 10:27:23 motivo volumio[1001]: [1729506443882] ---------------------------- Setting Multiroom Single Oct 21 10:27:23 motivo volumio[1001]: info: MRS: disable multiroom output Oct 21 10:27:23 motivo volumio[1001]: info: MRS: Set multiroom target PCM to volumioDiscard Oct 21 10:27:23 motivo volumio[1001]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Oct 21 10:27:24 motivo volumio[1001]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 21 10:27:24 motivo volumio[1001]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Oct 21 10:27:25 motivo volumio[1001]: info: MRS: STOPPING volumioStreaming Oct 21 10:27:25 motivo sudo[27153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 21 10:27:25 motivo sudo[27153]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 10:27:25 motivo sudo[27156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream2.ts /tmp/hls/stream3.ts /tmp/hls/stream4.ts /tmp/hls/stream5.ts /tmp/hls/stream6.ts Oct 21 10:27:25 motivo systemd[1]: Stopping VolumioStreamingService... Oct 21 10:27:25 motivo sudo[27156]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 10:27:25 motivo systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Oct 21 10:27:25 motivo sudo[27156]: pam_unix(sudo:session): session closed for user root Oct 21 10:27:25 motivo systemd[1]: volumioStreaming.service: Succeeded. Oct 21 10:27:25 motivo systemd[1]: Stopped VolumioStreamingService. Oct 21 10:27:25 motivo volumio[1001]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 21 10:27:25 motivo sudo[27153]: pam_unix(sudo:session): session closed for user root Oct 21 10:27:25 motivo volumio[1001]: info: MRS: Removed streaming files Oct 21 10:27:25 motivo volumio[1001]: info: MRS: volumioStreaming STOPPED Oct 21 10:27:26 motivo volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 21 10:27:26 motivo volumio[1001]: info: MRS: Starting cast device: Cambridge-Audio-EXN1-129b22a95721ec9f92e4d5c2680f4f87 Oct 21 10:27:26 motivo volumio[1001]: info: Enabled audio output: Cambridge-Audio-EXN1-129b22a95721ec9f92e4d5c2680f4f87 Oct 21 10:27:26 motivo volumio[1001]: info: MRS: Setting this device as Streaming Server Oct 21 10:27:26 motivo volumio[1001]: info: Oct 21 10:27:26 motivo volumio[1001]: [1729506446669] ---------------------------- MRS: Setting Streaming Server Oct 21 10:27:26 motivo volumio[1001]: info: MRS: enable multiroom server output Oct 21 10:27:26 motivo volumio[1001]: info: MRS: Set multiroom target PCM to volumioMultiRoom Oct 21 10:27:26 motivo volumio[1001]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Oct 21 10:27:26 motivo volumio[1001]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 21 10:27:26 motivo volumio[1001]: info: MRS: Connected to cast device: Cambridge-Audio-EXN1-129b22a95721ec9f92e4d5c2680f4f87 Oct 21 10:27:26 motivo volumio[1001]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Oct 21 10:27:26 motivo volumio[1001]: info: MRS: STARTING volumioStreaming Oct 21 10:27:26 motivo sudo[27166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 21 10:27:26 motivo sudo[27166]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 10:27:26 motivo sudo[27166]: pam_unix(sudo:session): session closed for user root Oct 21 10:27:26 motivo sudo[27169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Oct 21 10:27:26 motivo volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 10:27:26 motivo sudo[27169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 10:27:26 motivo volumio[1001]: TypeError: Cannot read property 'on' of undefined Oct 21 10:27:26 motivo volumio[1001]: at /volumio/app/plugins/audio_interface/multiroom/multiroom_real:1:29162 Oct 21 10:27:26 motivo volumio[1001]: at /volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Oct 21 10:27:26 motivo volumio[1001]: at /volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Oct 21 10:27:26 motivo volumio[1001]: at fn.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Oct 21 10:27:26 motivo volumio[1001]: at fn.emit (events.js:327:22) Oct 21 10:27:26 motivo volumio[1001]: at Channel.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Oct 21 10:27:26 motivo volumio[1001]: at Channel.emit (events.js:315:20) Oct 21 10:27:26 motivo volumio[1001]: at Client.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Oct 21 10:27:26 motivo volumio[1001]: at Client.emit (events.js:327:22) Oct 21 10:27:26 motivo volumio[1001]: at PacketStreamWrapper.onpacket (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Oct 21 10:27:26 motivo volumio[1001]: at PacketStreamWrapper.emit (events.js:315:20) Oct 21 10:27:26 motivo volumio[1001]: at TLSSocket. (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Oct 21 10:27:26 motivo volumio[1001]: at TLSSocket.emit (events.js:315:20) Oct 21 10:27:26 motivo volumio[1001]: at emitReadable_ (internal/streams/readable.js:569:12) Oct 21 10:27:26 motivo volumio[1001]: at processTicksAndRejections (internal/process/task_queues.js:79:21) Oct 21 10:27:26 motivo volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 10:27:26 motivo systemd[1]: Started VolumioStreamingService. Oct 21 10:27:26 motivo sudo[27169]: pam_unix(sudo:session): session closed for user root Oct 21 10:27:26 motivo sudo[27179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 21 10:27:26 motivo sudo[27179]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 10:27:26 motivo sudo[27179]: pam_unix(sudo:session): session closed for user root Oct 21 10:27:27 motivo sudo[27184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-21 10:26 Oct 21 10:27:27 motivo sudo[27184]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 07:14:04 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="1b70fb201c03de663c0d0492dcaf203c"