Jun 02 18:36:07 volumio1037 volumio[1184]: info: CALLMETHOD: music_service squeezelite_mc configSaveServerCredentials [object Object] Jun 02 18:36:07 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: squeezelite_mc , configSaveServerCredentials Jun 02 18:36:12 volumio1037 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 89. Jun 02 18:36:12 volumio1037 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:36:12 volumio1037 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:36:12 volumio1037 upmpdcli[5956]: Could not open config: /tmp/upmpdcli.conf Jun 02 18:36:12 volumio1037 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:36:12 volumio1037 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 02 18:36:27 volumio1037 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 90. Jun 02 18:36:27 volumio1037 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:36:27 volumio1037 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:36:27 volumio1037 upmpdcli[6001]: Could not open config: /tmp/upmpdcli.conf Jun 02 18:36:27 volumio1037 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:36:27 volumio1037 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 02 18:36:31 volumio1037 volumio[1184]: info: CALLMETHOD: music_service squeezelite_mc configSaveBasicSqueezeliteSettings [object Object] Jun 02 18:36:31 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: squeezelite_mc , configSaveBasicSqueezeliteSettings Jun 02 18:36:36 volumio1037 volumio[1184]: info: CALLMETHOD: music_service squeezelite_mc configStartSqueezelite [object Object] Jun 02 18:36:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: squeezelite_mc , configStartSqueezelite Jun 02 18:36:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:36:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:36:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:36:36 volumio1037 volumio[1184]: info: [squeezelite_mc] Restarting Squeezelite service with params: {"type":"basic","playerName":"volumio1037","dsdFormat":"DSD_U32_BE","card":"5","mixerType":"None","mixer":null} Jun 02 18:36:36 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 02 18:36:37 volumio1037 sudo[6013]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 02 18:36:37 volumio1037 sudo[6013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:37 volumio1037 sudo[6013]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:37 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Jun 02 18:36:37 volumio1037 sudo[6017]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Jun 02 18:36:37 volumio1037 sudo[6017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:37 volumio1037 sudo[6017]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:37 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 02 18:36:37 volumio1037 sudo[6021]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 02 18:36:37 volumio1037 sudo[6021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:37 volumio1037 sudo[6021]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:37 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jun 02 18:36:37 volumio1037 sudo[6024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 02 18:36:37 volumio1037 sudo[6024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:37 volumio1037 systemd[1]: Reloading. Jun 02 18:36:37 volumio1037 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 02 18:36:37 volumio1037 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 02 18:36:37 volumio1037 sudo[6024]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:37 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 02 18:36:37 volumio1037 sudo[6057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 02 18:36:37 volumio1037 sudo[6057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:37 volumio1037 sudo[6057]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:37 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Jun 02 18:36:37 volumio1037 sudo[6061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Jun 02 18:36:37 volumio1037 sudo[6061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:37 volumio1037 sudo[6061]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:37 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jun 02 18:36:37 volumio1037 sudo[6064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jun 02 18:36:37 volumio1037 sudo[6064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:37 volumio1037 systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Jun 02 18:36:37 volumio1037 systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:37 volumio1037 sudo[6064]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:37 volumio1037 systemd[1]: squeezelite.service: Deactivated successfully. Jun 02 18:36:37 volumio1037 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 1. Jun 02 18:36:37 volumio1037 systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:37 volumio1037 systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Jun 02 18:36:37 volumio1037 systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:37 volumio1037 systemd[1]: squeezelite.service: Deactivated successfully. Jun 02 18:36:38 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 02 18:36:38 volumio1037 sudo[6071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 02 18:36:38 volumio1037 sudo[6071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:38 volumio1037 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 2. Jun 02 18:36:38 volumio1037 systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:38 volumio1037 systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Jun 02 18:36:38 volumio1037 systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:38 volumio1037 sudo[6071]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:38 volumio1037 systemd[1]: squeezelite.service: Deactivated successfully. Jun 02 18:36:38 volumio1037 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 3. Jun 02 18:36:38 volumio1037 systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:38 volumio1037 systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Jun 02 18:36:38 volumio1037 systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:38 volumio1037 systemd[1]: squeezelite.service: Deactivated successfully. Jun 02 18:36:38 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 02 18:36:38 volumio1037 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 4. Jun 02 18:36:38 volumio1037 systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:38 volumio1037 sudo[6079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 02 18:36:38 volumio1037 sudo[6079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:38 volumio1037 systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Jun 02 18:36:38 volumio1037 systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:38 volumio1037 sudo[6079]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:38 volumio1037 systemd[1]: squeezelite.service: Deactivated successfully. Jun 02 18:36:38 volumio1037 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 5. Jun 02 18:36:38 volumio1037 systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:38 volumio1037 systemd[1]: squeezelite.service: Start request repeated too quickly. Jun 02 18:36:38 volumio1037 systemd[1]: squeezelite.service: Failed with result 'start-limit-hit'. Jun 02 18:36:38 volumio1037 systemd[1]: Failed to start squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Jun 02 18:36:39 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 02 18:36:39 volumio1037 sudo[6084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 02 18:36:39 volumio1037 sudo[6084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:39 volumio1037 sudo[6084]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:39 volumio1037 volumio[1184]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 18:36:39 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 02 18:36:39 volumio1037 sudo[6088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 02 18:36:39 volumio1037 sudo[6088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:36:39 volumio1037 sudo[6088]: pam_unix(sudo:session): session closed for user root Jun 02 18:36:43 volumio1037 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 91. Jun 02 18:36:43 volumio1037 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:36:43 volumio1037 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:36:43 volumio1037 upmpdcli[6093]: Could not open config: /tmp/upmpdcli.conf Jun 02 18:36:43 volumio1037 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:36:43 volumio1037 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 02 18:36:45 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 02 18:36:45 volumio1037 volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 02 18:36:48 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 18:36:48 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 02 18:36:53 volumio1037 volumio[1184]: info: Enabling plugin squeezelite_mc Jun 02 18:36:53 volumio1037 volumio[1184]: info: Loading plugin "squeezelite_mc"... Jun 02 18:36:53 volumio1037 volumio[1184]: info: PLUGIN START: squeezelite_mc Jun 02 18:36:53 volumio1037 volumio[1184]: info: [squeezelite_mc] Starting proxy server... Jun 02 18:36:53 volumio1037 volumio[1184]: info: [squeezelite_mc] Proxy server started on port 46001 Jun 02 18:36:53 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 18:36:53 volumio1037 volumio[1184]: info: CoreStateMachine::pushState Jun 02 18:36:53 volumio1037 volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 02 18:36:53 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 02 18:36:53 volumio1037 volumio[1184]: info: Error: Error: Discovery service is already running Jun 02 18:36:58 volumio1037 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 92. Jun 02 18:36:58 volumio1037 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:36:58 volumio1037 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:36:58 volumio1037 upmpdcli[6140]: Could not open config: /tmp/upmpdcli.conf Jun 02 18:36:58 volumio1037 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:36:58 volumio1037 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 02 18:37:02 volumio1037 volumio[1184]: info: Enabling plugin squeezelite_mc Jun 02 18:37:02 volumio1037 volumio[1184]: info: Loading plugin "squeezelite_mc"... Jun 02 18:37:02 volumio1037 volumio[1184]: info: PLUGIN START: squeezelite_mc Jun 02 18:37:02 volumio1037 volumio[1184]: info: [squeezelite_mc] Starting proxy server... Jun 02 18:37:02 volumio1037 volumio[1184]: info: [squeezelite_mc] Proxy server started on port 45583 Jun 02 18:37:02 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 18:37:02 volumio1037 volumio[1184]: info: CoreStateMachine::pushState Jun 02 18:37:02 volumio1037 volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 02 18:37:02 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 02 18:37:02 volumio1037 volumio[1184]: info: Error: Error: Discovery service is already running Jun 02 18:37:11 volumio1037 volumio[1184]: info: Starting Uninstall of plugin music_service - squeezelite_mc Jun 02 18:37:11 volumio1037 volumio[1184]: info: Uninstalling plugin squeezelite_mc Jun 02 18:37:11 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Jun 02 18:37:11 volumio1037 volumio[1184]: info: [squeezelite_mc] Proxy server stopped Jun 02 18:37:11 volumio1037 sudo[6170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Jun 02 18:37:11 volumio1037 sudo[6170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:11 volumio1037 sudo[6170]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:12 volumio1037 volumio[1184]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 02 18:37:12 volumio1037 sudo[6174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 02 18:37:12 volumio1037 sudo[6174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:12 volumio1037 sudo[6174]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:12 volumio1037 volumio5-onboarding[1830]: time=2026-06-02T18:37:12.528+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 02 18:37:12 volumio1037 volumio[1184]: info: Disabling plugin squeezelite_mc Jun 02 18:37:12 volumio1037 volumio5-onboarding[1830]: time=2026-06-02T18:37:12.535+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 02 18:37:12 volumio1037 volumio[1184]: info: Checking if uninstall.sh is present Jun 02 18:37:12 volumio1037 volumio[1184]: info: Executing uninstall.sh Jun 02 18:37:12 volumio1037 sudo[6178]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/squeezelite_mc/uninstall.sh Jun 02 18:37:12 volumio1037 sudo[6178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:12 volumio1037 systemd[1]: Reloading. Jun 02 18:37:12 volumio1037 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 02 18:37:12 volumio1037 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 02 18:37:12 volumio1037 sudo[6178]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:12 volumio1037 volumio[1184]: info: Uninstall script completed Jun 02 18:37:12 volumio1037 volumio5-onboarding[1830]: time=2026-06-02T18:37:12.983+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 02 18:37:12 volumio1037 volumio[1184]: info: Removing plugin squeezelite_mc from configuration Jun 02 18:37:13 volumio1037 volumio[1184]: info: Successfully removed squeezelite_mc configuration files Jun 02 18:37:13 volumio1037 volumio5-onboarding[1830]: time=2026-06-02T18:37:13.012+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 02 18:37:13 volumio1037 volumio[1184]: info: Plugin folders cleanup Jun 02 18:37:13 volumio1037 volumio[1184]: info: Scanning into folder /volumio/app/plugins/ Jun 02 18:37:13 volumio1037 volumio[1184]: info: Scanning category audio_interface Jun 02 18:37:13 volumio1037 volumio[1184]: info: Scanning category miscellanea Jun 02 18:37:13 volumio1037 volumio[1184]: info: Scanning category music_service Jun 02 18:37:13 volumio1037 volumio[1184]: info: Scanning category plugins.json Jun 02 18:37:13 volumio1037 volumio[1184]: info: Scanning category system_controller Jun 02 18:37:13 volumio1037 volumio[1184]: info: Scanning category user_interface Jun 02 18:37:13 volumio1037 volumio[1184]: info: Scanning into folder /data/plugins/ Jun 02 18:37:13 volumio1037 volumio[1184]: info: Scanning category music_service Jun 02 18:37:13 volumio1037 volumio[1184]: info: Cleaning folder for squeezelite_mc Jun 02 18:37:13 volumio1037 volumio[1184]: info: Plugin folders cleanup completed Jun 02 18:37:13 volumio1037 volumio5-onboarding[1830]: time=2026-06-02T18:37:13.308+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 02 18:37:13 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 18:37:13 volumio1037 volumio[1184]: info: [] Jun 02 18:37:13 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 02 18:37:13 volumio1037 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 93. Jun 02 18:37:13 volumio1037 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:37:13 volumio1037 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:37:13 volumio1037 upmpdcli[6208]: Could not open config: /tmp/upmpdcli.conf Jun 02 18:37:13 volumio1037 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:37:13 volumio1037 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 02 18:37:28 volumio1037 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 94. Jun 02 18:37:28 volumio1037 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:37:28 volumio1037 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:37:29 volumio1037 upmpdcli[6253]: Could not open config: /tmp/upmpdcli.conf Jun 02 18:37:29 volumio1037 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:37:29 volumio1037 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 02 18:37:33 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 02 18:37:33 volumio1037 volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 18:37:36 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 18:37:42 volumio1037 volumio[1184]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 02 18:37:42 volumio1037 volumio[1184]: info: Preparing to save Alsa Options, stopping services first Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioGetState Jun 02 18:37:42 volumio1037 volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioPause Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreStateMachine::pause Jun 02 18:37:42 volumio1037 volumio[1184]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL USB AUDIO"}} Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 18:37:42 volumio1037 volumio[1184]: info: Device 5 does not have any Mixer Control Available Jun 02 18:37:42 volumio1037 vtcs[2246]: [2026-06-02 18:37:42.903] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 02 18:37:42 volumio1037 vtcs[2246]: [2026-06-02 18:37:42.901] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 02 18:37:42 volumio1037 volumio[1184]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 02 18:37:42 volumio1037 volumio[1184]: info: Disabling external Volume Control Jun 02 18:37:42 volumio1037 sudo[6299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 18:37:42 volumio1037 sudo[6299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreStateMachine::pushState Jun 02 18:37:42 volumio1037 volumio[1184]: info: CorePlayQueue::getTrack 0 Jun 02 18:37:42 volumio1037 volumio[1184]: info: CoreCommandRouter::volumioPushState Jun 02 18:37:42 volumio1037 volumio[1184]: info: Preparing to generate the ALSA configuration file Jun 02 18:37:42 volumio1037 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 02 18:37:42 volumio1037 systemd[1]: vtcs.service: Deactivated successfully. Jun 02 18:37:42 volumio1037 sudo[6305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 18:37:42 volumio1037 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 02 18:37:42 volumio1037 systemd[1]: vtcs.service: Consumed 1.110s CPU time. Jun 02 18:37:42 volumio1037 sudo[6305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:42 volumio1037 sudo[6299]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 18:37:43 volumio1037 sudo[6305]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 02 18:37:43 volumio1037 volumio[1184]: info: Asound.conf file written Jun 02 18:37:43 volumio1037 sudo[6317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 02 18:37:43 volumio1037 sudo[6317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:43 volumio1037 sudo[6317]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:43 volumio1037 volumio[1184]: No state is present for card AUDIO Jun 02 18:37:43 volumio1037 volumio[1184]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" "" Jun 02 18:37:43 volumio1037 volumio[1184]: Hardware is initialized using a generic method Jun 02 18:37:43 volumio1037 volumio[1184]: No state is present for card AUDIO Jun 02 18:37:43 volumio1037 volumio[1184]: info: Output device has changed, restarting MPD Jun 02 18:37:43 volumio1037 sudo[6323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 18:37:43 volumio1037 volumio[1184]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 sudo[6323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:43 volumio1037 sudo[6325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 18:37:43 volumio1037 sudo[6325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:43 volumio1037 sudo[6323]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:43 volumio1037 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 02 18:37:43 volumio1037 volumio[1184]: info: Output device has changed, restarting MPD Jun 02 18:37:43 volumio1037 volumio[1184]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 sudo[6333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 18:37:43 volumio1037 sudo[6333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:43 volumio1037 sudo[6333]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:43 volumio1037 sudo[6331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 18:37:43 volumio1037 sudo[6331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:43 volumio1037 sudo[6335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 18:37:43 volumio1037 sudo[6335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:43 volumio1037 sudo[6341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 18:37:43 volumio1037 sudo[6341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:43 volumio1037 volumio[1184]: info: MPD Permissions set Jun 02 18:37:43 volumio1037 volumio[1184]: info: MPD Permissions set Jun 02 18:37:43 volumio1037 sudo[6344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 18:37:43 volumio1037 sudo[6344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 sudo[6341]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:43 volumio1037 sudo[6331]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:43 volumio1037 sudo[6344]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 18:37:43 volumio1037 sudo[6347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 sudo[6347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 18:37:43 volumio1037 volumio[1184]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 18:37:43 volumio1037 sudo[6347]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:43 volumio1037 systemd[1]: mpd.service: Deactivated successfully. Jun 02 18:37:43 volumio1037 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 02 18:37:43 volumio1037 systemd[1]: mpd.service: Consumed 4.300s CPU time. Jun 02 18:37:43 volumio1037 systemd[1]: mpd.socket: Deactivated successfully. Jun 02 18:37:43 volumio1037 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 02 18:37:43 volumio1037 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 02 18:37:43 volumio1037 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 02 18:37:43 volumio1037 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 02 18:37:43 volumio1037 sudo[6374]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 18:37:43 volumio1037 sudo[6374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 02 18:37:43 volumio1037 sudo[6374]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:44 volumio1037 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 95. Jun 02 18:37:44 volumio1037 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:37:44 volumio1037 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 02 18:37:44 volumio1037 mpd[6376]: 2026-06-02T18:37:44 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 18:37:44 volumio1037 upmpdcli[6377]: Could not open config: /tmp/upmpdcli.conf Jun 02 18:37:44 volumio1037 systemd[1]: Started mpd.service - Music Player Daemon. Jun 02 18:37:44 volumio1037 sudo[6335]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:44 volumio1037 sudo[6325]: pam_unix(sudo:session): session closed for user root Jun 02 18:37:44 volumio1037 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 02 18:37:44 volumio1037 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 02 18:37:44 volumio1037 volumio[1184]: error: MPD error: The expression evaluated to a falsy value: Jun 02 18:37:44 volumio1037 volumio[1184]: assert.ok(self.idling) Jun 02 18:37:44 volumio1037 volumio[1184]: error: The expression evaluated to a falsy value: Jun 02 18:37:44 volumio1037 volumio[1184]: assert.ok(self.idling) Jun 02 18:37:44 volumio1037 volumio[1184]: error: updateQueue error: null Jun 02 18:37:44 volumio1037 volumio[1184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 18:37:44 volumio1037 volumio[1184]: TypeError: Cannot read properties of null (reading 'logger') Jun 02 18:37:44 volumio1037 volumio[1184]: at SqueezeliteMCContext.getLogger (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:65:86) Jun 02 18:37:44 volumio1037 volumio[1184]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_stdLogError (/data/plugins/music_service/squeezelite_mc/dist/index.js:716:36) Jun 02 18:37:44 volumio1037 volumio[1184]: at /data/plugins/music_service/squeezelite_mc/dist/index.js:1216:121 Jun 02 18:37:44 volumio1037 volumio[1184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 18:37:44 volumio1037 sudo[6395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-02 18:36' Jun 02 18:37:44 volumio1037 sudo[6395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"