Dec 25 17:01:00 volumio volumio[9594]: info: Successfully removed fusiondsp configuration files Dec 25 17:01:00 volumio volumio[9594]: info: Plugin folders cleanup Dec 25 17:01:00 volumio volumio[9594]: info: Scanning into folder /volumio/app/plugins/ Dec 25 17:01:00 volumio volumio[9594]: info: Scanning category audio_interface Dec 25 17:01:00 volumio volumio[9594]: info: Scanning category miscellanea Dec 25 17:01:00 volumio volumio[9594]: info: Scanning category music_service Dec 25 17:01:00 volumio volumio[9594]: info: Scanning category plugins.json Dec 25 17:01:00 volumio volumio[9594]: info: Scanning category system_controller Dec 25 17:01:00 volumio volumio[9594]: info: Scanning category user_interface Dec 25 17:01:00 volumio volumio[9594]: info: Scanning into folder /data/plugins/ Dec 25 17:01:00 volumio volumio[9594]: info: Scanning category audio_interface Dec 25 17:01:00 volumio volumio[9594]: info: Cleaning folder for fusiondsp Dec 25 17:01:00 volumio go-librespot[9895]: time="2025-12-25T17:01:00+02:00" level=debug msg="fetched chunk 6/9, size: 524288" uri="spotify:track:12IQxinx5b8ftqC1VJit1f" Dec 25 17:01:00 volumio volumio[9594]: info: Scanning category music_service Dec 25 17:01:00 volumio volumio[9594]: info: Scanning category user_interface Dec 25 17:01:00 volumio volumio[9594]: info: Plugin folders cleanup completed Dec 25 17:01:00 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 25 17:01:00 volumio volumio[9594]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.3.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"2.4.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.15","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"1.0.39","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.6.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Dec 25 17:01:00 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 25 17:01:00 volumio volumio[9594]: error: updateQueue error: null Dec 25 17:01:03 volumio sudo[12235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 25 17:01:03 volumio sudo[12235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:03 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 25 17:01:03 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 25 17:01:03 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 25 17:01:03 volumio systemd[1]: mpd_monitor.service: Consumed 4.184s CPU time. Dec 25 17:01:03 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 25 17:01:03 volumio sudo[12235]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:03 volumio mpd_monitor.sh[12237]: MPD Monitor Service: Starting MPD Monitor Service Dec 25 17:01:03 volumio volumio[9594]: info: Successfully started MPD Monitor Dec 25 17:01:06 volumio go-librespot[9895]: time="2025-12-25T17:01:06+02:00" level=trace msg="sent dealer ping" Dec 25 17:01:06 volumio go-librespot[9895]: time="2025-12-25T17:01:06+02:00" level=trace msg="received dealer pong" Dec 25 17:01:16 volumio go-librespot[9895]: time="2025-12-25T17:01:16+02:00" level=debug msg="fetched chunk 7/9, size: 524288" uri="spotify:track:12IQxinx5b8ftqC1VJit1f" Dec 25 17:01:17 volumio volumio[9594]: info: Enabling plugin peppyspectrum Dec 25 17:01:17 volumio volumio[9594]: info: Loading plugin "peppyspectrum"... Dec 25 17:01:17 volumio volumio[9594]: info: Preparing to generate the ALSA configuration file Dec 25 17:01:17 volumio volumio[9594]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 25 17:01:17 volumio volumio[9594]: info: Reading ALSA contributions from plugins. Dec 25 17:01:17 volumio volumio[9594]: info: Asound.conf file written Dec 25 17:01:17 volumio sudo[12260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 25 17:01:17 volumio sudo[12260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:17 volumio sudo[12260]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:17 volumio volumio[9594]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 25 17:01:17 volumio volumio[9594]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 25 17:01:17 volumio volumio[9594]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 25 17:01:17 volumio volumio[9594]: info: Output device has changed, restarting MPD Dec 25 17:01:17 volumio volumio[9594]: info: Output device has changed, restarting Shairport Sync Dec 25 17:01:17 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:17 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 17:01:17 volumio sudo[12269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 17:01:17 volumio sudo[12269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:17 volumio sudo[12270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 17:01:17 volumio sudo[12270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:17 volumio sudo[12269]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:17 volumio volumio[9594]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 17:01:17 volumio volumio[9594]: info: PLUGIN START: peppyspectrum Dec 25 17:01:17 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 25 17:01:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 25 17:01:18 volumio volumio[9594]: info: Preparing to generate the ALSA configuration file Dec 25 17:01:18 volumio volumio[9594]: info: Done. Dec 25 17:01:18 volumio sudo[12278]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 25 17:01:18 volumio sudo[12278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:18 volumio volumio[9594]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 25 17:01:18 volumio volumio[9594]: info: Reading ALSA contributions from plugins. Dec 25 17:01:18 volumio volumio[9594]: info: MPD Permissions set Dec 25 17:01:18 volumio volumio[9594]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 25 17:01:18 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 25 17:01:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 17:01:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 25 17:01:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 17:01:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 17:01:18 volumio sudo[12278]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: snd_dummy loaded Dec 25 17:01:18 volumio volumio[9594]: info: Starting Shairport Sync Dec 25 17:01:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 17:01:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 17:01:18 volumio volumio[9594]: info: Asound.conf file unchanged, so no further update is needed Dec 25 17:01:18 volumio volumio[9594]: info: Output device has changed, restarting MPD Dec 25 17:01:18 volumio sudo[12288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 17:01:18 volumio sudo[12288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:18 volumio sudo[12292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 17:01:18 volumio sudo[12292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:18 volumio sudo[12292]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:18 volumio volumio[9594]: info: Output device has changed, restarting Shairport Sync Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 17:01:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 17:01:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 17:01:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 17:01:18 volumio systemd[1]: shairport-sync.service: Consumed 1.483s CPU time. Dec 25 17:01:18 volumio sudo[12295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 17:01:18 volumio sudo[12295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:18 volumio volumio[9594]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 17:01:18 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 17:01:18 volumio volumio[9594]: info: MPD Permissions set Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio sudo[12288]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio sudo[12289]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 17:01:18 volumio sudo[12289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 17:01:18 volumio sudo[12289]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:18 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 25 17:01:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 17:01:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 17:01:18 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 17:01:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 17:01:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 17:01:18 volumio volumio[9594]: info: Shairport-Sync Started Dec 25 17:01:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 17:01:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 17:01:18 volumio volumio[9594]: (node:9594) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 25 17:01:18 volumio volumio[9594]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 25 17:01:18 volumio volumio[9594]: (node:9594) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 25 17:01:18 volumio volumio[9594]: (node:9594) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 25 17:01:18 volumio volumio[9594]: (node:9594) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 25 17:01:18 volumio volumio[9594]: (node:9594) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 25 17:01:18 volumio volumio[9594]: info: Starting Shairport Sync Dec 25 17:01:18 volumio sudo[12327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 17:01:18 volumio sudo[12327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 17:01:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 17:01:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 17:01:18 volumio sudo[12323]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 17:01:18 volumio sudo[12323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 17:01:18 volumio sudo[12323]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:18 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 17:01:18 volumio sudo[12327]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:18 volumio volumio[9594]: info: Shairport-Sync Started Dec 25 17:01:18 volumio mpd[12332]: 2025-12-25T17:01:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 25 17:01:18 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 25 17:01:18 volumio sudo[12295]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:18 volumio sudo[12270]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:18 volumio volumio[9594]: error: MPD error: The expression evaluated to a falsy value: Dec 25 17:01:18 volumio volumio[9594]: assert.ok(self.idling) Dec 25 17:01:18 volumio volumio[9594]: error: The expression evaluated to a falsy value: Dec 25 17:01:18 volumio volumio[9594]: assert.ok(self.idling) Dec 25 17:01:18 volumio volumio[9594]: error: updateQueue error: null Dec 25 17:01:21 volumio sudo[12364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 25 17:01:21 volumio sudo[12364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:21 volumio sudo[12366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 25 17:01:21 volumio sudo[12366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:21 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 25 17:01:21 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 25 17:01:21 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 25 17:01:21 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 25 17:01:21 volumio sudo[12364]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:21 volumio mpd_monitor.sh[12369]: MPD Monitor Service: Starting MPD Monitor Service Dec 25 17:01:21 volumio volumio[9594]: info: Successfully started MPD Monitor Dec 25 17:01:21 volumio sudo[12366]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:21 volumio volumio[9594]: info: Successfully started MPD Monitor Dec 25 17:01:26 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 25 17:01:26 volumio volumio[9594]: info: In handleBrowseUri, curUri=spotify Dec 25 17:01:26 volumio volumio[9594]: info: New access token = BQCJIcVn8WOE8TKZeuh99QLH2Gcu1B8fwDiALrIbp-X4DMqcS93KcJ84NAaeVLyxZWUoLRbj0pdUJu35YFnTIn3-kOqso1Tk6PZ1IFKc8tPxgo6CbR0DLaaIvUBkrhL3oYOwSzlHSuzvklHwJUIQ1-mx0NI5E2fYNG2F_q2XQfhKegpihuZ9sBk9JV5UuyWj9f4XVifffWp-36aQyPa6nmwPv3z6RgKH7l1eE0tT7wLwe-t1Dv-cS-D1VieD07H8h8kwNBq3mvDrJXjHA96OEQ-K80F6-d5cKqNrSoXbH8iQutpU-s1Ntt0Db7r9 Dec 25 17:01:26 volumio volumio[9594]: info: New access token = BQDCEDvq6ezPCkedTk0Uyg-Nc-7-NJl46zYuAuMH1hJvGFln6f_HZk3SQs4WHBmhGwhHkuAdVDjRvbYVmfqJot0nC8H4SFFYtMMH48y4TVhSJ-g68ChgOnvOMysTFMclNI9n-ZcTEwwIPnUvp3z2PXG7SUFFT6SMwn_L20q6R9d50kodw7noOsd6JAupeeGUpOiLZnUoc9oJaALfuetOYGNmuhSZebtqiE_ZVvVx_MyzG-5Ic5qFvP1ErDcguaagBEDLHjYH_I7K8_uovKHQxLVcexz-ksMrbMrTYfvJGJqV5VUbBtZXHnCMPg1P Dec 25 17:01:26 volumio volumio[9594]: info: New access token = BQC6ih54s6-jh_xlqu1wXGyb4qzwNLskpfbpVm4O6ND7xTMMrijNclzwE0jPe3D3OCJkLq8oQZazOYFfn3lTo4uk31GWgM8HyWnPo0kNH5c8Wy-HVlEHwz3v32BHMPzVI6G85PeOwY8YDo4pDzUWQScfY6FuyraySAQj7zfmRE5XNGfJKP_-DaQENX5BsoDaFHL3Nrk8gYYzNOtew8d7Enka7cUiu6gGuh06rwozeNyhKO4OXAmeh19OIWiOQcSk9jljqjpEzlpITC0gGUKoJXoF1i_NfhrF0O0Up8YqzaeqW-e87Gzqa1w4EqmT Dec 25 17:01:27 volumio volumio[9594]: info: Preload queue cleared Dec 25 17:01:27 volumio volumio[9594]: info: Preload queue cleared Dec 25 17:01:27 volumio volumio[9594]: info: Preload queue cleared Dec 25 17:01:27 volumio volumio[9594]: info: Preload queue cleared Dec 25 17:01:31 volumio go-librespot[9895]: time="2025-12-25T17:01:31+02:00" level=debug msg="fetched chunk 8/9, size: 524288" uri="spotify:track:12IQxinx5b8ftqC1VJit1f" Dec 25 17:01:36 volumio go-librespot[9895]: time="2025-12-25T17:01:36+02:00" level=trace msg="sent dealer ping" Dec 25 17:01:36 volumio go-librespot[9895]: time="2025-12-25T17:01:36+02:00" level=trace msg="received dealer pong" Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:42 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:42 volumio volumio[9594]: info: Dec 25 17:01:42 volumio volumio[9594]: ---------------------------- Client requests Volume 94 Dec 25 17:01:42 volumio volumio[9594]: info: VolumeController::SetAlsaVolume94 Dec 25 17:01:42 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:42 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 100 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 94 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:42 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 94 Dec 25 17:01:42 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:42 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:42 volumio sudo[12411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:42 volumio sudo[12411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:42 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 25 17:01:42 volumio sudo[12411]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:42 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:42 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:42 volumio volumio[9594]: info: Dec 25 17:01:42 volumio volumio[9594]: ---------------------------- Client requests Volume 75 Dec 25 17:01:42 volumio volumio[9594]: info: VolumeController::SetAlsaVolume75 Dec 25 17:01:42 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:42 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 94 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 75 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:42 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 75 Dec 25 17:01:42 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:42 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:42 volumio sudo[12418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:42 volumio sudo[12418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:42 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:42 volumio volumio[9594]: info: Dec 25 17:01:42 volumio volumio[9594]: ---------------------------- Client requests Volume 62 Dec 25 17:01:42 volumio volumio[9594]: info: VolumeController::SetAlsaVolume62 Dec 25 17:01:42 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:42 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:42 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 75 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 62 Dec 25 17:01:42 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:42 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 62 Dec 25 17:01:42 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:42 volumio sudo[12418]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:42 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:42 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:42 volumio volumio[9594]: info: Dec 25 17:01:42 volumio volumio[9594]: ---------------------------- Client requests Volume 59 Dec 25 17:01:42 volumio volumio[9594]: info: VolumeController::SetAlsaVolume59 Dec 25 17:01:42 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:42 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:42 volumio sudo[12422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:42 volumio sudo[12422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:42 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:42 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:43 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 62 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 59 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:43 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 59 Dec 25 17:01:43 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:43 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:43 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:43 volumio volumio[9594]: info: Dec 25 17:01:43 volumio volumio[9594]: ---------------------------- Client requests Volume 46 Dec 25 17:01:43 volumio volumio[9594]: info: VolumeController::SetAlsaVolume46 Dec 25 17:01:43 volumio sudo[12426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:43 volumio sudo[12426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:43 volumio sudo[12422]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:43 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:43 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 59 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 46 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:43 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 46 Dec 25 17:01:43 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:43 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:43 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:43 volumio volumio[9594]: info: Dec 25 17:01:43 volumio volumio[9594]: ---------------------------- Client requests Volume 32 Dec 25 17:01:43 volumio volumio[9594]: info: VolumeController::SetAlsaVolume32 Dec 25 17:01:43 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:43 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:43 volumio sudo[12426]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:43 volumio volumio[9594]: info: Dec 25 17:01:43 volumio volumio[9594]: ---------------------------- Client requests Volume 38 Dec 25 17:01:43 volumio volumio[9594]: info: VolumeController::SetAlsaVolume38 Dec 25 17:01:43 volumio sudo[12430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:43 volumio sudo[12430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:43 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:43 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:43 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:43 volumio sudo[12430]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:43 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:43 volumio sudo[12436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:43 volumio sudo[12436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 46 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 32 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:43 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 32 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 32 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 38 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:43 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 38 Dec 25 17:01:43 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:43 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:43 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:43 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:43 volumio sudo[12439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:43 volumio sudo[12439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:43 volumio sudo[12439]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:43 volumio sudo[12436]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:43 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:43 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:43 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:43 volumio volumio[9594]: info: Dec 25 17:01:43 volumio volumio[9594]: ---------------------------- Client requests Volume 15 Dec 25 17:01:43 volumio volumio[9594]: info: VolumeController::SetAlsaVolume15 Dec 25 17:01:43 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:43 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 38 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 15 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:43 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 15 Dec 25 17:01:43 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:43 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:43 volumio sudo[12444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:43 volumio sudo[12444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:43 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:43 volumio volumio[9594]: info: Dec 25 17:01:43 volumio volumio[9594]: ---------------------------- Client requests Volume 14 Dec 25 17:01:43 volumio volumio[9594]: info: VolumeController::SetAlsaVolume14 Dec 25 17:01:43 volumio sudo[12444]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:43 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:43 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 15 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 14 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 25 17:01:43 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:43 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:43 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:43 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:43 volumio sudo[12448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:43 volumio sudo[12448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:43 volumio volumio[9594]: info: Dec 25 17:01:43 volumio volumio[9594]: ---------------------------- Client requests Volume 13 Dec 25 17:01:43 volumio volumio[9594]: info: VolumeController::SetAlsaVolume13 Dec 25 17:01:43 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:43 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:43 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 15 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 13 Dec 25 17:01:43 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:43 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 13 Dec 25 17:01:43 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:43 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:43 volumio sudo[12448]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:43 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:43 volumio sudo[12452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:43 volumio sudo[12452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:43 volumio sudo[12452]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:43 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:44 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:44 volumio volumio[9594]: info: Dec 25 17:01:44 volumio volumio[9594]: ---------------------------- Client requests Volume 12 Dec 25 17:01:44 volumio volumio[9594]: info: VolumeController::SetAlsaVolume12 Dec 25 17:01:44 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:44 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 12 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 13 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 12 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 25 17:01:44 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:44 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:44 volumio sudo[12460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:44 volumio sudo[12460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:44 volumio sudo[12460]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:44 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:44 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:44 volumio volumio[9594]: info: Dec 25 17:01:44 volumio volumio[9594]: ---------------------------- Client requests Volume 34 Dec 25 17:01:44 volumio volumio[9594]: info: VolumeController::SetAlsaVolume34 Dec 25 17:01:44 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:44 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 13 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 34 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:44 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 34 Dec 25 17:01:44 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:44 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:44 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:44 volumio volumio[9594]: info: Dec 25 17:01:44 volumio volumio[9594]: ---------------------------- Client requests Volume 39 Dec 25 17:01:44 volumio volumio[9594]: info: VolumeController::SetAlsaVolume39 Dec 25 17:01:44 volumio sudo[12464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:44 volumio sudo[12464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:44 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:44 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:44 volumio sudo[12464]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 34 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 39 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:44 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 39 Dec 25 17:01:44 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:44 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:44 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:44 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:44 volumio volumio[9594]: info: Dec 25 17:01:44 volumio volumio[9594]: ---------------------------- Client requests Volume 53 Dec 25 17:01:44 volumio volumio[9594]: info: VolumeController::SetAlsaVolume53 Dec 25 17:01:44 volumio sudo[12468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:44 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:44 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:44 volumio sudo[12468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 39 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 53 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:44 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 53 Dec 25 17:01:44 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:44 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:44 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:44 volumio volumio[9594]: info: Dec 25 17:01:44 volumio volumio[9594]: ---------------------------- Client requests Volume 59 Dec 25 17:01:44 volumio sudo[12468]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:44 volumio volumio[9594]: info: VolumeController::SetAlsaVolume59 Dec 25 17:01:44 volumio sudo[12472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:44 volumio sudo[12472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:44 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:44 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:44 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 53 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 59 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:44 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 59 Dec 25 17:01:44 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:44 volumio sudo[12472]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:44 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:44 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:44 volumio volumio[9594]: info: Dec 25 17:01:44 volumio volumio[9594]: ---------------------------- Client requests Volume 75 Dec 25 17:01:44 volumio volumio[9594]: info: VolumeController::SetAlsaVolume75 Dec 25 17:01:44 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:44 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:44 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:44 volumio sudo[12476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 59 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 75 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:44 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 75 Dec 25 17:01:44 volumio sudo[12476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:44 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:44 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:44 volumio sudo[12476]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:44 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:44 volumio sudo[12480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:44 volumio sudo[12480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:44 volumio sudo[12480]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:44 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:44 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:44 volumio volumio[9594]: info: Dec 25 17:01:44 volumio volumio[9594]: ---------------------------- Client requests Volume 100 Dec 25 17:01:44 volumio volumio[9594]: info: VolumeController::SetAlsaVolume100 Dec 25 17:01:44 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:44 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:44 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 75 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 100 Dec 25 17:01:44 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:44 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 100 Dec 25 17:01:44 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:44 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:45 volumio sudo[12484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:45 volumio sudo[12484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:45 volumio sudo[12484]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:45 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:45 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:45 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:45 volumio volumio[9594]: info: Dec 25 17:01:45 volumio volumio[9594]: ---------------------------- Client requests Volume 70 Dec 25 17:01:45 volumio volumio[9594]: info: VolumeController::SetAlsaVolume70 Dec 25 17:01:45 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:45 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:45 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:45 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 100 Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 70 Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:45 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 70 Dec 25 17:01:45 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:45 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:45 volumio sudo[12488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:45 volumio sudo[12488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:45 volumio sudo[12488]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:45 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:45 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:45 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:45 volumio volumio[9594]: info: Dec 25 17:01:45 volumio volumio[9594]: ---------------------------- Client requests Volume 92 Dec 25 17:01:45 volumio volumio[9594]: info: VolumeController::SetAlsaVolume92 Dec 25 17:01:45 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:45 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:45 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:45 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 70 Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 92 Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:45 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 92 Dec 25 17:01:45 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:45 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:45 volumio sudo[12492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:45 volumio sudo[12492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:45 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Dec 25 17:01:45 volumio volumio[9594]: info: Setting Remote Device Volume: http://192.168.0.80 Dec 25 17:01:45 volumio volumio[9594]: info: Dec 25 17:01:45 volumio volumio[9594]: ---------------------------- Client requests Volume 100 Dec 25 17:01:45 volumio volumio[9594]: info: VolumeController::SetAlsaVolume100 Dec 25 17:01:45 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:01:45 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:01:45 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:01:45 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:01:45 volumio sudo[12492]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: SPOTIFY VOLUME 92 Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: VOLUMIO VOLUME 100 Dec 25 17:01:45 volumio volumio[9594]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 25 17:01:45 volumio volumio[9594]: info: Setting Spotify Volume from Volumio: 100 Dec 25 17:01:45 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:01:45 volumio volumio[9594]: info: Done setting volume on: Dec 25 17:01:45 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:45 volumio sudo[12496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:01:45 volumio sudo[12496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:01:45 volumio sudo[12496]: pam_unix(sudo:session): session closed for user root Dec 25 17:01:45 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:01:46 volumio go-librespot[9895]: time="2025-12-25T17:01:46+02:00" level=debug msg="fetched chunk 9/9, size: 58936" uri="spotify:track:12IQxinx5b8ftqC1VJit1f" Dec 25 17:01:47 volumio volumio[9594]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Dec 25 17:01:47 volumio volumio[9594]: info: Sending Spotify command with payload to local API: /player/volume Dec 25 17:01:47 volumio go-librespot[9895]: time="2025-12-25T17:01:47+02:00" level=debug msg="update volume requested to 65535/65535" Dec 25 17:01:47 volumio go-librespot[9895]: time="2025-12-25T17:01:47+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 25 17:01:47 volumio go-librespot[9895]: time="2025-12-25T17:01:47+02:00" level=trace msg="emitting websocket event: volume" Dec 25 17:01:47 volumio volumio[9594]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Dec 25 17:01:47 volumio volumio[9594]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Dec 25 17:02:06 volumio go-librespot[9895]: time="2025-12-25T17:02:06+02:00" level=trace msg="sent dealer ping" Dec 25 17:02:06 volumio go-librespot[9895]: time="2025-12-25T17:02:06+02:00" level=trace msg="received dealer pong" Dec 25 17:02:35 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:02:35 volumio volumio[9594]: info: CorePlayQueue::getTrack 51 Dec 25 17:02:35 volumio volumio[9594]: info: Prefetching next song Dec 25 17:02:35 volumio volumio[9594]: info: [1766674955959] ControllerSpotify::prefetch Dec 25 17:02:35 volumio volumio[9594]: info: Sending Spotify command with payload to local API: /player/add_to_queue Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=debug msg="prefetching next track" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=trace msg="received accesspoint ping" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=debug msg="selected format OGG_VORBIS_320 (589444fca3ac7eaac766091c157c2bf985f0ae52)" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=debug msg="requested aes key for file 589444fca3ac7eaac766091c157c2bf985f0ae52, gid: 50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=trace msg="received accesspoint pong ack" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=trace msg="sent dealer ping" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=trace msg="received dealer pong" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=debug msg="fetched first chunk of 15, total size is 7512694 bytes" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=info msg="prefetched track \"Deeper\" (duration: 214987ms)" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:36 volumio go-librespot[9895]: time="2025-12-25T17:02:36+02:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:37 volumio go-librespot[9895]: time="2025-12-25T17:02:37+02:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:39 volumio go-librespot[9895]: time="2025-12-25T17:02:39+02:00" level=trace msg="emitting websocket event: not_playing" Dec 25 17:02:39 volumio go-librespot[9895]: time="2025-12-25T17:02:39+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:39 volumio volumio[9594]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:track:12IQxinx5b8ftqC1VJit1f","uri":"spotify:track:12IQxinx5b8ftqC1VJit1f","play_origin":"go-librespot"}} Dec 25 17:02:39 volumio volumio[9594]: error: Failed to decode event: not_playing Dec 25 17:02:40 volumio go-librespot[9895]: time="2025-12-25T17:02:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 25 17:02:40 volumio go-librespot[9895]: time="2025-12-25T17:02:40+02:00" level=trace msg="emitting websocket event: will_play" Dec 25 17:02:40 volumio go-librespot[9895]: time="2025-12-25T17:02:40+02:00" level=info msg="loaded track \"Deeper\" (paused: false, position: 0ms, duration: 214987ms, prefetched: true)" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:12IQxinx5b8ftqC1VJit1f","uri":"spotify:track:50JZxNaQBFWu1ziGkMebSr","play_origin":"go-librespot"}} Dec 25 17:02:40 volumio go-librespot[9895]: time="2025-12-25T17:02:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 25 17:02:40 volumio go-librespot[9895]: time="2025-12-25T17:02:40+02:00" level=trace msg="scheduling prefetch in 185s" Dec 25 17:02:40 volumio go-librespot[9895]: time="2025-12-25T17:02:40+02:00" level=trace msg="emitting websocket event: metadata" Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:50JZxNaQBFWu1ziGkMebSr","name":"Deeper","artist_names":["MVMURO"],"album_name":"Empty Dreams","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f16387cae0e7549c6657b783","position":0,"duration":214987,"release_date":"year:2025 month:11 day:13","track_number":6,"disc_number":1}} Dec 25 17:02:40 volumio go-librespot[9895]: time="2025-12-25T17:02:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 25 17:02:40 volumio go-librespot[9895]: time="2025-12-25T17:02:40+02:00" level=trace msg="emitting websocket event: playing" Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:12IQxinx5b8ftqC1VJit1f","uri":"spotify:track:50JZxNaQBFWu1ziGkMebSr","resume":false,"play_origin":"go-librespot"}} Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: PUSH STATE SPOTIFY Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: {"status":"play","service":"spop","title":"Deeper","artist":"MVMURO","album":"Empty Dreams","albumart":"https://i.scdn.co/image/ab67616d00001e02f16387cae0e7549c6657b783","uri":"spotify:track:50JZxNaQBFWu1ziGkMebSr","trackType":"spotify","seek":0,"duration":214,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::servicePushState Dec 25 17:02:40 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:02:40 volumio volumio[9594]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Deeper","artist":"MVMURO","album":"Empty Dreams","albumart":"https://i.scdn.co/image/ab67616d00001e02f16387cae0e7549c6657b783","uri":"spotify:track:50JZxNaQBFWu1ziGkMebSr","trackType":"spotify","seek":0,"duration":214,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 17:02:40 volumio volumio[9594]: verbose: CURRENT POSITION 80 Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::syncState stateService play Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::syncState currentStatus play Dec 25 17:02:40 volumio volumio[9594]: info: Received an update from plugin. extracting info from payload Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:02:40 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:02:40 volumio volumio[9594]: info: CorePlayQueue::getTrack 80 Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 17:02:40 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:02:40 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:02:40 volumio sudo[12594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:02:40 volumio sudo[12594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:02:40 volumio sudo[12596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:02:40 volumio sudo[12596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:02:40 volumio sudo[12594]: pam_unix(sudo:session): session closed for user root Dec 25 17:02:40 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:02:40 volumio sudo[12596]: pam_unix(sudo:session): session closed for user root Dec 25 17:02:40 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::startPlaybackTimer Dec 25 17:02:40 volumio volumio[9594]: info: CorePlayQueue::getTrack 51 Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: PUSH STATE SPOTIFY Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: {"status":"play","service":"spop","title":"Deeper","artist":"MVMURO","album":"Empty Dreams","albumart":"https://i.scdn.co/image/ab67616d00001e02f16387cae0e7549c6657b783","uri":"spotify:track:50JZxNaQBFWu1ziGkMebSr","trackType":"spotify","seek":0,"duration":214,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::servicePushState Dec 25 17:02:40 volumio volumio[9594]: info: CorePlayQueue::getTrack 51 Dec 25 17:02:40 volumio volumio[9594]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Deeper","artist":"MVMURO","album":"Empty Dreams","albumart":"https://i.scdn.co/image/ab67616d00001e02f16387cae0e7549c6657b783","uri":"spotify:track:50JZxNaQBFWu1ziGkMebSr","trackType":"spotify","seek":0,"duration":214,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 25 17:02:40 volumio volumio[9594]: verbose: CURRENT POSITION 51 Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::syncState stateService play Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::syncState currentStatus play Dec 25 17:02:40 volumio volumio[9594]: info: Received an update from plugin. extracting info from payload Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:02:40 volumio volumio[9594]: info: CorePlayQueue::getTrack 51 Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:02:40 volumio volumio[9594]: info: CorePlayQueue::getTrack 51 Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 17:02:40 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:02:40 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:02:40 volumio sudo[12600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:02:40 volumio sudo[12600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:02:40 volumio sudo[12602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:02:40 volumio sudo[12602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:02:40 volumio sudo[12602]: pam_unix(sudo:session): session closed for user root Dec 25 17:02:40 volumio sudo[12600]: pam_unix(sudo:session): session closed for user root Dec 25 17:02:40 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:02:40 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:02:40 volumio volumio[9594]: info: CoreStateMachine::pushState Dec 25 17:02:40 volumio volumio[9594]: info: CorePlayQueue::getTrack 51 Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 17:02:40 volumio volumio[9594]: info: CoreCommandRouter::volumioPushState Dec 25 17:02:40 volumio volumio[9594]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 25 17:02:40 volumio volumio[9594]: info: PeppySpectrum ---peppyspectrum status play Dec 25 17:02:40 volumio sudo[12606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 25 17:02:40 volumio sudo[12606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 17:02:41 volumio sudo[12606]: pam_unix(sudo:session): session closed for user root Dec 25 17:02:41 volumio volumio[9594]: info: peppyspectrum Daemon Started Dec 25 17:02:46 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 25 17:02:46 volumio volumio[9594]: info: In handleBrowseUri, curUri=spotify Dec 25 17:02:46 volumio volumio[9594]: info: Preload queue cleared Dec 25 17:02:46 volumio volumio[9594]: info: Preload queue cleared Dec 25 17:02:46 volumio volumio[9594]: info: Preload queue cleared Dec 25 17:02:46 volumio volumio[9594]: info: Preload queue cleared Dec 25 17:02:47 volumio volumio[9594]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.234 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 25 17:02:47 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 25 17:02:47 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 25 17:02:47 volumio volumio[9594]: info: Discovery: Getting this device information Dec 25 17:02:47 volumio volumio[9594]: info: CoreCommandRouter::volumioGetState Dec 25 17:02:47 volumio volumio[9594]: info: CorePlayQueue::getTrack 51 Dec 25 17:02:47 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 25 17:02:52 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 25 17:02:52 volumio volumio[9594]: info: In handleBrowseUri, curUri=spotify/playlists Dec 25 17:02:52 volumio volumio[9594]: info: Preload queue cleared Dec 25 17:02:56 volumio volumio[9594]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 25 17:02:56 volumio volumio[9594]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:7JuGFXiFffCmyz6zsDPFZx Dec 25 17:02:57 volumio volumio[9594]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 17:02:57 volumio volumio[9594]: TypeError: Cannot read properties of null (reading '0') Dec 25 17:02:57 volumio volumio[9594]: at /data/plugins/music_service/spop/index.js:2507:56 Dec 25 17:02:57 volumio volumio[9594]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 25 17:02:57 volumio volumio[9594]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 17:02:58 volumio go-librespot[9895]: time="2025-12-25T17:02:58+02:00" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:50JZxNaQBFWu1ziGkMebSr" Dec 25 17:02:58 volumio sudo[12641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-25 17:01' Dec 25 17:02:58 volumio sudo[12641]: 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"