Apr 14 14:11:01 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1314. Apr 14 14:11:01 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:11:01 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:11:01 volumio upmpdcli[694]: Could not open config: /tmp/upmpdcli.conf Apr 14 14:11:01 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:11:01 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 14:11:08 volumio volumio[1076]: info: CorePlayQueue::getTrack 65 Apr 14 14:11:08 volumio volumio[1076]: info: CorePlayQueue::getTrack 66 Apr 14 14:11:08 volumio volumio[1076]: info: Prefetching next song Apr 14 14:11:08 volumio volumio[1076]: info: [1776172268138] ControllerTidal::prefetch Apr 14 14:11:08 volumio volumio[1076]: info: Getting stream with soundQuality LOSSLESS Apr 14 14:11:08 volumio volumio[1076]: info: getStreamUrl took 553 milliseconds Apr 14 14:11:08 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic4ZWE0MTExMGNiNDQ4NzhkMTY0MmZiOWU3NGEwMmMzYV82MS5tcDQ/0.flac?token=1776175868~MWRjMzg4OWRhMGM5ODRkN2MyM2ExYjI0MzFjNjVlZjYwZmY0ODVkOQ==" Apr 14 14:11:08 volumio volumio[1076]: info: Apr 14 14:11:08 volumio volumio[1076]: ---------------------------- MPD announces system playlist update Apr 14 14:11:08 volumio volumio[1076]: info: Ignoring MPD Status Update Apr 14 14:11:08 volumio volumio[1076]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic4ZWE0MTExMGNiNDQ4NzhkMTY0MmZiOWU3NGEwMmMzYV82MS5tcDQ/0.flac?token=1776175868~MWRjMzg4OWRhMGM5ODRkN2MyM2ExYjI0MzFjNjVlZjYwZmY0ODVkOQ==" took 2 milliseconds Apr 14 14:11:08 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 14:11:08 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 14 14:11:08 volumio volumio[1076]: info: ------------------------------ 4ms Apr 14 14:11:08 volumio volumio[1076]: info: sendMpdCommand consume 1 took 3 milliseconds Apr 14 14:11:12 volumio volumio[1076]: info: Apr 14 14:11:12 volumio volumio[1076]: ---------------------------- MPD announces system playlist update Apr 14 14:11:12 volumio volumio[1076]: info: Ignoring MPD Status Update Apr 14 14:11:12 volumio volumio[1076]: info: Apr 14 14:11:12 volumio volumio[1076]: ---------------------------- MPD announces state update: player Apr 14 14:11:12 volumio volumio[1076]: info: ControllerMpd::getState Apr 14 14:11:12 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:11:12 volumio volumio[1076]: info: ------------------------------ 3ms Apr 14 14:11:12 volumio volumio[1076]: info: sendMpdCommand status took 4 milliseconds Apr 14 14:11:12 volumio volumio[1076]: verbose: ControllerMpd::parseState Apr 14 14:11:12 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:11:12 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 14:11:12 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:11:12 volumio volumio[1076]: info: ControllerMpd::pushState Apr 14 14:11:12 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Apr 14 14:11:12 volumio volumio[1076]: info: CorePlayQueue::getTrack 65 Apr 14 14:11:12 volumio volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"276 Kbps","isStreaming":false,"title":"0.flac?token=1776175868~MWRjMzg4OWRhMGM5ODRkN2MyM2ExYjI0MzFjNjVlZjYwZmY0ODVkOQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic4ZWE0MTExMGNiNDQ4NzhkMTY0MmZiOWU3NGEwMmMzYV82MS5tcDQ/0.flac?token=1776175868~MWRjMzg4OWRhMGM5ODRkN2MyM2ExYjI0MzFjNjVlZjYwZmY0ODVkOQ==","trackType":"tidal"} Apr 14 14:11:12 volumio volumio[1076]: verbose: CURRENT POSITION 65 Apr 14 14:11:12 volumio volumio[1076]: info: CoreStateMachine::syncState stateService play Apr 14 14:11:12 volumio volumio[1076]: info: CoreStateMachine::syncState currentStatus play Apr 14 14:11:12 volumio volumio[1076]: info: Received an update from plugin. extracting info from payload Apr 14 14:11:12 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:11:12 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:11:12 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:11:12 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:11:12 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:11:12 volumio volumio[1076]: info: ------------------------------ 27ms Apr 14 14:11:12 volumio volumio[1076]: info: CoreStateMachine::startPlaybackTimer Apr 14 14:11:12 volumio volumio[1076]: info: CorePlayQueue::getTrack 66 Apr 14 14:11:13 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:11:13 volumio volumio[1076]: info: CorePlayQueue::getTrack 66 Apr 14 14:11:13 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:11:13 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:11:16 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1315. Apr 14 14:11:16 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:11:17 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:11:17 volumio upmpdcli[727]: Could not open config: /tmp/upmpdcli.conf Apr 14 14:11:17 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:11:17 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 14:11:32 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1316. Apr 14 14:11:32 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:11:32 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:11:32 volumio upmpdcli[744]: Could not open config: /tmp/upmpdcli.conf Apr 14 14:11:32 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:11:32 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 14:11:47 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1317. Apr 14 14:11:47 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:11:47 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:11:47 volumio upmpdcli[778]: Could not open config: /tmp/upmpdcli.conf Apr 14 14:11:47 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:11:47 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 14:11:48 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 14:11:48 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 14 14:11:49 volumio volumio[1076]: info: searchTIDALUri took 281 milliseconds Apr 14 14:11:49 volumio volumio[1076]: info: search took 285 milliseconds Apr 14 14:11:50 volumio volumio[1076]: info: All search sources collected, pushing search results Apr 14 14:11:50 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 14 14:11:51 volumio volumio[1076]: info: browseTIDALUri took 607 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:11:51 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:11:51 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/415899479 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/418146840 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/400107476 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/418124725 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/428631564 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/425889372 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/434489175 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/426921783 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/506303344 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/446311333 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/443993020 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/453974240 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/439453787 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/446311334 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/506322386 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/506322385 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/439516457 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/426921784 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/443993019 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/453974241 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/468161851 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/397800568 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/468161852 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/439516455 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/456893167 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/469935549 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/426921788 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/398445429 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/456893166 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/426921787 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/451888542 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/485567749 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/485567750 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/393509883 Apr 14 14:11:51 volumio volumio[1076]: info: Preloading song: tidal://song/451888544 Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/415899479 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/418146840 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: Executing endpoint getSimilarArtists Apr 14 14:11:51 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 14 14:11:51 volumio volumio[1076]: info: Executing endpoint getSimilarArtists Apr 14 14:11:51 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 14 14:11:51 volumio volumio[1076]: info: Executing endpoint getSimilarArtists Apr 14 14:11:51 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/400107476 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: Executing endpoint metavolumio Apr 14 14:11:51 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/418124725 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: Executing endpoint metavolumio Apr 14 14:11:51 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 191 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: Executing endpoint metavolumio Apr 14 14:11:51 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/428631564 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/425889372 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/434489175 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/426921783 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 188 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/506303344 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 360 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/446311333 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 362 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 327 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/443993020 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 148 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/453974240 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 316 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 264 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 244 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 147 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/439453787 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 149 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/446311334 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/506322386 in service tidal Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 153 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: explodeTIDALUri took 147 milliseconds Apr 14 14:11:51 volumio volumio[1076]: info: Exploding uri tidal://song/506322385 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/439516457 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 151 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/426921784 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 159 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/443993019 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 151 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 150 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/453974241 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 145 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/468161851 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 147 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/397800568 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/468161852 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 153 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/439516455 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 155 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 144 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/456893167 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 143 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/469935549 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/426921788 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 152 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/398445429 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 152 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/456893166 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 153 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/426921787 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 153 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 150 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/451888542 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/485567749 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 151 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 144 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/485567750 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/393509883 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 156 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: Exploding uri tidal://song/451888544 in service tidal Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 153 milliseconds Apr 14 14:11:52 volumio volumio[1076]: info: explodeTIDALUri took 143 milliseconds Apr 14 14:11:53 volumio volumio[1076]: info: explodeTIDALUri took 152 milliseconds Apr 14 14:11:53 volumio volumio[1076]: info: explodeTIDALUri took 149 milliseconds Apr 14 14:11:57 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:11:57 volumio volumio[1076]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::addQueueItems Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::addQueueItems Apr 14 14:11:57 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:11:57 volumio volumio[1076]: info: Adding Item to queue: tidal://artist/51143805/506322384 Apr 14 14:11:57 volumio volumio[1076]: info: Exploding uri tidal://artist/51143805/506322384 in service tidal Apr 14 14:11:57 volumio volumio[1076]: info: explodeTIDALUri took 302 milliseconds Apr 14 14:11:57 volumio volumio[1076]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::saveQueue Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::updateTrackBlock Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::getTrackBlock Apr 14 14:11:57 volumio volumio[1076]: info: CoreCommandRouter::volumioPlay Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::play index 71 Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::stop Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::stPlaybackTimer Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::updateTrackBlock Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::getTrackBlock Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::getTrack 66 Apr 14 14:11:57 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:11:57 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::serviceStop Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::getTrack 66 Apr 14 14:11:57 volumio volumio[1076]: info: CoreCommandRouter::serviceStop Apr 14 14:11:57 volumio volumio[1076]: info: [1776172317798] ControllerTidal::stop Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 14:11:57 volumio volumio[1076]: info: ControllerMpd::stop Apr 14 14:11:57 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 14:11:57 volumio volumio[1076]: info: Apr 14 14:11:57 volumio volumio[1076]: ---------------------------- MPD announces state update: player Apr 14 14:11:57 volumio volumio[1076]: info: sendMpdCommand stop took 24 milliseconds Apr 14 14:11:57 volumio volumio[1076]: info: ControllerMpd::getState Apr 14 14:11:57 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::play index undefined Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::getTrack 71 Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::startPlaybackTimer Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::getTrack 71 Apr 14 14:11:57 volumio volumio[1076]: info: [1776172317824] ControllerTidal::clearAddPlayTrack Apr 14 14:11:57 volumio volumio[1076]: info: Getting stream with soundQuality LOSSLESS Apr 14 14:11:57 volumio volumio[1076]: info: sendMpdCommand status took 6 milliseconds Apr 14 14:11:57 volumio volumio[1076]: verbose: ControllerMpd::parseState Apr 14 14:11:57 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:11:57 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 14:11:57 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:11:57 volumio volumio[1076]: info: ControllerMpd::pushState Apr 14 14:11:57 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Apr 14 14:11:57 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::getTrack 71 Apr 14 14:11:57 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:11:57 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:11:57 volumio volumio[1076]: info: CorePlayQueue::getTrack 71 Apr 14 14:11:57 volumio volumio[1076]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 14 14:11:57 volumio volumio[1076]: info: ------------------------------ 18ms Apr 14 14:11:58 volumio volumio[1076]: info: getStreamUrl took 303 milliseconds Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 14:11:58 volumio volumio[1076]: info: sendMpdCommand stop took 1 milliseconds Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 14:11:58 volumio volumio[1076]: info: Apr 14 14:11:58 volumio volumio[1076]: ---------------------------- MPD announces system playlist update Apr 14 14:11:58 volumio volumio[1076]: info: Ignoring MPD Status Update Apr 14 14:11:58 volumio volumio[1076]: info: sendMpdCommand clear took 1 milliseconds Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYTYzN2Y3MmZkYjg1MDI2MDcwNTU5Zjg4MjJiN2M2NS5tcDQ/0.flac?token=1776175918~ZmExMTMzYzliODc5NDM0ZmVhYTA4MTMyZDI3NGM0Nzk2MDhiMDM5Nw==" Apr 14 14:11:58 volumio volumio[1076]: error: updateQueue error: null Apr 14 14:11:58 volumio volumio[1076]: info: ------------------------------ 1ms Apr 14 14:11:58 volumio volumio[1076]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYTYzN2Y3MmZkYjg1MDI2MDcwNTU5Zjg4MjJiN2M2NS5tcDQ/0.flac?token=1776175918~ZmExMTMzYzliODc5NDM0ZmVhYTA4MTMyZDI3NGM0Nzk2MDhiMDM5Nw==" took 1 milliseconds Apr 14 14:11:58 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand play Apr 14 14:11:58 volumio volumio[1076]: info: Apr 14 14:11:58 volumio volumio[1076]: ---------------------------- MPD announces system playlist update Apr 14 14:11:58 volumio volumio[1076]: info: Ignoring MPD Status Update Apr 14 14:11:58 volumio volumio[1076]: info: sendMpdCommand play took 1 milliseconds Apr 14 14:11:58 volumio volumio[1076]: info: ------------------------------ 2ms Apr 14 14:11:58 volumio volumio[1076]: info: Apr 14 14:11:58 volumio volumio[1076]: ---------------------------- MPD announces state update: player Apr 14 14:11:58 volumio volumio[1076]: info: ControllerMpd::getState Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:11:58 volumio volumio[1076]: info: Apr 14 14:11:58 volumio volumio[1076]: ---------------------------- MPD announces state update: player Apr 14 14:11:58 volumio volumio[1076]: info: sendMpdCommand status took 29 milliseconds Apr 14 14:11:58 volumio volumio[1076]: info: ControllerMpd::getState Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::parseState Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:11:58 volumio volumio[1076]: info: sendMpdCommand status took 4 milliseconds Apr 14 14:11:58 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::parseState Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:11:58 volumio volumio[1076]: info: ControllerMpd::pushState Apr 14 14:11:58 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Apr 14 14:11:58 volumio volumio[1076]: info: CorePlayQueue::getTrack 71 Apr 14 14:11:58 volumio volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1776175918~ZmExMTMzYzliODc5NDM0ZmVhYTA4MTMyZDI3NGM0Nzk2MDhiMDM5Nw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYTYzN2Y3MmZkYjg1MDI2MDcwNTU5Zjg4MjJiN2M2NS5tcDQ/0.flac?token=1776175918~ZmExMTMzYzliODc5NDM0ZmVhYTA4MTMyZDI3NGM0Nzk2MDhiMDM5Nw==","trackType":"tidal"} Apr 14 14:11:58 volumio volumio[1076]: verbose: CURRENT POSITION 71 Apr 14 14:11:58 volumio volumio[1076]: info: CoreStateMachine::syncState stateService play Apr 14 14:11:58 volumio volumio[1076]: info: CoreStateMachine::syncState currentStatus stop Apr 14 14:11:58 volumio volumio[1076]: info: ------------------------------ 36ms Apr 14 14:11:58 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 14 14:11:58 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:11:58 volumio volumio[1076]: info: ControllerMpd::pushState Apr 14 14:11:58 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Apr 14 14:11:58 volumio volumio[1076]: info: CorePlayQueue::getTrack 71 Apr 14 14:11:58 volumio volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1776175918~ZmExMTMzYzliODc5NDM0ZmVhYTA4MTMyZDI3NGM0Nzk2MDhiMDM5Nw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYTYzN2Y3MmZkYjg1MDI2MDcwNTU5Zjg4MjJiN2M2NS5tcDQ/0.flac?token=1776175918~ZmExMTMzYzliODc5NDM0ZmVhYTA4MTMyZDI3NGM0Nzk2MDhiMDM5Nw==","trackType":"tidal"} Apr 14 14:11:58 volumio volumio[1076]: verbose: CURRENT POSITION 71 Apr 14 14:11:58 volumio volumio[1076]: info: CoreStateMachine::syncState stateService play Apr 14 14:11:58 volumio volumio[1076]: info: CoreStateMachine::syncState currentStatus play Apr 14 14:11:58 volumio volumio[1076]: info: Received an update from plugin. extracting info from payload Apr 14 14:11:58 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:11:58 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:11:58 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:11:58 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:11:58 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:11:58 volumio volumio[1076]: info: ------------------------------ 25ms Apr 14 14:12:02 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1318. Apr 14 14:12:02 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:12:02 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:12:02 volumio upmpdcli[796]: Could not open config: /tmp/upmpdcli.conf Apr 14 14:12:02 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:12:02 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 14:12:17 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1319. Apr 14 14:12:17 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:12:18 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:12:18 volumio upmpdcli[826]: Could not open config: /tmp/upmpdcli.conf Apr 14 14:12:18 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:12:18 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 14:12:28 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 14 14:12:28 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: tidal , search Apr 14 14:12:28 volumio volumio[1076]: info: searchTIDALUri took 305 milliseconds Apr 14 14:12:28 volumio volumio[1076]: info: search took 307 milliseconds Apr 14 14:12:30 volumio volumio[1076]: info: All search sources collected, pushing search results Apr 14 14:12:30 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 14 14:12:30 volumio volumio[1076]: info: browseTIDALUri took 819 milliseconds Apr 14 14:12:30 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:12:30 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:12:30 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/240914434 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/332954126 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/386779063 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359859790 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/504340020 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/454419323 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/329210674 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/240161912 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359859791 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359916525 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359906745 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/454418643 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359859786 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359859789 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/234938303 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359899962 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/264961830 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/454413168 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/374729874 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/296412221 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359916530 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/241115112 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/443227129 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/255359350 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/443227128 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/230630127 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359916529 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/383087780 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359859788 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/425622288 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359859785 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/362122696 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/443227133 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359902029 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359916526 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/404277909 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/306301375 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/454421394 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359916531 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/251522631 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359916527 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359916528 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359902031 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359904468 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/454421400 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/361849567 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359907822 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/454413164 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/454421410 Apr 14 14:12:30 volumio volumio[1076]: info: Preloading song: tidal://song/359859787 Apr 14 14:12:30 volumio volumio[1076]: info: Exploding uri tidal://song/240914434 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: Executing endpoint getSimilarArtists Apr 14 14:12:31 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/332954126 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: Executing endpoint getSimilarArtists Apr 14 14:12:31 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 14 14:12:31 volumio volumio[1076]: info: Executing endpoint getSimilarArtists Apr 14 14:12:31 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/386779063 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/359859790 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 170 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Executing endpoint metavolumio Apr 14 14:12:31 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 14:12:31 volumio volumio[1076]: info: Executing endpoint metavolumio Apr 14 14:12:31 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/504340020 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: Executing endpoint metavolumio Apr 14 14:12:31 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/454419323 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/329210674 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 288 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 186 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/240161912 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/359859791 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 307 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/359916525 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/359906745 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 277 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 184 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 151 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/454418643 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 334 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 150 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/359859786 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 319 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/359859789 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/234938303 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 152 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 149 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/359899962 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 258 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/264961830 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 153 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 150 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/454413168 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 145 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/374729874 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/296412221 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 154 milliseconds Apr 14 14:12:31 volumio volumio[1076]: info: Exploding uri tidal://song/359916530 in service tidal Apr 14 14:12:31 volumio volumio[1076]: info: explodeTIDALUri took 152 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/241115112 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 154 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/443227129 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 164 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/255359350 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 153 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 149 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/443227128 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 149 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/230630127 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 149 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/359916529 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/383087780 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 154 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 146 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/359859788 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/425622288 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 152 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/359859785 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 145 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/362122696 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/443227133 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 167 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 144 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/359902029 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 333 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/359916526 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 173 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/404277909 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 156 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/306301375 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 159 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 149 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/454421394 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 145 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/359916531 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 149 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/251522631 in service tidal Apr 14 14:12:32 volumio volumio[1076]: info: explodeTIDALUri took 147 milliseconds Apr 14 14:12:32 volumio volumio[1076]: info: Exploding uri tidal://song/359916527 in service tidal Apr 14 14:12:33 volumio volumio[1076]: info: explodeTIDALUri took 142 milliseconds Apr 14 14:12:33 volumio volumio[1076]: info: Exploding uri tidal://song/359916528 in service tidal Apr 14 14:12:33 volumio volumio[1076]: info: explodeTIDALUri took 145 milliseconds Apr 14 14:12:33 volumio volumio[1076]: info: Exploding uri tidal://song/359902031 in service tidal Apr 14 14:12:33 volumio volumio[1076]: info: Exploding uri tidal://song/359904468 in service tidal Apr 14 14:12:33 volumio volumio[1076]: info: explodeTIDALUri took 152 milliseconds Apr 14 14:12:33 volumio volumio[1076]: info: Exploding uri tidal://song/454421400 in service tidal Apr 14 14:12:33 volumio volumio[1076]: info: explodeTIDALUri took 157 milliseconds Apr 14 14:12:33 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:12:33 volumio volumio[1076]: info: CoreCommandRouter::volumioAddQueueItems Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::addQueueItems Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::addQueueItems Apr 14 14:12:33 volumio volumio[1076]: info: Preload queue cleared Apr 14 14:12:33 volumio volumio[1076]: info: Adding Item to queue: tidal://artist/30291952/443227127 Apr 14 14:12:33 volumio volumio[1076]: info: Exploding uri tidal://artist/30291952/443227127 in service tidal Apr 14 14:12:33 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1320. Apr 14 14:12:33 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:12:33 volumio volumio[1076]: info: explodeTIDALUri took 145 milliseconds Apr 14 14:12:33 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 14:12:33 volumio volumio[1076]: info: explodeTIDALUri took 153 milliseconds Apr 14 14:12:33 volumio upmpdcli[844]: Could not open config: /tmp/upmpdcli.conf Apr 14 14:12:33 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 14 14:12:33 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 14 14:12:33 volumio volumio[1076]: info: explodeTIDALUri took 145 milliseconds Apr 14 14:12:33 volumio volumio[1076]: info: explodeTIDALUri took 301 milliseconds Apr 14 14:12:33 volumio volumio[1076]: info: CoreCommandRouter::volumioPushQueue Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::saveQueue Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::updateTrackBlock Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::getTrackBlock Apr 14 14:12:33 volumio volumio[1076]: info: CoreCommandRouter::volumioPlay Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::play index 73 Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::stop Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::stPlaybackTimer Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::updateTrackBlock Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::getTrackBlock Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::getTrack 71 Apr 14 14:12:33 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:12:33 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::serviceStop Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::getTrack 71 Apr 14 14:12:33 volumio volumio[1076]: info: CoreCommandRouter::serviceStop Apr 14 14:12:33 volumio volumio[1076]: info: [1776172353544] ControllerTidal::stop Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 14:12:33 volumio volumio[1076]: info: ControllerMpd::stop Apr 14 14:12:33 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 14:12:33 volumio volumio[1076]: info: Apr 14 14:12:33 volumio volumio[1076]: ---------------------------- MPD announces state update: player Apr 14 14:12:33 volumio volumio[1076]: info: sendMpdCommand stop took 61 milliseconds Apr 14 14:12:33 volumio volumio[1076]: info: ControllerMpd::getState Apr 14 14:12:33 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::play index undefined Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::getTrack 73 Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::startPlaybackTimer Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::getTrack 73 Apr 14 14:12:33 volumio volumio[1076]: info: [1776172353608] ControllerTidal::clearAddPlayTrack Apr 14 14:12:33 volumio volumio[1076]: info: Getting stream with soundQuality LOSSLESS Apr 14 14:12:33 volumio volumio[1076]: info: sendMpdCommand status took 4 milliseconds Apr 14 14:12:33 volumio volumio[1076]: verbose: ControllerMpd::parseState Apr 14 14:12:33 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:12:33 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 14 14:12:33 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:12:33 volumio volumio[1076]: info: ControllerMpd::pushState Apr 14 14:12:33 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Apr 14 14:12:33 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::getTrack 73 Apr 14 14:12:33 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:12:33 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:12:33 volumio volumio[1076]: info: CorePlayQueue::getTrack 73 Apr 14 14:12:33 volumio volumio[1076]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 14 14:12:33 volumio volumio[1076]: info: ------------------------------ 16ms Apr 14 14:12:34 volumio volumio[1076]: info: getStreamUrl took 426 milliseconds Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 14:12:34 volumio volumio[1076]: info: sendMpdCommand stop took 0 milliseconds Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 14:12:34 volumio volumio[1076]: info: Apr 14 14:12:34 volumio volumio[1076]: ---------------------------- MPD announces system playlist update Apr 14 14:12:34 volumio volumio[1076]: info: Ignoring MPD Status Update Apr 14 14:12:34 volumio volumio[1076]: info: sendMpdCommand clear took 1 milliseconds Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0ZWNlY2E3NWE4YmNiZjE2NTQ5YjEzNWFlOGViNmMwYi5tcDQ/0.flac?token=1776175953~YzliNzQzZDc5ZjQ3OGZmOTg2OWY2YmZmYzFhMGIyZDhlODRmMzNlZA==" Apr 14 14:12:34 volumio volumio[1076]: error: updateQueue error: null Apr 14 14:12:34 volumio volumio[1076]: info: Apr 14 14:12:34 volumio volumio[1076]: ---------------------------- MPD announces system playlist update Apr 14 14:12:34 volumio volumio[1076]: info: Ignoring MPD Status Update Apr 14 14:12:34 volumio volumio[1076]: info: ------------------------------ 2ms Apr 14 14:12:34 volumio volumio[1076]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0ZWNlY2E3NWE4YmNiZjE2NTQ5YjEzNWFlOGViNmMwYi5tcDQ/0.flac?token=1776175953~YzliNzQzZDc5ZjQ3OGZmOTg2OWY2YmZmYzFhMGIyZDhlODRmMzNlZA==" took 2 milliseconds Apr 14 14:12:34 volumio volumio[1076]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand play Apr 14 14:12:34 volumio volumio[1076]: info: ------------------------------ 2ms Apr 14 14:12:34 volumio volumio[1076]: info: sendMpdCommand play took 2 milliseconds Apr 14 14:12:34 volumio volumio[1076]: info: Apr 14 14:12:34 volumio volumio[1076]: ---------------------------- MPD announces state update: player Apr 14 14:12:34 volumio volumio[1076]: info: ControllerMpd::getState Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:12:34 volumio volumio[1076]: info: Apr 14 14:12:34 volumio volumio[1076]: ---------------------------- MPD announces state update: player Apr 14 14:12:34 volumio volumio[1076]: info: sendMpdCommand status took 76 milliseconds Apr 14 14:12:34 volumio volumio[1076]: info: ControllerMpd::getState Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand status Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::parseState Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:12:34 volumio volumio[1076]: info: sendMpdCommand status took 4 milliseconds Apr 14 14:12:34 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::parseState Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:12:34 volumio volumio[1076]: info: ControllerMpd::pushState Apr 14 14:12:34 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Apr 14 14:12:34 volumio volumio[1076]: info: CorePlayQueue::getTrack 73 Apr 14 14:12:34 volumio volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"493 Kbps","isStreaming":false,"title":"0.flac?token=1776175953~YzliNzQzZDc5ZjQ3OGZmOTg2OWY2YmZmYzFhMGIyZDhlODRmMzNlZA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0ZWNlY2E3NWE4YmNiZjE2NTQ5YjEzNWFlOGViNmMwYi5tcDQ/0.flac?token=1776175953~YzliNzQzZDc5ZjQ3OGZmOTg2OWY2YmZmYzFhMGIyZDhlODRmMzNlZA==","trackType":"tidal"} Apr 14 14:12:34 volumio volumio[1076]: verbose: CURRENT POSITION 73 Apr 14 14:12:34 volumio volumio[1076]: info: CoreStateMachine::syncState stateService play Apr 14 14:12:34 volumio volumio[1076]: info: CoreStateMachine::syncState currentStatus stop Apr 14 14:12:34 volumio volumio[1076]: info: ------------------------------ 87ms Apr 14 14:12:34 volumio volumio[1076]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 14 14:12:34 volumio volumio[1076]: verbose: ControllerMpd::parseTrackInfo Apr 14 14:12:34 volumio volumio[1076]: info: ControllerMpd::pushState Apr 14 14:12:34 volumio volumio[1076]: info: CoreCommandRouter::servicePushState Apr 14 14:12:34 volumio volumio[1076]: info: CorePlayQueue::getTrack 73 Apr 14 14:12:34 volumio volumio[1076]: verbose: STATE SERVICE {"status":"play","position":0,"seek":733,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"584 Kbps","isStreaming":false,"title":"0.flac?token=1776175953~YzliNzQzZDc5ZjQ3OGZmOTg2OWY2YmZmYzFhMGIyZDhlODRmMzNlZA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0ZWNlY2E3NWE4YmNiZjE2NTQ5YjEzNWFlOGViNmMwYi5tcDQ/0.flac?token=1776175953~YzliNzQzZDc5ZjQ3OGZmOTg2OWY2YmZmYzFhMGIyZDhlODRmMzNlZA==","trackType":"tidal"} Apr 14 14:12:34 volumio volumio[1076]: verbose: CURRENT POSITION 73 Apr 14 14:12:34 volumio volumio[1076]: info: CoreStateMachine::syncState stateService play Apr 14 14:12:34 volumio volumio[1076]: info: CoreStateMachine::syncState currentStatus play Apr 14 14:12:34 volumio volumio[1076]: info: Received an update from plugin. extracting info from payload Apr 14 14:12:34 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:12:34 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:12:34 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:12:34 volumio volumio[1076]: info: CoreStateMachine::pushState Apr 14 14:12:34 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Apr 14 14:12:34 volumio volumio[1076]: info: ------------------------------ 43ms Apr 14 14:12:35 volumio volumio[1076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 14:12:35 volumio volumio[1076]: TypeError: Cannot read properties of null (reading 'slice') Apr 14 14:12:35 volumio volumio[1076]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 14 14:12:35 volumio volumio[1076]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 14 14:12:35 volumio volumio[1076]: at Object.onceWrapper (node:events:629:26) Apr 14 14:12:35 volumio volumio[1076]: at ClientRequest.emit (node:events:514:28) Apr 14 14:12:35 volumio volumio[1076]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Apr 14 14:12:35 volumio volumio[1076]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Apr 14 14:12:35 volumio volumio[1076]: at TLSSocket.socketOnData (node:_http_client:535:22) Apr 14 14:12:35 volumio volumio[1076]: at TLSSocket.emit (node:events:514:28) Apr 14 14:12:35 volumio volumio[1076]: at addChunk (node:internal/streams/readable:343:12) Apr 14 14:12:35 volumio volumio[1076]: at readableAddChunk (node:internal/streams/readable:316:9) Apr 14 14:12:35 volumio volumio[1076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 14:12:36 volumio sudo[876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 14:11' Apr 14 14:12:36 volumio sudo[876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"