-- Logs begin at Sat 2026-08-29 11:15:56 CEST, end at Sat 2026-08-29 16:00:16 CEST. -- Aug 29 15:59:13 masters-room-smsl-n100 volumiologrotate[562]: ls: cannot access '/var/log/samba/log.wb-MASTERS': No such file or directory Aug 29 15:59:13 masters-room-smsl-n100 volumiologrotate[562]: ls: cannot access 'ROOM': No such file or directory Aug 29 15:59:13 masters-room-smsl-n100 volumiologrotate[562]: ls: cannot access 'SMSL': No such file or directory Aug 29 15:59:13 masters-room-smsl-n100 volumiologrotate[562]: ls: cannot access 'N100': No such file or directory Aug 29 15:59:16 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: browseTIDALUri took 398 milliseconds Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/444873848 Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/444873849 Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/444873850 Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/444873851 Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/444873852 Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/444873853 Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/444873848 in service tidal Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/444873849 in service tidal Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/444873850 in service tidal Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/444873851 in service tidal Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint getSimilarAlbums Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/444873852 in service tidal Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/444873853 in service tidal Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 288 milliseconds Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint metavolumio Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint metavolumio Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 351 milliseconds Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 305 milliseconds Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 380 milliseconds Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 386 milliseconds Aug 29 15:59:17 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 374 milliseconds Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::ClearQueue Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::updateTrackBlock Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrackBlock Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 14 Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::serviceStop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 14 Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::serviceStop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: [1788011959409] ControllerTidal::stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::clearPlayQueue Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::saveQueue Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::addQueueItems Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::addQueueItems Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://album/444873847 Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://album/444873847 in service tidal Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand stop took 56 milliseconds Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 16 milliseconds Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 12 milliseconds Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 10 milliseconds Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Upnp client error: Error: This socket has been ended by the other party Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 14 Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: 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":"354185196&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/354185196&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 14 Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: No code Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 14 Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: 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":"354185196&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/354185196&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 14 Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: No code Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 14 Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: 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":"354185196&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/354185196&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 14 Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: No code Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 169ms Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 166ms Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 165ms Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:19 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 628 milliseconds Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::saveQueue Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::updateTrackBlock Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrackBlock Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPlay Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::play index 0 Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stop Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::play index undefined Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::startPlaybackTimer Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: [1788011960080] ControllerTidal::clearAddPlayTrack Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Getting stream with soundQuality LOSSLESS Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand stop took 11 milliseconds Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand clear took 4 milliseconds Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS" Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: error: updateQueue error: null Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 15ms Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS" took 12 milliseconds Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 11ms Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 9ms Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand play Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Handling url /?data=tidal://song/444873848&soundQuality=LOSSLESS Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 17ms Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand play took 11 milliseconds Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 17ms Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 15ms Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint getStreamUrltidal Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: info: getStreamUrl took 734 milliseconds Aug 29 15:59:20 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQzZWZmMTIzNzJiN2E1MTRmNGY0NmRiZTVhNjQyZDY1Mi5tcDQ/0.flac?token=1788015560~NTM3NTNmYWUzYTRjY2Y3YTVhNGM2YjBjMDAyZDg1Y2YyYzJkYTUxOQ== Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Response: 200, length: 19285485 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 18 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 14 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 10 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 30 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 24 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 16 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 149ms Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 220ms Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 218ms Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 188 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 176 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 172 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 358ms Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 341ms Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 261 milliseconds Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1052,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"477 Kbps","isStreaming":false,"title":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 523ms Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 15:59:21 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::servicePause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: [1788011966146] ControllerTidal::pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand pause took 3 milliseconds Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 14 milliseconds Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 9 milliseconds Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 5 milliseconds Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6337,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6337,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":6337,"duration":221,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"641 Kbps","isStreaming":false,"title":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus pause Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 129ms Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 125ms Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 122ms Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 15:59:26 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:28 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Client dropped request, destroying Aug 29 15:59:29 masters-room-smsl-n100 autossh[4510]: starting ssh (count 10) Aug 29 15:59:29 masters-room-smsl-n100 autossh[4510]: ssh child pid is 4815 Aug 29 15:59:44 masters-room-smsl-n100 volumiossh-tunnel[4508]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 29 15:59:44 masters-room-smsl-n100 autossh[4510]: ssh exited with error status 255; restarting ssh Aug 29 15:59:50 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: browseTIDALUri took 383 milliseconds Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766120 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766122 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766123 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766125 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766127 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766128 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766129 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766130 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766131 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766132 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/385766133 Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766120 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766122 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766123 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766125 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766127 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766128 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 275 milliseconds Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint getSimilarAlbums Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766129 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766130 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 389 milliseconds Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766131 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 351 milliseconds Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint metavolumio Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint metavolumio Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766132 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/385766133 in service tidal Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 370 milliseconds Aug 29 15:59:51 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 369 milliseconds Aug 29 15:59:52 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1321 milliseconds Aug 29 15:59:52 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1292 milliseconds Aug 29 15:59:52 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1148 milliseconds Aug 29 15:59:52 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1226 milliseconds Aug 29 15:59:52 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1181 milliseconds Aug 29 15:59:52 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1220 milliseconds Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::ClearQueue Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::updateTrackBlock Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrackBlock Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::serviceStop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::serviceStop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: [1788011996710] ControllerTidal::stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::clearPlayQueue Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::saveQueue Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::addQueueItems Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::addQueueItems Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://album/385766114 Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://album/385766114 in service tidal Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand stop took 58 milliseconds Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 4 milliseconds Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 11 milliseconds Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 9 milliseconds Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: 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":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: No code Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 96ms Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 78 milliseconds Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 78 milliseconds Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: 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":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: No code Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: 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":"444873848&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444873848&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: No code Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 192ms Aug 29 15:59:56 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 191ms Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 600 milliseconds Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushQueue Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::saveQueue Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::updateTrackBlock Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrackBlock Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPlay Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::play index 0 Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stop Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::play index undefined Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::startPlaybackTimer Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: [1788011997353] ControllerTidal::clearAddPlayTrack Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Getting stream with soundQuality LOSSLESS Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand stop took 10 milliseconds Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand clear took 3 milliseconds Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/385766120&soundQuality=LOSSLESS" Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: error: updateQueue error: null Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 12ms Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/385766120&soundQuality=LOSSLESS" took 11 milliseconds Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 10ms Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 7ms Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand play Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Handling url /?data=tidal://song/385766120&soundQuality=LOSSLESS Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 15ms Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand play took 10 milliseconds Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 10ms Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 8ms Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint getStreamUrltidal Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: info: getStreamUrl took 387 milliseconds Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2MjUwNTRlOTJiMDM2ZjQ4MzFlNDM2MjYyYzZlOWE4MC5tcDQ/0.flac?token=1788015597~NDNkMzc5OGUwYmJiN2ViYjBhMGEwMDllMzQ3N2FjYzliOTA1NTMyYQ== Aug 29 15:59:57 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Response: 200, length: 23205498 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 22 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 19 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 14 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 53 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 47 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 44 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 43 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 31 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 19 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766120&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766120&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766120&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766120&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766120&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766120&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 220ms Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 297ms Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 297ms Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 224 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 219 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 215 milliseconds Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766120&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766120&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"654 Kbps","isStreaming":false,"title":"385766120&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766120&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"536 Kbps","isStreaming":false,"title":"385766120&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766120&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 0 Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 534ms Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 506ms Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 499ms Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 15:59:58 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::ClearQueue Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stop Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::updateTrackBlock Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrackBlock Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::serviceStop Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 0 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::serviceStop Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: [1788012000073] ControllerTidal::stop Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::stop Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::clearPlayQueue Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::saveQueue Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushQueue Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::addQueueItems Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::addQueueItems Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766120 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766120 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766122 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766122 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Client dropped request, destroying Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushQueue Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::saveQueue Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::updateTrackBlock Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrackBlock Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPlay Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::play index 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::addQueueItems Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::addQueueItems Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766123 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766123 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766125 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766125 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766127 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766127 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766128 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766128 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766129 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766129 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766130 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766130 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766131 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766131 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766132 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766132 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Adding Item to queue: tidal://song/385766133 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Using cached record of: tidal://song/385766133 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stop Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushQueue Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::saveQueue Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::play index undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::updateTrackBlock Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrackBlock Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::startPlaybackTimer Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: [1788012000151] ControllerTidal::clearAddPlayTrack Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Getting stream with soundQuality LOSSLESS Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand stop took 107 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand stop took 32 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 17 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand clear took 16 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 12 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 9 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS" Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 67ms Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 64ms Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: updateQueue error: null Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 71ms Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 65 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS" took 66 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 14ms Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 11ms Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand play Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 98ms Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Handling url /?data=tidal://song/385766122&soundQuality=LOSSLESS Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces system playlist update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Ignoring MPD Status Update Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 49ms Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand play took 43 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 41ms Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 32ms Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint getStreamUrltidal Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: getStreamUrl took 331 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRhMjE5YjcyNGU2N2U0YTE5ZTEyMTJmNjRkZWIyZWZiMy5tcDQ/0.flac?token=1788015600~ZDNiMGVkYWUwZjFiY2VlNjhiZTE4NzRjN2QxOWJhYTZiOGQxNDM2NQ== Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Response: 200, length: 26213199 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 13 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 11 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 8 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 31 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 27 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 21 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 16 milliseconds Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766122&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766122&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766122&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 1 Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:00 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 128ms Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 177ms Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 176ms Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 132 milliseconds Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 127 milliseconds Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 127 milliseconds Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766122&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 1 Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766122&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 1 Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"385766122&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 1 Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService play Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus play Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: Received an update from plugin. extracting info from payload Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 353ms Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 340ms Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 336ms Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:01 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::servicePause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: [1788012004098] ControllerTidal::pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand pause took 5 milliseconds Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: ---------------------------- MPD announces state update: player Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::getState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 22 milliseconds Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 11 milliseconds Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand status took 8 milliseconds Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4598,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"927 Kbps","isStreaming":false,"title":"385766122&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 1 Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4598,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"927 Kbps","isStreaming":false,"title":"385766122&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 1 Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ControllerMpd::pushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::servicePushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CorePlayQueue::getTrack 1 Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4598,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"927 Kbps","isStreaming":false,"title":"385766122&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/385766122&soundQuality=LOSSLESS","trackType":"tidal"} Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: verbose: CURRENT POSITION 1 Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState stateService pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::syncState currentStatus pause Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::stPlaybackTimer Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 204ms Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 196ms Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: ------------------------------ 191ms Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreStateMachine::pushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::volumioPushState Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output update for this device Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: MRS: Pushing multiroomSync output Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 16:00:04 masters-room-smsl-n100 volumio[1121]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2Bw0GX2HpWepOVPxLBw1PjGgbFv1.c6ce6f09a5bd66afbda4994f278bb8f1.state.mute' Aug 29 16:00:07 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: browseTIDALUri took 1991 milliseconds Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preload queue cleared Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569402 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/60344680 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/554464571 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569404 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569403 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569414 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569412 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569413 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569405 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569410 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569411 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/100370693 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569409 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/125569407 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/131560067 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/85372500 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/100370688 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/100370690 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/448737899 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/131560069 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971744 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/100370692 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971742 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971755 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971745 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971757 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/131560074 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/131560071 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971754 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/439498457 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971753 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/152065922 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/100370689 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971752 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971751 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/131560073 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971746 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971750 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/131560070 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/139299473 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/131560072 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971743 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/100370694 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/100370691 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/131560068 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971748 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971756 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Preloading song: tidal://song/168971749 Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569402 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/60344680 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/554464571 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569404 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569403 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569414 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569412 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569413 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569405 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569410 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569411 in service tidal Aug 29 16:00:09 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/100370693 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569409 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/125569407 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/131560067 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/85372500 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/100370688 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/100370690 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/448737899 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/131560069 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 991 milliseconds Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971744 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/100370692 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971742 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1117 milliseconds Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1083 milliseconds Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1127 milliseconds Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971755 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971745 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971757 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: STREAMING PROXY: Client dropped request, destroying Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/131560074 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/131560071 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971754 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/439498457 in service tidal Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1376 milliseconds Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1283 milliseconds Aug 29 16:00:10 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1347 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971753 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/152065922 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/100370689 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971752 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971751 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1325 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1436 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1497 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint getSimilarArtists Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Executing endpoint metavolumio Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/131560073 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971746 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971750 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/131560070 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/139299473 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/131560072 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971743 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1644 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1550 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1445 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1448 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1450 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/100370694 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/100370691 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/131560068 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971748 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971756 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: Exploding uri tidal://song/168971749 in service tidal Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1829 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1827 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1723 milliseconds Aug 29 16:00:11 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1723 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1724 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1585 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1572 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1572 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1413 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1415 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1421 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1536 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1538 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1773 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1781 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1536 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1537 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1539 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1031 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1577 milliseconds Aug 29 16:00:12 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1587 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1597 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1948 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 2203 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1883 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1878 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1891 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1367 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1378 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1381 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1924 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1415 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumio[1121]: info: explodeTIDALUri took 1493 milliseconds Aug 29 16:00:13 masters-room-smsl-n100 volumiologrotate[562]: ls: cannot access '/var/log/samba/log.wb-MASTERS': No such file or directory Aug 29 16:00:13 masters-room-smsl-n100 volumiologrotate[562]: ls: cannot access 'ROOM': No such file or directory Aug 29 16:00:13 masters-room-smsl-n100 volumiologrotate[562]: ls: cannot access 'SMSL': No such file or directory Aug 29 16:00:13 masters-room-smsl-n100 volumiologrotate[562]: ls: cannot access 'N100': No such file or directory Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: TypeError: Cannot read property 'slice' of null Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at Object.onceWrapper (events.js:520:26) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at ClientRequest.emit (events.js:400:28) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at TLSSocket.socketOnData (_http_client.js:515:22) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at TLSSocket.emit (events.js:400:28) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at addChunk (internal/streams/readable.js:293:12) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at readableAddChunk (internal/streams/readable.js:267:9) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Aug 29 16:00:15 masters-room-smsl-n100 volumio[1121]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 16:00:16 masters-room-smsl-n100 sudo[4978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-29 15:59 Aug 29 16:00:16 masters-room-smsl-n100 sudo[4978]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 13 Nov 2025 12:39:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="7a92d73f7a04bc7854b1812143743fd4"