Feb 11 22:06:01 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 11 22:06:01 volumio volumio[18285]: info: browseTIDALUri took 301 milliseconds Feb 11 22:06:01 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:08 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 22:06:08 volumio volumio[18285]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 11 22:06:08 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 11 22:06:08 volumio volumio[18285]: info: Received Get System Version Feb 11 22:06:08 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 22:06:08 volumio volumio[18285]: info: Received Get System Info Feb 11 22:06:08 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 22:06:08 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 22:06:08 volumio volumio[18285]: info: Discovery: Getting this device information Feb 11 22:06:08 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:08 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 22:06:08 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 11 22:06:08 volumio volumio[18285]: info: browseTIDALUri took 250 milliseconds Feb 11 22:06:08 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:08 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:10 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::ClearQueue Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::stop Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::stPlaybackTimer Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::updateTrackBlock Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrackBlock Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:10 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:10 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::serviceStop Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::serviceStop Feb 11 22:06:10 volumio volumio[18285]: info: [1770843970937] ControllerWebradio::stop Feb 11 22:06:10 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::clearPlayQueue Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::saveQueue Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioPushQueue Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::addQueueItems Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::addQueueItems Feb 11 22:06:10 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:10 volumio volumio[18285]: info: Adding Item to queue: tidal://genres/Electronic/playlists/1ab52cbf-4c99-41e3-9ca1-02a8261ba55a Feb 11 22:06:10 volumio volumio[18285]: info: Exploding uri tidal://genres/Electronic/playlists/1ab52cbf-4c99-41e3-9ca1-02a8261ba55a in service tidal Feb 11 22:06:10 volumio volumio[18285]: info: Feb 11 22:06:10 volumio volumio[18285]: ---------------------------- MPD announces state update: player Feb 11 22:06:10 volumio volumio[18285]: info: sendMpdCommand stop took 31 milliseconds Feb 11 22:06:10 volumio volumio[18285]: info: ControllerMpd::getState Feb 11 22:06:10 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand status Feb 11 22:06:10 volumio volumio[18285]: info: sendMpdCommand status took 1 milliseconds Feb 11 22:06:10 volumio volumio[18285]: verbose: ControllerMpd::parseState Feb 11 22:06:10 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 22:06:10 volumio volumio[18285]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 11 22:06:10 volumio volumio[18285]: verbose: ControllerMpd::parseTrackInfo Feb 11 22:06:10 volumio volumio[18285]: info: ControllerMpd::pushState Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::servicePushState Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:10 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:10 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:10 volumio volumio[18285]: 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":"Tommaso Paradiso - Forse","artist":"RADIO19","album":null,"uri":"https://rn1.fluidstream.eu/radio19.mp3?FLID=3&aw_0_req.gdpr=true&type=.mp3","trackType":"mp3"} Feb 11 22:06:10 volumio volumio[18285]: verbose: CURRENT POSITION 0 Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::syncState stateService stop Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:10 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:10 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:10 volumio volumio[18285]: info: No code Feb 11 22:06:10 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:10 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:10 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:10 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:10 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:11 volumio volumio[18285]: info: ------------------------------ 54ms Feb 11 22:06:11 volumio volumio[18285]: info: explodeTIDALUri took 335 milliseconds Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::volumioPushQueue Feb 11 22:06:11 volumio volumio[18285]: info: CorePlayQueue::saveQueue Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::updateTrackBlock Feb 11 22:06:11 volumio volumio[18285]: info: CorePlayQueue::getTrackBlock Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:11 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::volumioPlay Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::play index 0 Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::stop Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::play index undefined Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:11 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::startPlaybackTimer Feb 11 22:06:11 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:11 volumio volumio[18285]: info: [1770843971297] ControllerTidal::clearAddPlayTrack Feb 11 22:06:11 volumio volumio[18285]: info: Getting stream with soundQuality HI_RES Feb 11 22:06:11 volumio volumio[18285]: info: getStreamUrl took 177 milliseconds Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 22:06:11 volumio volumio[18285]: info: sendMpdCommand stop took 0 milliseconds Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 22:06:11 volumio volumio[18285]: info: Feb 11 22:06:11 volumio volumio[18285]: ---------------------------- MPD announces system playlist update Feb 11 22:06:11 volumio volumio[18285]: info: Ignoring MPD Status Update Feb 11 22:06:11 volumio volumio[18285]: info: sendMpdCommand clear took 0 milliseconds Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYWMxMjcwOTFlOTQwNWVlMGRhOTM4MGE2NmExZmIxZi5tcDQ/0.flac?token=1770847571~ZTlmODMyMjRjOGMyNDBhZjAwZjgyYjJlOWMxNmIxZmVlYzcwOGMxNg==" Feb 11 22:06:11 volumio volumio[18285]: error: updateQueue error: null Feb 11 22:06:11 volumio volumio[18285]: info: ------------------------------ 1ms Feb 11 22:06:11 volumio volumio[18285]: info: Feb 11 22:06:11 volumio volumio[18285]: ---------------------------- MPD announces system playlist update Feb 11 22:06:11 volumio volumio[18285]: info: Ignoring MPD Status Update Feb 11 22:06:11 volumio volumio[18285]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYWMxMjcwOTFlOTQwNWVlMGRhOTM4MGE2NmExZmIxZi5tcDQ/0.flac?token=1770847571~ZTlmODMyMjRjOGMyNDBhZjAwZjgyYjJlOWMxNmIxZmVlYzcwOGMxNg==" took 1 milliseconds Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand play Feb 11 22:06:11 volumio volumio[18285]: info: ------------------------------ 2ms Feb 11 22:06:11 volumio volumio[18285]: info: sendMpdCommand play took 2 milliseconds Feb 11 22:06:11 volumio volumio[18285]: info: Feb 11 22:06:11 volumio volumio[18285]: ---------------------------- MPD announces state update: player Feb 11 22:06:11 volumio volumio[18285]: info: ControllerMpd::getState Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand status Feb 11 22:06:11 volumio volumio[18285]: info: Feb 11 22:06:11 volumio volumio[18285]: ---------------------------- MPD announces state update: player Feb 11 22:06:11 volumio volumio[18285]: info: sendMpdCommand status took 2 milliseconds Feb 11 22:06:11 volumio volumio[18285]: info: ControllerMpd::getState Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand status Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::parseState Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 22:06:11 volumio volumio[18285]: info: sendMpdCommand status took 1 milliseconds Feb 11 22:06:11 volumio volumio[18285]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::parseState Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::parseTrackInfo Feb 11 22:06:11 volumio volumio[18285]: info: ControllerMpd::pushState Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::servicePushState Feb 11 22:06:11 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:11 volumio volumio[18285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1770847571~ZTlmODMyMjRjOGMyNDBhZjAwZjgyYjJlOWMxNmIxZmVlYzcwOGMxNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYWMxMjcwOTFlOTQwNWVlMGRhOTM4MGE2NmExZmIxZi5tcDQ/0.flac?token=1770847571~ZTlmODMyMjRjOGMyNDBhZjAwZjgyYjJlOWMxNmIxZmVlYzcwOGMxNg==","trackType":"tidal"} Feb 11 22:06:11 volumio volumio[18285]: verbose: CURRENT POSITION 0 Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::syncState stateService play Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:06:11 volumio volumio[18285]: info: ------------------------------ 4ms Feb 11 22:06:11 volumio volumio[18285]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 11 22:06:11 volumio volumio[18285]: verbose: ControllerMpd::parseTrackInfo Feb 11 22:06:11 volumio volumio[18285]: info: ControllerMpd::pushState Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::servicePushState Feb 11 22:06:11 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:11 volumio volumio[18285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1770847571~ZTlmODMyMjRjOGMyNDBhZjAwZjgyYjJlOWMxNmIxZmVlYzcwOGMxNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYWMxMjcwOTFlOTQwNWVlMGRhOTM4MGE2NmExZmIxZi5tcDQ/0.flac?token=1770847571~ZTlmODMyMjRjOGMyNDBhZjAwZjgyYjJlOWMxNmIxZmVlYzcwOGMxNg==","trackType":"tidal"} Feb 11 22:06:11 volumio volumio[18285]: verbose: CURRENT POSITION 0 Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::syncState stateService play Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::syncState currentStatus play Feb 11 22:06:11 volumio volumio[18285]: info: Received an update from plugin. extracting info from payload Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:11 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:11 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:11 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:11 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:11 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:11 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:11 volumio volumio[18285]: info: ------------------------------ 28ms Feb 11 22:06:17 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 11 22:06:17 volumio volumio[18285]: info: browseTIDALUri took 95 milliseconds Feb 11 22:06:18 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:18 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:20 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 11 22:06:20 volumio volumio[18285]: info: browseTIDALUri took 166 milliseconds Feb 11 22:06:20 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:24 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::ClearQueue Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::stop Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::stPlaybackTimer Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::updateTrackBlock Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrackBlock Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::serviceStop Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::serviceStop Feb 11 22:06:24 volumio volumio[18285]: info: [1770843984018] ControllerTidal::stop Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 22:06:24 volumio volumio[18285]: info: ControllerMpd::stop Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::clearPlayQueue Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::saveQueue Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioPushQueue Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::addQueueItems Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::addQueueItems Feb 11 22:06:24 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:24 volumio volumio[18285]: info: Adding Item to queue: tidal://mymusic/mixes/00206ac20f333cf1bc97a9262008b1 Feb 11 22:06:24 volumio volumio[18285]: info: Exploding uri tidal://mymusic/mixes/00206ac20f333cf1bc97a9262008b1 in service tidal Feb 11 22:06:24 volumio volumio[18285]: info: Feb 11 22:06:24 volumio volumio[18285]: ---------------------------- MPD announces state update: player Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand stop took 15 milliseconds Feb 11 22:06:24 volumio volumio[18285]: info: ControllerMpd::getState Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand status Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand status took 0 milliseconds Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::parseState Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::parseTrackInfo Feb 11 22:06:24 volumio volumio[18285]: info: ControllerMpd::pushState Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::servicePushState Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:24 volumio volumio[18285]: 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":"0.flac?token=1770847571~ZTlmODMyMjRjOGMyNDBhZjAwZjgyYjJlOWMxNmIxZmVlYzcwOGMxNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYWMxMjcwOTFlOTQwNWVlMGRhOTM4MGE2NmExZmIxZi5tcDQ/0.flac?token=1770847571~ZTlmODMyMjRjOGMyNDBhZjAwZjgyYjJlOWMxNmIxZmVlYzcwOGMxNg==","trackType":"tidal"} Feb 11 22:06:24 volumio volumio[18285]: verbose: CURRENT POSITION 0 Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::syncState stateService stop Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:24 volumio volumio[18285]: info: No code Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:24 volumio volumio[18285]: info: ------------------------------ 33ms Feb 11 22:06:24 volumio volumio[18285]: info: explodeTIDALUri took 302 milliseconds Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioPushQueue Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::saveQueue Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::updateTrackBlock Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrackBlock Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioPlay Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::play index 0 Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::stop Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::play index undefined Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::startPlaybackTimer Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:24 volumio volumio[18285]: info: [1770843984334] ControllerTidal::clearAddPlayTrack Feb 11 22:06:24 volumio volumio[18285]: info: Getting stream with soundQuality HI_RES Feb 11 22:06:24 volumio volumio[18285]: info: getStreamUrl took 169 milliseconds Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand stop took 0 milliseconds Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 22:06:24 volumio volumio[18285]: info: Feb 11 22:06:24 volumio volumio[18285]: ---------------------------- MPD announces system playlist update Feb 11 22:06:24 volumio volumio[18285]: info: Ignoring MPD Status Update Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand clear took 0 milliseconds Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1NWYyZjc5ZjAyMTQ5NGFiMWI0NTg1MzkxZTkzMmRjZF82MS5tcDQ/0.flac?token=1770847584~Yzc3N2E2ODdmY2YyY2UyNDBjODg3MDJlNjBjODJlZDcyYjg3ZmE2OA==" Feb 11 22:06:24 volumio volumio[18285]: error: updateQueue error: null Feb 11 22:06:24 volumio volumio[18285]: info: ------------------------------ 1ms Feb 11 22:06:24 volumio volumio[18285]: info: Feb 11 22:06:24 volumio volumio[18285]: ---------------------------- MPD announces system playlist update Feb 11 22:06:24 volumio volumio[18285]: info: Ignoring MPD Status Update Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1NWYyZjc5ZjAyMTQ5NGFiMWI0NTg1MzkxZTkzMmRjZF82MS5tcDQ/0.flac?token=1770847584~Yzc3N2E2ODdmY2YyY2UyNDBjODg3MDJlNjBjODJlZDcyYjg3ZmE2OA==" took 1 milliseconds Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand play Feb 11 22:06:24 volumio volumio[18285]: info: ------------------------------ 0ms Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand play took 1 milliseconds Feb 11 22:06:24 volumio volumio[18285]: info: Feb 11 22:06:24 volumio volumio[18285]: ---------------------------- MPD announces state update: player Feb 11 22:06:24 volumio volumio[18285]: info: ControllerMpd::getState Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand status Feb 11 22:06:24 volumio volumio[18285]: info: Feb 11 22:06:24 volumio volumio[18285]: ---------------------------- MPD announces state update: player Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand status took 2 milliseconds Feb 11 22:06:24 volumio volumio[18285]: info: ControllerMpd::getState Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand status Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::parseState Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand status took 1 milliseconds Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::parseState Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::parseTrackInfo Feb 11 22:06:24 volumio volumio[18285]: info: ControllerMpd::pushState Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::servicePushState Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:24 volumio volumio[18285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":424,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1770847584~Yzc3N2E2ODdmY2YyY2UyNDBjODg3MDJlNjBjODJlZDcyYjg3ZmE2OA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1NWYyZjc5ZjAyMTQ5NGFiMWI0NTg1MzkxZTkzMmRjZF82MS5tcDQ/0.flac?token=1770847584~Yzc3N2E2ODdmY2YyY2UyNDBjODg3MDJlNjBjODJlZDcyYjg3ZmE2OA==","trackType":"tidal"} Feb 11 22:06:24 volumio volumio[18285]: verbose: CURRENT POSITION 0 Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::syncState stateService play Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:06:24 volumio volumio[18285]: info: ------------------------------ 5ms Feb 11 22:06:24 volumio volumio[18285]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 11 22:06:24 volumio volumio[18285]: verbose: ControllerMpd::parseTrackInfo Feb 11 22:06:24 volumio volumio[18285]: info: ControllerMpd::pushState Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::servicePushState Feb 11 22:06:24 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:24 volumio volumio[18285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":424,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1770847584~Yzc3N2E2ODdmY2YyY2UyNDBjODg3MDJlNjBjODJlZDcyYjg3ZmE2OA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1NWYyZjc5ZjAyMTQ5NGFiMWI0NTg1MzkxZTkzMmRjZF82MS5tcDQ/0.flac?token=1770847584~Yzc3N2E2ODdmY2YyY2UyNDBjODg3MDJlNjBjODJlZDcyYjg3ZmE2OA==","trackType":"tidal"} Feb 11 22:06:24 volumio volumio[18285]: verbose: CURRENT POSITION 0 Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::syncState stateService play Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::syncState currentStatus play Feb 11 22:06:24 volumio volumio[18285]: info: Received an update from plugin. extracting info from payload Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:24 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:24 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:24 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:24 volumio volumio[18285]: info: ------------------------------ 39ms Feb 11 22:06:35 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::ClearQueue Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::stop Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::stPlaybackTimer Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::updateTrackBlock Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::getTrackBlock Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:35 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:35 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::serviceStop Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::serviceStop Feb 11 22:06:35 volumio volumio[18285]: info: [1770843995303] ControllerTidal::stop Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 22:06:35 volumio volumio[18285]: info: ControllerMpd::stop Feb 11 22:06:35 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::clearPlayQueue Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::saveQueue Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioPushQueue Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::addQueueItems Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::addQueueItems Feb 11 22:06:35 volumio volumio[18285]: info: Preload queue cleared Feb 11 22:06:35 volumio volumio[18285]: info: Adding Item to queue: tidal://mymusic/mixes/0022201ec0f87383a6eaa752c35fdb Feb 11 22:06:35 volumio volumio[18285]: info: Exploding uri tidal://mymusic/mixes/0022201ec0f87383a6eaa752c35fdb in service tidal Feb 11 22:06:35 volumio volumio[18285]: info: Feb 11 22:06:35 volumio volumio[18285]: ---------------------------- MPD announces state update: player Feb 11 22:06:35 volumio volumio[18285]: info: sendMpdCommand stop took 18 milliseconds Feb 11 22:06:35 volumio volumio[18285]: info: ControllerMpd::getState Feb 11 22:06:35 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand status Feb 11 22:06:35 volumio volumio[18285]: info: sendMpdCommand status took 1 milliseconds Feb 11 22:06:35 volumio volumio[18285]: verbose: ControllerMpd::parseState Feb 11 22:06:35 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 22:06:35 volumio volumio[18285]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 11 22:06:35 volumio volumio[18285]: verbose: ControllerMpd::parseTrackInfo Feb 11 22:06:35 volumio volumio[18285]: info: ControllerMpd::pushState Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::servicePushState Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:35 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:35 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:35 volumio volumio[18285]: 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":"0.flac?token=1770847584~Yzc3N2E2ODdmY2YyY2UyNDBjODg3MDJlNjBjODJlZDcyYjg3ZmE2OA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1NWYyZjc5ZjAyMTQ5NGFiMWI0NTg1MzkxZTkzMmRjZF82MS5tcDQ/0.flac?token=1770847584~Yzc3N2E2ODdmY2YyY2UyNDBjODg3MDJlNjBjODJlZDcyYjg3ZmE2OA==","trackType":"tidal"} Feb 11 22:06:35 volumio volumio[18285]: verbose: CURRENT POSITION 0 Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::syncState stateService stop Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:35 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:35 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:35 volumio volumio[18285]: info: No code Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:35 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:35 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:35 volumio volumio[18285]: info: ------------------------------ 45ms Feb 11 22:06:35 volumio volumio[18285]: info: explodeTIDALUri took 433 milliseconds Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioPushQueue Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::saveQueue Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::updateTrackBlock Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::getTrackBlock Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 11 22:06:35 volumio volumio[18285]: info: CoreCommandRouter::volumioPlay Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::play index 0 Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::stop Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::play index undefined Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::startPlaybackTimer Feb 11 22:06:35 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:35 volumio volumio[18285]: info: [1770843995757] ControllerTidal::clearAddPlayTrack Feb 11 22:06:35 volumio volumio[18285]: info: Getting stream with soundQuality HI_RES Feb 11 22:06:35 volumio volumio[18285]: info: getStreamUrl took 220 milliseconds Feb 11 22:06:35 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 22:06:35 volumio volumio[18285]: info: sendMpdCommand stop took 0 milliseconds Feb 11 22:06:35 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 22:06:35 volumio volumio[18285]: info: Feb 11 22:06:35 volumio volumio[18285]: ---------------------------- MPD announces system playlist update Feb 11 22:06:35 volumio volumio[18285]: info: Ignoring MPD Status Update Feb 11 22:06:35 volumio volumio[18285]: info: sendMpdCommand clear took 0 milliseconds Feb 11 22:06:35 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkZDI4NGZlOTk1OTA3ZTljODdmNzQ0MGNjNDIwY2VjN182MS5tcDQ/0.flac?token=1770847595~NTUxNmZmNmFjMTg2NmVhNmEwYjVlN2UwNDgxOGQ3YjkwNTFhMjU1ZQ==" Feb 11 22:06:35 volumio volumio[18285]: error: updateQueue error: null Feb 11 22:06:35 volumio volumio[18285]: info: ------------------------------ 0ms Feb 11 22:06:35 volumio volumio[18285]: info: Feb 11 22:06:35 volumio volumio[18285]: ---------------------------- MPD announces system playlist update Feb 11 22:06:35 volumio volumio[18285]: info: Ignoring MPD Status Update Feb 11 22:06:35 volumio volumio[18285]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkZDI4NGZlOTk1OTA3ZTljODdmNzQ0MGNjNDIwY2VjN182MS5tcDQ/0.flac?token=1770847595~NTUxNmZmNmFjMTg2NmVhNmEwYjVlN2UwNDgxOGQ3YjkwNTFhMjU1ZQ==" took 1 milliseconds Feb 11 22:06:35 volumio volumio[18285]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 22:06:35 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand play Feb 11 22:06:35 volumio volumio[18285]: info: ------------------------------ 0ms Feb 11 22:06:35 volumio volumio[18285]: info: sendMpdCommand play took 2 milliseconds Feb 11 22:06:36 volumio volumio[18285]: info: Feb 11 22:06:36 volumio volumio[18285]: ---------------------------- MPD announces state update: player Feb 11 22:06:36 volumio volumio[18285]: info: ControllerMpd::getState Feb 11 22:06:36 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand status Feb 11 22:06:36 volumio volumio[18285]: info: Feb 11 22:06:36 volumio volumio[18285]: ---------------------------- MPD announces state update: player Feb 11 22:06:36 volumio volumio[18285]: info: sendMpdCommand status took 2 milliseconds Feb 11 22:06:36 volumio volumio[18285]: info: ControllerMpd::getState Feb 11 22:06:36 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand status Feb 11 22:06:36 volumio volumio[18285]: verbose: ControllerMpd::parseState Feb 11 22:06:36 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 22:06:36 volumio volumio[18285]: info: sendMpdCommand status took 1 milliseconds Feb 11 22:06:36 volumio volumio[18285]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 11 22:06:36 volumio volumio[18285]: verbose: ControllerMpd::parseState Feb 11 22:06:36 volumio volumio[18285]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 22:06:36 volumio volumio[18285]: verbose: ControllerMpd::parseTrackInfo Feb 11 22:06:36 volumio volumio[18285]: info: ControllerMpd::pushState Feb 11 22:06:36 volumio volumio[18285]: info: CoreCommandRouter::servicePushState Feb 11 22:06:36 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:36 volumio volumio[18285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1770847595~NTUxNmZmNmFjMTg2NmVhNmEwYjVlN2UwNDgxOGQ3YjkwNTFhMjU1ZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkZDI4NGZlOTk1OTA3ZTljODdmNzQ0MGNjNDIwY2VjN182MS5tcDQ/0.flac?token=1770847595~NTUxNmZmNmFjMTg2NmVhNmEwYjVlN2UwNDgxOGQ3YjkwNTFhMjU1ZQ==","trackType":"tidal"} Feb 11 22:06:36 volumio volumio[18285]: verbose: CURRENT POSITION 0 Feb 11 22:06:36 volumio volumio[18285]: info: CoreStateMachine::syncState stateService play Feb 11 22:06:36 volumio volumio[18285]: info: CoreStateMachine::syncState currentStatus stop Feb 11 22:06:36 volumio volumio[18285]: info: ------------------------------ 4ms Feb 11 22:06:36 volumio volumio[18285]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 11 22:06:36 volumio volumio[18285]: verbose: ControllerMpd::parseTrackInfo Feb 11 22:06:36 volumio volumio[18285]: info: ControllerMpd::pushState Feb 11 22:06:36 volumio volumio[18285]: info: CoreCommandRouter::servicePushState Feb 11 22:06:36 volumio volumio[18285]: info: CorePlayQueue::getTrack 0 Feb 11 22:06:36 volumio volumio[18285]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1770847595~NTUxNmZmNmFjMTg2NmVhNmEwYjVlN2UwNDgxOGQ3YjkwNTFhMjU1ZQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkZDI4NGZlOTk1OTA3ZTljODdmNzQ0MGNjNDIwY2VjN182MS5tcDQ/0.flac?token=1770847595~NTUxNmZmNmFjMTg2NmVhNmEwYjVlN2UwNDgxOGQ3YjkwNTFhMjU1ZQ==","trackType":"tidal"} Feb 11 22:06:36 volumio volumio[18285]: verbose: CURRENT POSITION 0 Feb 11 22:06:36 volumio volumio[18285]: info: CoreStateMachine::syncState stateService play Feb 11 22:06:36 volumio volumio[18285]: info: CoreStateMachine::syncState currentStatus play Feb 11 22:06:36 volumio volumio[18285]: info: Received an update from plugin. extracting info from payload Feb 11 22:06:36 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:36 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 22:06:36 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:36 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:36 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:36 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:36 volumio volumio[18285]: info: CoreStateMachine::pushState Feb 11 22:06:36 volumio volumio[18285]: info: CoreCommandRouter::volumioPushState Feb 11 22:06:36 volumio volumio[18285]: info: MRS: Pushing multiroomSync output update for this device Feb 11 22:06:36 volumio volumio[18285]: info: MRS: Pushing multiroomSync output Feb 11 22:06:36 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:36 volumio volumio[18285]: info: ------------------------------ 35ms Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:49 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:49 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Feb 11 22:06:55 volumio volumio[18285]: error: MRS: impossible to set browserPlayback volume: device not found Feb 11 22:06:55 volumio volumio[18285]: info: Tunnel connection is inactive, restarting it Feb 11 22:06:55 volumio volumio[18285]: info: Starting Tunnel 1 Feb 11 22:06:55 volumio volumio[18285]: info: Starting Tunnel Connection Checker Feb 11 22:06:56 volumio sudo[19967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 11 22:06:56 volumio sudo[19967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 22:06:56 volumio autossh[19275]: received signal to exit (15) Feb 11 22:06:56 volumio systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Feb 11 22:06:56 volumio systemd[1]: sshtunnel.service: Deactivated successfully. Feb 11 22:06:56 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Feb 11 22:06:56 volumio sudo[19967]: pam_unix(sudo:session): session closed for user root Feb 11 22:06:56 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 11 22:06:56 volumio volumio[18285]: info: Remote SSH Started Feb 11 22:06:56 volumio autossh[19970]: port set to 0, monitoring disabled Feb 11 22:06:56 volumio autossh[19970]: starting ssh (count 1) Feb 11 22:06:56 volumio autossh[19970]: ssh child pid is 19973 Feb 11 22:06:56 volumio volumio[18285]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 11 22:06:56 volumio volumio[18285]: info: CoreCommandRouter::volumioGetState Feb 11 22:06:56 volumio volumiossh-tunnel[19973]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 11 22:07:02 volumio volumio[18285]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Feb 11 22:07:02 volumio volumio[18285]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Feb 11 22:07:02 volumio volumio[18285]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 22:07:02 volumio volumio[18285]: TypeError: Cannot read properties of undefined (reading 'then') Feb 11 22:07:02 volumio volumio[18285]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Feb 11 22:07:02 volumio volumio[18285]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Feb 11 22:07:02 volumio volumio[18285]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Feb 11 22:07:02 volumio volumio[18285]: at Socket.emit (node:events:514:28) Feb 11 22:07:02 volumio volumio[18285]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 11 22:07:02 volumio volumio[18285]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 11 22:07:02 volumio volumio[18285]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 22:07:02 volumio sudo[20008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 22:06' Feb 11 22:07:02 volumio sudo[20008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"