Dec 21 23:36:03 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:03+01:00" level=debug msg="fetched chunk 16/18, size: 524288" uri="spotify:track:0UAEHlFR79k9CJvknSGUNf" Dec 21 23:36:16 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:16+01:00" level=debug msg="fetched chunk 17/18, size: 524288" uri="spotify:track:0UAEHlFR79k9CJvknSGUNf" Dec 21 23:36:27 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:27+01:00" level=debug msg="fetched chunk 18/18, size: 146512" uri="spotify:track:0UAEHlFR79k9CJvknSGUNf" Dec 21 23:36:28 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:28+01:00" level=trace msg="sent dealer ping" Dec 21 23:36:28 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:28+01:00" level=trace msg="received dealer pong" Dec 21 23:36:40 volumiosalon volumio[2224]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/bookworm/armhf Dec 21 23:36:41 volumiosalon volumio[2224]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/bookworm/armhf Dec 21 23:36:41 volumiosalon volumio[2224]: info: Folder /tmp/plugins removed Dec 21 23:36:42 volumiosalon volumio[2224]: info: Check plugin dependencies Dec 21 23:36:42 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 23:36:42 volumiosalon volumio[2224]: info: Checking if plugin already exists Dec 21 23:36:42 volumiosalon volumio[2224]: info: Rename folder Dec 21 23:36:42 volumiosalon volumio[2224]: info: Folder /tmp/downloaded_plugin.zip removed Dec 21 23:36:42 volumiosalon volumio[2224]: info: Move to category Dec 21 23:36:42 volumiosalon volumio[2224]: info: Checking if install.sh is present Dec 21 23:36:42 volumiosalon volumio[2224]: info: Executing install.sh Dec 21 23:36:42 volumiosalon sudo[13854]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppyspectrum/install.sh Dec 21 23:36:42 volumiosalon sudo[13854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:42 volumiosalon volumio[2224]: info: Installing peppyalsa plugin dependencies Dec 21 23:36:42 volumiosalon sudo[13858]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/usermod -aG render volumio Dec 21 23:36:42 volumiosalon sudo[13858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:42 volumiosalon sudo[13858]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:42 volumiosalon sudo[13863]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Dec 21 23:36:42 volumiosalon sudo[13863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:42 volumiosalon volumio[2224]: info: Hit:1 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Dec 21 23:36:42 volumiosalon volumio[2224]: info: Hit:2 http://archive.raspberrypi.com/debian bookworm InRelease Dec 21 23:36:42 volumiosalon volumio[2224]: info: Hit:3 https://deb.nodesource.com/node_20.x nodistro InRelease Dec 21 23:36:44 volumiosalon volumio[2224]: info: Reading package lists... Dec 21 23:36:44 volumiosalon sudo[13863]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:44 volumiosalon sudo[14054]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chown -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Dec 21 23:36:44 volumiosalon sudo[14054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:44 volumiosalon sudo[14054]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:44 volumiosalon sudo[14056]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chgrp -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Dec 21 23:36:44 volumiosalon sudo[14056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:44 volumiosalon sudo[14056]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:44 volumiosalon volumio[2224]: info: installing apt packages Dec 21 23:36:44 volumiosalon sudo[14058]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install python3-pygame python3-pillow libfftw3-dev Dec 21 23:36:44 volumiosalon sudo[14058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:44 volumiosalon volumio[2224]: info: Reading package lists... Dec 21 23:36:45 volumiosalon volumio[2224]: info: Building dependency tree... Dec 21 23:36:45 volumiosalon volumio[2224]: info: Reading state information... Dec 21 23:36:45 volumiosalon volumio[2224]: info: python3-pygame is already the newest version (2.1.2+dfsg-5). Dec 21 23:36:45 volumiosalon volumio[2224]: info: libfftw3-dev is already the newest version (3.3.10-1). Dec 21 23:36:45 volumiosalon volumio[2224]: info: The following additional packages will be installed: Dec 21 23:36:45 volumiosalon volumio[2224]: info: bzip2 file libimagequant0 libraqm0 mailcap mime-support python3-olefile Dec 21 23:36:45 volumiosalon volumio[2224]: info: Suggested packages: Dec 21 23:36:45 volumiosalon volumio[2224]: info: bzip2-doc python-pil-doc Dec 21 23:36:45 volumiosalon volumio[2224]: info: The following NEW packages will be installed: Dec 21 23:36:45 volumiosalon volumio[2224]: info: bzip2 file libimagequant0 libraqm0 mailcap mime-support python3-olefile Dec 21 23:36:45 volumiosalon volumio[2224]: info: python3-pil Dec 21 23:36:46 volumiosalon volumio[2224]: info: 0 upgraded, 8 newly installed, 0 to remove and 18 not upgraded. Dec 21 23:36:46 volumiosalon volumio[2224]: info: Need to get 638 kB of archives. Dec 21 23:36:46 volumiosalon volumio[2224]: info: After this operation, 2248 kB of additional disk space will be used. Dec 21 23:36:46 volumiosalon volumio[2224]: info: Get:1 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf bzip2 armhf 1.0.8-5+b2 [47.4 kB] Dec 21 23:36:46 volumiosalon volumio[2224]: info: Get:2 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf file armhf 1:5.44-3 [41.5 kB] Dec 21 23:36:46 volumiosalon volumio[2224]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf mailcap all 3.70+nmu1 [32.0 kB] Dec 21 23:36:46 volumiosalon volumio[2224]: info: Get:4 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf mime-support all 3.66 [10.9 kB] Dec 21 23:36:47 volumiosalon volumio[2224]: info: Get:5 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libimagequant0 armhf 2.17.0-1 [28.0 kB] Dec 21 23:36:47 volumiosalon volumio[2224]: info: Get:6 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libraqm0 armhf 0.7.0-4.1 [9176 B] Dec 21 23:36:47 volumiosalon volumio[2224]: info: Get:7 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf python3-olefile all 0.46-3 [36.1 kB] Dec 21 23:36:47 volumiosalon volumio[2224]: info: Get:8 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pil armhf 9.4.0-1.1+deb12u1 [433 kB] Dec 21 23:36:48 volumiosalon volumio[2224]: info: Fetched 638 kB in 2s (398 kB/s) Dec 21 23:36:48 volumiosalon volumio[2224]: info: Selecting previously unselected package bzip2. Dec 21 23:36:48 volumiosalon volumio[2224]: [620B blob data] Dec 21 23:36:48 volumiosalon volumio[2224]: info: Preparing to unpack .../0-bzip2_1.0.8-5+b2_armhf.deb ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Unpacking bzip2 (1.0.8-5+b2) ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Selecting previously unselected package file. Dec 21 23:36:48 volumiosalon volumio[2224]: info: Preparing to unpack .../1-file_1%3a5.44-3_armhf.deb ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Unpacking file (1:5.44-3) ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Selecting previously unselected package mailcap. Dec 21 23:36:48 volumiosalon volumio[2224]: info: Preparing to unpack .../2-mailcap_3.70+nmu1_all.deb ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Unpacking mailcap (3.70+nmu1) ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Selecting previously unselected package mime-support. Dec 21 23:36:48 volumiosalon volumio[2224]: info: Preparing to unpack .../3-mime-support_3.66_all.deb ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Unpacking mime-support (3.66) ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Selecting previously unselected package libimagequant0:armhf. Dec 21 23:36:48 volumiosalon volumio[2224]: info: Preparing to unpack .../4-libimagequant0_2.17.0-1_armhf.deb ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Unpacking libimagequant0:armhf (2.17.0-1) ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Selecting previously unselected package libraqm0:armhf. Dec 21 23:36:48 volumiosalon volumio[2224]: info: Preparing to unpack .../5-libraqm0_0.7.0-4.1_armhf.deb ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Unpacking libraqm0:armhf (0.7.0-4.1) ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Selecting previously unselected package python3-olefile. Dec 21 23:36:48 volumiosalon volumio[2224]: info: Preparing to unpack .../6-python3-olefile_0.46-3_all.deb ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Unpacking python3-olefile (0.46-3) ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Selecting previously unselected package python3-pil:armhf. Dec 21 23:36:48 volumiosalon volumio[2224]: info: Preparing to unpack .../7-python3-pil_9.4.0-1.1+deb12u1_armhf.deb ... Dec 21 23:36:48 volumiosalon volumio[2224]: info: Unpacking python3-pil:armhf (9.4.0-1.1+deb12u1) ... Dec 21 23:36:49 volumiosalon volumio[2224]: info: Setting up python3-olefile (0.46-3) ... Dec 21 23:36:49 volumiosalon volumio[2224]: info: Setting up file (1:5.44-3) ... Dec 21 23:36:49 volumiosalon volumio[2224]: info: Setting up bzip2 (1.0.8-5+b2) ... Dec 21 23:36:49 volumiosalon volumio[2224]: info: Setting up libraqm0:armhf (0.7.0-4.1) ... Dec 21 23:36:49 volumiosalon volumio[2224]: info: Setting up libimagequant0:armhf (2.17.0-1) ... Dec 21 23:36:49 volumiosalon volumio[2224]: info: Setting up mailcap (3.70+nmu1) ... Dec 21 23:36:49 volumiosalon volumio[2224]: info: Setting up mime-support (3.66) ... Dec 21 23:36:49 volumiosalon volumio[2224]: info: Setting up python3-pil:armhf (9.4.0-1.1+deb12u1) ... Dec 21 23:36:49 volumiosalon volumio[2224]: info: Processing triggers for man-db (2.11.2-2) ... Dec 21 23:36:50 volumiosalon volumio[2224]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Dec 21 23:36:50 volumiosalon sudo[14058]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:50 volumiosalon volumio[2224]: info: Linked /data/plugins/user_interface/peppyspectrum/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so Dec 21 23:36:50 volumiosalon volumio[2224]: info: Linked /data/plugins/user_interface/peppyspectrum/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so.0 Dec 21 23:36:50 volumiosalon sudo[14200]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppyspectrum/startpeppyspectrum.sh Dec 21 23:36:50 volumiosalon sudo[14200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:50 volumiosalon sudo[14200]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:50 volumiosalon sudo[14202]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Dec 21 23:36:50 volumiosalon sudo[14202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:50 volumiosalon systemd[1]: Reloading. Dec 21 23:36:51 volumiosalon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 21 23:36:51 volumiosalon systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 21 23:36:51 volumiosalon sudo[14202]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:51 volumiosalon sudo[13854]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:51 volumiosalon volumio[2224]: Plugin install end detected on script Dec 21 23:36:51 volumiosalon volumio[2224]: info: Install script completed Dec 21 23:36:51 volumiosalon volumio[2224]: info: Adding reference to registry Dec 21 23:36:51 volumiosalon volumio[2224]: info: Done installing plugin. Dec 21 23:36:51 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 23:36:51 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 21 23:36:51 volumiosalon volumio[2224]: info: Folder /tmp/plugins removed Dec 21 23:36:51 volumiosalon volumio[2224]: info: Folder /tmp/downloaded_plugin.zip removed Dec 21 23:36:51 volumiosalon volumio[2224]: info: Folder /data/temp removed Dec 21 23:36:54 volumiosalon volumio[2224]: info: Enabling plugin peppyspectrum Dec 21 23:36:54 volumiosalon volumio[2224]: info: Loading plugin "peppyspectrum"... Dec 21 23:36:55 volumiosalon volumio[2224]: info: Preparing to generate the ALSA configuration file Dec 21 23:36:55 volumiosalon volumio[2224]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 21 23:36:55 volumiosalon volumio[2224]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 21 23:36:55 volumiosalon volumio[2224]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 21 23:36:55 volumiosalon volumio[2224]: info: Reading ALSA contributions from plugins. Dec 21 23:36:55 volumiosalon volumio[2224]: info: Asound.conf file written Dec 21 23:36:55 volumiosalon sudo[14240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 21 23:36:55 volumiosalon sudo[14240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:55 volumiosalon sudo[14240]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:55 volumiosalon volumio[2224]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 21 23:36:55 volumiosalon volumio[2224]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 21 23:36:55 volumiosalon volumio[2224]: Found hardware: "USB-Audio" "USB Mixer" "USB3353:a002" "" "" Dec 21 23:36:55 volumiosalon volumio[2224]: Hardware is initialized using a generic method Dec 21 23:36:55 volumiosalon volumio[2224]: info: Output device has changed, restarting MPD Dec 21 23:36:55 volumiosalon volumio[2224]: info: Output device has changed, restarting Shairport Sync Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 23:36:55 volumiosalon sudo[14246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 23:36:55 volumiosalon sudo[14246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:55 volumiosalon sudo[14246]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:55 volumiosalon sudo[14248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 23:36:55 volumiosalon sudo[14248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:55 volumiosalon volumio[2224]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 23:36:55 volumiosalon volumio[2224]: info: PLUGIN START: peppyspectrum Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 21 23:36:55 volumiosalon systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 21 23:36:55 volumiosalon volumio[2224]: info: Preparing to generate the ALSA configuration file Dec 21 23:36:55 volumiosalon volumio[2224]: info: Done. Dec 21 23:36:55 volumiosalon sudo[14256]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 21 23:36:55 volumiosalon sudo[14256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:55 volumiosalon volumio[2224]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 21 23:36:55 volumiosalon volumio[2224]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 21 23:36:55 volumiosalon volumio[2224]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 21 23:36:55 volumiosalon volumio[2224]: info: Reading ALSA contributions from plugins. Dec 21 23:36:55 volumiosalon volumio[2224]: info: MPD Permissions set Dec 21 23:36:55 volumiosalon systemd[1]: mpd.service: Deactivated successfully. Dec 21 23:36:55 volumiosalon volumio[2224]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 21 23:36:55 volumiosalon systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 23:36:55 volumiosalon systemd[1]: mpd.socket: Deactivated successfully. Dec 21 23:36:55 volumiosalon systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 23:36:55 volumiosalon systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 23:36:55 volumiosalon sudo[14256]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 23:36:55 volumiosalon systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 23:36:55 volumiosalon (udev-worker)[14264]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: snd_dummy loaded Dec 21 23:36:55 volumiosalon volumio[2224]: info: Starting Shairport Sync Dec 21 23:36:55 volumiosalon volumio[2224]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioGetState Dec 21 23:36:55 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPause Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::pause Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::stPlaybackTimer Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::servicePause Dec 21 23:36:55 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePause Dec 21 23:36:55 volumiosalon volumio[2224]: info: Spotify Received pause Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: SPOTIFY PAUSE Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: {"status":"play","position":7,"title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d0000b273f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","codec":"ogg","seek":0,"duration":215,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":1,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Dec 21 23:36:55 volumiosalon volumio[2224]: info: Sending Spotify command to local API: /player/pause Dec 21 23:36:55 volumiosalon volumio[2224]: info: mpdhttpout --- Volumio set on pause Dec 21 23:36:55 volumiosalon sudo[14272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 23:36:55 volumiosalon sudo[14272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:55 volumiosalon volumio[2224]: info: Asound.conf file unchanged, so no further update is needed Dec 21 23:36:55 volumiosalon volumio[2224]: info: Output device has changed, restarting MPD Dec 21 23:36:55 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:55+01:00" level=debug msg="pause track at 203687ms" Dec 21 23:36:55 volumiosalon sudo[14265]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 23:36:55 volumiosalon sudo[14265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:55 volumiosalon sudo[14265]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:55 volumiosalon volumio[2224]: info: Output device has changed, restarting Shairport Sync Dec 21 23:36:55 volumiosalon systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 23:36:55 volumiosalon systemd[1]: shairport-sync.service: Deactivated successfully. Dec 21 23:36:55 volumiosalon sudo[14275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 23:36:55 volumiosalon sudo[14275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:55 volumiosalon systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 23:36:55 volumiosalon systemd[1]: shairport-sync.service: Consumed 7.645s CPU time. Dec 21 23:36:55 volumiosalon sudo[14275]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:55 volumiosalon sudo[14278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 23:36:55 volumiosalon sudo[14278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:55 volumiosalon systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 23:36:55 volumiosalon volumio[2224]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 23:36:55 volumiosalon sudo[14272]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:55 volumiosalon volumio[2224]: info: MPD Permissions set Dec 21 23:36:55 volumiosalon volumio[2224]: info: Shairport-Sync Started Dec 21 23:36:55 volumiosalon systemd[1]: mpd.service: Deactivated successfully. Dec 21 23:36:55 volumiosalon systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 23:36:55 volumiosalon systemd[1]: mpd.socket: Deactivated successfully. Dec 21 23:36:55 volumiosalon systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 23:36:55 volumiosalon systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 23:36:55 volumiosalon systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 23:36:55 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:55+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 23:36:55 volumiosalon volumio[2224]: info: Starting Shairport Sync Dec 21 23:36:55 volumiosalon sudo[14314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 23:36:55 volumiosalon volumio[2224]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioGetState Dec 21 23:36:55 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:36:55 volumiosalon volumio[2224]: info: mpdhttpout --- Volumio set on pause Dec 21 23:36:55 volumiosalon sudo[14314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:55 volumiosalon sudo[14307]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 23:36:55 volumiosalon sudo[14307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:55 volumiosalon sudo[14307]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:55 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:55+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 23:36:55 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:55+01:00" level=trace msg="emitting websocket event: paused" Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","play_origin":"go-librespot"}} Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: {"status":"pause","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:36:55 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:36:55 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:36:55 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService pause Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus pause Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:36:55 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::stPlaybackTimer Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","play_origin":"go-librespot"}} Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: {"status":"pause","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:36:55 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:36:55 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:36:55 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService pause Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus pause Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:36:55 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:36:55 volumiosalon systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 21 23:36:55 volumiosalon volumio[2224]: info: CoreStateMachine::stPlaybackTimer Dec 21 23:36:55 volumiosalon systemd[1]: shairport-sync.service: Deactivated successfully. Dec 21 23:36:55 volumiosalon systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 23:36:55 volumiosalon volumio[2224]: info: FusionDsp - Volumio is not playing Dec 21 23:36:55 volumiosalon volumio[2224]: info: FusionDsp - Clipped samples monitor stopped Dec 21 23:36:55 volumiosalon volumio[2224]: info: FusionDsp - Volumio is not playing Dec 21 23:36:55 volumiosalon volumio[2224]: info: FusionDsp - Clipped samples monitor stopped Dec 21 23:36:55 volumiosalon systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:36:55 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:36:55 volumiosalon sudo[14314]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:55 volumiosalon volumio[2224]: info: Shairport-Sync Started Dec 21 23:36:55 volumiosalon volumio[2224]: info: touch_display: Setting screensaver timeout to 9999999 seconds. Dec 21 23:36:56 volumiosalon mpd[14318]: 2025-12-21T23:36:56 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 23:36:56 volumiosalon systemd[1]: Started mpd.service - Music Player Daemon. Dec 21 23:36:56 volumiosalon sudo[14278]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:56 volumiosalon sudo[14248]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:56 volumiosalon volumio[2224]: error: MPD error: The expression evaluated to a falsy value: Dec 21 23:36:56 volumiosalon volumio[2224]: assert.ok(self.idling) Dec 21 23:36:56 volumiosalon volumio[2224]: error: The expression evaluated to a falsy value: Dec 21 23:36:56 volumiosalon volumio[2224]: assert.ok(self.idling) Dec 21 23:36:56 volumiosalon volumio[2224]: error: updateQueue error: null Dec 21 23:36:57 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 21 23:36:57 volumiosalon sudo[14342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 23:36:57 volumiosalon sudo[14342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:57 volumiosalon systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 21 23:36:57 volumiosalon systemd[1]: mpd.service: Deactivated successfully. Dec 21 23:36:57 volumiosalon systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 23:36:57 volumiosalon systemd[1]: mpd.socket: Deactivated successfully. Dec 21 23:36:57 volumiosalon systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 23:36:57 volumiosalon systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 23:36:57 volumiosalon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 23:36:57 volumiosalon systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 23:36:57 volumiosalon sudo[14345]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 23:36:57 volumiosalon sudo[14345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:57 volumiosalon sudo[14345]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:57 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 21 23:36:57 volumiosalon sudo[14349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 23:36:57 volumiosalon sudo[14349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:57 volumiosalon systemd[1]: mpd.service: Deactivated successfully. Dec 21 23:36:57 volumiosalon systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 23:36:57 volumiosalon systemd[1]: mpd.socket: Deactivated successfully. Dec 21 23:36:57 volumiosalon systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 23:36:57 volumiosalon systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 23:36:57 volumiosalon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 23:36:57 volumiosalon systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 23:36:57 volumiosalon sudo[14352]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 23:36:57 volumiosalon sudo[14352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 23:36:57 volumiosalon sudo[14352]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:58 volumiosalon mpd[14354]: 2025-12-21T23:36:58 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 23:36:58 volumiosalon systemd[1]: Started mpd.service - Music Player Daemon. Dec 21 23:36:58 volumiosalon sudo[14342]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:58 volumiosalon sudo[14349]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:58 volumiosalon volumio[2224]: error: MPD error: The expression evaluated to a falsy value: Dec 21 23:36:58 volumiosalon volumio[2224]: assert.ok(self.idling) Dec 21 23:36:58 volumiosalon volumio[2224]: error: The expression evaluated to a falsy value: Dec 21 23:36:58 volumiosalon volumio[2224]: assert.ok(self.idling) Dec 21 23:36:58 volumiosalon volumio[2224]: error: updateQueue error: null Dec 21 23:36:58 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:58+01:00" level=trace msg="sent dealer ping" Dec 21 23:36:58 volumiosalon go-librespot[5135]: time="2025-12-21T23:36:58+01:00" level=trace msg="received dealer pong" Dec 21 23:36:59 volumiosalon sudo[14358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 21 23:36:59 volumiosalon sudo[14358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:59 volumiosalon sudo[14360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 21 23:36:59 volumiosalon sudo[14360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:36:59 volumiosalon systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 21 23:36:59 volumiosalon systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 21 23:36:59 volumiosalon systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 21 23:36:59 volumiosalon systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 21 23:36:59 volumiosalon sudo[14360]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:59 volumiosalon mpd_monitor.sh[14363]: MPD Monitor Service: Starting MPD Monitor Service Dec 21 23:36:59 volumiosalon sudo[14358]: pam_unix(sudo:session): session closed for user root Dec 21 23:36:59 volumiosalon volumio[2224]: info: Successfully started MPD Monitor Dec 21 23:36:59 volumiosalon volumio[2224]: info: Successfully started MPD Monitor Dec 21 23:37:01 volumiosalon sudo[14368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 21 23:37:01 volumiosalon sudo[14368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:01 volumiosalon sudo[14370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 21 23:37:01 volumiosalon sudo[14370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:01 volumiosalon systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 21 23:37:01 volumiosalon systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 21 23:37:01 volumiosalon systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 21 23:37:01 volumiosalon systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 21 23:37:01 volumiosalon sudo[14368]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:01 volumiosalon sudo[14370]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:01 volumiosalon mpd_monitor.sh[14373]: MPD Monitor Service: Starting MPD Monitor Service Dec 21 23:37:01 volumiosalon volumio[2224]: info: Successfully started MPD Monitor Dec 21 23:37:01 volumiosalon volumio[2224]: info: Successfully started MPD Monitor Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPlay Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::play index undefined Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::startPlaybackTimer Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: Spotify Resume Dec 21 23:37:07 volumiosalon volumio[2224]: info: Sending Spotify command to local API: /player/resume Dec 21 23:37:07 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:07+01:00" level=trace msg="seek to 203687ms (diff: 191ms, samples: 8982596, bytes: 9085399)" uri="spotify:track:0UAEHlFR79k9CJvknSGUNf" Dec 21 23:37:07 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:07+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Dec 21 23:37:07 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:07+01:00" level=debug msg="resume track at 203496ms" Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Effects disabled Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Effects disabled Dec 21 23:37:07 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 23:37:07 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 23:37:07 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:07+01:00" level=trace msg="emitting websocket event: playing" Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","resume":true,"play_origin":"go-librespot"}} Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: {"status":"play","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:07 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService play Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus pause Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","resume":true,"play_origin":"go-librespot"}} Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: {"status":"play","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:07 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService play Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus play Dec 21 23:37:07 volumiosalon volumio[2224]: info: Received an update from plugin. extracting info from payload Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:07 volumiosalon volumio[2224]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:07 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:07 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:07 volumiosalon sudo[14404]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:07 volumiosalon sudo[14404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:07 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:07 volumiosalon sudo[14406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:07 volumiosalon sudo[14406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Clipping Monitor started Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Clipping Monitor started Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Clipping Monitor started Dec 21 23:37:07 volumiosalon sudo[14410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:07 volumiosalon sudo[14410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:07 volumiosalon volumio[2224]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 21 23:37:07 volumiosalon systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 21 23:37:07 volumiosalon sudo[14406]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:07 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:07 volumiosalon sudo[14404]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:07 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:07 volumiosalon sudo[14410]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:07 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: {"status":"play","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:07 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService play Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus play Dec 21 23:37:07 volumiosalon volumio[2224]: info: Received an update from plugin. extracting info from payload Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:07 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:07 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: {"status":"play","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Pump Up The Jam - Edit","artist":"Technotronic","album":"Best Of","albumart":"https://i.scdn.co/image/ab67616d00001e02f1291485a66e5cbd0d9c878d","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","trackType":"spotify","seek":203000,"duration":215,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:07 volumiosalon sudo[14420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:07 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:37:07 volumiosalon sudo[14420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService play Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus play Dec 21 23:37:07 volumiosalon volumio[2224]: info: Received an update from plugin. extracting info from payload Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:07 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:07 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:07 volumiosalon sudo[14422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:07 volumiosalon sudo[14422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:07 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:07 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:07 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:07 volumiosalon sudo[14420]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:07 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:07 volumiosalon sudo[14422]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:07 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:07 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:07 volumiosalon sudo[14426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:07 volumiosalon sudo[14426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:07 volumiosalon sudo[14428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:07 volumiosalon sudo[14428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:07 volumiosalon sudo[14428]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:07 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:07 volumiosalon sudo[14426]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:07 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:07 volumiosalon systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 21 23:37:14 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:14 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 8 Dec 21 23:37:14 volumiosalon volumio[2224]: info: Prefetching next song Dec 21 23:37:14 volumiosalon volumio[2224]: info: [1766356634321] ControllerSpotify::prefetch Dec 21 23:37:14 volumiosalon volumio[2224]: info: Sending Spotify command with payload to local API: /player/add_to_queue Dec 21 23:37:14 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:14+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 23:37:14 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:14+01:00" level=debug msg="prefetching next track" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:14 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:14+01:00" level=debug msg="selected format OGG_VORBIS_320 (3c43c09e868bc546d41279bae334ce06e93b4c08)" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:14 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:14+01:00" level=debug msg="requested aes key for file 3c43c09e868bc546d41279bae334ce06e93b4c08, gid: 3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:14 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:14+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:14 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:14+01:00" level=debug msg="fetched first chunk of 30, total size is 15675788 bytes" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:14 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:14+01:00" level=info msg="prefetched track \"Chord Orbit\" (duration: 341760ms)" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:14 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:14+01:00" level=debug msg="fetched chunk 1/29, size: 524288" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:14 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:14+01:00" level=debug msg="fetched chunk 3/29, size: 524288" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:15 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:15+01:00" level=debug msg="fetched chunk 2/29, size: 524288" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=trace msg="emitting websocket event: not_playing" Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","play_origin":"go-librespot"}} Dec 21 23:37:17 volumiosalon volumio[2224]: error: Failed to decode event: not_playing Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","play_origin":"go-librespot"}} Dec 21 23:37:17 volumiosalon volumio[2224]: error: Failed to decode event: not_playing Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=trace msg="emitting websocket event: will_play" Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=info msg="loaded track \"Chord Orbit\" (paused: false, position: 0ms, duration: 341760ms, prefetched: true)" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","play_origin":"go-librespot"}} Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","play_origin":"go-librespot"}} Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=trace msg="scheduling prefetch in 312s" Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=trace msg="emitting websocket event: metadata" Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","name":"Chord Orbit","artist_names":["Gerd"],"album_name":"Planet F.M.D.X. Pt. 2","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","position":0,"duration":341760,"release_date":"year:2018 month:6 day:29","track_number":4,"disc_number":1}} Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","name":"Chord Orbit","artist_names":["Gerd"],"album_name":"Planet F.M.D.X. Pt. 2","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","position":0,"duration":341760,"release_date":"year:2018 month:6 day:29","track_number":4,"disc_number":1}} Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 21 23:37:17 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:17+01:00" level=trace msg="emitting websocket event: playing" Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","resume":false,"play_origin":"go-librespot"}} Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: {"status":"play","service":"spop","title":"Chord Orbit","artist":"Gerd","album":"Planet F.M.D.X. Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","trackType":"spotify","seek":0,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Chord Orbit","artist":"Gerd","album":"Planet F.M.D.X. Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","trackType":"spotify","seek":0,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:17 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService play Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus play Dec 21 23:37:17 volumiosalon volumio[2224]: info: Received an update from plugin. extracting info from payload Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:0UAEHlFR79k9CJvknSGUNf","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","resume":false,"play_origin":"go-librespot"}} Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: {"status":"play","service":"spop","title":"Chord Orbit","artist":"Gerd","album":"Planet F.M.D.X. Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","trackType":"spotify","seek":0,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Chord Orbit","artist":"Gerd","album":"Planet F.M.D.X. Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","trackType":"spotify","seek":0,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:17 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService play Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus play Dec 21 23:37:17 volumiosalon volumio[2224]: info: Received an update from plugin. extracting info from payload Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:17 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:17 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:17 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:17 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:17 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:17 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:17 volumiosalon sudo[14448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:17 volumiosalon sudo[14448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:17 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:17 volumiosalon sudo[14450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:17 volumiosalon sudo[14450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:17 volumiosalon sudo[14453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:17 volumiosalon sudo[14453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:17 volumiosalon sudo[14456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:17 volumiosalon sudo[14456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:17 volumiosalon systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 21 23:37:17 volumiosalon sudo[14450]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:17 volumiosalon sudo[14448]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:17 volumiosalon sudo[14453]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:17 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:17 volumiosalon sudo[14456]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:17 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:17 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:17 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: {"status":"play","service":"spop","title":"Chord Orbit","artist":"Gerd","album":"Planet F.M.D.X. Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","trackType":"spotify","seek":0,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Chord Orbit","artist":"Gerd","album":"Planet F.M.D.X. Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","trackType":"spotify","seek":0,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:17 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService play Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus play Dec 21 23:37:17 volumiosalon volumio[2224]: info: Received an update from plugin. extracting info from payload Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:17 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:17 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:17 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: PUSH STATE SPOTIFY Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: {"status":"play","service":"spop","title":"Chord Orbit","artist":"Gerd","album":"Planet F.M.D.X. Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","trackType":"spotify","seek":0,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::servicePushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Chord Orbit","artist":"Gerd","album":"Planet F.M.D.X. Pt. 2","albumart":"https://i.scdn.co/image/ab67616d00001e02fd848250154e51c05c356fd5","uri":"spotify:track:3vFj9rJXCjvfFUnXxNBRri","trackType":"spotify","seek":0,"duration":341,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 21 23:37:17 volumiosalon volumio[2224]: verbose: CURRENT POSITION 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::syncState stateService play Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::syncState currentStatus play Dec 21 23:37:17 volumiosalon volumio[2224]: info: Received an update from plugin. extracting info from payload Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:17 volumiosalon sudo[14462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:17 volumiosalon sudo[14462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 7 Dec 21 23:37:17 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:17 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:17 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:17 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:17 volumiosalon sudo[14464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:17 volumiosalon sudo[14464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:17 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:17 volumiosalon sudo[14462]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:17 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:17 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:17 volumiosalon sudo[14464]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:17 volumiosalon sudo[14468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:17 volumiosalon sudo[14468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:17 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:17 volumiosalon sudo[14470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:17 volumiosalon sudo[14470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:17 volumiosalon sudo[14468]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:17 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:17 volumiosalon sudo[14470]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:17 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:17 volumiosalon systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 21 23:37:18 volumiosalon volumio[2224]: info: CoreStateMachine::startPlaybackTimer Dec 21 23:37:18 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 8 Dec 21 23:37:19 volumiosalon volumio[2224]: info: CoreStateMachine::pushState Dec 21 23:37:19 volumiosalon volumio[2224]: info: CorePlayQueue::getTrack 8 Dec 21 23:37:19 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 23:37:19 volumiosalon volumio[2224]: info: CoreCommandRouter::volumioPushState Dec 21 23:37:19 volumiosalon volumio[2224]: info: FusionDsp - Volumio is playing Dec 21 23:37:19 volumiosalon volumio[2224]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Dec 21 23:37:19 volumiosalon volumio[2224]: info: PeppySpectrum ---peppyspectrum status play Dec 21 23:37:19 volumiosalon sudo[14474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 21 23:37:19 volumiosalon sudo[14474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 23:37:19 volumiosalon systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 21 23:37:19 volumiosalon sudo[14474]: pam_unix(sudo:session): session closed for user root Dec 21 23:37:19 volumiosalon volumio[2224]: info: peppyspectrum Daemon Started Dec 21 23:37:19 volumiosalon systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 21 23:37:21 volumiosalon volumio[2224]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 23:37:28 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:28+01:00" level=trace msg="sent dealer ping" Dec 21 23:37:28 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:28+01:00" level=trace msg="received dealer pong" Dec 21 23:37:29 volumiosalon go-librespot[5135]: time="2025-12-21T23:37:29+01:00" level=debug msg="fetched chunk 4/29, size: 524288" uri="spotify:track:3vFj9rJXCjvfFUnXxNBRri" Dec 21 23:37:32 volumiosalon volumio[2224]: info: CALLMETHOD: user_interface peppyspectrum updatelist Dec 21 23:37:32 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Dec 21 23:37:32 volumiosalon volumio[2224]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Dec 21 23:37:33 volumiosalon volumio[2224]: --2025-12-21 23:37:32-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Dec 21 23:37:33 volumiosalon volumio[2224]: Resolving github.com (github.com)... 140.82.121.4 Dec 21 23:37:33 volumiosalon volumio[2224]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 21 23:37:33 volumiosalon volumio[2224]: HTTP request sent, awaiting response... 302 Found Dec 21 23:37:33 volumiosalon volumio[2224]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Dec 21 23:37:33 volumiosalon volumio[2224]: --2025-12-21 23:37:33-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Dec 21 23:37:33 volumiosalon volumio[2224]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.110.133, 185.199.109.133, 185.199.111.133, ... Dec 21 23:37:33 volumiosalon volumio[2224]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.110.133|:443... connected. Dec 21 23:37:33 volumiosalon volumio[2224]: HTTP request sent, awaiting response... 200 OK Dec 21 23:37:33 volumiosalon volumio[2224]: Length: 471 [text/plain] Dec 21 23:37:33 volumiosalon volumio[2224]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Dec 21 23:37:33 volumiosalon volumio[2224]: 0K 100% 12.1M=0s Dec 21 23:37:33 volumiosalon volumio[2224]: 2025-12-21 23:37:33 (12.1 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Dec 21 23:37:33 volumiosalon volumio[2224]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 23:37:33 volumiosalon volumio[2224]: info: CoreCommandRouter::Close All Modals sent Dec 21 23:37:36 volumiosalon volumio[2224]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Dec 21 23:37:36 volumiosalon volumio[2224]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Dec 21 23:37:37 volumiosalon volumio[2224]: --2025-12-21 23:37:36-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Dec 21 23:37:37 volumiosalon volumio[2224]: Resolving github.com (github.com)... 140.82.121.3 Dec 21 23:37:37 volumiosalon volumio[2224]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 21 23:37:37 volumiosalon volumio[2224]: HTTP request sent, awaiting response... 404 Not Found Dec 21 23:37:37 volumiosalon volumio[2224]: 2025-12-21 23:37:37 ERROR 404: Not Found. Dec 21 23:37:37 volumiosalon volumio[2224]: --2025-12-21 23:37:37-- http://a/ Dec 21 23:37:37 volumiosalon volumio[2224]: Resolving a (a)... failed: Device or resource busy. Dec 21 23:37:37 volumiosalon volumio[2224]: wget: unable to resolve host address ‘a’ Dec 21 23:37:37 volumiosalon volumio[2224]: --2025-12-21 23:37:37-- http://packet.zip/ Dec 21 23:37:37 volumiosalon volumio[2224]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 21 23:37:37 volumiosalon volumio[2224]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 21 23:37:37 volumiosalon volumio[2224]: HTTP request sent, awaiting response... 302 Found Dec 21 23:37:37 volumiosalon volumio[2224]: Location: http://www.packet.zip/ [following] Dec 21 23:37:37 volumiosalon volumio[2224]: --2025-12-21 23:37:37-- http://www.packet.zip/ Dec 21 23:37:37 volumiosalon volumio[2224]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 21 23:37:37 volumiosalon volumio[2224]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 21 23:37:37 volumiosalon volumio[2224]: HTTP request sent, awaiting response... 403 Forbidden Dec 21 23:37:37 volumiosalon volumio[2224]: 2025-12-21 23:37:37 ERROR 403: Forbidden. Dec 21 23:37:37 volumiosalon volumio[2224]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Dec 21 23:37:37 volumiosalon volumio[2224]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 21 23:37:37 volumiosalon volumio[2224]: /bin/rm: cannot remove 'a': No such file or directory Dec 21 23:37:37 volumiosalon volumio[2224]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 21 23:37:37 volumiosalon volumio[2224]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 23:37:37 volumiosalon volumio[2224]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 21 23:37:37 volumiosalon volumio[2224]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 21 23:37:37 volumiosalon volumio[2224]: /bin/rm: cannot remove 'a': No such file or directory Dec 21 23:37:37 volumiosalon volumio[2224]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 21 23:37:37 volumiosalon volumio[2224]: at checkExecSyncError (node:child_process:887:11) Dec 21 23:37:37 volumiosalon volumio[2224]: at execSync (node:child_process:959:15) Dec 21 23:37:37 volumiosalon volumio[2224]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Dec 21 23:37:37 volumiosalon volumio[2224]: at new Promise () Dec 21 23:37:37 volumiosalon volumio[2224]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Dec 21 23:37:37 volumiosalon volumio[2224]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 21 23:37:37 volumiosalon volumio[2224]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 21 23:37:37 volumiosalon volumio[2224]: at Socket.emit (node:events:514:28) Dec 21 23:37:37 volumiosalon volumio[2224]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 21 23:37:37 volumiosalon volumio[2224]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 21 23:37:37 volumiosalon volumio[2224]: status: 1, Dec 21 23:37:37 volumiosalon volumio[2224]: signal: null, Dec 21 23:37:37 volumiosalon volumio[2224]: output: [ Dec 21 23:37:37 volumiosalon volumio[2224]: null, Dec 21 23:37:37 volumiosalon volumio[2224]: , Dec 21 23:37:37 volumiosalon volumio[2224]: Dec 21 23:37:37 volumiosalon volumio[2224]: ], Dec 21 23:37:37 volumiosalon volumio[2224]: pid: 14517, Dec 21 23:37:37 volumiosalon volumio[2224]: stdout: , Dec 21 23:37:37 volumiosalon volumio[2224]: stderr: Dec 21 23:37:37 volumiosalon volumio[2224]: } Dec 21 23:37:37 volumiosalon volumio[2224]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 23:37:37 volumiosalon sudo[14534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 23:36' Dec 21 23:37:37 volumiosalon sudo[14534]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"