-- Logs begin at Sun 2025-06-22 21:02:14 CEST, end at Sun 2025-06-22 21:18:57 CEST. -- Jun 22 21:17:05 volumio volumio[1167]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/amd64 Jun 22 21:17:07 volumio volumio[1167]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/amd64 Jun 22 21:17:07 volumio volumio[1167]: info: Folder /tmp/plugins removed Jun 22 21:17:07 volumio volumio[1167]: info: Check plugin dependencies Jun 22 21:17:07 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 21:17:07 volumio volumio[1167]: info: Checking if plugin already exists Jun 22 21:17:07 volumio volumio[1167]: info: Rename folder Jun 22 21:17:07 volumio volumio[1167]: info: Folder /tmp/downloaded_plugin.zip removed Jun 22 21:17:08 volumio volumio[1167]: info: Move to category Jun 22 21:17:08 volumio volumio[1167]: info: Checking if install.sh is present Jun 22 21:17:08 volumio volumio[1167]: info: Executing install.sh Jun 22 21:17:08 volumio sudo[7464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Jun 22 21:17:08 volumio sudo[7464]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 21:17:08 volumio volumio[1167]: info: Installing Go-librespot Jun 22 21:17:08 volumio volumio[1167]: info: Checking old vollibrespot installs Jun 22 21:17:08 volumio systemd[1]: Starting Cleanup of Temporary Directories... Jun 22 21:17:08 volumio systemd[1]: Reloading. Jun 22 21:17:08 volumio 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. Jun 22 21:17:08 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jun 22 21:17:08 volumio systemd[1]: Started Cleanup of Temporary Directories. Jun 22 21:17:08 volumio volumio[1167]: info: Downloading daemon Jun 22 21:17:12 volumio volumio[1167]: info: Creating directories Jun 22 21:17:12 volumio systemd[1]: Reloading. Jun 22 21:17:12 volumio volumio[1167]: info: Creating data directory Jun 22 21:17:12 volumio volumio[1167]: info: Creating Start Script Jun 22 21:17:13 volumio 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. Jun 22 21:17:13 volumio sudo[7464]: pam_unix(sudo:session): session closed for user root Jun 22 21:17:13 volumio volumio[1167]: Plugin install end detected on script Jun 22 21:17:13 volumio volumio[1167]: info: Install script completed Jun 22 21:17:13 volumio volumio[1167]: info: Adding reference to registry Jun 22 21:17:13 volumio volumio[1167]: info: Done installing plugin. Jun 22 21:17:13 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 22 21:17:13 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 22 21:17:13 volumio volumio[1167]: info: Folder /tmp/plugins removed Jun 22 21:17:13 volumio volumio[1167]: info: Folder /tmp/downloaded_plugin.zip removed Jun 22 21:17:13 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:17:13 volumio volumio[1167]: info: Folder /data/temp removed Jun 22 21:17:13 volumio volumio[1167]: info: FusionDsp - Jun 22 21:17:13 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:17:13 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:17:13 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:17:13 volumio volumio[1167]: info: FusionDsp - Jun 22 21:17:13 volumio volumio[1167]: info: FusionDsp - Jun 22 21:17:14 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:17:14 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:17:16 volumio volumio[1167]: info: Enabling plugin spop Jun 22 21:17:16 volumio volumio[1167]: info: Loading plugin "spop"... Jun 22 21:17:17 volumio volumio[1167]: info: PLUGIN START: spop Jun 22 21:17:17 volumio volumio[1167]: info: Creating Spotify config file Jun 22 21:17:17 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 21:17:17 volumio volumio[1167]: info: Done. Jun 22 21:17:17 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:17:17 volumio volumio[1167]: info: Spotify config file written Jun 22 21:17:17 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 21:17:17 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 21:17:17 volumio volumio[1167]: info: Discovery: Getting this device information Jun 22 21:17:17 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Jun 22 21:17:17 volumio volumio[1167]: info: CorePlayQueue::getTrack 0 Jun 22 21:17:17 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 21:17:17 volumio volumio[1167]: info: No need to fix Spotify hosts Jun 22 21:17:17 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:17:17 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:17:17 volumio sudo[7528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 22 21:17:17 volumio sudo[7528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 21:17:17 volumio volumio[1167]: info: FusionDsp - Jun 22 21:17:17 volumio systemd[1]: Started go-librespot Daemon. Jun 22 21:17:17 volumio go-librespot[7530]: go-librespot daemon starting... Jun 22 21:17:17 volumio sudo[7528]: pam_unix(sudo:session): session closed for user root Jun 22 21:17:17 volumio go-librespot[7530]: time="2025-06-22T21:17:17+02:00" level=info msg="running go-librespot 0.2.0" Jun 22 21:17:17 volumio go-librespot[7530]: time="2025-06-22T21:17:17+02:00" level=debug msg="no app state found" Jun 22 21:17:17 volumio go-librespot[7530]: time="2025-06-22T21:17:17+02:00" level=debug msg="stored credentials not found" Jun 22 21:17:17 volumio go-librespot[7530]: time="2025-06-22T21:17:17+02:00" level=info msg="generated new device id: 049ed525faacd1f59febab6aa9cd243ff6662f47" Jun 22 21:17:17 volumio go-librespot[7530]: time="2025-06-22T21:17:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 22 21:17:17 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:17:17 volumio go-librespot[7530]: time="2025-06-22T21:17:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 22 21:17:17 volumio go-librespot[7530]: time="2025-06-22T21:17:17+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 22 21:17:17 volumio go-librespot[7530]: time="2025-06-22T21:17:17+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 22 21:17:17 volumio go-librespot[7530]: time="2025-06-22T21:17:17+02:00" level=info msg="zeroconf server listening on port 38315" Jun 22 21:17:17 volumio volumio[1167]: info: FusionDsp - Jun 22 21:17:18 volumio volumio[1167]: info: FusionDsp - Jun 22 21:17:18 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:17:18 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:17:20 volumio volumio[1167]: info: go-librespot daemon successfully initialized Jun 22 21:17:23 volumio volumio[1167]: info: Initializing connection to go-librespot Websocket Jun 22 21:17:23 volumio go-librespot[7530]: time="2025-06-22T21:17:23+02:00" level=debug msg="new websocket client" Jun 22 21:17:23 volumio volumio[1167]: info: Connection to go-librespot Websocket established Jun 22 21:17:26 volumio volumio[1167]: info: Getting Spotify volume Jun 22 21:17:26 volumio volumio[1167]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 22 21:17:26 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Jun 22 21:17:26 volumio volumio[1167]: info: CorePlayQueue::getTrack 0 Jun 22 21:17:26 volumio volumio[1167]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jun 22 21:17:26 volumio volumio[1167]: SPOTIFY: SPOTIFY VOLUME undefined Jun 22 21:17:26 volumio volumio[1167]: SPOTIFY: VOLUMIO VOLUME 41 Jun 22 21:17:26 volumio volumio[1167]: info: Aligning Spotify Volume to Volumio Volume Jun 22 21:17:26 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Jun 22 21:17:26 volumio volumio[1167]: info: CorePlayQueue::getTrack 0 Jun 22 21:17:26 volumio volumio[1167]: info: Setting Spotify Volume from Volumio: 41 Jun 22 21:17:28 volumio volumio[1167]: SPOTIFY: SETTING SPOTIFY VOLUME 41 Jun 22 21:17:28 volumio volumio[1167]: info: Sending Spotify command with payload to local API: /player/volume Jun 22 21:17:39 volumio kernel: perf: interrupt took too long (2529 > 2500), lowering kernel.perf_event_max_sample_rate to 79000 Jun 22 21:17:46 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 21:17:46 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 21:17:46 volumio volumio[1167]: info: Discovery: Getting this device information Jun 22 21:17:46 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Jun 22 21:17:46 volumio volumio[1167]: info: CorePlayQueue::getTrack 0 Jun 22 21:17:46 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 21:17:51 volumio nmbd[1017]: [2025/06/22 21:17:51.640456, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 22 21:17:51 volumio nmbd[1017]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jun 22 21:17:54 volumio nmbd[1017]: [2025/06/22 21:17:54.648930, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 22 21:17:54 volumio nmbd[1017]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jun 22 21:18:12 volumio volumio[1167]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/amd64 Jun 22 21:18:13 volumio volumio[1167]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/amd64 Jun 22 21:18:13 volumio volumio[1167]: info: Folder /tmp/plugins removed Jun 22 21:18:13 volumio volumio[1167]: info: Check plugin dependencies Jun 22 21:18:14 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 21:18:14 volumio volumio[1167]: info: Checking if plugin already exists Jun 22 21:18:14 volumio volumio[1167]: info: Rename folder Jun 22 21:18:14 volumio volumio[1167]: info: Folder /tmp/downloaded_plugin.zip removed Jun 22 21:18:14 volumio volumio[1167]: info: Move to category Jun 22 21:18:14 volumio volumio[1167]: info: Checking if install.sh is present Jun 22 21:18:14 volumio volumio[1167]: info: Executing install.sh Jun 22 21:18:14 volumio sudo[7694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/autostart/install.sh Jun 22 21:18:14 volumio sudo[7694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 21:18:14 volumio sudo[7694]: pam_unix(sudo:session): session closed for user root Jun 22 21:18:14 volumio volumio[1167]: info: Install script completed Jun 22 21:18:14 volumio volumio[1167]: info: Adding reference to registry Jun 22 21:18:14 volumio volumio[1167]: info: Done installing plugin. Jun 22 21:18:14 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 22 21:18:14 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 22 21:18:14 volumio volumio[1167]: info: Installing autostart Dependencies - no special dependencies needed Jun 22 21:18:14 volumio volumio[1167]: info: Folder /tmp/plugins removed Jun 22 21:18:14 volumio volumio[1167]: info: Folder /tmp/downloaded_plugin.zip removed Jun 22 21:18:14 volumio volumio[1167]: info: Folder /data/temp removed Jun 22 21:18:14 volumio volumio[1167]: Plugin install end detected on script Jun 22 21:18:14 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:14 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:14 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:14 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:14 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:14 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:14 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:14 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:15 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:16 volumio volumio[1167]: info: Enabling plugin autostart Jun 22 21:18:16 volumio volumio[1167]: info: Loading plugin "autostart"... Jun 22 21:18:16 volumio volumio[1167]: info: AutoStart - onVolumioStart - read config.json Jun 22 21:18:16 volumio volumio[1167]: info: PLUGIN START: autostart Jun 22 21:18:16 volumio volumio[1167]: info: AutoStart - onStart Jun 22 21:18:16 volumio volumio[1167]: info: Done. Jun 22 21:18:16 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:16 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:16 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:16 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:16 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:16 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 21:18:16 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 21:18:16 volumio volumio[1167]: info: Discovery: Getting this device information Jun 22 21:18:16 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Jun 22 21:18:16 volumio volumio[1167]: info: CorePlayQueue::getTrack 0 Jun 22 21:18:16 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 21:18:16 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:17 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:17 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:17 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:29 volumio volumio[1167]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/amd64 Jun 22 21:18:30 volumio volumio[1167]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/amd64 Jun 22 21:18:30 volumio volumio[1167]: info: Folder /tmp/plugins removed Jun 22 21:18:31 volumio volumio[1167]: info: Check plugin dependencies Jun 22 21:18:31 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 21:18:31 volumio volumio[1167]: info: Checking if plugin already exists Jun 22 21:18:31 volumio volumio[1167]: info: Rename folder Jun 22 21:18:31 volumio volumio[1167]: info: Folder /tmp/downloaded_plugin.zip removed Jun 22 21:18:31 volumio volumio[1167]: info: Move to category Jun 22 21:18:31 volumio volumio[1167]: info: Checking if install.sh is present Jun 22 21:18:31 volumio volumio[1167]: info: Executing install.sh Jun 22 21:18:31 volumio sudo[7801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/backup_restore/install.sh Jun 22 21:18:31 volumio sudo[7801]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 21:18:31 volumio sudo[7801]: pam_unix(sudo:session): session closed for user root Jun 22 21:18:31 volumio volumio[1167]: info: Installing backup restore Dependencies Jun 22 21:18:31 volumio volumio[1167]: info: Install script completed Jun 22 21:18:31 volumio volumio[1167]: info: Adding reference to registry Jun 22 21:18:31 volumio volumio[1167]: info: Done installing plugin. Jun 22 21:18:31 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 22 21:18:31 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 22 21:18:31 volumio volumio[1167]: info: Folder /tmp/plugins removed Jun 22 21:18:31 volumio volumio[1167]: info: Folder /tmp/downloaded_plugin.zip removed Jun 22 21:18:31 volumio volumio[1167]: Plugin install end detected on script Jun 22 21:18:31 volumio volumio[1167]: info: Folder /data/temp removed Jun 22 21:18:31 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:31 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:31 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:31 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:31 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:31 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:31 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:32 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:32 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:34 volumio volumio[1167]: info: Enabling plugin backup_restore Jun 22 21:18:34 volumio volumio[1167]: info: Loading plugin "backup_restore"... Jun 22 21:18:34 volumio volumio[1167]: info: PLUGIN START: backup_restore Jun 22 21:18:34 volumio volumio[1167]: info: Done. Jun 22 21:18:34 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:34 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:34 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:35 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:35 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:35 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:35 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:35 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:35 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:36 volumio volumio[1167]: info: AutoStart - Plugin is starting Jun 22 21:18:36 volumio volumio[1167]: info: CoreCommandRouter::volumioGetQueue Jun 22 21:18:36 volumio volumio[1167]: info: CoreStateMachine::getQueue Jun 22 21:18:36 volumio volumio[1167]: info: CorePlayQueue::getQueue Jun 22 21:18:46 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 21:18:46 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 21:18:46 volumio volumio[1167]: info: Discovery: Getting this device information Jun 22 21:18:46 volumio volumio[1167]: info: CoreCommandRouter::volumioGetState Jun 22 21:18:46 volumio volumio[1167]: info: CorePlayQueue::getTrack 0 Jun 22 21:18:46 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 21:18:47 volumio volumio[1167]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/amd64 Jun 22 21:18:48 volumio volumio[1167]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/autostart/volumio/buster/amd64 Jun 22 21:18:48 volumio volumio[1167]: info: AutoStart - onStop Jun 22 21:18:48 volumio volumio[1167]: info: Check plugin dependencies Jun 22 21:18:48 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 21:18:48 volumio volumio[1167]: info: Rename folder Jun 22 21:18:48 volumio volumio[1167]: info: Folder /tmp/downloaded_plugin.zip removed Jun 22 21:18:48 volumio volumio[1167]: info: Move to category Jun 22 21:18:49 volumio volumio[1167]: info: Checking if install.sh is present Jun 22 21:18:49 volumio volumio[1167]: info: Executing install.sh Jun 22 21:18:49 volumio sudo[7849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/autostart/install.sh Jun 22 21:18:49 volumio sudo[7849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 21:18:49 volumio sudo[7849]: pam_unix(sudo:session): session closed for user root Jun 22 21:18:49 volumio volumio[1167]: info: Install script completed Jun 22 21:18:49 volumio volumio[1167]: info: Done installing plugin. Jun 22 21:18:49 volumio volumio[1167]: info: Enabling plugin autostart Jun 22 21:18:49 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 22 21:18:49 volumio volumio[1167]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false},{"prettyName":"Jellyfin","name":"jellyfin","category":"music_service","version":"1.1.1","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Volusonic","name":"volusonic","category":"music_service","version":"2.3.0","icon":"fa-ship","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true}] Jun 22 21:18:49 volumio volumio[1167]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 22 21:18:49 volumio volumio[1167]: info: Folder /tmp/downloaded_plugin.zip removed Jun 22 21:18:49 volumio volumio[1167]: info: Folder /tmp/plugins removed Jun 22 21:18:49 volumio volumio[1167]: info: Installing autostart Dependencies - no special dependencies needed Jun 22 21:18:49 volumio volumio[1167]: Plugin install end detected on script Jun 22 21:18:49 volumio volumio[1167]: info: Folder /data/temp removed Jun 22 21:18:49 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:49 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:49 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:49 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:49 volumio volumio[1167]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 22 21:18:49 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:49 volumio volumio[1167]: info: FusionDsp - Jun 22 21:18:49 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:49 volumio volumio[1167]: info: FusionDsp - undefined Jun 22 21:18:54 volumio volumio[1167]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jun 22 21:18:56 volumio volumio[1167]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jun 22 21:18:56 volumio volumio[1167]: info: FusionDsp - Stopping FusionDsp service Jun 22 21:18:56 volumio volumio[1167]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 21:18:56 volumio volumio[1167]: TypeError: Cannot read property 'stop' of null Jun 22 21:18:56 volumio volumio[1167]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jun 22 21:18:56 volumio volumio[1167]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 22 21:18:56 volumio volumio[1167]: at /volumio/app/pluginmanager.js:956:12 Jun 22 21:18:56 volumio volumio[1167]: at ChildProcess.exithandler (child_process.js:374:7) Jun 22 21:18:56 volumio volumio[1167]: at ChildProcess.emit (events.js:400:28) Jun 22 21:18:56 volumio volumio[1167]: at maybeClose (internal/child_process.js:1088:16) Jun 22 21:18:56 volumio volumio[1167]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) Jun 22 21:18:56 volumio volumio[1167]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 21:18:57 volumio sudo[7877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-22 21:17 Jun 22 21:18:57 volumio sudo[7877]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"