Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:00 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:01 volumiocasa startpeppyspectrum.sh[13165]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:01 volumiocasa volumio[1130]: info: CALLMETHOD: user_interface peppyspectrum updatelist Feb 23 08:11:01 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Feb 23 08:11:01 volumiocasa volumio[1130]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Feb 23 08:11:02 volumiocasa volumio[1130]: --2026-02-23 08:11:01-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Feb 23 08:11:02 volumiocasa volumio[1130]: Resolving github.com (github.com)... 140.82.121.3 Feb 23 08:11:02 volumiocasa volumio[1130]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Feb 23 08:11:02 volumiocasa volumio[1130]: HTTP request sent, awaiting response... 302 Found Feb 23 08:11:02 volumiocasa volumio[1130]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Feb 23 08:11:02 volumiocasa volumio[1130]: --2026-02-23 08:11:02-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Feb 23 08:11:02 volumiocasa volumio[1130]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.109.133, 185.199.108.133, 185.199.111.133, ... Feb 23 08:11:02 volumiocasa volumio[1130]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.109.133|:443... connected. Feb 23 08:11:02 volumiocasa volumio[1130]: HTTP request sent, awaiting response... 200 OK Feb 23 08:11:02 volumiocasa volumio[1130]: Length: 471 [text/plain] Feb 23 08:11:02 volumiocasa volumio[1130]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Feb 23 08:11:02 volumiocasa volumio[1130]: 0K 100% 1.99M=0s Feb 23 08:11:02 volumiocasa volumio[1130]: 2026-02-23 08:11:02 (1.99 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Feb 23 08:11:02 volumiocasa volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 08:11:02 volumiocasa volumio[1130]: info: CoreCommandRouter::Close All Modals sent Feb 23 08:11:10 volumiocasa volumio[1130]: info: CALLMETHOD: user_interface peppyspectrum delayspectrum [object Object] Feb 23 08:11:10 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , delayspectrum Feb 23 08:11:10 volumiocasa volumio[1130]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 23 08:11:10 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------spectrum Feb 23 08:11:10 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------$basefolder Feb 23 08:11:10 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------screensize320x240 Feb 23 08:11:10 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------screenwidth Feb 23 08:11:10 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------screenheight Feb 23 08:11:10 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------spectrumsize30 Feb 23 08:11:10 volumiocasa volumio[1130]: error: PeppySpectrum ---Error writing config null Feb 23 08:11:10 volumiocasa sudo[13202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Feb 23 08:11:10 volumiocasa sudo[13202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:10 volumiocasa volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 08:11:10 volumiocasa volumio[1130]: info: CoreCommandRouter::Close All Modals sent Feb 23 08:11:10 volumiocasa systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Feb 23 08:11:10 volumiocasa systemd[1]: peppyspectrum.service: Deactivated successfully. Feb 23 08:11:10 volumiocasa systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Feb 23 08:11:10 volumiocasa systemd[1]: peppyspectrum.service: Consumed 6.711s CPU time. Feb 23 08:11:11 volumiocasa systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Feb 23 08:11:11 volumiocasa sudo[13202]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:11 volumiocasa startpeppyspectrum.sh[13206]: sleep: invalid time interval ‘undefined’ Feb 23 08:11:11 volumiocasa startpeppyspectrum.sh[13206]: Try 'sleep --help' for more information. Feb 23 08:11:11 volumiocasa volumio[1130]: info: peppyspectrum Daemon Started Feb 23 08:11:12 volumiocasa startpeppyspectrum.sh[13207]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:13 volumiocasa startpeppyspectrum.sh[13207]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:16 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 23 08:11:16 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:17 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPlay Feb 23 08:11:17 volumiocasa volumio[1130]: info: CoreStateMachine::play index undefined Feb 23 08:11:17 volumiocasa volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 08:11:17 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:17 volumiocasa volumio[1130]: info: CoreStateMachine::startPlaybackTimer Feb 23 08:11:17 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:17 volumiocasa volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks NAS/Nas/[2021] Happier Than Ever/04 - my future.flac Feb 23 08:11:17 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 08:11:17 volumiocasa volumio[1130]: info: sendMpdCommand stop took 2 milliseconds Feb 23 08:11:17 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 08:11:17 volumiocasa volumio[1130]: info: Feb 23 08:11:17 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:17 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:17 volumiocasa volumio[1130]: info: sendMpdCommand clear took 1 milliseconds Feb 23 08:11:17 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" Feb 23 08:11:17 volumiocasa volumio[1130]: info: Feb 23 08:11:17 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:17 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:17 volumiocasa volumio[1130]: error: updateQueue error: null Feb 23 08:11:17 volumiocasa volumio[1130]: info: Feb 23 08:11:17 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:17 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:17 volumiocasa volumio[1130]: info: ------------------------------ 6ms Feb 23 08:11:17 volumiocasa volumio[1130]: info: sendMpdCommand add "NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" took 5 milliseconds Feb 23 08:11:17 volumiocasa volumio[1130]: info: ------------------------------ 4ms Feb 23 08:11:17 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Feb 23 08:11:17 volumiocasa volumio[1130]: info: Feb 23 08:11:17 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:17 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:17 volumiocasa volumio[1130]: info: ------------------------------ 7ms Feb 23 08:11:17 volumiocasa volumio[1130]: info: sendMpdCommand play took 4 milliseconds Feb 23 08:11:17 volumiocasa volumio[1130]: info: ------------------------------ 3ms Feb 23 08:11:17 volumiocasa volumio[1130]: info: Feb 23 08:11:17 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:17 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:17 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:17 volumiocasa volumio[1130]: info: Feb 23 08:11:17 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:17 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:17 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:21 volumiocasa volumio[1130]: info: Feb 23 08:11:21 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:21 volumiocasa volumio[1130]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Error opening ALSA device "volumio"; snd_pcm_hw_params_set_buffer_time_near() failed: Invalid argument Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 23 08:11:21 volumiocasa volumio[1130]: info: sendMpdCommand status took 4667 milliseconds Feb 23 08:11:21 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:21 volumiocasa volumio[1130]: info: Feb 23 08:11:21 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:21 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:21 volumiocasa volumio[1130]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Error opening ALSA device "volumio"; snd_pcm_hw_params_set_buffer_time_near() failed: Invalid argument Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 23 08:11:21 volumiocasa volumio[1130]: info: sendMpdCommand status took 4676 milliseconds Feb 23 08:11:21 volumiocasa volumio[1130]: info: sendMpdCommand clearerror took 14 milliseconds Feb 23 08:11:21 volumiocasa volumio[1130]: info: sendMpdCommand status took 13 milliseconds Feb 23 08:11:21 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 23 08:11:21 volumiocasa volumio[1130]: info: sendMpdCommand status took 9 milliseconds Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:21 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:21 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:21 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"my future","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/04 - my future.flac","trackType":"flac"} Feb 23 08:11:21 volumiocasa volumio[1130]: verbose: CURRENT POSITION 3 Feb 23 08:11:21 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:21 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:21 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:21 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:21 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 08:11:21 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:21 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:21.974Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=4753 volume=100 Feb 23 08:11:21 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:21.975Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=4753 volume=100 Feb 23 08:11:21 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:21.976Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" title="my future" Feb 23 08:11:21 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:21.976Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" title="my future" Feb 23 08:11:21 volumiocasa volumio[1130]: info: ------------------------------ 4705ms Feb 23 08:11:21 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:22 volumiocasa volumio[1130]: info: sendMpdCommand clearerror took 58 milliseconds Feb 23 08:11:22 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 55 milliseconds Feb 23 08:11:22 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 56 milliseconds Feb 23 08:11:22 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 56 milliseconds Feb 23 08:11:22 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:22 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:22 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:22 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:22 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"my future","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/04 - my future.flac","trackType":"flac"} Feb 23 08:11:22 volumiocasa volumio[1130]: verbose: CURRENT POSITION 3 Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:22 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"my future","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/04 - my future.flac","trackType":"flac"} Feb 23 08:11:22 volumiocasa volumio[1130]: verbose: CURRENT POSITION 3 Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:22 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"my future","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/04 - my future.flac","trackType":"flac"} Feb 23 08:11:22 volumiocasa volumio[1130]: verbose: CURRENT POSITION 3 Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:22 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 3 Feb 23 08:11:22 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.049Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=4753 volume=100 Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.049Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=4753 volume=100 Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.050Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=4753 volume=100 Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.050Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=4753 volume=100 Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.050Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" title="my future" Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.051Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" title="my future" Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.051Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" title="my future" Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.051Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" title="my future" Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.055Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=4753 volume=100 Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.056Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=4753 volume=100 Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.057Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" title="my future" Feb 23 08:11:22 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:22.058Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/04 - my future.flac" title="my future" Feb 23 08:11:22 volumiocasa volumio[1130]: info: ------------------------------ 4791ms Feb 23 08:11:22 volumiocasa volumio[1130]: info: ------------------------------ 134ms Feb 23 08:11:22 volumiocasa sudo[13232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:22 volumiocasa volumio[1130]: info: ------------------------------ 123ms Feb 23 08:11:22 volumiocasa sudo[13232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:22 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:22 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:22 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:22 volumiocasa sudo[13235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:22 volumiocasa sudo[13235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:22 volumiocasa sudo[13237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:22 volumiocasa sudo[13237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:22 volumiocasa systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Feb 23 08:11:22 volumiocasa sudo[13239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:22 volumiocasa sudo[13239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:22 volumiocasa systemd[1]: peppyspectrum.service: Deactivated successfully. Feb 23 08:11:22 volumiocasa systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Feb 23 08:11:22 volumiocasa systemd[1]: peppyspectrum.service: Consumed 5.453s CPU time. Feb 23 08:11:22 volumiocasa sudo[13232]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:22 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:22 volumiocasa sudo[13235]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:22 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:22 volumiocasa sudo[13237]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:22 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:22 volumiocasa sudo[13239]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:22 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPlay Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::play index 6 Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::stop Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::play index undefined Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 08:11:23 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::startPlaybackTimer Feb 23 08:11:23 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand stop took 4 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:23 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:23 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand status took 5 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand clear took 5 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand status took 3 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:23 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:23 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:23 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: CURRENT POSITION 6 Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService stop Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:23 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:23 volumiocasa volumio[1130]: info: No code Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:23 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:23 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.607Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=8 volume=100 Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.607Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=8 volume=100 Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.607Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=8 volume=100 Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.607Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=8 volume=100 Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.608Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.608Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.609Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=8 volume=100 Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.609Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.610Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=8 volume=100 Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.611Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.612Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:23 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:23.609Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:23 volumiocasa volumio[1130]: info: ------------------------------ 45ms Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:23 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:23 volumiocasa volumio[1130]: error: updateQueue error: null Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:23 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:23 volumiocasa volumio[1130]: info: ------------------------------ 47ms Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 46 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand add "NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" took 46 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Feb 23 08:11:23 volumiocasa volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 23 08:11:23 volumiocasa volumio[1130]: info: ------------------------------ 58ms Feb 23 08:11:23 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:23 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:23 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:23 volumiocasa sudo[13244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:23 volumiocasa sudo[13244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:23 volumiocasa sudo[13246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:23 volumiocasa sudo[13246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:23 volumiocasa volumio[1130]: info: ------------------------------ 110ms Feb 23 08:11:23 volumiocasa volumio[1130]: info: ------------------------------ 109ms Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand play took 105 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:23 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:23 volumiocasa volumio[1130]: info: ------------------------------ 7ms Feb 23 08:11:23 volumiocasa sudo[13249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:23 volumiocasa sudo[13249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:23 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:23 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:23 volumiocasa volumio[1130]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Error opening ALSA device "volumio"; snd_pcm_hw_params_set_buffer_time_near() failed: Invalid argument Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand status took 154 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Error opening ALSA device "volumio"; snd_pcm_hw_params_set_buffer_time_near() failed: Invalid argument Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand status took 156 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:23 volumiocasa sudo[13246]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:23 volumiocasa volumio[1130]: info: Feb 23 08:11:23 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:23 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:23 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:23 volumiocasa sudo[13244]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand clearerror took 30 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand clearerror took 28 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand status took 27 milliseconds Feb 23 08:11:23 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 23 08:11:24 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 31 milliseconds Feb 23 08:11:24 volumiocasa volumio[1130]: info: sendMpdCommand status took 24 milliseconds Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:24 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost Cause","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac","trackType":"flac"} Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: CURRENT POSITION 6 Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost Cause","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac","trackType":"flac"} Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: CURRENT POSITION 6 Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:24 volumiocasa sudo[13249]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.062Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=419 volume=100 Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.063Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=419 volume=100 Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.064Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.064Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.066Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=419 volume=100 Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.066Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=419 volume=100 Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.067Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.067Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:24 volumiocasa volumio[1130]: info: ------------------------------ 272ms Feb 23 08:11:24 volumiocasa volumio[1130]: info: ------------------------------ 271ms Feb 23 08:11:24 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:24 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:24 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 123 milliseconds Feb 23 08:11:24 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 118 milliseconds Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:24 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost Cause","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac","trackType":"flac"} Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: CURRENT POSITION 6 Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lost Cause","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac","trackType":"flac"} Feb 23 08:11:24 volumiocasa volumio[1130]: verbose: CURRENT POSITION 6 Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:24 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 6 Feb 23 08:11:24 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:24 volumiocasa sudo[13253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:24 volumiocasa sudo[13253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.165Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=563 volume=100 Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.166Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=563 volume=100 Feb 23 08:11:24 volumiocasa volumio[1130]: info: ------------------------------ 214ms Feb 23 08:11:24 volumiocasa volumio[1130]: info: ------------------------------ 186ms Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.168Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=563 volume=100 Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.169Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=563 volume=100 Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.172Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.172Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.172Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:24 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:24.172Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/07 - Lost Cause.flac" title="Lost Cause" Feb 23 08:11:24 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:24 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:24 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:24 volumiocasa sudo[13255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:24 volumiocasa sudo[13255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:24 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:24 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:24 volumiocasa sudo[13259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:24 volumiocasa sudo[13259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:24 volumiocasa sudo[13261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:24 volumiocasa sudo[13261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:24 volumiocasa sudo[13259]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:24 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:24 volumiocasa sudo[13255]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:24 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:24 volumiocasa sudo[13253]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:24 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:24 volumiocasa sudo[13261]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:24 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPlay Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::play index 7 Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::stop Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::play index undefined Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 08:11:28 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::startPlaybackTimer Feb 23 08:11:28 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop Feb 23 08:11:28 volumiocasa volumio[1130]: info: Feb 23 08:11:28 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:28 volumiocasa volumio[1130]: info: sendMpdCommand stop took 6 milliseconds Feb 23 08:11:28 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear Feb 23 08:11:28 volumiocasa volumio[1130]: info: Feb 23 08:11:28 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:28 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:28 volumiocasa volumio[1130]: info: Feb 23 08:11:28 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:28 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:28 volumiocasa volumio[1130]: info: sendMpdCommand status took 6 milliseconds Feb 23 08:11:28 volumiocasa volumio[1130]: info: sendMpdCommand clear took 5 milliseconds Feb 23 08:11:28 volumiocasa volumio[1130]: info: sendMpdCommand status took 3 milliseconds Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:28 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:28 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:28 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: CURRENT POSITION 7 Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService stop Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:28 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:28 volumiocasa volumio[1130]: info: No code Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:28 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:28 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.960Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=0 volume=100 Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.960Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=0 volume=100 Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.960Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=0 volume=100 Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.961Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.962Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.963Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=0 volume=100 Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.964Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=0 volume=100 Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.964Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=0 volume=100 Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.964Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.965Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.965Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:28 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:28.966Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:28 volumiocasa volumio[1130]: info: ------------------------------ 39ms Feb 23 08:11:28 volumiocasa volumio[1130]: info: Feb 23 08:11:28 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:28 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:28 volumiocasa volumio[1130]: error: updateQueue error: null Feb 23 08:11:28 volumiocasa volumio[1130]: info: Feb 23 08:11:28 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:28 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:28 volumiocasa volumio[1130]: info: ------------------------------ 42ms Feb 23 08:11:28 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 23 08:11:28 volumiocasa volumio[1130]: info: sendMpdCommand add "NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" took 41 milliseconds Feb 23 08:11:28 volumiocasa volumio[1130]: info: ------------------------------ 6ms Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:28 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand play Feb 23 08:11:28 volumiocasa volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 23 08:11:28 volumiocasa volumio[1130]: info: ------------------------------ 52ms Feb 23 08:11:28 volumiocasa volumio[1130]: info: Feb 23 08:11:28 volumiocasa volumio[1130]: ---------------------------- MPD announces system playlist update Feb 23 08:11:28 volumiocasa volumio[1130]: info: Ignoring MPD Status Update Feb 23 08:11:29 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: ------------------------------ 84ms Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand play took 80 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: info: ------------------------------ 79ms Feb 23 08:11:29 volumiocasa sudo[13279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:29 volumiocasa sudo[13279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:29 volumiocasa sudo[13281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:29 volumiocasa sudo[13281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:29 volumiocasa sudo[13283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:29 volumiocasa sudo[13283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:29 volumiocasa volumio[1130]: info: Feb 23 08:11:29 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:29 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:29 volumiocasa volumio[1130]: info: Feb 23 08:11:29 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:29 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:29 volumiocasa volumio[1130]: info: Feb 23 08:11:29 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:29 volumiocasa volumio[1130]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Error opening ALSA device "volumio"; snd_pcm_hw_params_set_buffer_time_near() failed: Invalid argument Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand status took 120 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:29 volumiocasa volumio[1130]: info: Feb 23 08:11:29 volumiocasa volumio[1130]: ---------------------------- MPD announces state update: player Feb 23 08:11:29 volumiocasa volumio[1130]: info: ControllerMpd::getState Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 23 08:11:29 volumiocasa sudo[13281]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:29 volumiocasa volumio[1130]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Error opening ALSA device "volumio"; snd_pcm_hw_params_set_buffer_time_near() failed: Invalid argument Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand status took 137 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand clearerror took 22 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand status took 22 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand status took 19 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::parseState Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 08:11:29 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Halley's Comet","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac","trackType":"flac"} Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: CURRENT POSITION 7 Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:29 volumiocasa sudo[13279]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.396Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=383 volume=100 Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.397Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=383 volume=100 Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.398Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.398Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:29 volumiocasa volumio[1130]: info: ------------------------------ 177ms Feb 23 08:11:29 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand clearerror took 73 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 67 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 66 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: info: sendMpdCommand playlistinfo took 65 milliseconds Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 23 08:11:29 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Halley's Comet","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac","trackType":"flac"} Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: CURRENT POSITION 7 Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Halley's Comet","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac","trackType":"flac"} Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: CURRENT POSITION 7 Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:29 volumiocasa sudo[13283]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: ControllerMpd::pushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::servicePushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Halley's Comet","artist":"Billie Eilish","album":"Happier Than Ever","uri":"NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac","trackType":"flac"} Feb 23 08:11:29 volumiocasa volumio[1130]: verbose: CURRENT POSITION 7 Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::syncState stateService pause Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::syncState currentStatus stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreStateMachine::pushState Feb 23 08:11:29 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:29 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.506Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=513 volume=100 Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.507Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=513 volume=100 Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.508Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.508Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=513 volume=100 Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.508Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=513 volume=100 Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.508Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.510Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" state=STATUS_STOPPED positionMs=513 volume=100 Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.512Z level=INFO msg="emitting player state changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" state=STATUS_STOPPED positionMs=513 volume=100 Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.512Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.513Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.514Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.207:39818 @ 0x24b8c30" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:29 volumiocasa volumio5-onboarding[1775]: time=2026-02-23T08:11:29.514Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.9.239:40981 @ 0x2746c90" id="mnt/NAS/Nas/[2021] Happier Than Ever/08 - Halley’s Comet.flac" title="Halley's Comet" Feb 23 08:11:29 volumiocasa volumio[1130]: info: ------------------------------ 293ms Feb 23 08:11:29 volumiocasa volumio[1130]: info: ------------------------------ 187ms Feb 23 08:11:29 volumiocasa volumio[1130]: info: ------------------------------ 174ms Feb 23 08:11:29 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:29 volumiocasa sudo[13288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:29 volumiocasa sudo[13288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:29 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:29 volumiocasa volumio[1130]: info: PeppySpectrum ---peppyspectrum status stop Feb 23 08:11:29 volumiocasa sudo[13291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:29 volumiocasa sudo[13291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:29 volumiocasa sudo[13293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:29 volumiocasa sudo[13293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:29 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:29 volumiocasa sudo[13288]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:29 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:29 volumiocasa sudo[13297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 23 08:11:29 volumiocasa sudo[13297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:29 volumiocasa sudo[13291]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:29 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:30 volumiocasa sudo[13297]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:30 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:30 volumiocasa sudo[13293]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:30 volumiocasa volumio[1130]: info: peppyspectrum Daemon Stop Feb 23 08:11:32 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioMoveQueue Feb 23 08:11:32 volumiocasa volumio[1130]: info: CoreStateMachine::moveQueueItem 6 6 Feb 23 08:11:32 volumiocasa volumio[1130]: info: CorePlayQueue::moveQueueItem 6 --> 6 Feb 23 08:11:32 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioPushQueue Feb 23 08:11:36 volumiocasa volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 23 08:11:36 volumiocasa volumio[1130]: info: CorePlayQueue::getTrack 7 Feb 23 08:11:38 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 08:11:38 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 23 08:11:42 volumiocasa volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 08:11:49 volumiocasa volumio[1130]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Feb 23 08:11:49 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Feb 23 08:11:49 volumiocasa volumio[1130]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 23 08:11:49 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------spectrums.1 Feb 23 08:11:49 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------$basefolder Feb 23 08:11:49 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------screensize320x240 Feb 23 08:11:49 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------screenwidth Feb 23 08:11:49 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------screenheight Feb 23 08:11:49 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------spectrumsize30 Feb 23 08:11:49 volumiocasa volumio[1130]: error: PeppySpectrum ---Error writing config null Feb 23 08:11:49 volumiocasa sudo[13331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Feb 23 08:11:49 volumiocasa sudo[13331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:49 volumiocasa volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 08:11:49 volumiocasa volumio[1130]: info: CoreCommandRouter::Close All Modals sent Feb 23 08:11:49 volumiocasa systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Feb 23 08:11:49 volumiocasa startpeppyspectrum.sh[13335]: sleep: invalid time interval ‘undefined’ Feb 23 08:11:49 volumiocasa startpeppyspectrum.sh[13335]: Try 'sleep --help' for more information. Feb 23 08:11:49 volumiocasa sudo[13331]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:49 volumiocasa volumio[1130]: info: peppyspectrum Daemon Started Feb 23 08:11:51 volumiocasa startpeppyspectrum.sh[13336]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Feb 23 08:11:51 volumiocasa volumio[1130]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] Feb 23 08:11:51 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy Feb 23 08:11:51 volumiocasa volumio[1130]: aaaaaaaaaaa 320,240 Feb 23 08:11:51 volumiocasa volumio[1130]: info: PeppySpectrum ---320240 Feb 23 08:11:51 volumiocasa volumio[1130]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 23 08:11:51 volumiocasa volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 08:11:51 volumiocasa volumio[1130]: info: CoreCommandRouter::Close All Modals sent Feb 23 08:11:52 volumiocasa startpeppyspectrum.sh[13336]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:52 volumiocasa startpeppyspectrum.sh[13336]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:52 volumiocasa startpeppyspectrum.sh[13336]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:55 volumiocasa volumio[1130]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Feb 23 08:11:55 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Feb 23 08:11:55 volumiocasa volumio[1130]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 23 08:11:55 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------spectrums.1 Feb 23 08:11:55 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------$basefolder Feb 23 08:11:55 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------screensize320x240 Feb 23 08:11:55 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------screenwidth Feb 23 08:11:55 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------screenheight Feb 23 08:11:55 volumiocasa volumio[1130]: info: PeppySpectrum -----------------------spectrumsize30 Feb 23 08:11:55 volumiocasa volumio[1130]: error: PeppySpectrum ---Error writing config null Feb 23 08:11:55 volumiocasa sudo[13342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Feb 23 08:11:55 volumiocasa sudo[13342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:11:55 volumiocasa volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 08:11:55 volumiocasa volumio[1130]: info: CoreCommandRouter::Close All Modals sent Feb 23 08:11:55 volumiocasa systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Feb 23 08:11:55 volumiocasa systemd[1]: peppyspectrum.service: Deactivated successfully. Feb 23 08:11:55 volumiocasa systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Feb 23 08:11:55 volumiocasa systemd[1]: peppyspectrum.service: Consumed 3.824s CPU time. Feb 23 08:11:55 volumiocasa systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Feb 23 08:11:55 volumiocasa sudo[13342]: pam_unix(sudo:session): session closed for user root Feb 23 08:11:55 volumiocasa startpeppyspectrum.sh[13345]: sleep: invalid time interval ‘undefined’ Feb 23 08:11:55 volumiocasa startpeppyspectrum.sh[13345]: Try 'sleep --help' for more information. Feb 23 08:11:55 volumiocasa volumio[1130]: info: peppyspectrum Daemon Started Feb 23 08:11:57 volumiocasa startpeppyspectrum.sh[13346]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Feb 23 08:11:58 volumiocasa startpeppyspectrum.sh[13346]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:58 volumiocasa startpeppyspectrum.sh[13346]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:11:58 volumiocasa startpeppyspectrum.sh[13346]: libpng warning: iCCP: known incorrect sRGB profile Feb 23 08:12:02 volumiocasa volumio[1130]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Feb 23 08:12:02 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Feb 23 08:12:03 volumiocasa volumio[1130]: --2026-02-23 08:12:02-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/800x480+99-99bars-balbuze.zip Feb 23 08:12:03 volumiocasa volumio[1130]: Resolving github.com (github.com)... 140.82.121.3 Feb 23 08:12:03 volumiocasa volumio[1130]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Feb 23 08:12:03 volumiocasa volumio[1130]: HTTP request sent, awaiting response... 302 Found Feb 23 08:12:03 volumiocasa volumio[1130]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/Zipped-folders/800x480%2B99-99bars-balbuze.zip [following] Feb 23 08:12:03 volumiocasa volumio[1130]: --2026-02-23 08:12:03-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/Zipped-folders/800x480%2B99-99bars-balbuze.zip Feb 23 08:12:03 volumiocasa volumio[1130]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.110.133, 185.199.109.133, 185.199.108.133, ... Feb 23 08:12:03 volumiocasa volumio[1130]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.110.133|:443... connected. Feb 23 08:12:03 volumiocasa volumio[1130]: HTTP request sent, awaiting response... 200 OK Feb 23 08:12:03 volumiocasa volumio[1130]: Length: 34614 (34K) [application/zip] Feb 23 08:12:03 volumiocasa volumio[1130]: Saving to: ‘/tmp/800x480+99-99bars-balbuze.zip’ Feb 23 08:12:03 volumiocasa volumio[1130]: 0K .......... .......... .......... ... 100% 1.80M=0.02s Feb 23 08:12:03 volumiocasa volumio[1130]: 2026-02-23 08:12:03 (1.80 MB/s) - ‘/tmp/800x480+99-99bars-balbuze.zip’ saved [34614/34614] Feb 23 08:12:03 volumiocasa sudo[13373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /data/INTERNAL/PeppySpectrum/Templates/ Feb 23 08:12:03 volumiocasa sudo[13373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 08:12:03 volumiocasa sudo[13373]: pam_unix(sudo:session): session closed for user root Feb 23 08:12:03 volumiocasa volumio[1130]: info: PeppySpectrum ---message miniunzip -o /tmp/800x480+99-99bars-balbuze.zip -d /data/INTERNAL/PeppySpectrum/Templates/ Feb 23 08:12:03 volumiocasa volumio[1130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 08:12:03 volumiocasa volumio[1130]: info: CoreCommandRouter::Close All Modals sent Feb 23 08:12:05 volumiocasa volumio[1130]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Feb 23 08:12:05 volumiocasa volumio[1130]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Feb 23 08:12:08 volumiocasa volumio[1130]: --2026-02-23 08:12:05-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Feb 23 08:12:08 volumiocasa volumio[1130]: Resolving github.com (github.com)... 140.82.121.3 Feb 23 08:12:08 volumiocasa volumio[1130]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Feb 23 08:12:08 volumiocasa volumio[1130]: HTTP request sent, awaiting response... 404 Not Found Feb 23 08:12:08 volumiocasa volumio[1130]: 2026-02-23 08:12:06 ERROR 404: Not Found. Feb 23 08:12:08 volumiocasa volumio[1130]: --2026-02-23 08:12:06-- http://a/ Feb 23 08:12:08 volumiocasa volumio[1130]: Resolving a (a)... failed: Device or resource busy. Feb 23 08:12:08 volumiocasa volumio[1130]: wget: unable to resolve host address ‘a’ Feb 23 08:12:08 volumiocasa volumio[1130]: --2026-02-23 08:12:06-- http://packet.zip/ Feb 23 08:12:08 volumiocasa volumio[1130]: Resolving packet.zip (packet.zip)... 162.255.119.167 Feb 23 08:12:08 volumiocasa volumio[1130]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Feb 23 08:12:08 volumiocasa volumio[1130]: HTTP request sent, awaiting response... 302 Found Feb 23 08:12:08 volumiocasa volumio[1130]: Location: http://www.packet.zip/ [following] Feb 23 08:12:08 volumiocasa volumio[1130]: --2026-02-23 08:12:07-- http://www.packet.zip/ Feb 23 08:12:08 volumiocasa volumio[1130]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Feb 23 08:12:08 volumiocasa volumio[1130]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Feb 23 08:12:08 volumiocasa volumio[1130]: HTTP request sent, awaiting response... 403 Forbidden Feb 23 08:12:08 volumiocasa volumio[1130]: 2026-02-23 08:12:08 ERROR 403: Forbidden. Feb 23 08:12:08 volumiocasa volumio[1130]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Feb 23 08:12:08 volumiocasa volumio[1130]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Feb 23 08:12:08 volumiocasa volumio[1130]: /bin/rm: cannot remove 'a': No such file or directory Feb 23 08:12:08 volumiocasa volumio[1130]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Feb 23 08:12:08 volumiocasa volumio[1130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 08:12:08 volumiocasa volumio[1130]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Feb 23 08:12:08 volumiocasa volumio[1130]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Feb 23 08:12:08 volumiocasa volumio[1130]: /bin/rm: cannot remove 'a': No such file or directory Feb 23 08:12:08 volumiocasa volumio[1130]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Feb 23 08:12:08 volumiocasa volumio[1130]: at checkExecSyncError (node:child_process:887:11) Feb 23 08:12:08 volumiocasa volumio[1130]: at execSync (node:child_process:959:15) Feb 23 08:12:08 volumiocasa volumio[1130]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Feb 23 08:12:08 volumiocasa volumio[1130]: at new Promise () Feb 23 08:12:08 volumiocasa volumio[1130]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Feb 23 08:12:08 volumiocasa volumio[1130]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Feb 23 08:12:08 volumiocasa volumio[1130]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Feb 23 08:12:08 volumiocasa volumio[1130]: at Socket.emit (node:events:514:28) Feb 23 08:12:08 volumiocasa volumio[1130]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 23 08:12:08 volumiocasa volumio[1130]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Feb 23 08:12:08 volumiocasa volumio[1130]: status: 1, Feb 23 08:12:08 volumiocasa volumio[1130]: signal: null, Feb 23 08:12:08 volumiocasa volumio[1130]: output: [ Feb 23 08:12:08 volumiocasa volumio[1130]: null, Feb 23 08:12:08 volumiocasa volumio[1130]: , Feb 23 08:12:08 volumiocasa volumio[1130]: Feb 23 08:12:08 volumiocasa volumio[1130]: ], Feb 23 08:12:08 volumiocasa volumio[1130]: pid: 13396, Feb 23 08:12:08 volumiocasa volumio[1130]: stdout: , Feb 23 08:12:08 volumiocasa volumio[1130]: stderr: Feb 23 08:12:08 volumiocasa volumio[1130]: } Feb 23 08:12:08 volumiocasa volumio[1130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 08:12:09 volumiocasa sudo[13412]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 08:11' Feb 23 08:12:09 volumiocasa sudo[13412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"