-- Logs begin at Tue 2025-12-23 16:55:53 CET, end at Tue 2025-12-23 17:02:54 CET. -- Dec 23 17:01:59 volumio volumio[1093]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/music_services_shield/volumio/buster/armhf Dec 23 17:02:00 volumio volumio[1093]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/music_services_shield/volumio/buster/armhf Dec 23 17:02:00 volumio sudo[4309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Dec 23 17:02:00 volumio sudo[4309]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:00 volumio sudo[4312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/removeservice.sh Dec 23 17:02:00 volumio sudo[4312]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:00 volumio systemd[1]: musicservicesshield.service: Succeeded. Dec 23 17:02:00 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Dec 23 17:02:00 volumio systemd[1]: Reloading. Dec 23 17:02:01 volumio volumio[1093]: info: Check plugin dependencies Dec 23 17:02:01 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 17:02:01 volumio volumio[1093]: info: Rename folder Dec 23 17:02:01 volumio volumio[1093]: info: Folder /tmp/downloaded_plugin.zip removed Dec 23 17:02:01 volumio volumio[1093]: info: Move to category Dec 23 17:02:01 volumio sudo[4349]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/cset shield -r Dec 23 17:02:01 volumio sudo[4349]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:01 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. Dec 23 17:02:01 volumio sudo[4349]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:01 volumio sudo[4309]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:01 volumio volumio[1093]: info: Checking if install.sh is present Dec 23 17:02:01 volumio volumio[1093]: info: Executing install.sh Dec 23 17:02:01 volumio volumio[1093]: info: succeeded Dec 23 17:02:01 volumio sudo[4358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/music_services_shield/install.sh Dec 23 17:02:01 volumio sudo[4358]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:01 volumio sudo[4362]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /data/configuration/system_hardware/music_services_shield/config.json Dec 23 17:02:01 volumio sudo[4362]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:01 volumio volumio[1093]: info: Installing Music Services Shield Dependencies Dec 23 17:02:01 volumio systemd[1]: Reloading. Dec 23 17:02:01 volumio sudo[4362]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:01 volumio volumio[1093]: info: Cleaning old config file Dec 23 17:02:01 volumio sudo[4367]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Dec 23 17:02:01 volumio sudo[4367]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:01 volumio volumio[1093]: info: Detected cpu architecture as armv7l Dec 23 17:02:02 volumio volumio[1093]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease Dec 23 17:02:02 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. Dec 23 17:02:02 volumio volumio[1093]: info: Hit:1 https://apt2.volumio.org/raspbian buster InRelease Dec 23 17:02:02 volumio volumio[1093]: info: Hit:2 https://archive2.volumio.org/debian buster InRelease Dec 23 17:02:02 volumio sudo[4312]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:02 volumio volumio[1093]: info: succeeded Dec 23 17:02:06 volumio volumio[1093]: info: Reading package lists... Dec 23 17:02:06 volumio sudo[4367]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:06 volumio sudo[4586]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install cpuset Dec 23 17:02:06 volumio sudo[4586]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:06 volumio volumio[1093]: info: Reading package lists... Dec 23 17:02:07 volumio volumio[1093]: info: Building dependency tree... Dec 23 17:02:07 volumio volumio[1093]: info: Reading state information... Dec 23 17:02:08 volumio volumio[1093]: info: cpuset is already the newest version (1.5.6-5.1). Dec 23 17:02:08 volumio volumio[1093]: info: The following packages were automatically installed and are no longer required: Dec 23 17:02:08 volumio volumio[1093]: info: libfftw3-3 libiniparser1 Dec 23 17:02:08 volumio volumio[1093]: info: Use 'sudo apt autoremove' to remove them. Dec 23 17:02:08 volumio sudo[4586]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio volumio[1093]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Dec 23 17:02:08 volumio sudo[4592]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/system_hardware/music_services_shield/moveprocess.sh Dec 23 17:02:08 volumio sudo[4592]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:08 volumio sudo[4592]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio sudo[4594]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Dec 23 17:02:08 volumio sudo[4594]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:08 volumio sudo[4594]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio sudo[4596]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/system_hardware/music_services_shield/usertaskstable.sh Dec 23 17:02:08 volumio sudo[4596]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:08 volumio sudo[4596]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio sudo[4598]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/system_hardware/music_services_shield/builduiconfig.sh Dec 23 17:02:08 volumio sudo[4598]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:08 volumio sudo[4598]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio sudo[4600]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/system_hardware/music_services_shield/setconfigparameter.sh Dec 23 17:02:08 volumio sudo[4600]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:08 volumio sudo[4600]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio sudo[4602]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/system_hardware/music_services_shield/setrtpriority.sh Dec 23 17:02:08 volumio sudo[4602]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:08 volumio sudo[4602]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio sudo[4604]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/system_hardware/music_services_shield/addservice.sh Dec 23 17:02:08 volumio sudo[4604]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:08 volumio sudo[4604]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio sudo[4606]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/system_hardware/music_services_shield/removeservice.sh Dec 23 17:02:08 volumio sudo[4606]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:08 volumio sudo[4606]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio sudo[4358]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:08 volumio volumio[1093]: Plugin install end detected on script Dec 23 17:02:08 volumio volumio[1093]: info: Install script completed Dec 23 17:02:08 volumio volumio[1093]: info: Done installing plugin. Dec 23 17:02:08 volumio volumio[1093]: info: Enabling plugin music_services_shield Dec 23 17:02:08 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 23 17:02:08 volumio volumio[1093]: 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":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Peppy-meter-Basic","name":"peppymeterbasic","category":"user_interface","version":"0.0.3","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true},{"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 23 17:02:08 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 23 17:02:08 volumio volumio[1093]: info: Folder /tmp/plugins removed Dec 23 17:02:08 volumio volumio[1093]: info: Folder /tmp/downloaded_plugin.zip removed Dec 23 17:02:08 volumio volumio[1093]: info: Folder /data/temp removed Dec 23 17:02:08 volumio volumio[1093]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 17:02:08 volumio volumio[1093]: info: FusionDsp - IP adress is ---------------------------192.168.1.38 Dec 23 17:02:08 volumio volumio[1093]: info: FusionDsp - Dec 23 17:02:09 volumio volumio[1093]: info: FusionDsp - undefined Dec 23 17:02:11 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Dec 23 17:02:11 volumio volumio[1093]: info: CorePlayQueue::getTrack 1 Dec 23 17:02:11 volumio volumio[1093]: info: Prefetching next song Dec 23 17:02:11 volumio volumio[1093]: info: [1766505731266] ControllerQobuz::prefetch Dec 23 17:02:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/360752993" Dec 23 17:02:11 volumio volumio[1093]: STREAMING PROXY: Handling url /?data=qobuz://song/360752993 Dec 23 17:02:11 volumio volumio[1093]: info: Executing endpoint getStreamUrlqobuz Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 23 17:02:11 volumio volumio[1093]: info: getStreamUrl took 293 milliseconds Dec 23 17:02:11 volumio volumio[1093]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1338433&eid=360752993&fmt=7&profile=raw&app_id=539451548&cid=2147268&etsp=1766509331&hmac=YW02FRx37Kcb9mSXoHYdlL3xv-g Dec 23 17:02:11 volumio volumio[1093]: STREAMING PROXY: Response: 200, length: 62921399 Dec 23 17:02:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/360752993" Dec 23 17:02:11 volumio volumio[1093]: info: Dec 23 17:02:11 volumio volumio[1093]: ---------------------------- MPD announces system playlist update Dec 23 17:02:11 volumio volumio[1093]: info: Ignoring MPD Status Update Dec 23 17:02:11 volumio volumio[1093]: STREAMING PROXY: Client dropped request, destroying Dec 23 17:02:11 volumio volumio[1093]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/360752993" took 3 milliseconds Dec 23 17:02:11 volumio volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 17:02:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 23 17:02:11 volumio volumio[1093]: info: Dec 23 17:02:11 volumio volumio[1093]: ---------------------------- MPD announces state update: options Dec 23 17:02:11 volumio volumio[1093]: info: ------------------------------ 5ms Dec 23 17:02:11 volumio volumio[1093]: info: sendMpdCommand consume 1 took 2 milliseconds Dec 23 17:02:11 volumio volumio[1093]: info: ControllerMpd::getState Dec 23 17:02:11 volumio volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Dec 23 17:02:11 volumio volumio[1093]: info: sendMpdCommand status took 2 milliseconds Dec 23 17:02:11 volumio volumio[1093]: verbose: ControllerMpd::parseState Dec 23 17:02:11 volumio volumio[1093]: info: ControllerMpd::pushState Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::servicePushState Dec 23 17:02:11 volumio volumio[1093]: info: CoreStateMachine::pushState Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Dec 23 17:02:11 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Dec 23 17:02:11 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::volumioGetState Dec 23 17:02:11 volumio volumio[1093]: info: CorePlayQueue::getTrack 0 Dec 23 17:02:11 volumio volumio[1093]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 17:02:11 volumio volumio[1093]: verbose: CURRENT POSITION 0 Dec 23 17:02:11 volumio volumio[1093]: info: CoreStateMachine::syncState stateService stop Dec 23 17:02:11 volumio volumio[1093]: info: CoreStateMachine::syncState currentStatus play Dec 23 17:02:11 volumio volumio[1093]: info: CoreStateMachine::pushState Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Dec 23 17:02:11 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Dec 23 17:02:11 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::volumioGetState Dec 23 17:02:11 volumio volumio[1093]: info: CoreStateMachine::pushState Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::volumioPushState Dec 23 17:02:11 volumio volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Dec 23 17:02:11 volumio volumio[1093]: info: MRS: Pushing multiroomSync output Dec 23 17:02:11 volumio volumio[1093]: info: CoreCommandRouter::volumioGetState Dec 23 17:02:11 volumio volumio[1093]: info: ------------------------------ 71ms Dec 23 17:02:11 volumio volumio[1093]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 23 17:02:11 volumio volumio[1093]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 23 17:02:11 volumio sudo[4643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 23 17:02:11 volumio sudo[4643]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:11 volumio volumio[1093]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 23 17:02:11 volumio sudo[4647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 23 17:02:11 volumio sudo[4647]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:11 volumio sudo[4643]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:11 volumio sudo[4650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 23 17:02:11 volumio sudo[4650]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:02:11 volumio systemd[1]: Stopping peppymeterbasic Daemon... Dec 23 17:02:11 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Dec 23 17:02:11 volumio volumio[1093]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 23 17:02:11 volumio volumio[1093]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 23 17:02:11 volumio volumio[1093]: info: peppymeterbasic Daemon Started Dec 23 17:02:11 volumio volumio[1093]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 23 17:02:11 volumio systemd[1]: peppymeterbasic.service: Succeeded. Dec 23 17:02:11 volumio systemd[1]: Stopped peppymeterbasic Daemon. Dec 23 17:02:11 volumio sudo[4650]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:11 volumio sudo[4647]: pam_unix(sudo:session): session closed for user root Dec 23 17:02:11 volumio volumio[1093]: info: peppymeterbasic Daemon Stop Dec 23 17:02:11 volumio volumio[1093]: info: peppymeterbasic Daemon Stop Dec 23 17:02:52 volumio volumio[1093]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Dec 23 17:02:53 volumio volumio[1093]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Dec 23 17:02:53 volumio volumio[1093]: info: FusionDsp - Stopping FusionDsp service Dec 23 17:02:53 volumio volumio[1093]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 17:02:53 volumio volumio[1093]: TypeError: Cannot read property 'stop' of null Dec 23 17:02:53 volumio volumio[1093]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Dec 23 17:02:53 volumio volumio[1093]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 23 17:02:53 volumio volumio[1093]: at /volumio/app/pluginmanager.js:956:12 Dec 23 17:02:53 volumio volumio[1093]: at ChildProcess.exithandler (child_process.js:299:7) Dec 23 17:02:53 volumio volumio[1093]: at ChildProcess.emit (events.js:315:20) Dec 23 17:02:53 volumio volumio[1093]: at maybeClose (internal/child_process.js:1048:16) Dec 23 17:02:53 volumio volumio[1093]: at Socket. (internal/child_process.js:439:11) Dec 23 17:02:53 volumio volumio[1093]: at Socket.emit (events.js:315:20) Dec 23 17:02:53 volumio volumio[1093]: at Pipe. (net.js:673:12) Dec 23 17:02:53 volumio volumio[1093]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 17:02:54 volumio sudo[4771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 17:01 Dec 23 17:02:54 volumio sudo[4771]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"