Dec 07 01:02:05 volumio volumio[981]: info: Preload queue cleared Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::ClearQueue Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::stop Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::serviceStop Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::serviceStop Dec 07 01:02:05 volumio volumio[981]: info: ControllerMpd::stop Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::saveQueue Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::addQueueItems Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::addQueueItems Dec 07 01:02:05 volumio volumio[981]: info: Preload queue cleared Dec 07 01:02:05 volumio volumio[981]: info: Adding Item to queue: webrns/0 Dec 07 01:02:05 volumio volumio[981]: info: Using cached record of: webrns/0 Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::saveQueue Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::play index 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::stop Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::play index undefined Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 01:02:05 volumio volumio[981]: info: Dec 07 01:02:05 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand stop took 6 milliseconds Dec 07 01:02:05 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand stop took 2 milliseconds Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: verbose: STATE SERVICE {"status":"stop"} Dec 07 01:02:05 volumio volumio[981]: verbose: CURRENT POSITION 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::syncState stateService stop Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:05 volumio volumio[981]: info: No code Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 01:02:05 volumio volumio[981]: info: Dec 07 01:02:05 volumio volumio[981]: ---------------------------- MPD announces system playlist update Dec 07 01:02:05 volumio volumio[981]: info: Ignoring MPD Status Update Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand status took 6 milliseconds Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand clear took 4 milliseconds Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Dec 07 01:02:05 volumio volumio[981]: error: updateQueue error: null Dec 07 01:02:05 volumio volumio[981]: info: Dec 07 01:02:05 volumio volumio[981]: ---------------------------- MPD announces system playlist update Dec 07 01:02:05 volumio volumio[981]: info: Ignoring MPD Status Update Dec 07 01:02:05 volumio volumio[981]: info: ------------------------------ 4ms Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 3 milliseconds Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand play Dec 07 01:02:05 volumio volumio[981]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 07 01:02:05 volumio volumio[981]: info: ------------------------------ 10ms Dec 07 01:02:05 volumio volumio[981]: info: ------------------------------ 2ms Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand play took 1 milliseconds Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 07 01:02:05 volumio volumio[981]: info: Dec 07 01:02:05 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:05 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:05 volumio volumio[981]: info: Dec 07 01:02:05 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:05 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand status took 1 milliseconds Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand status took 0 milliseconds Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:05 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 07 01:02:05 volumio volumio[981]: verbose: CURRENT POSITION 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Dec 07 01:02:05 volumio volumio[981]: info: ------------------------------ 2ms Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:05 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 07 01:02:05 volumio volumio[981]: verbose: CURRENT POSITION 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Dec 07 01:02:05 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:05 volumio volumio[981]: info: ------------------------------ 6ms Dec 07 01:02:05 volumio volumio[981]: info: Dec 07 01:02:05 volumio volumio[981]: ---------------------------- MPD announces system playlist update Dec 07 01:02:05 volumio volumio[981]: info: Ignoring MPD Status Update Dec 07 01:02:05 volumio volumio[981]: info: Dec 07 01:02:05 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:05 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:05 volumio volumio[981]: info: ------------------------------ 12ms Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand status took 11 milliseconds Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:05 volumio volumio[981]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 07 01:02:05 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:05 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:05 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:05 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1231,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Arlo Parks - Hurt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 07 01:02:05 volumio volumio[981]: verbose: CURRENT POSITION 0 Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Dec 07 01:02:05 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:05 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:05 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:05 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:05 volumio volumio[981]: info: ------------------------------ 25ms Dec 07 01:02:07 volumio volumio[981]: info: Executing endpoint metavolumio Dec 07 01:02:07 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 07 01:02:07 volumio volumio[981]: info: Executing endpoint metavolumio Dec 07 01:02:07 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::play index 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::stop Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::serviceStop Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::serviceStop Dec 07 01:02:16 volumio volumio[981]: info: ControllerMpd::stop Dec 07 01:02:16 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 01:02:16 volumio volumio[981]: info: Dec 07 01:02:16 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:16 volumio volumio[981]: info: sendMpdCommand stop took 4 milliseconds Dec 07 01:02:16 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:16 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: verbose: STATE SERVICE {"status":"stop"} Dec 07 01:02:16 volumio volumio[981]: verbose: CURRENT POSITION 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::syncState stateService stop Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:16 volumio volumio[981]: info: No code Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::play index undefined Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 07 01:02:16 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 01:02:16 volumio volumio[981]: info: sendMpdCommand status took 4 milliseconds Dec 07 01:02:16 volumio volumio[981]: info: sendMpdCommand stop took 0 milliseconds Dec 07 01:02:16 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:16 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:16 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 01:02:16 volumio volumio[981]: info: Dec 07 01:02:16 volumio volumio[981]: ---------------------------- MPD announces system playlist update Dec 07 01:02:16 volumio volumio[981]: info: Ignoring MPD Status Update Dec 07 01:02:16 volumio volumio[981]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 07 01:02:16 volumio volumio[981]: info: sendMpdCommand clear took 3 milliseconds Dec 07 01:02:16 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:16 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Dec 07 01:02:16 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:16 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:16 volumio volumio[981]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rad357pl Received mpd Dec 07 01:02:16 volumio volumio[981]: info: ------------------------------ 9ms Dec 07 01:02:16 volumio volumio[981]: error: updateQueue error: null Dec 07 01:02:16 volumio volumio[981]: info: Dec 07 01:02:16 volumio volumio[981]: ---------------------------- MPD announces system playlist update Dec 07 01:02:16 volumio volumio[981]: info: Ignoring MPD Status Update Dec 07 01:02:16 volumio volumio[981]: info: ------------------------------ 2ms Dec 07 01:02:16 volumio volumio[981]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 3 milliseconds Dec 07 01:02:16 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand play Dec 07 01:02:16 volumio volumio[981]: info: ------------------------------ 3ms Dec 07 01:02:16 volumio volumio[981]: info: sendMpdCommand play took 3 milliseconds Dec 07 01:02:16 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 07 01:02:17 volumio volumio[981]: info: Dec 07 01:02:17 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:17 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:17 volumio volumio[981]: info: Dec 07 01:02:17 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:17 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:17 volumio volumio[981]: info: sendMpdCommand status took 2 milliseconds Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:17 volumio volumio[981]: info: Dec 07 01:02:17 volumio volumio[981]: ---------------------------- MPD announces system playlist update Dec 07 01:02:17 volumio volumio[981]: info: Ignoring MPD Status Update Dec 07 01:02:17 volumio volumio[981]: info: Dec 07 01:02:17 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:17 volumio volumio[981]: info: sendMpdCommand status took 1 milliseconds Dec 07 01:02:17 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:17 volumio volumio[981]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 07 01:02:17 volumio volumio[981]: info: ------------------------------ 1ms Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:17 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:17 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:17 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:17 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Arlo Parks - Hurt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 07 01:02:17 volumio volumio[981]: verbose: CURRENT POSITION 0 Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Dec 07 01:02:17 volumio volumio[981]: info: ------------------------------ 4ms Dec 07 01:02:17 volumio volumio[981]: info: sendMpdCommand status took 3 milliseconds Dec 07 01:02:17 volumio volumio[981]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:17 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:17 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:17 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:17 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":444,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Arlo Parks - Hurt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 07 01:02:17 volumio volumio[981]: verbose: CURRENT POSITION 0 Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Dec 07 01:02:17 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:17 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:17 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:17 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:17 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:17 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:17 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:17 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:17 volumio volumio[981]: info: ------------------------------ 7ms Dec 07 01:02:17 volumio volumio[981]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 07 01:02:17 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:17 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:17 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:17 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:17 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1231,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Arlo Parks - Hurt","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Dec 07 01:02:17 volumio volumio[981]: verbose: CURRENT POSITION 0 Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Dec 07 01:02:17 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:17 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:17 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:17 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:17 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:17 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:17 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:17 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:17 volumio volumio[981]: info: ------------------------------ 9ms Dec 07 01:02:19 volumio volumio[981]: info: Executing endpoint metavolumio Dec 07 01:02:19 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 07 01:02:19 volumio volumio[981]: info: Executing endpoint metavolumio Dec 07 01:02:19 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 07 01:02:32 volumio volumio[981]: info: Preload queue cleared Dec 07 01:02:32 volumio volumio[981]: info: Preloading song: mnt/NAS/Old/Media/Music/Air/Album/07 Track 07.mp3 Dec 07 01:02:32 volumio volumio[981]: info: Preloading song: mnt/NAS/Old/Blur/Think Tank/02 Out Of Time.mp3 Dec 07 01:02:32 volumio volumio[981]: info: Preloading song: mnt/NAS/Old/Blur/Think Tank/04 Good Song.mp3 Dec 07 01:02:32 volumio volumio[981]: info: Exploding uri mnt/NAS/Old/Media/Music/Air/Album/07 Track 07.mp3 in service mpd Dec 07 01:02:32 volumio volumio[981]: error: scanFolder - failure to stat '/mnt/NAS/Old/Media/Music/Air/Album/07 Track 07.mp3' Dec 07 01:02:32 volumio volumio[981]: info: Exploding uri mnt/NAS/Old/Blur/Think Tank/02 Out Of Time.mp3 in service mpd Dec 07 01:02:32 volumio volumio[981]: info: ALBUMART /albumart?cacheid=334&web=Blur/Think%20Tank/extralarge&path=%2Fmnt%2FNAS%2FOld%2FBlur%2FThink%20Tank%2F02%20Out%20Of%20Time.mp3&metadata=false Dec 07 01:02:32 volumio volumio[981]: info: URI /mnt/NAS/Old/Blur/Think Tank/02 Out Of Time.mp3 Dec 07 01:02:32 volumio volumio[981]: info: Exploding uri mnt/NAS/Old/Blur/Think Tank/04 Good Song.mp3 in service mpd Dec 07 01:02:32 volumio volumio[981]: info: ALBUMART /albumart?cacheid=334&web=Blur/Think%20Tank/extralarge&path=%2Fmnt%2FNAS%2FOld%2FBlur%2FThink%20Tank%2F04%20Good%20Song.mp3&metadata=false Dec 07 01:02:32 volumio volumio[981]: info: URI /mnt/NAS/Old/Blur/Think Tank/04 Good Song.mp3 Dec 07 01:02:32 volumio volumio[981]: info: Preload queue cleared Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::ClearQueue Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::stop Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:32 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:32 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::serviceStop Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::serviceStop Dec 07 01:02:32 volumio volumio[981]: info: ControllerMpd::stop Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::saveQueue Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::addQueueItems Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::addQueueItems Dec 07 01:02:32 volumio volumio[981]: info: Preload queue cleared Dec 07 01:02:32 volumio volumio[981]: info: Adding Item to queue: mnt/NAS/Old/Media/Music/Air/Album/07 Track 07.mp3 Dec 07 01:02:32 volumio volumio[981]: info: Using cached record of: mnt/NAS/Old/Media/Music/Air/Album/07 Track 07.mp3 Dec 07 01:02:32 volumio volumio[981]: info: Adding Item to queue: mnt/NAS/Old/Blur/Think Tank/02 Out Of Time.mp3 Dec 07 01:02:32 volumio volumio[981]: info: Using cached record of: mnt/NAS/Old/Blur/Think Tank/02 Out Of Time.mp3 Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::saveQueue Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::play index 1 Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::addQueueItems Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::addQueueItems Dec 07 01:02:32 volumio volumio[981]: info: Preload queue cleared Dec 07 01:02:32 volumio volumio[981]: info: Adding Item to queue: mnt/NAS/Old/Blur/Think Tank/04 Good Song.mp3 Dec 07 01:02:32 volumio volumio[981]: info: Using cached record of: mnt/NAS/Old/Blur/Think Tank/04 Good Song.mp3 Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::stop Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::saveQueue Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::play index undefined Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::clearAddPlayTracks NAS/Old/Blur/Think Tank/04 Good Song.mp3 Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 01:02:32 volumio volumio[981]: info: Dec 07 01:02:32 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand stop took 8 milliseconds Dec 07 01:02:32 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand stop took 4 milliseconds Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:32 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:32 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:32 volumio volumio[981]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received rad357pl Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand clear Dec 07 01:02:32 volumio volumio[981]: info: Dec 07 01:02:32 volumio volumio[981]: ---------------------------- MPD announces system playlist update Dec 07 01:02:32 volumio volumio[981]: info: Ignoring MPD Status Update Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand status took 8 milliseconds Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand clear took 7 milliseconds Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand add "NAS/Old/Blur/Think Tank/04 Good Song.mp3" Dec 07 01:02:32 volumio volumio[981]: error: updateQueue error: null Dec 07 01:02:32 volumio volumio[981]: info: Dec 07 01:02:32 volumio volumio[981]: ---------------------------- MPD announces system playlist update Dec 07 01:02:32 volumio volumio[981]: info: Ignoring MPD Status Update Dec 07 01:02:32 volumio volumio[981]: info: ------------------------------ 1ms Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand add "NAS/Old/Blur/Think Tank/04 Good Song.mp3" took 0 milliseconds Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand play Dec 07 01:02:32 volumio volumio[981]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 07 01:02:32 volumio volumio[981]: info: ------------------------------ 8ms Dec 07 01:02:32 volumio volumio[981]: info: ------------------------------ 0ms Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand play took 3 milliseconds Dec 07 01:02:32 volumio volumio[981]: info: Dec 07 01:02:32 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:32 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:32 volumio volumio[981]: info: Dec 07 01:02:32 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand status took 2 milliseconds Dec 07 01:02:32 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand status took 0 milliseconds Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:32 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:32 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":456,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Good Song","artist":"Blur","album":"Think Tank","uri":"NAS/Old/Blur/Think Tank/04 Good Song.mp3","trackType":"mp3"} Dec 07 01:02:32 volumio volumio[981]: verbose: CURRENT POSITION 1 Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Dec 07 01:02:32 volumio volumio[981]: info: ------------------------------ 3ms Dec 07 01:02:32 volumio volumio[981]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 07 01:02:32 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:32 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:32 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Good Song","artist":"Blur","album":"Think Tank","uri":"NAS/Old/Blur/Think Tank/04 Good Song.mp3","trackType":"mp3"} Dec 07 01:02:32 volumio volumio[981]: verbose: CURRENT POSITION 1 Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Dec 07 01:02:32 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:32 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:32 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:32 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:32 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:32 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:32 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:32 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:32 volumio volumio[981]: info: ------------------------------ 8ms Dec 07 01:02:34 volumio volumio[981]: info: Executing endpoint metavolumio Dec 07 01:02:34 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 07 01:02:34 volumio volumio[981]: info: Executing endpoint metavolumio Dec 07 01:02:34 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 07 01:02:34 volumio volumio[981]: info: Executing endpoint metavolumio Dec 07 01:02:34 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::removeQueueItem Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::stop Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Dec 07 01:02:45 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:45 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:45 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:45 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::serviceStop Dec 07 01:02:45 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::serviceStop Dec 07 01:02:45 volumio volumio[981]: info: ControllerMpd::stop Dec 07 01:02:45 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Dec 07 01:02:45 volumio volumio[981]: info: CorePlayQueue::removeQueueItem 1 Dec 07 01:02:45 volumio volumio[981]: info: CorePlayQueue::saveQueue Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Dec 07 01:02:45 volumio volumio[981]: info: Dec 07 01:02:45 volumio volumio[981]: ---------------------------- MPD announces state update: player Dec 07 01:02:45 volumio volumio[981]: info: sendMpdCommand stop took 9 milliseconds Dec 07 01:02:45 volumio volumio[981]: info: ControllerMpd::getState Dec 07 01:02:45 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Dec 07 01:02:45 volumio volumio[981]: info: sendMpdCommand status took 0 milliseconds Dec 07 01:02:45 volumio volumio[981]: verbose: ControllerMpd::parseState Dec 07 01:02:45 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 07 01:02:45 volumio volumio[981]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 07 01:02:45 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Dec 07 01:02:45 volumio volumio[981]: info: ControllerMpd::pushState Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::servicePushState Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:45 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:45 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:45 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:45 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:45 volumio volumio[981]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Song","artist":"Blur","album":"Think Tank","uri":"NAS/Old/Blur/Think Tank/04 Good Song.mp3","trackType":"mp3"} Dec 07 01:02:45 volumio volumio[981]: verbose: CURRENT POSITION 1 Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::syncState stateService stop Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:45 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:45 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:45 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:45 volumio volumio[981]: info: No code Dec 07 01:02:45 volumio volumio[981]: info: CoreStateMachine::pushState Dec 07 01:02:45 volumio volumio[981]: info: CorePlayQueue::getTrack 1 Dec 07 01:02:45 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Dec 07 01:02:45 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Dec 07 01:02:45 volumio volumio[981]: info: MRS: Pushing multiroomSync output Dec 07 01:02:45 volumio volumio[981]: info: ------------------------------ 4ms Dec 07 01:02:48 volumio volumio[981]: info: MRS: STARTING volumioStreaming Dec 07 01:02:48 volumio sudo[67661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream151.ts /tmp/hls/stream152.ts /tmp/hls/stream153.ts /tmp/hls/stream154.ts Dec 07 01:02:48 volumio sudo[67661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 01:02:48 volumio sudo[67661]: pam_unix(sudo:session): session closed for user root Dec 07 01:02:48 volumio sudo[67663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Dec 07 01:02:48 volumio sudo[67663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 01:02:48 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Dec 07 01:02:48 volumio systemd[1]: volumioStreaming.service: Killing process 67169 (ffmpeg) with signal SIGKILL. Dec 07 01:02:48 volumio systemd[1]: volumioStreaming.service: Killing process 67208 (ffmpeg) with signal SIGKILL. Dec 07 01:02:48 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. Dec 07 01:02:48 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Dec 07 01:02:48 volumio systemd[1]: volumioStreaming.service: Consumed 4.601s CPU time. Dec 07 01:02:48 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Dec 07 01:02:48 volumio sudo[67663]: pam_unix(sudo:session): session closed for user root Dec 07 01:02:48 volumio volumio[981]: info: MRS: volumioStreaming STARTED Dec 07 01:02:48 volumio sudo[67667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 07 01:02:48 volumio sudo[67667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 01:02:48 volumio sudo[67667]: pam_unix(sudo:session): session closed for user root Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 01:02:56 volumio volumio[981]: info: Retrieving Cloud Streaming UI Dec 07 01:02:56 volumio volumio[981]: info: Getting Tidal Cloud Configuration Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 01:02:56 volumio volumio[981]: info: Getting Qobuz Cloud Configuration Dec 07 01:02:56 volumio volumio[981]: info: Asking plugin for UI Config Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 01:02:56 volumio volumio[981]: info: Getting Spotify Cloud Configuration Dec 07 01:02:56 volumio volumio[981]: info: Asking plugin for UI Config Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 01:02:56 volumio volumio[981]: info: Saving Spotify Acccount Dec 07 01:02:56 volumio volumio[981]: info: Got it Dec 07 01:02:56 volumio volumio[981]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 07 01:02:56 volumio volumio[981]: info: Got Tidal Cloud Configuration Dec 07 01:02:56 volumio volumio[981]: info: Got it Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::volumioGetBrowseSources Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 01:02:56 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 07 01:03:00 volumio volumio[981]: info: Disabling MyMusic plugin upnp Dec 07 01:03:00 volumio sudo[67704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 07 01:03:00 volumio sudo[67704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 01:03:00 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Dec 07 01:03:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 07 01:03:01 volumio volumio[981]: info: Enabling MyMusic plugin upnp Dec 07 01:03:01 volumio volumio[981]: info: Enabling plugin upnp Dec 07 01:03:01 volumio volumio[981]: info: Loading plugin "upnp"... Dec 07 01:03:01 volumio volumio[981]: info: [1765065781605] Starting Upmpd Daemon Dec 07 01:03:01 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 07 01:03:01 volumio volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 01:03:01 volumio volumio[981]: Error: listen EADDRINUSE: address already in use :::6599 Dec 07 01:03:01 volumio volumio[981]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Dec 07 01:03:01 volumio volumio[981]: at listenInCluster (node:net:1920:12) Dec 07 01:03:01 volumio volumio[981]: at Server.listen (node:net:2008:7) Dec 07 01:03:01 volumio volumio[981]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 07 01:03:01 volumio volumio[981]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 07 01:03:01 volumio volumio[981]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 07 01:03:01 volumio volumio[981]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 07 01:03:01 volumio volumio[981]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 07 01:03:01 volumio volumio[981]: code: 'EADDRINUSE', Dec 07 01:03:01 volumio volumio[981]: errno: -98, Dec 07 01:03:01 volumio volumio[981]: syscall: 'listen', Dec 07 01:03:01 volumio volumio[981]: address: '::', Dec 07 01:03:01 volumio volumio[981]: port: 6599 Dec 07 01:03:01 volumio volumio[981]: } Dec 07 01:03:01 volumio volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 01:03:01 volumio sudo[67721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 01:02' Dec 07 01:03:01 volumio sudo[67721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"