Mar 29 12:49:00 volumio volumio[1380]: info: browseTIDALUri took 292 milliseconds Mar 29 12:49:00 volumio volumio[1380]: info: Preload queue cleared Mar 29 12:49:00 volumio volumio[1380]: info: Preload queue cleared Mar 29 12:49:10 volumio volumio[1380]: info: Preload queue cleared Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::ClearQueue Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::stop Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::stPlaybackTimer Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 16 Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:10 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:10 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 16 Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::serviceStop Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 16 Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::serviceStop Mar 29 12:49:10 volumio volumio[1380]: info: [1774806550621] ControllerTidal::stop Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:49:10 volumio volumio[1380]: info: ControllerMpd::stop Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::clearPlayQueue Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::saveQueue Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::addQueueItems Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::addQueueItems Mar 29 12:49:10 volumio volumio[1380]: info: Preload queue cleared Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/384275179 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/384275179 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/354365598 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/354365598 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/572131 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/572131 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/95488050 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/95488050 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/40617348 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/40617348 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/86974592 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/86974592 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/229888181 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/229888181 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/158002 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/158002 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1274623 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1274623 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/31402063 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/31402063 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/238082518 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/238082518 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/77168304 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/77168304 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/26543730 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/26543730 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/5120012 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/5120012 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/3826837 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/3826837 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/45155848 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/45155848 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/3267925 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/3267925 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/2420807 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/2420807 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/354205264 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/354205264 Mar 29 12:49:10 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:10.624-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 12:49:10 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:10.624-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/539215 title="I Won't Hold You Back" Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::saveQueue Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioPlay Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::play index 18 Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::addQueueItems Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::addQueueItems Mar 29 12:49:10 volumio volumio[1380]: info: Preload queue cleared Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/77630365 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/77630365 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/182206206 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/182206206 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/19373935 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/19373935 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/95384245 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/95384245 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1781812 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1781812 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/26948458 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/26948458 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1852096 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1852096 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/233064405 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/233064405 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1852403 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1852403 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/100578190 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/100578190 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/64629993 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/64629993 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/105828757 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/105828757 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1886608 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1886608 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/392116481 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/392116481 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/97552225 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/97552225 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/371774475 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/371774475 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/25029974 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/25029974 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/62522013 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/62522013 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/99179014 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/99179014 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/4071713 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/4071713 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/100577909 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/100577909 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/323717055 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/323717055 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/208697704 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/208697704 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1995774 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1995774 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/81126405 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/81126405 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/218002 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/218002 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/35708001 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/35708001 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1637465 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1637465 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/19442765 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/19442765 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/19994081 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/19994081 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/190725736 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/190725736 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/15920382 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/15920382 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/26948462 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/26948462 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/391722 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/391722 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1350767 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1350767 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1290351 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1290351 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1781801 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1781801 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/292582 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/292582 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/238082391 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/238082391 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/3383233 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/3383233 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/66063742 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/66063742 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/121092018 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/121092018 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/4075303 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/4075303 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/6508567 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/6508567 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/26542853 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/26542853 Mar 29 12:49:10 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/4530888 Mar 29 12:49:10 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/4530888 Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::stop Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::saveQueue Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::play index undefined Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::startPlaybackTimer Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:10 volumio volumio[1380]: info: [1774806550642] ControllerTidal::clearAddPlayTrack Mar 29 12:49:10 volumio volumio[1380]: info: Getting stream with soundQuality LOSSLESS Mar 29 12:49:10 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:49:10 volumio volumio[1380]: info: Mar 29 12:49:10 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand stop took 39 milliseconds Mar 29 12:49:10 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand status took 2 milliseconds Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:49:10 volumio sudo[18966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:49:10 volumio sudo[18966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:49:10 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:10 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:10 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:10 volumio volumio[1380]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Mar 29 12:49:10 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:10.670-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 12:49:10 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:10.670-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/354205264 title="Good Luck, Babe!" Mar 29 12:49:10 volumio volumio[1380]: info: ------------------------------ 12ms Mar 29 12:49:10 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:49:10 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Mar 29 12:49:10 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 160 seconds. Mar 29 12:49:10 volumio sudo[18971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:49:10 volumio sudo[18971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:10 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Mar 29 12:49:10 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:49:10 volumio systemd[1]: peppymeterbasic.service: Consumed 12.837s CPU time. Mar 29 12:49:10 volumio sudo[18971]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:10 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:49:10 volumio sudo[18966]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:10 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:49:10 volumio volumio[1380]: info: getStreamUrl took 249 milliseconds Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand stop took 0 milliseconds Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 12:49:10 volumio volumio[1380]: info: Mar 29 12:49:10 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:49:10 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand clear took 0 milliseconds Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDNiY2Q4ODg2ODExYzQ2NzQ1ZTMwNTQ3MGVkZGZmMy5tcDQ/0.flac?token=1774810051~NGIyY2ZjMTkyMTRiZTZjYWFkNDFmMTQ4OWI1NmFkN2EyYTU3OTNlNQ==" Mar 29 12:49:10 volumio volumio[1380]: error: updateQueue error: null Mar 29 12:49:10 volumio volumio[1380]: info: ------------------------------ 0ms Mar 29 12:49:10 volumio volumio[1380]: info: Mar 29 12:49:10 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:49:10 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDNiY2Q4ODg2ODExYzQ2NzQ1ZTMwNTQ3MGVkZGZmMy5tcDQ/0.flac?token=1774810051~NGIyY2ZjMTkyMTRiZTZjYWFkNDFmMTQ4OWI1NmFkN2EyYTU3OTNlNQ==" took 0 milliseconds Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand play Mar 29 12:49:10 volumio volumio[1380]: info: ------------------------------ 0ms Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand play took 1 milliseconds Mar 29 12:49:10 volumio volumio[1380]: info: Mar 29 12:49:10 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:49:10 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:49:10 volumio volumio[1380]: info: Mar 29 12:49:10 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand status took 37 milliseconds Mar 29 12:49:10 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand status took 1 milliseconds Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:49:10 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:10 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"912 Kbps","isStreaming":false,"title":"0.flac?token=1774810051~NGIyY2ZjMTkyMTRiZTZjYWFkNDFmMTQ4OWI1NmFkN2EyYTU3OTNlNQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDNiY2Q4ODg2ODExYzQ2NzQ1ZTMwNTQ3MGVkZGZmMy5tcDQ/0.flac?token=1774810051~NGIyY2ZjMTkyMTRiZTZjYWFkNDFmMTQ4OWI1NmFkN2EyYTU3OTNlNQ==","trackType":"tidal"} Mar 29 12:49:10 volumio volumio[1380]: verbose: CURRENT POSITION 18 Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus stop Mar 29 12:49:10 volumio volumio[1380]: info: ------------------------------ 39ms Mar 29 12:49:10 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 12:49:10 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:49:10 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:49:10 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:10 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"830 Kbps","isStreaming":false,"title":"0.flac?token=1774810051~NGIyY2ZjMTkyMTRiZTZjYWFkNDFmMTQ4OWI1NmFkN2EyYTU3OTNlNQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNDNiY2Q4ODg2ODExYzQ2NzQ1ZTMwNTQ3MGVkZGZmMy5tcDQ/0.flac?token=1774810051~NGIyY2ZjMTkyMTRiZTZjYWFkNDFmMTQ4OWI1NmFkN2EyYTU3OTNlNQ==","trackType":"tidal"} Mar 29 12:49:10 volumio volumio[1380]: verbose: CURRENT POSITION 18 Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus play Mar 29 12:49:10 volumio volumio[1380]: info: Received an update from plugin. extracting info from payload Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:10 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:10 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:10 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:10 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:10 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:10 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:10 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:10.966-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:49:10 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:10.966-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/354205264 title="Good Luck, Babe!" Mar 29 12:49:10 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:10.967-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:49:10 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:10.968-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/354205264 title="Good Luck, Babe!" Mar 29 12:49:10 volumio volumio[1380]: info: ------------------------------ 9ms Mar 29 12:49:10 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:49:10 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:49:10 volumio sudo[18981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:49:10 volumio sudo[18981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:10 volumio sudo[18984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:49:11 volumio sudo[18984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:11 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 29 12:49:11 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:49:11 volumio sudo[18981]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:11 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:49:11 volumio sudo[18984]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:11 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:49:11 volumio volumio[18990]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:49:11 volumio volumio[18990]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:49:21 volumio volumio[1380]: info: Preload queue cleared Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::ClearQueue Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::stop Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::stPlaybackTimer Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:21 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:21 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::serviceStop Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrack 18 Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::serviceStop Mar 29 12:49:21 volumio volumio[1380]: info: [1774806561360] ControllerTidal::stop Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:49:21 volumio volumio[1380]: info: ControllerMpd::stop Mar 29 12:49:21 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::clearPlayQueue Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::saveQueue Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::addQueueItems Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::addQueueItems Mar 29 12:49:21 volumio volumio[1380]: info: Preload queue cleared Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/384275179 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/384275179 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/354365598 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/354365598 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/572131 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/572131 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/95488050 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/95488050 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/40617348 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/40617348 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/86974592 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/86974592 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/229888181 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/229888181 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/158002 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/158002 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1274623 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1274623 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/31402063 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/31402063 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/238082518 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/238082518 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/77168304 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/77168304 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/26543730 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/26543730 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/5120012 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/5120012 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/3826837 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/3826837 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/45155848 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/45155848 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/3267925 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/3267925 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/2420807 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/2420807 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/354205264 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/354205264 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/77630365 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/77630365 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/182206206 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/182206206 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/19373935 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/19373935 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/95384245 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/95384245 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1781812 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1781812 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/26948458 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/26948458 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1852096 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1852096 Mar 29 12:49:21 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:21.363-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 12:49:21 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:21.363-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/354205264 title="Good Luck, Babe!" Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::saveQueue Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioPlay Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::play index 25 Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::addQueueItems Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::addQueueItems Mar 29 12:49:21 volumio volumio[1380]: info: Preload queue cleared Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/233064405 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/233064405 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1852403 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1852403 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/100578190 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/100578190 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/64629993 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/64629993 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/105828757 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/105828757 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1886608 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1886608 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/392116481 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/392116481 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/97552225 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/97552225 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/371774475 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/371774475 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/25029974 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/25029974 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/62522013 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/62522013 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/99179014 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/99179014 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/4071713 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/4071713 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/100577909 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/100577909 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/323717055 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/323717055 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/208697704 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/208697704 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1995774 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1995774 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/81126405 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/81126405 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/218002 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/218002 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/35708001 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/35708001 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1637465 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1637465 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/19442765 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/19442765 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/19994081 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/19994081 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/190725736 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/190725736 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/15920382 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/15920382 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/26948462 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/26948462 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/391722 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/391722 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1350767 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1350767 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1290351 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1290351 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/1781801 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/1781801 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/292582 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/292582 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/238082391 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/238082391 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/3383233 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/3383233 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/66063742 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/66063742 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/121092018 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/121092018 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/4075303 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/4075303 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/6508567 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/6508567 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/26542853 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/26542853 Mar 29 12:49:21 volumio volumio[1380]: info: Adding Item to queue: tidal://mymusic/tracks/az/4530888 Mar 29 12:49:21 volumio volumio[1380]: info: Using cached record of: tidal://mymusic/tracks/az/4530888 Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::stop Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::saveQueue Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::play index undefined Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::startPlaybackTimer Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:21 volumio volumio[1380]: info: [1774806561381] ControllerTidal::clearAddPlayTrack Mar 29 12:49:21 volumio volumio[1380]: info: Getting stream with soundQuality LOSSLESS Mar 29 12:49:21 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:49:21 volumio volumio[1380]: info: Mar 29 12:49:21 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:49:21 volumio volumio[1380]: info: sendMpdCommand stop took 41 milliseconds Mar 29 12:49:21 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:49:21 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:49:21 volumio sudo[19051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:49:21 volumio sudo[19051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:21 volumio volumio[1380]: info: sendMpdCommand status took 2 milliseconds Mar 29 12:49:21 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:49:21 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:49:21 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 12:49:21 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:49:21 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:49:21 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:21 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:21 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:21 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:21 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:21 volumio volumio[1380]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Mar 29 12:49:21 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:21.411-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=22 volume=100 Mar 29 12:49:21 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:21.411-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/1852096 title="I Will Remember" Mar 29 12:49:21 volumio volumio[1380]: info: ------------------------------ 12ms Mar 29 12:49:21 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Mar 29 12:49:21 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:49:21 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 160 seconds. Mar 29 12:49:21 volumio sudo[19056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:49:21 volumio sudo[19056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:21 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Mar 29 12:49:21 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:49:21 volumio systemd[1]: peppymeterbasic.service: Consumed 4.962s CPU time. Mar 29 12:49:21 volumio sudo[19051]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:21 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:49:21 volumio sudo[19056]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:21 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:49:22 volumio volumio[1380]: info: getStreamUrl took 1144 milliseconds Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:49:22 volumio volumio[1380]: info: sendMpdCommand stop took 0 milliseconds Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 12:49:22 volumio volumio[1380]: info: sendMpdCommand clear took 1 milliseconds Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhODVkN2Y0MTM0YmY5ZmU3YjAxZTY0Y2RmYjA1MDZlYV82MS5tcDQ/0.flac?token=1774810162~NTdlMzE4MGIxODZjZTY5MzcxMGRlMmNkMTc0ZTM1YjQwZDA4MDVlOA==" Mar 29 12:49:22 volumio volumio[1380]: info: Mar 29 12:49:22 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:49:22 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:49:22 volumio volumio[1380]: info: Mar 29 12:49:22 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:49:22 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:49:22 volumio volumio[1380]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhODVkN2Y0MTM0YmY5ZmU3YjAxZTY0Y2RmYjA1MDZlYV82MS5tcDQ/0.flac?token=1774810162~NTdlMzE4MGIxODZjZTY5MzcxMGRlMmNkMTc0ZTM1YjQwZDA4MDVlOA==" took 1 milliseconds Mar 29 12:49:22 volumio volumio[1380]: info: ------------------------------ 1ms Mar 29 12:49:22 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand play Mar 29 12:49:22 volumio volumio[1380]: info: ------------------------------ 1ms Mar 29 12:49:22 volumio volumio[1380]: info: sendMpdCommand play took 1 milliseconds Mar 29 12:49:22 volumio volumio[1380]: info: Mar 29 12:49:22 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:49:22 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:49:22 volumio volumio[1380]: info: Mar 29 12:49:22 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:49:22 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:49:22 volumio volumio[1380]: info: sendMpdCommand status took 1 milliseconds Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:49:22 volumio volumio[1380]: info: sendMpdCommand status took 0 milliseconds Mar 29 12:49:22 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:49:22 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:49:22 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:49:22 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:22 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":366,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774810162~NTdlMzE4MGIxODZjZTY5MzcxMGRlMmNkMTc0ZTM1YjQwZDA4MDVlOA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhODVkN2Y0MTM0YmY5ZmU3YjAxZTY0Y2RmYjA1MDZlYV82MS5tcDQ/0.flac?token=1774810162~NTdlMzE4MGIxODZjZTY5MzcxMGRlMmNkMTc0ZTM1YjQwZDA4MDVlOA==","trackType":"tidal"} Mar 29 12:49:22 volumio volumio[1380]: verbose: CURRENT POSITION 25 Mar 29 12:49:22 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:49:22 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus stop Mar 29 12:49:22 volumio volumio[1380]: info: ------------------------------ 2ms Mar 29 12:49:22 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 12:49:22 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:49:22 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:49:22 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:49:22 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:22 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":366,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774810162~NTdlMzE4MGIxODZjZTY5MzcxMGRlMmNkMTc0ZTM1YjQwZDA4MDVlOA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhODVkN2Y0MTM0YmY5ZmU3YjAxZTY0Y2RmYjA1MDZlYV82MS5tcDQ/0.flac?token=1774810162~NTdlMzE4MGIxODZjZTY5MzcxMGRlMmNkMTc0ZTM1YjQwZDA4MDVlOA==","trackType":"tidal"} Mar 29 12:49:22 volumio volumio[1380]: verbose: CURRENT POSITION 25 Mar 29 12:49:22 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:49:22 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus play Mar 29 12:49:22 volumio volumio[1380]: info: Received an update from plugin. extracting info from payload Mar 29 12:49:22 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:22 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:49:22 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:22 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:22 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:22 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:22 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:22 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:22 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:22 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:22 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:22 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:22.646-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:49:22 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:22.646-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:49:22 volumio volumio[1380]: info: ------------------------------ 7ms Mar 29 12:49:22 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:22.647-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/1852096 title="I Will Remember" Mar 29 12:49:22 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:22.647-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/1852096 title="I Will Remember" Mar 29 12:49:22 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:49:22 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:49:22 volumio sudo[19070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:49:22 volumio sudo[19070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:22 volumio sudo[19072]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:49:22 volumio sudo[19072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:22 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 29 12:49:22 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:49:22 volumio sudo[19070]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:22 volumio sudo[19072]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:22 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:49:22 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:49:23 volumio volumio[19079]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:49:23 volumio volumio[19079]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:49:29 volumio volumio[1380]: info: Retrieving Cloud Streaming UI Mar 29 12:49:29 volumio volumio[1380]: info: Getting Tidal Cloud Configuration Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:49:29 volumio volumio[1380]: info: Getting Qobuz Cloud Configuration Mar 29 12:49:29 volumio volumio[1380]: info: Asking plugin for UI Config Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:49:29 volumio volumio[1380]: info: Getting Spotify Cloud Configuration Mar 29 12:49:29 volumio volumio[1380]: info: Asking plugin for UI Config Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:49:29 volumio volumio[1380]: info: Saving Spotify Acccount Mar 29 12:49:29 volumio volumio[1380]: info: Got it Mar 29 12:49:29 volumio volumio[1380]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 12:49:29 volumio volumio[1380]: info: Got it Mar 29 12:49:29 volumio volumio[1380]: info: Got Tidal Cloud Configuration Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 12:49:29 volumio volumio[1380]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 12:49:30 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 12:49:30 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 12:49:34 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 12:49:39 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 12:49:42 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 12:49:43 volumio volumio[1380]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Mar 29 12:49:43 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Mar 29 12:49:43 volumio volumio[1380]: info: Saving configuration Mar 29 12:49:43 volumio volumio[1380]: info: Done Mar 29 12:49:44 volumio volumio[1380]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Mar 29 12:49:44 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Mar 29 12:49:44 volumio volumio[1380]: info: Saving configuration Mar 29 12:49:44 volumio volumio[1380]: info: Done Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::volumioNext Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::next Mar 29 12:49:47 volumio volumio[1380]: info: [1774806587218] ControllerTidal::next Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::next Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::stop Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::stPlaybackTimer Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:47 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:47 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::serviceStop Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 25 Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::serviceStop Mar 29 12:49:47 volumio volumio[1380]: info: [1774806587220] ControllerTidal::stop Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:49:47 volumio volumio[1380]: info: ControllerMpd::stop Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:49:47 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:47.224-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 12:49:47 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:47.224-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/1852096 title="I Will Remember" Mar 29 12:49:47 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:49:47 volumio sudo[19169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:49:47 volumio sudo[19169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:47 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 160 seconds. Mar 29 12:49:47 volumio volumio[1380]: info: Mar 29 12:49:47 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand stop took 31 milliseconds Mar 29 12:49:47 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::play index undefined Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::startPlaybackTimer Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:49:47 volumio volumio[1380]: info: [1774806587252] ControllerTidal::clearAddPlayTrack Mar 29 12:49:47 volumio volumio[1380]: info: Getting stream with soundQuality HI_RES Mar 29 12:49:47 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand status took 5 milliseconds Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:49:47 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:47 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:47 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:49:47 volumio volumio[1380]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Mar 29 12:49:47 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:47.259-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 12:49:47 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:47.259-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/233064405 title="If You Ever Change Your Mind" Mar 29 12:49:47 volumio volumio[1380]: info: ------------------------------ 9ms Mar 29 12:49:47 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:49:47 volumio sudo[19174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:49:47 volumio sudo[19174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:47 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Mar 29 12:49:47 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:49:47 volumio systemd[1]: peppymeterbasic.service: Consumed 10.112s CPU time. Mar 29 12:49:47 volumio sudo[19169]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:47 volumio sudo[19174]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:47 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:49:47 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:49:47 volumio volumio[1380]: info: getStreamUrl took 206 milliseconds Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand stop took 0 milliseconds Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 12:49:47 volumio volumio[1380]: info: Mar 29 12:49:47 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:49:47 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand clear took 0 milliseconds Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2NGIyOTUxOGM1MDMwYzUxZGIzYzBjNmE2Yzg1YjNkMi5tcDQ/0.flac?token=1774810187~YTRjYzFjOTU5YjMwNzNkZmQwMjEyYjA5MWRlNmIyNWQxZTFmYWJlZA==" Mar 29 12:49:47 volumio volumio[1380]: error: updateQueue error: null Mar 29 12:49:47 volumio volumio[1380]: info: ------------------------------ 1ms Mar 29 12:49:47 volumio volumio[1380]: info: Mar 29 12:49:47 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:49:47 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2NGIyOTUxOGM1MDMwYzUxZGIzYzBjNmE2Yzg1YjNkMi5tcDQ/0.flac?token=1774810187~YTRjYzFjOTU5YjMwNzNkZmQwMjEyYjA5MWRlNmIyNWQxZTFmYWJlZA==" took 1 milliseconds Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand play Mar 29 12:49:47 volumio volumio[1380]: info: ------------------------------ 0ms Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand play took 0 milliseconds Mar 29 12:49:47 volumio volumio[1380]: info: Mar 29 12:49:47 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:49:47 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:49:47 volumio volumio[1380]: info: Mar 29 12:49:47 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand status took 25 milliseconds Mar 29 12:49:47 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand status took 0 milliseconds Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:49:47 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:49:47 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774810187~YTRjYzFjOTU5YjMwNzNkZmQwMjEyYjA5MWRlNmIyNWQxZTFmYWJlZA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2NGIyOTUxOGM1MDMwYzUxZGIzYzBjNmE2Yzg1YjNkMi5tcDQ/0.flac?token=1774810187~YTRjYzFjOTU5YjMwNzNkZmQwMjEyYjA5MWRlNmIyNWQxZTFmYWJlZA==","trackType":"tidal"} Mar 29 12:49:47 volumio volumio[1380]: verbose: CURRENT POSITION 26 Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus stop Mar 29 12:49:47 volumio volumio[1380]: info: ------------------------------ 25ms Mar 29 12:49:47 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 12:49:47 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:49:47 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:49:47 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:49:47 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":206,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774810187~YTRjYzFjOTU5YjMwNzNkZmQwMjEyYjA5MWRlNmIyNWQxZTFmYWJlZA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ2NGIyOTUxOGM1MDMwYzUxZGIzYzBjNmE2Yzg1YjNkMi5tcDQ/0.flac?token=1774810187~YTRjYzFjOTU5YjMwNzNkZmQwMjEyYjA5MWRlNmIyNWQxZTFmYWJlZA==","trackType":"tidal"} Mar 29 12:49:47 volumio volumio[1380]: verbose: CURRENT POSITION 26 Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus play Mar 29 12:49:47 volumio volumio[1380]: info: Received an update from plugin. extracting info from payload Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:47 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:47 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:47 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:49:47 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:49:47 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:49:47 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:47 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:47.558-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:49:47 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:47.558-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:49:47 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:47.558-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/233064405 title="If You Ever Change Your Mind" Mar 29 12:49:47 volumio volumio5-onboarding[1999]: time=2026-03-29T12:49:47.558-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/233064405 title="If You Ever Change Your Mind" Mar 29 12:49:47 volumio volumio[1380]: info: ------------------------------ 12ms Mar 29 12:49:47 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:49:47 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:49:47 volumio sudo[19183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:49:47 volumio sudo[19183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:47 volumio sudo[19185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:49:47 volumio sudo[19185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:47 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 29 12:49:47 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:49:47 volumio sudo[19183]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:47 volumio sudo[19185]: pam_unix(sudo:session): session closed for user root Mar 29 12:49:47 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:49:47 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:49:48 volumio volumio[19192]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:49:48 volumio volumio[19192]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:50:01 volumio volumio[1380]: info: CoreCommandRouter::volumioNext Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::next Mar 29 12:50:01 volumio volumio[1380]: info: [1774806601604] ControllerTidal::next Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::next Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::stop Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::stPlaybackTimer Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:50:01 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:50:01 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:01 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:01 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:01 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::serviceStop Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrack 26 Mar 29 12:50:01 volumio volumio[1380]: info: CoreCommandRouter::serviceStop Mar 29 12:50:01 volumio volumio[1380]: info: [1774806601606] ControllerTidal::stop Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:50:01 volumio volumio[1380]: info: ControllerMpd::stop Mar 29 12:50:01 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:50:01 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:01.607-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 12:50:01 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:01.607-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/233064405 title="If You Ever Change Your Mind" Mar 29 12:50:01 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:50:01 volumio sudo[19260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:50:01 volumio sudo[19260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:01 volumio volumio[1380]: info: Mar 29 12:50:01 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:50:01 volumio volumio[1380]: info: sendMpdCommand stop took 19 milliseconds Mar 29 12:50:01 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:50:01 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::play index undefined Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::startPlaybackTimer Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:01 volumio volumio[1380]: info: [1774806601626] ControllerTidal::clearAddPlayTrack Mar 29 12:50:01 volumio volumio[1380]: info: Getting stream with soundQuality HI_RES Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:50:01 volumio volumio[1380]: info: sendMpdCommand status took 6 milliseconds Mar 29 12:50:01 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:50:01 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:50:01 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 160 seconds. Mar 29 12:50:01 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 29 12:50:01 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:50:01 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:50:01 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:50:01 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:01 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:50:01 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:01 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:01 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:01 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:01 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:01 volumio volumio[1380]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Mar 29 12:50:01 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:01.637-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=8 volume=100 Mar 29 12:50:01 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:01.637-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/1852403 title="Into Your Arms" Mar 29 12:50:01 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Mar 29 12:50:01 volumio volumio[1380]: info: ------------------------------ 14ms Mar 29 12:50:01 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:50:01 volumio sudo[19267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:50:01 volumio sudo[19267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:01 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Mar 29 12:50:01 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:50:01 volumio systemd[1]: peppymeterbasic.service: Consumed 6.017s CPU time. Mar 29 12:50:01 volumio sudo[19260]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:01 volumio sudo[19267]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:01 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:50:01 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:50:02 volumio volumio[1380]: info: getStreamUrl took 527 milliseconds Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:50:02 volumio volumio[1380]: info: sendMpdCommand stop took 1 milliseconds Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 12:50:02 volumio volumio[1380]: info: Mar 29 12:50:02 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:50:02 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:50:02 volumio volumio[1380]: info: sendMpdCommand clear took 1 milliseconds Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlODkyNzI4MmM1MmZlNjhmYWYzYWRhMDYzOGU5NmNkNF82MS5tcDQ/0.flac?token=1774810202~Y2Y1Y2VmNGZhZmQxZjY4MzEzOThhOWQwYjMzNGU2ZjM4MGE0N2Q3OQ==" Mar 29 12:50:02 volumio volumio[1380]: error: updateQueue error: null Mar 29 12:50:02 volumio volumio[1380]: info: Mar 29 12:50:02 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:50:02 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:50:02 volumio volumio[1380]: info: ------------------------------ 1ms Mar 29 12:50:02 volumio volumio[1380]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlODkyNzI4MmM1MmZlNjhmYWYzYWRhMDYzOGU5NmNkNF82MS5tcDQ/0.flac?token=1774810202~Y2Y1Y2VmNGZhZmQxZjY4MzEzOThhOWQwYjMzNGU2ZjM4MGE0N2Q3OQ==" took 0 milliseconds Mar 29 12:50:02 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand play Mar 29 12:50:02 volumio volumio[1380]: info: ------------------------------ 1ms Mar 29 12:50:02 volumio volumio[1380]: info: sendMpdCommand play took 1 milliseconds Mar 29 12:50:02 volumio volumio[1380]: info: Mar 29 12:50:02 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:50:02 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:50:02 volumio volumio[1380]: info: Mar 29 12:50:02 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:50:02 volumio volumio[1380]: info: sendMpdCommand status took 38 milliseconds Mar 29 12:50:02 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:50:02 volumio volumio[1380]: info: sendMpdCommand status took 1 milliseconds Mar 29 12:50:02 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:50:02 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:50:02 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:50:02 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:02 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774810202~Y2Y1Y2VmNGZhZmQxZjY4MzEzOThhOWQwYjMzNGU2ZjM4MGE0N2Q3OQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlODkyNzI4MmM1MmZlNjhmYWYzYWRhMDYzOGU5NmNkNF82MS5tcDQ/0.flac?token=1774810202~Y2Y1Y2VmNGZhZmQxZjY4MzEzOThhOWQwYjMzNGU2ZjM4MGE0N2Q3OQ==","trackType":"tidal"} Mar 29 12:50:02 volumio volumio[1380]: verbose: CURRENT POSITION 27 Mar 29 12:50:02 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:50:02 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus stop Mar 29 12:50:02 volumio volumio[1380]: info: ------------------------------ 41ms Mar 29 12:50:02 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 12:50:02 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:50:02 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:50:02 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:50:02 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:02 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774810202~Y2Y1Y2VmNGZhZmQxZjY4MzEzOThhOWQwYjMzNGU2ZjM4MGE0N2Q3OQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlODkyNzI4MmM1MmZlNjhmYWYzYWRhMDYzOGU5NmNkNF82MS5tcDQ/0.flac?token=1774810202~Y2Y1Y2VmNGZhZmQxZjY4MzEzOThhOWQwYjMzNGU2ZjM4MGE0N2Q3OQ==","trackType":"tidal"} Mar 29 12:50:02 volumio volumio[1380]: verbose: CURRENT POSITION 27 Mar 29 12:50:02 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:50:02 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus play Mar 29 12:50:02 volumio volumio[1380]: info: Received an update from plugin. extracting info from payload Mar 29 12:50:02 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:02 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:50:02 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:02 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:02 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:02 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:02 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:02 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:02 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:02 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:02 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:02 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:02.414-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:50:02 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:02.414-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:50:02 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:02.414-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/1852403 title="Into Your Arms" Mar 29 12:50:02 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:02.414-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/1852403 title="Into Your Arms" Mar 29 12:50:02 volumio volumio[1380]: info: ------------------------------ 11ms Mar 29 12:50:02 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:50:02 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:50:02 volumio sudo[19275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:50:02 volumio sudo[19275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:02 volumio sudo[19277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:50:02 volumio sudo[19277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:02 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 29 12:50:02 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:50:02 volumio sudo[19275]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:02 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:50:02 volumio sudo[19277]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:02 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:50:03 volumio volumio[19284]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:50:03 volumio volumio[19284]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:50:04 volumio volumio[1380]: info: CoreCommandRouter::volumioNext Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::next Mar 29 12:50:04 volumio volumio[1380]: info: [1774806604556] ControllerTidal::next Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::next Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::stop Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::stPlaybackTimer Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:04 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:50:04 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:04 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:04 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:04 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::serviceStop Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrack 27 Mar 29 12:50:04 volumio volumio[1380]: info: CoreCommandRouter::serviceStop Mar 29 12:50:04 volumio volumio[1380]: info: [1774806604558] ControllerTidal::stop Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:50:04 volumio volumio[1380]: info: ControllerMpd::stop Mar 29 12:50:04 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:50:04 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:04.560-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 12:50:04 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:04.563-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/1852403 title="Into Your Arms" Mar 29 12:50:04 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:50:04 volumio sudo[19298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:50:04 volumio sudo[19298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:04 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 160 seconds. Mar 29 12:50:04 volumio volumio[1380]: info: Mar 29 12:50:04 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:50:04 volumio volumio[1380]: info: sendMpdCommand stop took 28 milliseconds Mar 29 12:50:04 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:50:04 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::play index undefined Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrack 28 Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::startPlaybackTimer Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrack 28 Mar 29 12:50:04 volumio volumio[1380]: info: [1774806604586] ControllerTidal::clearAddPlayTrack Mar 29 12:50:04 volumio volumio[1380]: info: Getting stream with soundQuality HI_RES Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock Mar 29 12:50:04 volumio volumio[1380]: info: sendMpdCommand status took 2 milliseconds Mar 29 12:50:04 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:50:04 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:50:04 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 12:50:04 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:50:04 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:50:04 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:50:04 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrack 28 Mar 29 12:50:04 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:50:04 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:04 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:04 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:04 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrack 28 Mar 29 12:50:04 volumio volumio[1380]: info: CorePlayQueue::getTrack 28 Mar 29 12:50:04 volumio volumio[1380]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Mar 29 12:50:04 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:04.591-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 12:50:04 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:04.591-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/100578190 title="It's Not Enough" Mar 29 12:50:04 volumio volumio[1380]: info: ------------------------------ 6ms Mar 29 12:50:04 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 29 12:50:04 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Mar 29 12:50:04 volumio sudo[19305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:50:04 volumio sudo[19305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:04 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Mar 29 12:50:04 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:50:04 volumio systemd[1]: peppymeterbasic.service: Consumed 1.555s CPU time. Mar 29 12:50:04 volumio sudo[19305]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:04 volumio sudo[19298]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:04 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:50:04 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:50:05 volumio volumio[1380]: info: getStreamUrl took 1115 milliseconds Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 12:50:05 volumio volumio[1380]: info: sendMpdCommand stop took 0 milliseconds Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 12:50:05 volumio volumio[1380]: info: Mar 29 12:50:05 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:50:05 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:50:05 volumio volumio[1380]: info: sendMpdCommand clear took 1 milliseconds Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNTk0N2ZhNTEwZjVkMmQzZTVlMTBkYTI1NTRjYzViYy5tcDQ/0.flac?token=1774810205~NzcyMWExNTU5ZmRhMDFhYzMyNWQ5NDBmNTA0NGRmZjZhZDExZjExZg==" Mar 29 12:50:05 volumio volumio[1380]: error: updateQueue error: null Mar 29 12:50:05 volumio volumio[1380]: info: Mar 29 12:50:05 volumio volumio[1380]: ---------------------------- MPD announces system playlist update Mar 29 12:50:05 volumio volumio[1380]: info: Ignoring MPD Status Update Mar 29 12:50:05 volumio volumio[1380]: info: ------------------------------ 1ms Mar 29 12:50:05 volumio volumio[1380]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNTk0N2ZhNTEwZjVkMmQzZTVlMTBkYTI1NTRjYzViYy5tcDQ/0.flac?token=1774810205~NzcyMWExNTU5ZmRhMDFhYzMyNWQ5NDBmNTA0NGRmZjZhZDExZjExZg==" took 0 milliseconds Mar 29 12:50:05 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand play Mar 29 12:50:05 volumio volumio[1380]: info: ------------------------------ 1ms Mar 29 12:50:05 volumio volumio[1380]: info: sendMpdCommand play took 1 milliseconds Mar 29 12:50:05 volumio volumio[1380]: info: Mar 29 12:50:05 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:50:05 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:50:05 volumio volumio[1380]: info: Mar 29 12:50:05 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:50:05 volumio volumio[1380]: info: sendMpdCommand status took 37 milliseconds Mar 29 12:50:05 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:50:05 volumio volumio[1380]: info: sendMpdCommand status took 1 milliseconds Mar 29 12:50:05 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:50:05 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:50:05 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:50:05 volumio volumio[1380]: info: CorePlayQueue::getTrack 28 Mar 29 12:50:05 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1774810205~NzcyMWExNTU5ZmRhMDFhYzMyNWQ5NDBmNTA0NGRmZjZhZDExZjExZg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNTk0N2ZhNTEwZjVkMmQzZTVlMTBkYTI1NTRjYzViYy5tcDQ/0.flac?token=1774810205~NzcyMWExNTU5ZmRhMDFhYzMyNWQ5NDBmNTA0NGRmZjZhZDExZjExZg==","trackType":"tidal"} Mar 29 12:50:05 volumio volumio[1380]: verbose: CURRENT POSITION 28 Mar 29 12:50:05 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:50:05 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus stop Mar 29 12:50:05 volumio volumio[1380]: info: ------------------------------ 38ms Mar 29 12:50:05 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 12:50:05 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:50:05 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:50:05 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:50:05 volumio volumio[1380]: info: CorePlayQueue::getTrack 28 Mar 29 12:50:05 volumio volumio[1380]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":290,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"380 Kbps","isStreaming":false,"title":"0.flac?token=1774810205~NzcyMWExNTU5ZmRhMDFhYzMyNWQ5NDBmNTA0NGRmZjZhZDExZjExZg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNTk0N2ZhNTEwZjVkMmQzZTVlMTBkYTI1NTRjYzViYy5tcDQ/0.flac?token=1774810205~NzcyMWExNTU5ZmRhMDFhYzMyNWQ5NDBmNTA0NGRmZjZhZDExZjExZg==","trackType":"tidal"} Mar 29 12:50:05 volumio volumio[1380]: verbose: CURRENT POSITION 28 Mar 29 12:50:05 volumio volumio[1380]: info: CoreStateMachine::syncState stateService play Mar 29 12:50:05 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus play Mar 29 12:50:05 volumio volumio[1380]: info: Received an update from plugin. extracting info from payload Mar 29 12:50:05 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:05 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:50:05 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:05 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:05 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:05 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:05 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:05 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:05 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:05 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:05 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:05 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:05.790-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:50:05 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:05.790-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 12:50:05 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:05.790-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/100578190 title="It's Not Enough" Mar 29 12:50:05 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:05.790-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/100578190 title="It's Not Enough" Mar 29 12:50:05 volumio volumio[1380]: info: ------------------------------ 9ms Mar 29 12:50:05 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:50:05 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 29 12:50:05 volumio sudo[19317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:50:05 volumio sudo[19317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:05 volumio sudo[19319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 29 12:50:05 volumio sudo[19319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:05 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 29 12:50:05 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:50:05 volumio sudo[19319]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:05 volumio sudo[19317]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:05 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:50:05 volumio volumio[1380]: info: peppymeterbasic Daemon Started Mar 29 12:50:06 volumio volumio[19326]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:50:06 volumio volumio[19326]: libpng warning: iCCP: known incorrect sRGB profile Mar 29 12:50:07 volumio volumio[1380]: info: CoreCommandRouter::volumioPause Mar 29 12:50:07 volumio volumio[1380]: info: CoreStateMachine::pause Mar 29 12:50:07 volumio volumio[1380]: info: CoreStateMachine::stPlaybackTimer Mar 29 12:50:07 volumio volumio[1380]: info: CoreStateMachine::servicePause Mar 29 12:50:07 volumio volumio[1380]: info: CorePlayQueue::getTrack 28 Mar 29 12:50:07 volumio volumio[1380]: info: CoreCommandRouter::servicePause Mar 29 12:50:07 volumio volumio[1380]: info: [1774806607532] ControllerTidal::pause Mar 29 12:50:07 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 12:50:07 volumio volumio[1380]: info: ControllerMpd::pause Mar 29 12:50:07 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand pause Mar 29 12:50:07 volumio volumio[1380]: info: Mar 29 12:50:07 volumio volumio[1380]: ---------------------------- MPD announces state update: player Mar 29 12:50:07 volumio volumio[1380]: info: sendMpdCommand pause took 2 milliseconds Mar 29 12:50:07 volumio volumio[1380]: info: ControllerMpd::getState Mar 29 12:50:07 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand status Mar 29 12:50:07 volumio volumio[1380]: info: sendMpdCommand status took 0 milliseconds Mar 29 12:50:07 volumio volumio[1380]: verbose: ControllerMpd::parseState Mar 29 12:50:07 volumio volumio[1380]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 12:50:07 volumio volumio[1380]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 12:50:07 volumio volumio[1380]: verbose: ControllerMpd::parseTrackInfo Mar 29 12:50:07 volumio volumio[1380]: info: ControllerMpd::pushState Mar 29 12:50:07 volumio volumio[1380]: info: CoreCommandRouter::servicePushState Mar 29 12:50:07 volumio volumio[1380]: info: CorePlayQueue::getTrack 28 Mar 29 12:50:07 volumio volumio[1380]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3094,"duration":290,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"845 Kbps","isStreaming":false,"title":"0.flac?token=1774810205~NzcyMWExNTU5ZmRhMDFhYzMyNWQ5NDBmNTA0NGRmZjZhZDExZjExZg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyNTk0N2ZhNTEwZjVkMmQzZTVlMTBkYTI1NTRjYzViYy5tcDQ/0.flac?token=1774810205~NzcyMWExNTU5ZmRhMDFhYzMyNWQ5NDBmNTA0NGRmZjZhZDExZjExZg==","trackType":"tidal"} Mar 29 12:50:07 volumio volumio[1380]: verbose: CURRENT POSITION 28 Mar 29 12:50:07 volumio volumio[1380]: info: CoreStateMachine::syncState stateService pause Mar 29 12:50:07 volumio volumio[1380]: info: CoreStateMachine::syncState currentStatus pause Mar 29 12:50:07 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:07 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:50:07 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:07 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:07 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:07 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:07 volumio volumio[1380]: info: CoreStateMachine::stPlaybackTimer Mar 29 12:50:07 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:07.538-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PAUSED positionMs=1751 volume=100 Mar 29 12:50:07 volumio volumio[1380]: info: ------------------------------ 5ms Mar 29 12:50:07 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:07.539-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/100578190 title="It's Not Enough" Mar 29 12:50:07 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status pause Mar 29 12:50:07 volumio sudo[19338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:50:07 volumio sudo[19338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:07 volumio volumio[1380]: info: touch_display: Setting screensaver timeout to 160 seconds. Mar 29 12:50:07 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Mar 29 12:50:07 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Mar 29 12:50:07 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Mar 29 12:50:07 volumio systemd[1]: peppymeterbasic.service: Consumed 1.082s CPU time. Mar 29 12:50:07 volumio sudo[19338]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:07 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:50:17 volumio volumio[1380]: info: Getting Alsa Cards List without I2S DAC Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 12:50:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 12:50:23 volumio volumio[1380]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 29 12:50:23 volumio volumio[1380]: info: Preparing to save Alsa Options, stopping services first Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::volumioPause Mar 29 12:50:23 volumio volumio[1380]: info: CoreStateMachine::pause Mar 29 12:50:23 volumio volumio[1380]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":true,"i2sid":{"value":"volumio-zero","label":"Volumio Zero ES9039 DAC"}} Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: Enabling I2S DAC: Volumio Zero ES9039 DAC Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio sudo[19393]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 29 12:50:23 volumio sudo[19393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19393]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: No Overlays Loaded Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio sudo[19396]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay es9039q2m-i2s Mar 29 12:50:23 volumio sudo[19396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2s@a0000/status Mar 29 12:50:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/compatible Mar 29 12:50:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/i2s-controller Mar 29 12:50:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/status Mar 29 12:50:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/simple-audio-card,name Mar 29 12:50:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/simple-audio-card,format Mar 29 12:50:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/simple-audio-card,bitclock-master Mar 29 12:50:23 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/simple-audio-card,frame-master Mar 29 12:50:23 volumio sudo[19404]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -y 1 0x48 0x00 0x02 Mar 29 12:50:23 volumio volumio[1380]: warn: Unable to locate the device HDMI 0 Out a reboot may be required. Mar 29 12:50:23 volumio sudo[19404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19396]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio sudo[19404]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: Setting default mixerSoftMaster for Softvolume device Mar 29 12:50:23 volumio volumio[1380]: info: QobuzConnect: setDeactiveState invoked Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:23 volumio volumio[1380]: info: Relinquishing Volumio State Mar 29 12:50:23 volumio sudo[19421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:50:23 volumio sudo[19421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 29 12:50:23 volumio volumio[1380]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 29 12:50:23 volumio sudo[19421]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 29 12:50:23 volumio volumio[1380]: info: Disabling external Volume Control Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:50:23 volumio volumio[1380]: info: Enable softmixer device for audio device undefined Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Mar 29 12:50:23 volumio sudo[19435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:50:23 volumio sudo[19435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio volumio[1380]: info: CoreStateMachine::pushState Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState Mar 29 12:50:23 volumio sudo[19435]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:50:23 volumio volumio[1380]: info: MRS: Pushing multiroomSync output Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:23 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:23.159-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" state=STATUS_PAUSED positionMs=2671 volume=100 Mar 29 12:50:23 volumio volumio5-onboarding[1999]: time=2026-03-29T12:50:23.159-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42134,00:00:00:00:00:00%02 @ 0x1fc3260" id=tidal://song/100578190 title="It's Not Enough" Mar 29 12:50:23 volumio sudo[19442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 29 12:50:23 volumio sudo[19442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio volumio[1380]: info: Data script executed Mar 29 12:50:23 volumio volumio[1380]: info: I2S Param [object Object] successfully enabled Mar 29 12:50:23 volumio volumio[1380]: info: PeppyMeterBasic ---peppymeterbasic status pause Mar 29 12:50:23 volumio sudo[19442]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: Software Volume ALSA configuration written Mar 29 12:50:23 volumio sudo[19446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 29 12:50:23 volumio sudo[19446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio volumio[1380]: info: Preparing to generate the ALSA configuration file Mar 29 12:50:23 volumio sudo[19447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 29 12:50:23 volumio sudo[19447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:50:23 volumio volumio[1380]: info: Getting Alsa Cards List without I2S DAC Mar 29 12:50:23 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 29 12:50:23 volumio qobuz-connect[2359]: 20260329 12:50:23.212 [2359.2359] INFO SampleApp: Stopping Local configuration server Mar 29 12:50:23 volumio sudo[19446]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 12:50:23 volumio volumio[1380]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 29 12:50:23 volumio volumio[1380]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 29 12:50:23 volumio volumio[1380]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 29 12:50:23 volumio volumio[1380]: info: Reading ALSA contributions from plugins. Mar 29 12:50:23 volumio volumio[1380]: info: peppymeterbasic Daemon Stop Mar 29 12:50:23 volumio volumio[1380]: info: Asound.conf file written Mar 29 12:50:23 volumio sudo[19466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 29 12:50:23 volumio sudo[19466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19466]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 29 12:50:23 volumio volumio[1380]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 29 12:50:23 volumio volumio[1380]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 29 12:50:23 volumio volumio[1380]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Mar 29 12:50:23 volumio volumio[1380]: No state is present for card Loopback Mar 29 12:50:23 volumio volumio[1380]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Mar 29 12:50:23 volumio volumio[1380]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Mar 29 12:50:23 volumio volumio[1380]: Hardware is initialized using a generic method Mar 29 12:50:23 volumio volumio[1380]: No state is present for card Loopback Mar 29 12:50:23 volumio volumio[1380]: info: Output device has changed, restarting MPD Mar 29 12:50:23 volumio volumio[1380]: info: Output device has changed, restarting Shairport Sync Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio sudo[19473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 12:50:23 volumio sudo[19473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 12:50:23 volumio sudo[19473]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio sudo[19474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio volumio[1380]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 12:50:23 volumio volumio[1380]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: QobuzConnect: setDeactiveState invoked Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:23 volumio volumio[1380]: info: Relinquishing Volumio State Mar 29 12:50:23 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 29 12:50:23 volumio volumio[1380]: info: Output device has changed, restarting MPD Mar 29 12:50:23 volumio sudo[19484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:50:23 volumio sudo[19484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio volumio[1380]: info: Output device has changed, restarting Shairport Sync Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 29 12:50:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 12:50:23 volumio systemd[1]: mpd.service: Consumed 43.319s CPU time. Mar 29 12:50:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 29 12:50:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 12:50:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 12:50:23 volumio sudo[19489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 12:50:23 volumio sudo[19489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 12:50:23 volumio sudo[19487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19487]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 12:50:23 volumio volumio[1380]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: QobuzConnect: setDeactiveState invoked Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:23 volumio volumio[1380]: info: Relinquishing Volumio State Mar 29 12:50:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 12:50:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 12:50:23 volumio sudo[19502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:50:23 volumio sudo[19500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:50:23 volumio sudo[19502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:50:23 volumio sudo[19507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 29 12:50:23 volumio sudo[19513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 29 12:50:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 12:50:23 volumio volumio[1380]: info: MPD Permissions set Mar 29 12:50:23 volumio volumio[1380]: info: MPD Permissions set Mar 29 12:50:23 volumio sudo[19484]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio sudo[19513]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio sudo[19521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 29 12:50:23 volumio sudo[19521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 29 12:50:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 12:50:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 12:50:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 12:50:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio sudo[19525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 29 12:50:23 volumio sudo[19525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19521]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio sudo[19507]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio sudo[19502]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio sudo[19500]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio sudo[19533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 29 12:50:23 volumio sudo[19533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:23 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:50:23 volumio sudo[19531]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 12:50:23 volumio sudo[19531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 12:50:23 volumio sudo[19531]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: Starting Shairport Sync Mar 29 12:50:23 volumio volumio[1380]: info: Starting Shairport Sync Mar 29 12:50:23 volumio sudo[19547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 12:50:23 volumio sudo[19547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio sudo[19549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 12:50:23 volumio sudo[19549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 12:50:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 12:50:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 12:50:23 volumio systemd[1]: shairport-sync.service: Consumed 8.238s CPU time. Mar 29 12:50:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 12:50:23 volumio sudo[19547]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio sudo[19549]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: info: Shairport-Sync Started Mar 29 12:50:23 volumio volumio[1380]: info: Shairport-Sync Started Mar 29 12:50:23 volumio mpd[19544]: 2026-03-29T12:50:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 12:50:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 29 12:50:23 volumio sudo[19489]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio sudo[19474]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:23 volumio volumio[1380]: error: updateQueue error: null Mar 29 12:50:23 volumio volumio[1380]: error: updateQueue error: null Mar 29 12:50:24 volumio volumio[1380]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Mar 29 12:50:24 volumio volumio[1380]: info: PLUGIN onReboot : networkfs Mar 29 12:50:24 volumio volumio[1380]: info: PLUGIN onReboot : touch_display Mar 29 12:50:24 volumio sudo[19570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Mar 29 12:50:24 volumio sudo[19570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:24 volumio startx[1856]: xinit: connection to X server lost Mar 29 12:50:24 volumio systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Mar 29 12:50:24 volumio startx[1856]: Mar 29 12:50:24 volumio sudo[19577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot Mar 29 12:50:24 volumio sudo[19577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:24 volumio systemd-logind[1010]: The system will reboot now! Mar 29 12:50:24 volumio systemd-logind[1010]: System is rebooting. Mar 29 12:50:24 volumio startx[1856]: waiting for X server to shut down Mar 29 12:50:24 volumio startx[1859]: (II) Server terminated successfully (0). Closing log file. Mar 29 12:50:24 volumio sudo[19525]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:24 volumio sudo[19577]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:24 volumio sudo[19533]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:24 volumio sudo[19447]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:24 volumio volumio[1380]: error: QobuzConnect: could not execute /bin/systemctl to restart qobuz connect process. Reason: Job for qobuz-connect.service canceled. Mar 29 12:50:24 volumio volumio[1380]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 12:50:24 volumio volumio[1380]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "undefined".] { Mar 29 12:50:24 volumio volumio[1380]: code: 'ERR_UNHANDLED_REJECTION' Mar 29 12:50:24 volumio volumio[1380]: } Mar 29 12:50:24 volumio volumio[1380]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 12:50:24 volumio systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. Mar 29 12:50:24 volumio systemd[1]: Stopped target bluetooth.target - Bluetooth Support. Mar 29 12:50:24 volumio systemd[1]: Stopped target graphical.target - Graphical Interface. Mar 29 12:50:24 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 29 12:50:24 volumio systemd[1]: Stopped target rpc_pipefs.target. Mar 29 12:50:24 volumio systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. Mar 29 12:50:24 volumio systemd[1]: Stopped target timers.target - Timer Units. Mar 29 12:50:24 volumio systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Mar 29 12:50:24 volumio systemd[1]: apt-daily.timer: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped apt-daily.timer - Daily apt download activities. Mar 29 12:50:24 volumio systemd[1]: dpkg-db-backup.timer: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. Mar 29 12:50:24 volumio systemd[1]: e2scrub_all.timer: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Mar 29 12:50:24 volumio systemd[1]: fstrim.timer: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. Mar 29 12:50:24 volumio systemd[1]: man-db.timer: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped man-db.timer - Daily man-db regeneration. Mar 29 12:50:24 volumio systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Mar 29 12:50:24 volumio systemd[1]: setdatetime-helper.timer: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Mar 29 12:50:24 volumio systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 29 12:50:24 volumio systemd[1]: systemd-rfkill.socket: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 29 12:50:24 volumio systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... Mar 29 12:50:24 volumio systemd[1]: Stopping bluealsa.service - BlueALSA service... Mar 29 12:50:24 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 29 12:50:24 volumio systemd[1]: rpi-display-backlight.service - Turns off Raspberry Pi display backlight on shutdown/reboot was skipped because of an unmet condition check (ConditionPathIsDirectory=/proc/device-tree/rpi_backlight). Mar 29 12:50:24 volumio autossh[2419]: received signal to exit (15) Mar 29 12:50:24 volumio bluealsa[1135]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Mar 29 12:50:24 volumio systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Mar 29 12:50:24 volumio bluetoothd[996]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Mar 29 12:50:24 volumio bluetoothd[996]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Mar 29 12:50:24 volumio systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... Mar 29 12:50:24 volumio bluetoothd[996]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Mar 29 12:50:24 volumio systemd[1]: Stopping upower.service - Daemon for power management... Mar 29 12:50:24 volumio bluetoothd[996]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Mar 29 12:50:24 volumio systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... Mar 29 12:50:24 volumio systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Mar 29 12:50:24 volumio volumiobt[19596]: INFO [BTSTART] Disconnecting all Bluetooth devices... Mar 29 12:50:24 volumio systemd[1]: bluealsa.service: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped bluealsa.service - BlueALSA service. Mar 29 12:50:24 volumio systemd[1]: upower.service: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped upower.service - Daemon for power management. Mar 29 12:50:24 volumio systemd[1]: volumio5-onboarding.service: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 29 12:50:24 volumio systemd[1]: volumio5-onboarding.service: Consumed 3.868s CPU time. Mar 29 12:50:24 volumio systemd[1]: sshtunnel.service: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Mar 29 12:50:24 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 12:50:24 volumio systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. Mar 29 12:50:24 volumio systemd[1]: systemd-random-seed.service: Deactivated successfully. Mar 29 12:50:24 volumio systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. Mar 29 12:50:24 volumio bluetoothd[996]: Path / reserved for Adv Monitor app :1.48 Mar 29 12:50:24 volumio bluetoothd[996]: Adv Monitor app :1.48 disconnected from D-Bus Mar 29 12:50:24 volumio volumiobt[19605]: Attempting to disconnect from 59:C0:37:9B:D8:58 Mar 29 12:50:24 volumio volumiobt[19605]: [NEW] Media /org/bluez/hci0 Mar 29 12:50:24 volumio volumiobt[19605]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Mar 29 12:50:24 volumio volumiobt[19605]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Mar 29 12:50:24 volumio volumiobt[19605]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Mar 29 12:50:24 volumio bluetoothd[996]: Path / reserved for Adv Monitor app :1.49 Mar 29 12:50:24 volumio volumiobt[19605]: AdvertisementMonitor path registered Mar 29 12:50:24 volumio sudo[19608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 12:49' Mar 29 12:50:24 volumio sudo[19608]: 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"