May 30 16:26:07 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 30 16:26:07 volumio volumio[2235]: info: browseTIDALUri took 296 milliseconds May 30 16:26:07 volumio volumio[2235]: info: Preload queue cleared May 30 16:26:07 volumio volumio[2235]: info: Preload queue cleared May 30 16:26:10 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 30 16:26:10 volumio volumio[2235]: info: browseTIDALUri took 353 milliseconds May 30 16:26:11 volumio volumio[2235]: info: Preload queue cleared May 30 16:26:11 volumio volumio[2235]: info: Preload queue cleared May 30 16:26:14 volumio volumio[2235]: info: Preload queue cleared May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::ClearQueue May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::stop May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::stPlaybackTimer May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::updateTrackBlock May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrackBlock May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::serviceStop May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::serviceStop May 30 16:26:14 volumio volumio[2235]: info: ControllerMpd::stop May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::clearPlayQueue May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::saveQueue May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushQueue May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::addQueueItems May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::addQueueItems May 30 16:26:14 volumio volumio[2235]: info: Preload queue cleared May 30 16:26:14 volumio volumio[2235]: info: Adding Item to queue: tidal://mymusic/playlists/az/47c87f28-a9e2-477d-907b-b4d61041b6bd May 30 16:26:14 volumio volumio[2235]: info: Exploding uri tidal://mymusic/playlists/az/47c87f28-a9e2-477d-907b-b4d61041b6bd in service tidal May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.455+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.455+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=http://stream-uk1.radioparadise.com/aac-128 title="Radio Paradise Main Mix (AAC 128k)" May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio volumio[2235]: info: May 30 16:26:14 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:14 volumio volumio[2235]: info: sendMpdCommand stop took 7 milliseconds May 30 16:26:14 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/0.jpg","uri":"http://stream.radioparadise.com/radio2050-flacm","name":null,"title":null,"artist":"","album":"The Universe Smiles Upon You ii","streaming":true,"disableUiControls":true,"duration":10,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 30 16:26:14 volumio volumio[2235]: verbose: CURRENT POSITION 0 May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::syncState stateService stop May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus stop May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: No code May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.464+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.465+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.465+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.465+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id= title= May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.465+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id= title= May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.465+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id= title= May 30 16:26:14 volumio sudo[40109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio sudo[40109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio volumio[2235]: info: May 30 16:26:14 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:14 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:14 volumio volumio[2235]: info: sendMpdCommand status took 4 milliseconds May 30 16:26:14 volumio volumio[2235]: info: sendMpdCommand status took 0 milliseconds May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio sudo[40112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio sudo[40112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 17 milliseconds May 30 16:26:14 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 18 milliseconds May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:14 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio sudo[40114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Police - Invisible Sun","artist":"Radio Paradise (128k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-128","trackType":""} May 30 16:26:14 volumio volumio[2235]: verbose: CURRENT POSITION 0 May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::syncState stateService stop May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus stop May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio sudo[40114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: No code May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Police - Invisible Sun","artist":"Radio Paradise (128k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-128","trackType":""} May 30 16:26:14 volumio volumio[2235]: verbose: CURRENT POSITION 0 May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::syncState stateService stop May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus stop May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: No code May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:14 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.489+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.489+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id= title= May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.489+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.489+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id= title= May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.490+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.490+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id= title= May 30 16:26:14 volumio sudo[40117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.490+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.490+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.490+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id= title= May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.490+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id= title= May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.490+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:14 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:14.490+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id= title= May 30 16:26:14 volumio sudo[40117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio volumio[2235]: info: ------------------------------ 30ms May 30 16:26:14 volumio volumio[2235]: info: ------------------------------ 26ms May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:14 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio sudo[40121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio sudo[40121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio sudo[40124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio sudo[40124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio sudo[40126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio sudo[40126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:14 volumio sudo[40129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio sudo[40129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio sudo[40132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio sudo[40132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio sudo[40135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:14 volumio sudo[40135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:14 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. May 30 16:26:14 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. May 30 16:26:14 volumio systemd[1]: peppymeterbasic.service: Consumed 1.095s CPU time. May 30 16:26:14 volumio sudo[40126]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio sudo[40129]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio sudo[40112]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio sudo[40124]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio sudo[40121]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio sudo[40117]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio sudo[40132]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio sudo[40114]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio sudo[40135]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio sudo[40109]: pam_unix(sudo:session): session closed for user root May 30 16:26:14 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:14 volumio volumio[2235]: info: explodeTIDALUri took 301 milliseconds May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPushQueue May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::saveQueue May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::updateTrackBlock May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrackBlock May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 30 16:26:14 volumio volumio[2235]: info: CoreCommandRouter::volumioPlay May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::play index 0 May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::stop May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::play index undefined May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::startPlaybackTimer May 30 16:26:14 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:14 volumio volumio[2235]: info: [1780151174759] ControllerTidal::clearAddPlayTrack May 30 16:26:14 volumio volumio[2235]: info: Getting stream with soundQuality HI_RES May 30 16:26:14 volumio volumio[2235]: info: getStreamUrl took 164 milliseconds May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:26:14 volumio volumio[2235]: info: sendMpdCommand stop took 0 milliseconds May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand clear May 30 16:26:14 volumio volumio[2235]: info: May 30 16:26:14 volumio volumio[2235]: ---------------------------- MPD announces system playlist update May 30 16:26:14 volumio volumio[2235]: info: Ignoring MPD Status Update May 30 16:26:14 volumio volumio[2235]: info: sendMpdCommand clear took 0 milliseconds May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MTI3MTFlMjg2MmE1YWU0YjY2YjlhMmU1ZjEwZGE3Yl82MS5tcDQ/0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==" May 30 16:26:14 volumio volumio[2235]: info: May 30 16:26:14 volumio volumio[2235]: ---------------------------- MPD announces system playlist update May 30 16:26:14 volumio volumio[2235]: info: Ignoring MPD Status Update May 30 16:26:14 volumio volumio[2235]: error: updateQueue error: null May 30 16:26:14 volumio volumio[2235]: info: May 30 16:26:14 volumio volumio[2235]: ---------------------------- MPD announces system playlist update May 30 16:26:14 volumio volumio[2235]: info: Ignoring MPD Status Update May 30 16:26:14 volumio volumio[2235]: info: ------------------------------ 0ms May 30 16:26:14 volumio volumio[2235]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MTI3MTFlMjg2MmE1YWU0YjY2YjlhMmU1ZjEwZGE3Yl82MS5tcDQ/0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==" took 0 milliseconds May 30 16:26:14 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 16:26:14 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand play May 30 16:26:14 volumio volumio[2235]: info: ------------------------------ 0ms May 30 16:26:14 volumio volumio[2235]: info: ------------------------------ 0ms May 30 16:26:14 volumio volumio[2235]: info: May 30 16:26:14 volumio volumio[2235]: ---------------------------- MPD announces system playlist update May 30 16:26:14 volumio volumio[2235]: info: Ignoring MPD Status Update May 30 16:26:14 volumio volumio[2235]: info: sendMpdCommand play took 1 milliseconds May 30 16:26:14 volumio volumio[2235]: info: ------------------------------ 1ms May 30 16:26:15 volumio volumio[2235]: info: May 30 16:26:15 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:15 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:15 volumio volumio[2235]: info: May 30 16:26:15 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:15 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:15 volumio volumio[2235]: info: May 30 16:26:15 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:15 volumio volumio[2235]: info: sendMpdCommand status took 2 milliseconds May 30 16:26:15 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:15 volumio volumio[2235]: info: May 30 16:26:15 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:15 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:15 volumio volumio[2235]: info: sendMpdCommand status took 2 milliseconds May 30 16:26:15 volumio volumio[2235]: info: sendMpdCommand status took 0 milliseconds May 30 16:26:15 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 0 milliseconds May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:15 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:15 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:15 volumio volumio[2235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MTI3MTFlMjg2MmE1YWU0YjY2YjlhMmU1ZjEwZGE3Yl82MS5tcDQ/0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==","trackType":"tidal"} May 30 16:26:15 volumio volumio[2235]: verbose: CURRENT POSITION 0 May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus stop May 30 16:26:15 volumio volumio[2235]: info: ------------------------------ 2ms May 30 16:26:15 volumio volumio[2235]: info: sendMpdCommand status took 1 milliseconds May 30 16:26:15 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 16:26:15 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:15 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:15 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:15 volumio volumio[2235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MTI3MTFlMjg2MmE1YWU0YjY2YjlhMmU1ZjEwZGE3Yl82MS5tcDQ/0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==","trackType":"tidal"} May 30 16:26:15 volumio volumio[2235]: verbose: CURRENT POSITION 0 May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus play May 30 16:26:15 volumio volumio[2235]: info: Received an update from plugin. extracting info from payload May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:15 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:15 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:15 volumio volumio[2235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MTI3MTFlMjg2MmE1YWU0YjY2YjlhMmU1ZjEwZGE3Yl82MS5tcDQ/0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==","trackType":"tidal"} May 30 16:26:15 volumio volumio[2235]: verbose: CURRENT POSITION 0 May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus play May 30 16:26:15 volumio volumio[2235]: info: Received an update from plugin. extracting info from payload May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.016+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.016+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.016+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/143145364 title="Sphère et solo" May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.016+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/143145364 title="Sphère et solo" May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.016+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.016+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.016+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/143145364 title="Sphère et solo" May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.016+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/143145364 title="Sphère et solo" May 30 16:26:15 volumio volumio[2235]: info: ------------------------------ 7ms May 30 16:26:15 volumio volumio[2235]: info: ------------------------------ 5ms May 30 16:26:15 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 16:26:15 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:15 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:15 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:15 volumio volumio[2235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2MTI3MTFlMjg2MmE1YWU0YjY2YjlhMmU1ZjEwZGE3Yl82MS5tcDQ/0.flac?token=1780154774~MGM2NGRmZmQ2YTFlN2EzMGY3MmI4MTg5Y2YxYjdiOTM4NDYxODQ1MA==","trackType":"tidal"} May 30 16:26:15 volumio volumio[2235]: verbose: CURRENT POSITION 0 May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus play May 30 16:26:15 volumio volumio[2235]: info: Received an update from plugin. extracting info from payload May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:15 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:15 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:15 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.019+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.019+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.020+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/143145364 title="Sphère et solo" May 30 16:26:15 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:15.020+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/143145364 title="Sphère et solo" May 30 16:26:15 volumio volumio[2235]: info: ------------------------------ 7ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:15 volumio volumio[2235]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:15 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:15 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:15 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:15 volumio sudo[40143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:15 volumio sudo[40143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:15 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:15 volumio sudo[40145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:15 volumio sudo[40145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:15 volumio sudo[40148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:15 volumio sudo[40148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:15 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:15 volumio sudo[40151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:15 volumio sudo[40151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:15 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:15 volumio sudo[40156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:15 volumio sudo[40156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:15 volumio sudo[40159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:15 volumio sudo[40159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:15 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. May 30 16:26:15 volumio sudo[40143]: pam_unix(sudo:session): session closed for user root May 30 16:26:15 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:15 volumio sudo[40148]: pam_unix(sudo:session): session closed for user root May 30 16:26:15 volumio sudo[40145]: pam_unix(sudo:session): session closed for user root May 30 16:26:15 volumio sudo[40156]: pam_unix(sudo:session): session closed for user root May 30 16:26:15 volumio sudo[40151]: pam_unix(sudo:session): session closed for user root May 30 16:26:15 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:15 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:15 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:15 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:15 volumio sudo[40159]: pam_unix(sudo:session): session closed for user root May 30 16:26:15 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - ---- read samplerate from file: 44100 May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms May 30 16:26:15 volumio volumio[2235]: info: camilladsp respawn in 100 ms (attempt 1/10) May 30 16:26:15 volumio volumio[2235]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 30 16:26:15 volumio volumio[40168]: libpng warning: iCCP: known incorrect sRGB profile May 30 16:26:15 volumio volumio[40168]: libpng warning: iCCP: known incorrect sRGB profile May 30 16:26:15 volumio volumio[40168]: libpng warning: iCCP: known incorrect sRGB profile May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:17 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:26 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioNext May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::next May 30 16:26:28 volumio volumio[2235]: info: [1780151188597] ControllerTidal::next May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::next May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::stop May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::stPlaybackTimer May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::updateTrackBlock May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrackBlock May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::serviceStop May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 0 May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::serviceStop May 30 16:26:28 volumio volumio[2235]: info: [1780151188598] ControllerTidal::stop May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::stop May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.599+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.599+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/143145364 title="Sphère et solo" May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:28 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand stop took 6 milliseconds May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::play index undefined May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::startPlaybackTimer May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: info: [1780151188604] ControllerTidal::clearAddPlayTrack May 30 16:26:28 volumio volumio[2235]: info: Getting stream with soundQuality HI_RES May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::updateTrackBlock May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrackBlock May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:28 volumio sudo[40233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:28 volumio sudo[40233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand status took 5 milliseconds May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand status took 4 milliseconds May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 0 milliseconds May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 0 milliseconds May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 8ms May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 7ms May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.612+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.612+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_STOPPED positionMs=0 volume=57 May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.612+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.612+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:28 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... May 30 16:26:28 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:28 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status stop May 30 16:26:28 volumio sudo[40236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:28 volumio sudo[40236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:28 volumio sudo[40238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:28 volumio sudo[40238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:28 volumio volumio[2235]: info: Executing endpoint metavolumio May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 16:26:28 volumio volumio[2235]: info: Executing endpoint metavolumio May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 16:26:28 volumio volumio[2235]: info: Executing endpoint metavolumio May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 16:26:28 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. May 30 16:26:28 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. May 30 16:26:28 volumio sudo[40238]: pam_unix(sudo:session): session closed for user root May 30 16:26:28 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:28 volumio sudo[40233]: pam_unix(sudo:session): session closed for user root May 30 16:26:28 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:28 volumio sudo[40236]: pam_unix(sudo:session): session closed for user root May 30 16:26:28 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:28 volumio volumio[2235]: info: getStreamUrl took 247 milliseconds May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand stop May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand stop took 0 milliseconds May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand clear May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces system playlist update May 30 16:26:28 volumio volumio[2235]: info: Ignoring MPD Status Update May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand clear took 0 milliseconds May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==" May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces system playlist update May 30 16:26:28 volumio volumio[2235]: info: Ignoring MPD Status Update May 30 16:26:28 volumio volumio[2235]: error: updateQueue error: null May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces system playlist update May 30 16:26:28 volumio volumio[2235]: info: Ignoring MPD Status Update May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 1ms May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==" took 1 milliseconds May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 1ms May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand play May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces system playlist update May 30 16:26:28 volumio volumio[2235]: info: Ignoring MPD Status Update May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 0ms May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand play took 0 milliseconds May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 0ms May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand status took 1 milliseconds May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:28 volumio volumio[2235]: info: May 30 16:26:28 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand status took 1 milliseconds May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand status took 0 milliseconds May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 0 milliseconds May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: 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=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","trackType":"tidal"} May 30 16:26:28 volumio volumio[2235]: verbose: CURRENT POSITION 1 May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus stop May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 2ms May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand status took 2 milliseconds May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: 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=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","trackType":"tidal"} May 30 16:26:28 volumio volumio[2235]: verbose: CURRENT POSITION 1 May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus play May 30 16:26:28 volumio volumio[2235]: info: Received an update from plugin. extracting info from payload May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: 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=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","trackType":"tidal"} May 30 16:26:28 volumio volumio[2235]: verbose: CURRENT POSITION 1 May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus play May 30 16:26:28 volumio volumio[2235]: info: Received an update from plugin. extracting info from payload May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.968+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.968+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.968+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.968+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.968+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.968+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.968+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.968+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 7ms May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 6ms May 30 16:26:28 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 16:26:28 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:28 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:28 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:28 volumio volumio[2235]: 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=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","trackType":"tidal"} May 30 16:26:28 volumio volumio[2235]: verbose: CURRENT POSITION 1 May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus play May 30 16:26:28 volumio volumio[2235]: info: Received an update from plugin. extracting info from payload May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:28 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:28 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.972+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.972+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.972+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=0 volume=57 May 30 16:26:28 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:28.972+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:28 volumio volumio[2235]: info: ------------------------------ 9ms May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:28 volumio volumio[2235]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:28 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:28 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:28 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:28 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:28 volumio sudo[40245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:28 volumio sudo[40245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:28 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:28 volumio sudo[40247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:28 volumio sudo[40247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:28 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:28 volumio sudo[40250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:28 volumio sudo[40250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:28 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:28 volumio sudo[40253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:28 volumio sudo[40253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:28 volumio sudo[40257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:29 volumio sudo[40257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:29 volumio sudo[40260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:29 volumio sudo[40260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:29 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. May 30 16:26:29 volumio sudo[40245]: pam_unix(sudo:session): session closed for user root May 30 16:26:29 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:29 volumio sudo[40250]: pam_unix(sudo:session): session closed for user root May 30 16:26:29 volumio sudo[40247]: pam_unix(sudo:session): session closed for user root May 30 16:26:29 volumio sudo[40257]: pam_unix(sudo:session): session closed for user root May 30 16:26:29 volumio sudo[40253]: pam_unix(sudo:session): session closed for user root May 30 16:26:29 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:29 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:29 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:29 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:29 volumio sudo[40260]: pam_unix(sudo:session): session closed for user root May 30 16:26:29 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - ---- read samplerate from file: 44100 May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 30 16:26:29 volumio volumio[2235]: info: camilladsp respawn in 200 ms (attempt 2/10) May 30 16:26:29 volumio volumio[40270]: libpng warning: iCCP: known incorrect sRGB profile May 30 16:26:29 volumio volumio[40270]: libpng warning: iCCP: known incorrect sRGB profile May 30 16:26:29 volumio volumio[2235]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 30 16:26:29 volumio volumio[2235]: error: FusionDsp - Reload WebSocket error: [object Object] May 30 16:26:29 volumio volumio[40270]: libpng warning: iCCP: known incorrect sRGB profile May 30 16:26:30 volumio volumio[2235]: info: Executing endpoint metavolumio May 30 16:26:30 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 16:26:30 volumio volumio[2235]: info: Executing endpoint metavolumio May 30 16:26:30 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 16:26:30 volumio volumio[2235]: info: Executing endpoint metavolumio May 30 16:26:30 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 16:26:31 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:43 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:45 volumio volumio[2235]: info: Executing endpoint metavolumio May 30 16:26:45 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 16:26:45 volumio volumio[2235]: info: Executing endpoint metavolumio May 30 16:26:45 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 16:26:45 volumio volumio[2235]: info: Executing endpoint metavolumio May 30 16:26:45 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 30 16:26:47 volumio volumio[2235]: info: CoreCommandRouter::volumioPause May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::pause May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::stPlaybackTimer May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::servicePause May 30 16:26:47 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:47 volumio volumio[2235]: info: CoreCommandRouter::servicePause May 30 16:26:47 volumio volumio[2235]: info: [1780151207462] ControllerTidal::pause May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 16:26:47 volumio volumio[2235]: info: ControllerMpd::pause May 30 16:26:47 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand pause May 30 16:26:47 volumio volumio[2235]: info: May 30 16:26:47 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:47 volumio volumio[2235]: info: sendMpdCommand pause took 0 milliseconds May 30 16:26:47 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:47 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:47 volumio volumio[2235]: info: May 30 16:26:47 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:47 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:47 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:47 volumio volumio[2235]: info: sendMpdCommand status took 1 milliseconds May 30 16:26:47 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:47 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:47 volumio volumio[2235]: info: sendMpdCommand status took 1 milliseconds May 30 16:26:47 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:47 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:47 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 16:26:47 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:47 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:47 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:47 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:47 volumio volumio[2235]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20348,"duration":366,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"884 Kbps","isStreaming":false,"title":"0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","trackType":"tidal"} May 30 16:26:47 volumio volumio[2235]: verbose: CURRENT POSITION 1 May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::syncState stateService pause May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus pause May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:47 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:47 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:47 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:47 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:47 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::stPlaybackTimer May 30 16:26:47 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:47.465+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PAUSED positionMs=18512 volume=57 May 30 16:26:47 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:47.465+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:47 volumio volumio[2235]: info: ------------------------------ 3ms May 30 16:26:47 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 16:26:47 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:47 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:47 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:47 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:47 volumio volumio[2235]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20348,"duration":366,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"884 Kbps","isStreaming":false,"title":"0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","trackType":"tidal"} May 30 16:26:47 volumio volumio[2235]: verbose: CURRENT POSITION 1 May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::syncState stateService pause May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus pause May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:47 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:47 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:47 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:47 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:47 volumio volumio[2235]: info: CoreStateMachine::stPlaybackTimer May 30 16:26:47 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:47.466+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PAUSED positionMs=18512 volume=57 May 30 16:26:47 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:47.466+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:47 volumio volumio[2235]: info: ------------------------------ 5ms May 30 16:26:47 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:47 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:47 volumio volumio[2235]: info: FusionDsp - Volumio is not playing May 30 16:26:47 volumio volumio[2235]: info: FusionDsp - Clipped samples monitor stopped May 30 16:26:47 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status pause May 30 16:26:47 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status pause May 30 16:26:47 volumio sudo[40339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:47 volumio sudo[40339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:47 volumio sudo[40342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 30 16:26:47 volumio sudo[40342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:47 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... May 30 16:26:47 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. May 30 16:26:47 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. May 30 16:26:47 volumio sudo[40339]: pam_unix(sudo:session): session closed for user root May 30 16:26:47 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:47 volumio sudo[40342]: pam_unix(sudo:session): session closed for user root May 30 16:26:47 volumio volumio[2235]: info: peppymeterbasic Daemon Stop May 30 16:26:48 volumio volumio[2235]: info: camilladsp respawn in 400 ms (attempt 3/10) May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::volumioPlay May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::play index undefined May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 16:26:48 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::startPlaybackTimer May 30 16:26:48 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:48 volumio volumio[2235]: info: [1780151208974] ControllerTidal::resume May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 16:26:48 volumio volumio[2235]: info: ControllerMpd::resume May 30 16:26:48 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand play May 30 16:26:48 volumio volumio[2235]: info: May 30 16:26:48 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:48 volumio volumio[2235]: info: sendMpdCommand play took 2 milliseconds May 30 16:26:48 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:48 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:48 volumio volumio[2235]: info: May 30 16:26:48 volumio volumio[2235]: ---------------------------- MPD announces state update: player May 30 16:26:48 volumio volumio[2235]: info: ControllerMpd::getState May 30 16:26:48 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand status May 30 16:26:48 volumio volumio[2235]: info: sendMpdCommand status took 0 milliseconds May 30 16:26:48 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:48 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:48 volumio volumio[2235]: info: sendMpdCommand status took 0 milliseconds May 30 16:26:48 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 0 milliseconds May 30 16:26:48 volumio volumio[2235]: verbose: ControllerMpd::parseState May 30 16:26:48 volumio volumio[2235]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 16:26:48 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:48 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:48 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:48 volumio volumio[2235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21579,"duration":366,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1015 Kbps","isStreaming":false,"title":"0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","trackType":"tidal"} May 30 16:26:48 volumio volumio[2235]: verbose: CURRENT POSITION 1 May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus pause May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:48 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:48 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:48 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:48.977+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=18938 volume=57 May 30 16:26:48 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:48.978+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:48 volumio volumio[2235]: info: ------------------------------ 2ms May 30 16:26:48 volumio volumio[2235]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 16:26:48 volumio volumio[2235]: verbose: ControllerMpd::parseTrackInfo May 30 16:26:48 volumio volumio[2235]: info: ControllerMpd::pushState May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::servicePushState May 30 16:26:48 volumio volumio[2235]: info: CorePlayQueue::getTrack 1 May 30 16:26:48 volumio volumio[2235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21579,"duration":366,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1015 Kbps","isStreaming":false,"title":"0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidhZTUyMzk3NzhjNGYwZmVlMDZmY2Q0N2Y3NzY0ZWJhNl82MS5tcDQ/0.flac?token=1780154788~MjBmNzZhYzc2NTJiZjI5YTcyYTU2YTFiZjExNmQwZDIwZTU5ZmRmNg==","trackType":"tidal"} May 30 16:26:48 volumio volumio[2235]: verbose: CURRENT POSITION 1 May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::syncState stateService play May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::syncState currentStatus play May 30 16:26:48 volumio volumio[2235]: info: Received an update from plugin. extracting info from payload May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:48 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:48 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:48 volumio volumio[2235]: info: CoreStateMachine::pushState May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::volumioPushState May 30 16:26:48 volumio volumio[2235]: info: MRS: Pushing multiroomSync output update for this device May 30 16:26:48 volumio volumio[2235]: info: MRS: Pushing multiroomSync output May 30 16:26:48 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:26:48 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:48.982+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=18938 volume=57 May 30 16:26:48 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:48.982+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" state=STATUS_PLAYING positionMs=18938 volume=57 May 30 16:26:48 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:48.982+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:48 volumio volumio5-onboarding[1705]: time=2026-05-30T16:26:48.982+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.140:55326,00:00:00:00:00:00%09 @ 0xc00042b080" id=tidal://song/7047133 title="Too Darn Hot" May 30 16:26:48 volumio volumio[2235]: info: ------------------------------ 6ms May 30 16:26:48 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:48 volumio volumio[2235]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 30 16:26:48 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:48 volumio volumio[2235]: info: FusionDsp - Volumio is playing May 30 16:26:48 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:48 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:48 volumio volumio[2235]: info: PeppyMeterBasic ---peppymeterbasic status play May 30 16:26:48 volumio sudo[40357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:48 volumio sudo[40357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:48 volumio sudo[40359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:48 volumio sudo[40359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:48 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:48 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:48 volumio volumio[2235]: info: FusionDsp - Clipping Monitor started May 30 16:26:48 volumio sudo[40362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 30 16:26:49 volumio sudo[40362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 16:26:49 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. May 30 16:26:49 volumio sudo[40357]: pam_unix(sudo:session): session closed for user root May 30 16:26:49 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:49 volumio sudo[40359]: pam_unix(sudo:session): session closed for user root May 30 16:26:49 volumio sudo[40362]: pam_unix(sudo:session): session closed for user root May 30 16:26:49 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:49 volumio volumio[2235]: info: peppymeterbasic Daemon Started May 30 16:26:49 volumio volumio[2235]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 30 16:26:49 volumio volumio[2235]: info: FusionDsp - ---- read samplerate from file: 44100 May 30 16:26:49 volumio volumio[2235]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 30 16:26:49 volumio volumio[40370]: libpng warning: iCCP: known incorrect sRGB profile May 30 16:26:49 volumio volumio[40370]: libpng warning: iCCP: known incorrect sRGB profile May 30 16:26:49 volumio volumio[40370]: libpng warning: iCCP: known incorrect sRGB profile May 30 16:27:02 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 30 16:27:02 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 30 16:27:02 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 30 16:27:10 volumio volumio5-onboarding[1705]: time=2026-05-30T16:27:10.855+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.140:55326 May 30 16:27:10 volumio volumio5-onboarding[1705]: time=2026-05-30T16:27:10.855+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.140:55326 May 30 16:27:10 volumio volumio5-onboarding[1705]: time=2026-05-30T16:27:10.876+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.140:55329 May 30 16:27:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 16:27:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 16:27:14 volumio volumio[2235]: info: Discovery: Getting this device information May 30 16:27:14 volumio volumio[2235]: info: CoreCommandRouter::volumioGetState May 30 16:27:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 16:27:14 volumio volumio[2235]: verbose: New Socket.io Connection to 192.168.0.99:3000 from 192.168.0.140 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 May 30 16:27:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 16:27:14 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 16:27:52 volumio volumio[2235]: info: CALLMETHOD: user_interface peppymeterbasic updatelist May 30 16:27:52 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist May 30 16:27:52 volumio volumio[2235]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' May 30 16:27:53 volumio volumio[2235]: --2026-05-30 16:27:52-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt May 30 16:27:53 volumio volumio[2235]: Resolving github.com (github.com)... 140.82.121.3 May 30 16:27:53 volumio volumio[2235]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. May 30 16:27:53 volumio volumio[2235]: HTTP request sent, awaiting response... 302 Found May 30 16:27:53 volumio volumio[2235]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] May 30 16:27:53 volumio volumio[2235]: --2026-05-30 16:27:53-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt May 30 16:27:53 volumio volumio[2235]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.108.133, 185.199.109.133, ... May 30 16:27:53 volumio volumio[2235]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. May 30 16:27:53 volumio volumio[2235]: HTTP request sent, awaiting response... 200 OK May 30 16:27:53 volumio volumio[2235]: Length: 362 [text/plain] May 30 16:27:53 volumio volumio[2235]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ May 30 16:27:53 volumio volumio[2235]: 0K 100% 21.8M=0s May 30 16:27:53 volumio volumio[2235]: 2026-05-30 16:27:53 (21.8 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] May 30 16:27:53 volumio volumio[2235]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 16:27:53 volumio volumio[2235]: info: CoreCommandRouter::Close All Modals sent May 30 16:27:55 volumio volumio[2235]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] May 30 16:27:55 volumio volumio[2235]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter May 30 16:27:56 volumio volumio[2235]: --2026-05-30 16:27:55-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose May 30 16:27:56 volumio volumio[2235]: Resolving github.com (github.com)... 140.82.121.3 May 30 16:27:56 volumio volumio[2235]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. May 30 16:27:56 volumio volumio[2235]: HTTP request sent, awaiting response... 404 Not Found May 30 16:27:56 volumio volumio[2235]: 2026-05-30 16:27:56 ERROR 404: Not Found. May 30 16:27:56 volumio volumio[2235]: --2026-05-30 16:27:56-- http://a/ May 30 16:27:56 volumio volumio[2235]: Resolving a (a)... failed: Device or resource busy. May 30 16:27:56 volumio volumio[2235]: wget: unable to resolve host address ‘a’ May 30 16:27:56 volumio volumio[2235]: --2026-05-30 16:27:56-- http://packet.zip/ May 30 16:27:56 volumio volumio[2235]: Resolving packet.zip (packet.zip)... 162.255.119.167 May 30 16:27:56 volumio volumio[2235]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. May 30 16:27:56 volumio volumio[2235]: HTTP request sent, awaiting response... 302 Found May 30 16:27:56 volumio volumio[2235]: Location: http://www.packet.zip/ [following] May 30 16:27:56 volumio volumio[2235]: --2026-05-30 16:27:56-- http://www.packet.zip/ May 30 16:27:56 volumio volumio[2235]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 May 30 16:27:56 volumio volumio[2235]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. May 30 16:27:56 volumio volumio[2235]: HTTP request sent, awaiting response... 403 Forbidden May 30 16:27:56 volumio volumio[2235]: 2026-05-30 16:27:56 ERROR 403: Forbidden. May 30 16:27:56 volumio volumio[2235]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters May 30 16:27:56 volumio volumio[2235]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory May 30 16:27:56 volumio volumio[2235]: /bin/rm: cannot remove 'a': No such file or directory May 30 16:27:56 volumio volumio[2235]: /bin/rm: cannot remove 'packet.zip*': No such file or directory May 30 16:27:56 volumio volumio[2235]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 16:27:56 volumio volumio[2235]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* May 30 16:27:56 volumio volumio[2235]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory May 30 16:27:56 volumio volumio[2235]: /bin/rm: cannot remove 'a': No such file or directory May 30 16:27:56 volumio volumio[2235]: /bin/rm: cannot remove 'packet.zip*': No such file or directory May 30 16:27:56 volumio volumio[2235]: at checkExecSyncError (node:child_process:887:11) May 30 16:27:56 volumio volumio[2235]: at execSync (node:child_process:959:15) May 30 16:27:56 volumio volumio[2235]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 May 30 16:27:56 volumio volumio[2235]: at new Promise () May 30 16:27:56 volumio volumio[2235]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) May 30 16:27:56 volumio volumio[2235]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 30 16:27:56 volumio volumio[2235]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) May 30 16:27:56 volumio volumio[2235]: at Socket.emit (node:events:514:28) May 30 16:27:56 volumio volumio[2235]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 30 16:27:56 volumio volumio[2235]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { May 30 16:27:56 volumio volumio[2235]: status: 1, May 30 16:27:56 volumio volumio[2235]: signal: null, May 30 16:27:56 volumio volumio[2235]: output: [ May 30 16:27:56 volumio volumio[2235]: null, May 30 16:27:56 volumio volumio[2235]: , May 30 16:27:56 volumio volumio[2235]: May 30 16:27:56 volumio volumio[2235]: ], May 30 16:27:56 volumio volumio[2235]: pid: 40585, May 30 16:27:56 volumio volumio[2235]: stdout: , May 30 16:27:56 volumio volumio[2235]: stderr: May 30 16:27:56 volumio volumio[2235]: } May 30 16:27:56 volumio volumio[2235]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 16:27:57 volumio sudo[40603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 16:26' May 30 16:27:57 volumio sudo[40603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"