Dec 20 22:37:36 volumio01 volumio[1171]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:36 volumio01 sudo[9956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 22:37:36 volumio01 sudo[9956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 22:37:36 volumio01 sudo[9958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 22:37:36 volumio01 sudo[9958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 22:37:36 volumio01 sudo[9956]: pam_unix(sudo:session): session closed for user root Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 22:37:36 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:36 volumio01 volumio[1171]: info: MPD Permissions set Dec 20 22:37:36 volumio01 systemd[1]: musicservicesshield.service: Deactivated successfully. Dec 20 22:37:36 volumio01 systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 20 22:37:36 volumio01 systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 20 22:37:36 volumio01 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 20 22:37:36 volumio01 systemd[1]: mpd.service: Deactivated successfully. Dec 20 22:37:36 volumio01 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 20 22:37:36 volumio01 systemd[1]: mpd.service: Consumed 1.428s CPU time. Dec 20 22:37:36 volumio01 systemd[1]: mpd.socket: Deactivated successfully. Dec 20 22:37:36 volumio01 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 20 22:37:36 volumio01 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 20 22:37:36 volumio01 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 20 22:37:36 volumio01 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 20 22:37:36 volumio01 sudo[9967]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 22:37:36 volumio01 sudo[9967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 20 22:37:36 volumio01 sudo[9967]: pam_unix(sudo:session): session closed for user root Dec 20 22:37:37 volumio01 volumio[1171]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:37 volumio01 sudo[9971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 22:37:37 volumio01 sudo[9971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 22:37:37 volumio01 sudo[9973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 22:37:37 volumio01 sudo[9971]: pam_unix(sudo:session): session closed for user root Dec 20 22:37:37 volumio01 sudo[9973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 22:37:37 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 22:37:37 volumio01 volumio[1171]: info: MPD Permissions set Dec 20 22:37:37 volumio01 systemd[1]: mpd.service: Deactivated successfully. Dec 20 22:37:37 volumio01 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 20 22:37:37 volumio01 systemd[1]: mpd.service: Consumed 1.021s CPU time. Dec 20 22:37:37 volumio01 systemd[1]: mpd.socket: Deactivated successfully. Dec 20 22:37:37 volumio01 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 20 22:37:37 volumio01 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 20 22:37:37 volumio01 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 20 22:37:37 volumio01 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 20 22:37:37 volumio01 sudo[9983]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 22:37:37 volumio01 sudo[9983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 20 22:37:37 volumio01 sudo[9983]: pam_unix(sudo:session): session closed for user root Dec 20 22:37:38 volumio01 mpd[9985]: 2025-12-20T22:37:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 22:37:38 volumio01 systemd[1]: Started mpd.service - Music Player Daemon. Dec 20 22:37:38 volumio01 sudo[9973]: pam_unix(sudo:session): session closed for user root Dec 20 22:37:38 volumio01 sudo[9958]: pam_unix(sudo:session): session closed for user root Dec 20 22:37:38 volumio01 volumio[1171]: error: MPD error: The expression evaluated to a falsy value: Dec 20 22:37:38 volumio01 volumio[1171]: assert.ok(self.idling) Dec 20 22:37:38 volumio01 volumio[1171]: error: The expression evaluated to a falsy value: Dec 20 22:37:38 volumio01 volumio[1171]: assert.ok(self.idling) Dec 20 22:37:38 volumio01 volumio[1171]: error: updateQueue error: null Dec 20 22:37:38 volumio01 systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 20 22:37:39 volumio01 bash[10016]: cset: --> shielding system active with Dec 20 22:37:39 volumio01 bash[10016]: cset: "system" cpuset of CPUSPEC(1-3) with 170 tasks running Dec 20 22:37:39 volumio01 bash[10016]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Dec 20 22:37:39 volumio01 systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 20 22:37:41 volumio01 sudo[10021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 22:37:41 volumio01 sudo[10021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 22:37:41 volumio01 sudo[10023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 22:37:41 volumio01 sudo[10023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 20 22:37:41 volumio01 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 20 22:37:41 volumio01 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 20 22:37:41 volumio01 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 20 22:37:41 volumio01 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 20 22:37:41 volumio01 sudo[10021]: pam_unix(sudo:session): session closed for user root Dec 20 22:37:41 volumio01 sudo[10023]: pam_unix(sudo:session): session closed for user root Dec 20 22:37:41 volumio01 mpd_monitor.sh[10026]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 22:37:41 volumio01 volumio[1171]: info: Successfully started MPD Monitor Dec 20 22:37:41 volumio01 volumio[1171]: info: Successfully started MPD Monitor Dec 20 22:37:49 volumio01 volumio[1171]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:37:49 volumio01 volumio[1171]: info: CoreStateMachine::getQueue Dec 20 22:37:49 volumio01 volumio[1171]: info: CorePlayQueue::getQueue Dec 20 22:37:53 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 20 22:37:54 volumio01 volumio[1171]: info: handleBrowseUri took 1021 milliseconds Dec 20 22:37:54 volumio01 volumio[1171]: info: Preload queue cleared Dec 20 22:37:54 volumio01 volumio[1171]: info: Preload queue cleared Dec 20 22:38:00 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 20 22:38:02 volumio01 volumio[1171]: info: handleBrowseUri took 2313 milliseconds Dec 20 22:38:02 volumio01 volumio[1171]: info: Preload queue cleared Dec 20 22:38:05 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 20 22:38:06 volumio01 volumio[1171]: info: handleBrowseUri took 496 milliseconds Dec 20 22:38:06 volumio01 volumio[1171]: info: Preload queue cleared Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514060 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514061 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514062 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514063 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514064 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514065 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514066 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514067 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514068 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514069 Dec 20 22:38:06 volumio01 volumio[1171]: info: Preloading song: qobuz://song/129514070 Dec 20 22:38:06 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514060 in service qobuz Dec 20 22:38:06 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:06 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514061 in service qobuz Dec 20 22:38:06 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:06 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514062 in service qobuz Dec 20 22:38:06 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:06 volumio01 volumio[1171]: info: Executing endpoint getSimilarAlbums Dec 20 22:38:06 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 20 22:38:06 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514063 in service qobuz Dec 20 22:38:06 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:06 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514064 in service qobuz Dec 20 22:38:06 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:06 volumio01 volumio[1171]: info: Executing endpoint metavolumio Dec 20 22:38:06 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 22:38:06 volumio01 volumio[1171]: info: Executing endpoint metavolumio Dec 20 22:38:06 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 22:38:06 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514065 in service qobuz Dec 20 22:38:06 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:06 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514066 in service qobuz Dec 20 22:38:06 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:06 volumio01 volumio[1171]: info: explodeUri took 333 milliseconds Dec 20 22:38:06 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514067 in service qobuz Dec 20 22:38:06 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:06 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514068 in service qobuz Dec 20 22:38:06 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:07 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514069 in service qobuz Dec 20 22:38:07 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:07 volumio01 volumio[1171]: info: Exploding uri qobuz://song/129514070 in service qobuz Dec 20 22:38:07 volumio01 volumio[1171]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 20 22:38:07 volumio01 volumio[1171]: info: explodeUri took 459 milliseconds Dec 20 22:38:07 volumio01 volumio[1171]: info: explodeUri took 865 milliseconds Dec 20 22:38:07 volumio01 volumio[1171]: info: explodeUri took 839 milliseconds Dec 20 22:38:07 volumio01 volumio[1171]: info: explodeUri took 859 milliseconds Dec 20 22:38:07 volumio01 volumio[1171]: info: explodeUri took 1062 milliseconds Dec 20 22:38:07 volumio01 volumio[1171]: info: explodeUri took 924 milliseconds Dec 20 22:38:07 volumio01 volumio[1171]: info: explodeUri took 845 milliseconds Dec 20 22:38:07 volumio01 volumio[1171]: info: explodeUri took 818 milliseconds Dec 20 22:38:07 volumio01 volumio[1171]: info: explodeUri took 838 milliseconds Dec 20 22:38:09 volumio01 volumio[1171]: info: explodeUri took 2464 milliseconds Dec 20 22:38:15 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList Dec 20 22:38:15 volumio01 volumio[1171]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 22:38:15 volumio01 volumio[1171]: info: CoreCommandRouter::volumioGetBrowseSources Dec 20 22:38:15 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 20 22:38:15 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 20 22:38:17 volumio01 volumio[1171]: info: searchQOBUZUri took 2364 milliseconds Dec 20 22:38:17 volumio01 volumio[1171]: info: All search sources collected, pushing search results Dec 20 22:38:17 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 20 22:38:18 volumio01 volumio[1171]: info: handleBrowseUri took 976 milliseconds Dec 20 22:38:19 volumio01 volumio[1171]: info: Preload queue cleared Dec 20 22:38:19 volumio01 volumio[1171]: info: Executing endpoint getSimilarArtists Dec 20 22:38:19 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Dec 20 22:38:19 volumio01 volumio[1171]: info: Executing endpoint metavolumio Dec 20 22:38:19 volumio01 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 22:38:21 volumio01 volumio[1171]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 22:38:21 volumio01 volumio[1171]: Error: connect ECONNREFUSED 0.0.0.0:443 Dec 20 22:38:21 volumio01 volumio[1171]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 20 22:38:21 volumio01 volumio[1171]: errno: -111, Dec 20 22:38:21 volumio01 volumio[1171]: code: 'ECONNREFUSED', Dec 20 22:38:21 volumio01 volumio[1171]: syscall: 'connect', Dec 20 22:38:21 volumio01 volumio[1171]: address: '0.0.0.0', Dec 20 22:38:21 volumio01 volumio[1171]: port: 443 Dec 20 22:38:21 volumio01 volumio[1171]: } Dec 20 22:38:21 volumio01 volumio[1171]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 22:38:21 volumio01 sudo[10108]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 22:37' Dec 20 22:38:21 volumio01 sudo[10108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"