-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-12-14 16:18:12 CET. -- Dec 14 16:17:06 spotifyrpi volumio[991]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.30 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 14 16:17:06 spotifyrpi volumio[991]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.30 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 14 16:17:06 spotifyrpi volumio[991]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.30 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 14 16:17:06 spotifyrpi volumio[991]: verbose: New Socket.io Connection to 192.168.178.79:3000 from 192.168.178.30 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 14 16:17:06 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 16:17:06 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 16:17:06 spotifyrpi volumio[991]: info: Discovery: Getting this device information Dec 14 16:17:06 spotifyrpi volumio[991]: info: CoreCommandRouter::volumioGetState Dec 14 16:17:06 spotifyrpi volumio[991]: info: CorePlayQueue::getTrack 37 Dec 14 16:17:06 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 16:17:06 spotifyrpi volumio[991]: verbose: New Socket.io Connection to 192.168.178.79:3000 from 192.168.178.30 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Dec 14 16:17:08 spotifyrpi volumio[991]: verbose: New Socket.io Connection to 192.168.178.79 from 192.168.178.30 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::volumioGetState Dec 14 16:17:08 spotifyrpi volumio[991]: info: CorePlayQueue::getTrack 37 Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 16:17:08 spotifyrpi volumio[991]: info: Received Get System Info Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 16:17:08 spotifyrpi volumio[991]: info: Discovery: Getting this device information Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::volumioGetState Dec 14 16:17:08 spotifyrpi volumio[991]: info: CorePlayQueue::getTrack 37 Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 16:17:08 spotifyrpi volumio[991]: info: CoreCommandRouter::volumioGetState Dec 14 16:17:08 spotifyrpi volumio[991]: info: CorePlayQueue::getTrack 37 Dec 14 16:17:08 spotifyrpi volumio[991]: info: Listing playlists Dec 14 16:17:09 spotifyrpi volumio[991]: info: CoreCommandRouter::volumioGetQueue Dec 14 16:17:09 spotifyrpi volumio[991]: info: CoreStateMachine::getQueue Dec 14 16:17:09 spotifyrpi volumio[991]: info: CorePlayQueue::getQueue Dec 14 16:17:09 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 14 16:17:09 spotifyrpi volumio[991]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 16:17:09 spotifyrpi volumio[991]: info: FusionDsp - no preset used Dec 14 16:17:09 spotifyrpi volumio[991]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Dec 14 16:17:13 spotifyrpi volumio[991]: verbose: New Socket.io Connection to 192.168.178.79:3000 from 192.168.178.30 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Dec 14 16:17:26 spotifyrpi go-librespot[2962]: time="2025-12-14T16:17:26+01:00" level=trace msg="sent dealer ping" Dec 14 16:17:26 spotifyrpi go-librespot[2962]: time="2025-12-14T16:17:26+01:00" level=trace msg="received dealer pong" Dec 14 16:17:42 spotifyrpi volumio[991]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Dec 14 16:17:48 spotifyrpi volumio[991]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Dec 14 16:17:48 spotifyrpi go-librespot[2962]: time="2025-12-14T16:17:48+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 14 16:17:48 spotifyrpi volumio[991]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Dec 14 16:17:48 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 16:17:48 spotifyrpi sudo[3685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Dec 14 16:17:48 spotifyrpi sudo[3685]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:17:48 spotifyrpi systemd[1]: Stopping go-librespot Daemon... Dec 14 16:17:48 spotifyrpi volumio[991]: info: Connection to go-librespot Websocket closed Dec 14 16:17:48 spotifyrpi systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 14 16:17:48 spotifyrpi systemd[1]: go-librespot-daemon.service: Succeeded. Dec 14 16:17:48 spotifyrpi systemd[1]: Stopped go-librespot Daemon. Dec 14 16:17:48 spotifyrpi sudo[3685]: pam_unix(sudo:session): session closed for user root Dec 14 16:17:48 spotifyrpi volumio[991]: info: Connection to go-librespot Websocket closed Dec 14 16:17:48 spotifyrpi volumio[991]: info: Connection to go-librespot Websocket closed Dec 14 16:17:49 spotifyrpi volumio[991]: info: Check plugin dependencies Dec 14 16:17:49 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 16:17:49 spotifyrpi volumio[991]: info: Rename folder Dec 14 16:17:49 spotifyrpi volumio[991]: info: Folder /tmp/downloaded_plugin.zip removed Dec 14 16:17:49 spotifyrpi volumio[991]: info: Move to category Dec 14 16:17:50 spotifyrpi volumio[991]: info: Checking if install.sh is present Dec 14 16:17:50 spotifyrpi volumio[991]: info: Executing install.sh Dec 14 16:17:50 spotifyrpi sudo[3700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Dec 14 16:17:50 spotifyrpi sudo[3700]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:17:50 spotifyrpi volumio[991]: info: Installing Go-librespot Dec 14 16:17:50 spotifyrpi volumio[991]: info: Checking old vollibrespot installs Dec 14 16:17:50 spotifyrpi systemd[1]: Reloading. Dec 14 16:17:51 spotifyrpi systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 14 16:17:51 spotifyrpi volumio[991]: info: Downloading daemon Dec 14 16:17:53 spotifyrpi volumio[991]: info: Creating directories Dec 14 16:17:53 spotifyrpi volumio[991]: info: Creating Start Script Dec 14 16:17:53 spotifyrpi systemd[1]: Reloading. Dec 14 16:17:54 spotifyrpi systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 14 16:17:54 spotifyrpi sudo[3700]: pam_unix(sudo:session): session closed for user root Dec 14 16:17:54 spotifyrpi volumio[991]: Plugin install end detected on script Dec 14 16:17:54 spotifyrpi volumio[991]: info: Install script completed Dec 14 16:17:54 spotifyrpi volumio[991]: info: Done installing plugin. Dec 14 16:17:54 spotifyrpi volumio[991]: info: Enabling plugin spop Dec 14 16:17:54 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 16:17:54 spotifyrpi volumio[991]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.3.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.1","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Dec 14 16:17:54 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 16:17:54 spotifyrpi volumio[991]: info: Folder /tmp/plugins removed Dec 14 16:17:54 spotifyrpi volumio[991]: info: Folder /tmp/downloaded_plugin.zip removed Dec 14 16:17:54 spotifyrpi volumio[991]: info: Folder /data/temp removed Dec 14 16:17:54 spotifyrpi volumio[991]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 16:17:54 spotifyrpi volumio[991]: info: FusionDsp - no preset used Dec 14 16:17:54 spotifyrpi volumio[991]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Dec 14 16:18:07 spotifyrpi volumio[991]: info: Enabling plugin spop Dec 14 16:18:07 spotifyrpi volumio[991]: info: Loading plugin "spop"... Dec 14 16:18:07 spotifyrpi volumio[991]: info: PLUGIN START: spop Dec 14 16:18:07 spotifyrpi volumio[991]: info: Creating Spotify config file Dec 14 16:18:07 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:07 spotifyrpi volumio[991]: info: Done. Dec 14 16:18:07 spotifyrpi volumio[991]: info: Spotify config file written Dec 14 16:18:07 spotifyrpi volumio[991]: info: No need to fix Spotify hosts Dec 14 16:18:07 spotifyrpi volumio[991]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 16:18:07 spotifyrpi volumio[991]: info: FusionDsp - no preset used Dec 14 16:18:07 spotifyrpi sudo[3823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 14 16:18:07 spotifyrpi sudo[3823]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:18:07 spotifyrpi systemd[1]: Started go-librespot Daemon. Dec 14 16:18:07 spotifyrpi go-librespot[3825]: go-librespot daemon starting... Dec 14 16:18:07 spotifyrpi sudo[3823]: pam_unix(sudo:session): session closed for user root Dec 14 16:18:07 spotifyrpi go-librespot[3825]: time="2025-12-14T16:18:07+01:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 2 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax" Dec 14 16:18:07 spotifyrpi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 16:18:07 spotifyrpi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 16:18:07 spotifyrpi volumio[991]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Dec 14 16:18:08 spotifyrpi volumio[991]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 16:18:08 spotifyrpi volumio[991]: SPOTIFY: BQDoufQaZ1aGZwMxF6Ik25adKSPaDQnUXd0lh9N9r0HtdzkOeDbWCU2RTIyPPDAl26wEExAHNtREjjv1nyLr0x1ANw8yt1nql_VPTjOOrwboQMh5MHUP8VJupUxu5aKmECjBKZy-cTX67vbAxvlnn72CC-pGYhaLiJtyhqmxdSYYdC72M5u0zgutbcNobnJo0N5xjn0VQNGugnr1vHszbwmoMoF_5wyZi3cn8YAnaCkVu574CODlLNuc3r1un_RHy-htOzXPXcUTRa_FXTt-Bf1701o4pV55v1ZTElBTYNfpUwovz-P7oCFU Dec 14 16:18:08 spotifyrpi volumio[991]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 16:18:08 spotifyrpi volumio[991]: info: New Spotify access token = BQDoufQaZ1aGZwMxF6Ik25adKSPaDQnUXd0lh9N9r0HtdzkOeDbWCU2RTIyPPDAl26wEExAHNtREjjv1nyLr0x1ANw8yt1nql_VPTjOOrwboQMh5MHUP8VJupUxu5aKmECjBKZy-cTX67vbAxvlnn72CC-pGYhaLiJtyhqmxdSYYdC72M5u0zgutbcNobnJo0N5xjn0VQNGugnr1vHszbwmoMoF_5wyZi3cn8YAnaCkVu574CODlLNuc3r1un_RHy-htOzXPXcUTRa_FXTt-Bf1701o4pV55v1ZTElBTYNfpUwovz-P7oCFU Dec 14 16:18:08 spotifyrpi volumio[991]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 14 16:18:08 spotifyrpi volumio[991]: SPOTIFY: User informations: {"country":"DE","display_name":"Marci","email":"bauknechtmarcel2004@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/qh0s1h4dcz4sx7hsk73ikmngw"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/qh0s1h4dcz4sx7hsk73ikmngw","id":"qh0s1h4dcz4sx7hsk73ikmngw","images":[],"product":"premium","type":"user","uri":"spotify:user:qh0s1h4dcz4sx7hsk73ikmngw"} Dec 14 16:18:08 spotifyrpi volumio[991]: info: Spotify Successfully logged in Dec 14 16:18:08 spotifyrpi volumio[991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 16:18:08 spotifyrpi volumio[991]: info: [1765725488398] CoreMusicLibrary::Adding element Spotify Dec 14 16:18:08 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 16:18:08 spotifyrpi volumio[991]: Cannot find translation for source Spotify Dec 14 16:18:10 spotifyrpi volumio[991]: info: Enabling plugin fusiondsp Dec 14 16:18:10 spotifyrpi volumio[991]: info: Loading plugin "fusiondsp"... Dec 14 16:18:10 spotifyrpi volumio[991]: info: Applying required configuration parameters for plugin fusiondsp Dec 14 16:18:10 spotifyrpi volumio[991]: info: Preparing to generate the ALSA configuration file Dec 14 16:18:10 spotifyrpi volumio[991]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 14 16:18:10 spotifyrpi volumio[991]: info: Reading ALSA contributions from plugins. Dec 14 16:18:10 spotifyrpi volumio[991]: info: Asound.conf file unchanged, so no further update is needed Dec 14 16:18:10 spotifyrpi volumio[991]: info: Output device has changed, restarting MPD Dec 14 16:18:10 spotifyrpi sudo[3835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 16:18:10 spotifyrpi sudo[3835]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:18:10 spotifyrpi sudo[3835]: pam_unix(sudo:session): session closed for user root Dec 14 16:18:10 spotifyrpi volumio[991]: info: Output device has changed, restarting Shairport Sync Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 16:18:10 spotifyrpi sudo[3839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 16:18:10 spotifyrpi sudo[3839]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:18:10 spotifyrpi systemd[1]: Stopping Music Player Daemon... Dec 14 16:18:10 spotifyrpi volumio[991]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 16:18:10 spotifyrpi volumio[991]: info: PLUGIN START: fusiondsp Dec 14 16:18:10 spotifyrpi volumio[991]: info: Loading i18n strings for locale de Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: FusionDsp - mixtype--------------------- Hardware Dec 14 16:18:10 spotifyrpi volumio[991]: info: Preparing to generate the ALSA configuration file Dec 14 16:18:10 spotifyrpi volumio[991]: info: Done. Dec 14 16:18:10 spotifyrpi volumio[991]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 14 16:18:10 spotifyrpi volumio[991]: info: Reading ALSA contributions from plugins. Dec 14 16:18:10 spotifyrpi volumio[991]: info: MPD Permissions set Dec 14 16:18:10 spotifyrpi volumio[991]: info: Asound.conf file unchanged, so no further update is needed Dec 14 16:18:10 spotifyrpi volumio[991]: info: Output device has changed, restarting MPD Dec 14 16:18:10 spotifyrpi volumio[991]: info: Output device has changed, restarting Shairport Sync Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 16:18:10 spotifyrpi sudo[3847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 16:18:10 spotifyrpi sudo[3847]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:18:10 spotifyrpi sudo[3845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 16:18:10 spotifyrpi sudo[3845]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:18:10 spotifyrpi sudo[3845]: pam_unix(sudo:session): session closed for user root Dec 14 16:18:10 spotifyrpi volumio[991]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 16:18:10 spotifyrpi volumio[991]: info: MPD Permissions set Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 16:18:10 spotifyrpi volumio[991]: info: Starting Shairport Sync Dec 14 16:18:10 spotifyrpi volumio[991]: info: Starting Shairport Sync Dec 14 16:18:10 spotifyrpi sudo[3862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 16:18:10 spotifyrpi sudo[3862]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:18:10 spotifyrpi volumio[991]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 16:18:10 spotifyrpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 14 16:18:10 spotifyrpi sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 16:18:10 spotifyrpi sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:18:10 spotifyrpi systemd[1]: shairport-sync.service: Succeeded. Dec 14 16:18:10 spotifyrpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 14 16:18:10 spotifyrpi volumio[991]: info: FusionDsp - no preset used Dec 14 16:18:10 spotifyrpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 14 16:18:10 spotifyrpi systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 16:18:10 spotifyrpi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 14 16:18:10 spotifyrpi sudo[3862]: pam_unix(sudo:session): session closed for user root Dec 14 16:18:10 spotifyrpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 14 16:18:10 spotifyrpi systemd[1]: Stopped go-librespot Daemon. Dec 14 16:18:10 spotifyrpi go-librespot[3868]: go-librespot daemon starting... Dec 14 16:18:10 spotifyrpi systemd[1]: Started go-librespot Daemon. Dec 14 16:18:10 spotifyrpi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 14 16:18:10 spotifyrpi systemd[1]: shairport-sync.service: Succeeded. Dec 14 16:18:10 spotifyrpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 14 16:18:10 spotifyrpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 14 16:18:10 spotifyrpi sudo[3865]: pam_unix(sudo:session): session closed for user root Dec 14 16:18:11 spotifyrpi go-librespot[3868]: time="2025-12-14T16:18:11+01:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 2 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax" Dec 14 16:18:11 spotifyrpi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 16:18:11 spotifyrpi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 16:18:11 spotifyrpi systemd[1]: mpd.service: Succeeded. Dec 14 16:18:11 spotifyrpi systemd[1]: Stopped Music Player Daemon. Dec 14 16:18:11 spotifyrpi systemd[1]: Starting Music Player Daemon... Dec 14 16:18:11 spotifyrpi volumio[991]: info: go-librespot daemon successfully initialized Dec 14 16:18:11 spotifyrpi volumio[991]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Dec 14 16:18:11 spotifyrpi sudo[3878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 16:18:11 spotifyrpi sudo[3878]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 16:18:11 spotifyrpi sudo[3878]: pam_unix(sudo:session): session closed for user root Dec 14 16:18:11 spotifyrpi volumio[991]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 16:18:11 spotifyrpi volumio[991]: TypeError: Cannot read property 'off' of undefined Dec 14 16:18:11 spotifyrpi volumio[991]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Dec 14 16:18:11 spotifyrpi volumio[991]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 14 16:18:11 spotifyrpi volumio[991]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Dec 14 16:18:11 spotifyrpi volumio[991]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Dec 14 16:18:11 spotifyrpi volumio[991]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) Dec 14 16:18:11 spotifyrpi volumio[991]: at Socket.emit (events.js:315:20) Dec 14 16:18:11 spotifyrpi volumio[991]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 14 16:18:11 spotifyrpi volumio[991]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 14 16:18:11 spotifyrpi volumio[991]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 16:18:12 spotifyrpi sudo[3891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-14 16:17 Dec 14 16:18:12 spotifyrpi sudo[3891]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"