Dec 14 20:27:00 volumio-hr volumio[1216]: info: Get:11 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libportmidi0 armhf 1:217-6.1 [20.1 kB] Dec 14 20:27:00 volumio-hr volumio[1216]: info: Get:12 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libraqm0 armhf 0.7.0-4.1 [9176 B] Dec 14 20:27:00 volumio-hr volumio[1216]: info: Get:13 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl2-image-2.0-0 armhf 2.6.3+dfsg-1 [54.6 kB] Dec 14 20:27:00 volumio-hr volumio[1216]: info: Get:14 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl2-mixer-2.0-0 armhf 2.6.2+dfsg-2 [69.2 kB] Dec 14 20:27:00 volumio-hr volumio[1216]: info: Get:15 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl2-ttf-2.0-0 armhf 2.20.1+dfsg-2 [26.2 kB] Dec 14 20:27:01 volumio-hr volumio[1216]: info: Get:17 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf python3-olefile all 0.46-3 [36.1 kB] Dec 14 20:27:01 volumio-hr volumio[1216]: info: Get:16 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-numpy armhf 1:1.24.2-1+deb12u1 [3827 kB] Dec 14 20:27:01 volumio-hr volumio[1216]: info: Get:19 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pygame armhf 2.1.2+dfsg-5 [1061 kB] Dec 14 20:27:02 volumio-hr volumio[1216]: info: Get:18 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pil armhf 9.4.0-1.1+deb12u1 [433 kB] Dec 14 20:27:07 volumio-hr volumio[1216]: info: Fetched 9480 kB in 4s (2285 kB/s) Dec 14 20:27:08 volumio-hr volumio[1216]: info: Selecting previously unselected package bzip2. Dec 14 20:27:09 volumio-hr volumio[1216]: [620B blob data] Dec 14 20:27:09 volumio-hr volumio[1216]: info: Preparing to unpack .../00-bzip2_1.0.8-5+b2_armhf.deb ... Dec 14 20:27:09 volumio-hr volumio[1216]: info: Unpacking bzip2 (1.0.8-5+b2) ... Dec 14 20:27:09 volumio-hr volumio[1216]: info: Selecting previously unselected package file. Dec 14 20:27:09 volumio-hr volumio[1216]: info: Preparing to unpack .../01-file_1%3a5.44-3_armhf.deb ... Dec 14 20:27:09 volumio-hr volumio[1216]: info: Unpacking file (1:5.44-3) ... Dec 14 20:27:09 volumio-hr volumio[1216]: info: Selecting previously unselected package mailcap. Dec 14 20:27:09 volumio-hr volumio[1216]: info: Preparing to unpack .../02-mailcap_3.70+nmu1_all.deb ... Dec 14 20:27:09 volumio-hr volumio[1216]: info: Unpacking mailcap (3.70+nmu1) ... Dec 14 20:27:10 volumio-hr volumio[1216]: info: Selecting previously unselected package mime-support. Dec 14 20:27:10 volumio-hr volumio[1216]: info: Preparing to unpack .../03-mime-support_3.66_all.deb ... Dec 14 20:27:10 volumio-hr volumio[1216]: info: Unpacking mime-support (3.66) ... Dec 14 20:27:10 volumio-hr volumio[1216]: info: Selecting previously unselected package fonts-freefont-ttf. Dec 14 20:27:10 volumio-hr volumio[1216]: info: Preparing to unpack .../04-fonts-freefont-ttf_20120503-10_all.deb ... Dec 14 20:27:10 volumio-hr volumio[1216]: info: Unpacking fonts-freefont-ttf (20120503-10) ... Dec 14 20:27:11 volumio-hr volumio[1216]: info: Selecting previously unselected package libfftw3-double3:armhf. Dec 14 20:27:11 volumio-hr volumio[1216]: info: Preparing to unpack .../05-libfftw3-double3_3.3.10-1_armhf.deb ... Dec 14 20:27:11 volumio-hr volumio[1216]: info: Unpacking libfftw3-double3:armhf (3.3.10-1) ... Dec 14 20:27:11 volumio-hr volumio[1216]: info: Selecting previously unselected package libfftw3-bin. Dec 14 20:27:11 volumio-hr volumio[1216]: info: Preparing to unpack .../06-libfftw3-bin_3.3.10-1_armhf.deb ... Dec 14 20:27:11 volumio-hr volumio[1216]: info: Unpacking libfftw3-bin (3.3.10-1) ... Dec 14 20:27:11 volumio-hr volumio[1216]: info: Selecting previously unselected package libfftw3-dev:armhf. Dec 14 20:27:12 volumio-hr volumio[1216]: info: Preparing to unpack .../07-libfftw3-dev_3.3.10-1_armhf.deb ... Dec 14 20:27:12 volumio-hr volumio[1216]: info: Unpacking libfftw3-dev:armhf (3.3.10-1) ... Dec 14 20:27:12 volumio-hr volumio[1216]: info: Selecting previously unselected package libimagequant0:armhf. Dec 14 20:27:12 volumio-hr volumio[1216]: info: Preparing to unpack .../08-libimagequant0_2.17.0-1_armhf.deb ... Dec 14 20:27:12 volumio-hr volumio[1216]: info: Unpacking libimagequant0:armhf (2.17.0-1) ... Dec 14 20:27:12 volumio-hr volumio[1216]: info: Selecting previously unselected package libopusfile0:armhf. Dec 14 20:27:12 volumio-hr volumio[1216]: info: Preparing to unpack .../09-libopusfile0_0.12-4_armhf.deb ... Dec 14 20:27:12 volumio-hr volumio[1216]: info: Unpacking libopusfile0:armhf (0.12-4) ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Selecting previously unselected package libportmidi0:armhf. Dec 14 20:27:13 volumio-hr volumio[1216]: info: Preparing to unpack .../10-libportmidi0_1%3a217-6.1_armhf.deb ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Unpacking libportmidi0:armhf (1:217-6.1) ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Selecting previously unselected package libraqm0:armhf. Dec 14 20:27:13 volumio-hr volumio[1216]: info: Preparing to unpack .../11-libraqm0_0.7.0-4.1_armhf.deb ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Unpacking libraqm0:armhf (0.7.0-4.1) ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Selecting previously unselected package libsdl2-image-2.0-0:armhf. Dec 14 20:27:13 volumio-hr volumio[1216]: info: Preparing to unpack .../12-libsdl2-image-2.0-0_2.6.3+dfsg-1_armhf.deb ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Unpacking libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Selecting previously unselected package libsdl2-mixer-2.0-0:armhf. Dec 14 20:27:13 volumio-hr volumio[1216]: info: Preparing to unpack .../13-libsdl2-mixer-2.0-0_2.6.2+dfsg-2_armhf.deb ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Unpacking libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Selecting previously unselected package libsdl2-ttf-2.0-0:armhf. Dec 14 20:27:13 volumio-hr volumio[1216]: info: Preparing to unpack .../14-libsdl2-ttf-2.0-0_2.20.1+dfsg-2_armhf.deb ... Dec 14 20:27:13 volumio-hr volumio[1216]: info: Unpacking libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Dec 14 20:27:14 volumio-hr volumio[1216]: info: Selecting previously unselected package python3-numpy. Dec 14 20:27:14 volumio-hr volumio[1216]: info: Preparing to unpack .../15-python3-numpy_1%3a1.24.2-1+deb12u1_armhf.deb ... Dec 14 20:27:14 volumio-hr volumio[1216]: info: Unpacking python3-numpy (1:1.24.2-1+deb12u1) ... Dec 14 20:27:19 volumio-hr volumio[1216]: info: Selecting previously unselected package python3-olefile. Dec 14 20:27:19 volumio-hr volumio[1216]: info: Preparing to unpack .../16-python3-olefile_0.46-3_all.deb ... Dec 14 20:27:19 volumio-hr volumio[1216]: info: Unpacking python3-olefile (0.46-3) ... Dec 14 20:27:20 volumio-hr volumio[1216]: info: Selecting previously unselected package python3-pil:armhf. Dec 14 20:27:20 volumio-hr volumio[1216]: info: Preparing to unpack .../17-python3-pil_9.4.0-1.1+deb12u1_armhf.deb ... Dec 14 20:27:20 volumio-hr volumio[1216]: info: Unpacking python3-pil:armhf (9.4.0-1.1+deb12u1) ... Dec 14 20:27:21 volumio-hr volumio[1216]: info: Selecting previously unselected package python3-pygame. Dec 14 20:27:21 volumio-hr volumio[1216]: info: Preparing to unpack .../18-python3-pygame_2.1.2+dfsg-5_armhf.deb ... Dec 14 20:27:21 volumio-hr volumio[1216]: info: Unpacking python3-pygame (2.1.2+dfsg-5) ... Dec 14 20:27:23 volumio-hr volumio[1216]: info: Setting up libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Dec 14 20:27:23 volumio-hr volumio[1216]: info: Setting up python3-olefile (0.46-3) ... Dec 14 20:27:24 volumio-hr volumio[1216]: info: Setting up fonts-freefont-ttf (20120503-10) ... Dec 14 20:27:24 volumio-hr volumio[1216]: info: Setting up file (1:5.44-3) ... Dec 14 20:27:24 volumio-hr volumio[1216]: info: Setting up bzip2 (1.0.8-5+b2) ... Dec 14 20:27:24 volumio-hr volumio[1216]: info: Setting up libraqm0:armhf (0.7.0-4.1) ... Dec 14 20:27:24 volumio-hr volumio[1216]: info: Setting up libportmidi0:armhf (1:217-6.1) ... Dec 14 20:27:25 volumio-hr volumio[1216]: info: Setting up libimagequant0:armhf (2.17.0-1) ... Dec 14 20:27:25 volumio-hr volumio[1216]: info: Setting up python3-numpy (1:1.24.2-1+deb12u1) ... Dec 14 20:27:30 volumio-hr volumio[1216]: info: Setting up libfftw3-double3:armhf (3.3.10-1) ... Dec 14 20:27:31 volumio-hr volumio[1216]: info: Setting up libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Dec 14 20:27:31 volumio-hr volumio[1216]: info: Setting up mailcap (3.70+nmu1) ... Dec 14 20:27:31 volumio-hr volumio[1216]: info: Setting up libopusfile0:armhf (0.12-4) ... Dec 14 20:27:31 volumio-hr volumio[1216]: info: Setting up mime-support (3.66) ... Dec 14 20:27:31 volumio-hr volumio[1216]: info: Setting up libfftw3-bin (3.3.10-1) ... Dec 14 20:27:31 volumio-hr volumio[1216]: info: Setting up python3-pil:armhf (9.4.0-1.1+deb12u1) ... Dec 14 20:27:32 volumio-hr volumio[1216]: info: Setting up libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Dec 14 20:27:32 volumio-hr volumio[1216]: info: Setting up python3-pygame (2.1.2+dfsg-5) ... Dec 14 20:27:33 volumio-hr volumio[1216]: info: Setting up libfftw3-dev:armhf (3.3.10-1) ... Dec 14 20:27:33 volumio-hr volumio[1216]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Dec 14 20:27:33 volumio-hr volumio[1216]: info: Processing triggers for man-db (2.11.2-2) ... Dec 14 20:27:33 volumio-hr volumio[1216]: info: Processing triggers for fontconfig (2.14.1-4) ... Dec 14 20:27:39 volumio-hr sudo[2759]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:39 volumio-hr volumio[1216]: info: Linked /data/plugins/user_interface/peppyspectrum/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so Dec 14 20:27:39 volumio-hr sudo[3126]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppyspectrum/startpeppyspectrum.sh Dec 14 20:27:39 volumio-hr sudo[3126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 20:27:39 volumio-hr volumio[1216]: info: Linked /data/plugins/user_interface/peppyspectrum/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so.0 Dec 14 20:27:39 volumio-hr sudo[3126]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:39 volumio-hr sudo[3142]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Dec 14 20:27:39 volumio-hr sudo[3142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 20:27:39 volumio-hr systemd[1]: Reloading. Dec 14 20:27:40 volumio-hr 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. Dec 14 20:27:40 volumio-hr 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. Dec 14 20:27:40 volumio-hr sudo[3142]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:40 volumio-hr sudo[2537]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:40 volumio-hr volumio[1216]: info: Install script completed Dec 14 20:27:40 volumio-hr volumio[1216]: info: Adding reference to registry Dec 14 20:27:40 volumio-hr volumio[1216]: info: Done installing plugin. Dec 14 20:27:40 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 20:27:40 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 20:27:40 volumio-hr volumio[1216]: Plugin install end detected on script Dec 14 20:27:40 volumio-hr volumio[1216]: info: Folder /tmp/plugins removed Dec 14 20:27:40 volumio-hr volumio[1216]: info: Folder /tmp/downloaded_plugin.zip removed Dec 14 20:27:40 volumio-hr volumio[1216]: info: Folder /data/temp removed Dec 14 20:27:52 volumio-hr volumio[1216]: info: Enabling plugin peppyspectrum Dec 14 20:27:52 volumio-hr volumio[1216]: info: Loading plugin "peppyspectrum"... Dec 14 20:27:54 volumio-hr volumio[1216]: info: Preparing to generate the ALSA configuration file Dec 14 20:27:54 volumio-hr volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 20:27:54 volumio-hr volumio[1216]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 20:27:54 volumio-hr volumio[1216]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 20:27:54 volumio-hr volumio[1216]: info: Reading ALSA contributions from plugins. Dec 14 20:27:55 volumio-hr volumio[1216]: info: Asound.conf file written Dec 14 20:27:55 volumio-hr sudo[3182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 14 20:27:55 volumio-hr sudo[3182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:55 volumio-hr sudo[3182]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:55 volumio-hr volumio[1216]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 14 20:27:55 volumio-hr volumio[1216]: info: Output device has changed, restarting MPD Dec 14 20:27:55 volumio-hr volumio[1216]: info: Output device has changed, restarting Shairport Sync Dec 14 20:27:55 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:55 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 20:27:55 volumio-hr sudo[3191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 20:27:55 volumio-hr sudo[3189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 20:27:55 volumio-hr sudo[3189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:55 volumio-hr sudo[3191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:55 volumio-hr sudo[3189]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:55 volumio-hr volumio[1216]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 20:27:55 volumio-hr volumio[1216]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 20:27:55 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:55 volumio-hr systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 20:27:55 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 20:27:55 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:27:55 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 20:27:55 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 20:27:55 volumio-hr systemd[1]: mpd.service: Consumed 1.666s CPU time. Dec 14 20:27:55 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 20:27:55 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 20:27:55 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 20:27:55 volumio-hr vtcs[2288]: [2025-12-14 20:27:55.621] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 20:27:55 volumio-hr vtcs[2288]: [2025-12-14 20:27:55.625] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 14 20:27:55 volumio-hr vtcs[2288]: [2025-12-14 20:27:55.626] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 20:27:55 volumio-hr volumio[1216]: info: PLUGIN START: peppyspectrum Dec 14 20:27:55 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 20:27:55 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 20:27:55 volumio-hr sudo[3203]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 20:27:55 volumio-hr sudo[3203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 20:27:55 volumio-hr sudo[3203]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:55 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 14 20:27:55 volumio-hr sudo[3202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 20:27:55 volumio-hr sudo[3202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:55 volumio-hr volumio[1216]: info: Preparing to generate the ALSA configuration file Dec 14 20:27:55 volumio-hr systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 20:27:56 volumio-hr systemd[1]: vtcs.service: Killing process 2294 (vtcs) with signal SIGKILL. Dec 14 20:27:56 volumio-hr systemd[1]: vtcs.service: Deactivated successfully. Dec 14 20:27:56 volumio-hr systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 20:27:56 volumio-hr sudo[3208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 14 20:27:56 volumio-hr sudo[3202]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:56 volumio-hr sudo[3208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:56 volumio-hr volumio[1216]: info: Done. Dec 14 20:27:56 volumio-hr sudo[3208]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:56 volumio-hr (udev-worker)[3212]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Dec 14 20:27:56 volumio-hr sudo[3215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 20:27:56 volumio-hr sudo[3215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:56 volumio-hr sudo[3215]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:56 volumio-hr volumio[1216]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 20:27:56 volumio-hr volumio[1216]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 20:27:56 volumio-hr volumio[1216]: info: Reading ALSA contributions from plugins. Dec 14 20:27:56 volumio-hr sudo[3222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 20:27:56 volumio-hr sudo[3222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:56 volumio-hr volumio[1216]: info: snd_dummy loaded Dec 14 20:27:56 volumio-hr volumio[1216]: info: MPD Permissions set Dec 14 20:27:56 volumio-hr sudo[3222]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:56 volumio-hr volumio[1216]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 14 20:27:56 volumio-hr sudo[3225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 20:27:56 volumio-hr sudo[3225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:57 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 20:27:57 volumio-hr qobuz-connect[2199]: 20251214 20:27:57.128 [2199.2199] INFO SampleApp: Stopping Local configuration server Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr mpd[3205]: decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 20:27:57 volumio-hr mpd[3205]: output: No 'audio_output' defined in config file Dec 14 20:27:57 volumio-hr qobuz-connect[2199]: 20251214 20:27:57.410 [2199.2199] INFO SampleApp: shat down connection on UNIX socket Dec 14 20:27:57 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 20:27:57 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 20:27:57 volumio-hr mpd[3205]: output: Successfully detected a sndio audio device Dec 14 20:27:57 volumio-hr mpd[3205]: zeroconf: No global port, disabling zeroconf Dec 14 20:27:57 volumio-hr volumio[1216]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 20:27:57 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:27:57 volumio-hr volumio[1216]: info: Starting Shairport Sync Dec 14 20:27:57 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 20:27:57 volumio-hr systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 20:27:57 volumio-hr volumio[1216]: info: Asound.conf file unchanged, so no further update is needed Dec 14 20:27:57 volumio-hr volumio[1216]: info: Output device has changed, restarting MPD Dec 14 20:27:57 volumio-hr sudo[3191]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:57 volumio-hr sudo[3225]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:57 volumio-hr volumio[1216]: info: Output device has changed, restarting Shairport Sync Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 20:27:57 volumio-hr sudo[3238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 20:27:57 volumio-hr sudo[3236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 20:27:57 volumio-hr sudo[3236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:57 volumio-hr sudo[3238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:57 volumio-hr sudo[3238]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:57 volumio-hr sudo[3241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 20:27:57 volumio-hr sudo[3241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:57 volumio-hr volumio[1216]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 20:27:57 volumio-hr volumio[1216]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:57 volumio-hr systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 20:27:57 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 20:27:57 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 20:27:57 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 20:27:57 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.927s CPU time. Dec 14 20:27:57 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 20:27:57 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:27:57 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 20:27:57 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 20:27:57 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 20:27:57 volumio-hr systemd[1]: mpd.service: Consumed 1.553s CPU time. Dec 14 20:27:57 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 20:27:57 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 20:27:57 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 20:27:57 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 20:27:57 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 20:27:57 volumio-hr sudo[3236]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:57 volumio-hr sudo[3268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 20:27:57 volumio-hr sudo[3268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:57 volumio-hr sudo[3279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 20:27:57 volumio-hr sudo[3279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:57 volumio-hr volumio[1216]: info: MPD Permissions set Dec 14 20:27:57 volumio-hr volumio[1216]: info: Shairport-Sync Started Dec 14 20:27:57 volumio-hr sudo[3253]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 20:27:57 volumio-hr sudo[3253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 20:27:57 volumio-hr sudo[3253]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:58 volumio-hr volumio[1216]: info: Executing endpoint qc_getconfig Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 20:27:58 volumio-hr sudo[3279]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:58 volumio-hr sudo[3268]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:58 volumio-hr sudo[3272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 20:27:58 volumio-hr sudo[3272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.264 [3232.3232] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 20:27:58 volumio-hr volumio[1216]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.268 [3232.3232] INFO VolumeManager: [0x177f348]: Setting new playback volume: 75 Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.268 [3232.3232] INFO VolumeManager: [0x177f348]: Setting new mute state: 0 Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.268 [3232.3232] INFO QobuzConnect: [0x177fd18]: Client initialized! Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.268 [3232.3232] INFO SampleApp: Starting Avahi advertising, name: Volumio HR, service name: _qobuz-connect._tcp Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.295 [3232.3232] INFO LocalConfigManager: [0x177ec28]: Starting Local Configuration server Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.295 [3232.3232] INFO SampleApp: Starting Local configuration server Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.296 [3232.3232] INFO SampleApp: Connected to UNIX socket client 0x1769818 Dec 14 20:27:58 volumio-hr sudo[3272]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:58 volumio-hr sudo[3284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 20:27:58 volumio-hr volumio[1216]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:58 volumio-hr sudo[3284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.414 [3232.3232] INFO SampleApp: Playback volume changed: 75 Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 20:27:58 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 20:27:58 volumio-hr qobuz-connect[3232]: 20251214 20:27:58.657 [3232.3232] INFO SampleApp: Stopping Local configuration server Dec 14 20:27:58 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:27:58 volumio-hr volumio[1216]: info: Starting Shairport Sync Dec 14 20:27:59 volumio-hr sudo[3300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 20:27:59 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 20:27:59 volumio-hr sudo[3300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:27:59 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 20:27:59 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 20:27:59 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 20:27:59 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.264s CPU time. Dec 14 20:27:59 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 20:27:59 volumio-hr sudo[3300]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:59 volumio-hr volumio[1216]: info: Shairport-Sync Started Dec 14 20:27:59 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 20:27:59 volumio-hr mpd[3283]: 2025-12-14T20:27:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 20:27:59 volumio-hr systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 20:27:59 volumio-hr volumio[1216]: error: updateQueue error: null Dec 14 20:27:59 volumio-hr sudo[3241]: pam_unix(sudo:session): session closed for user root Dec 14 20:27:59 volumio-hr volumio[1216]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 20:27:59 volumio-hr volumio[1216]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 20:27:59 volumio-hr volumio[1216]: error: updateQueue error: null Dec 14 20:28:00 volumio-hr sudo[3342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 20:28:00 volumio-hr sudo[3342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:28:00 volumio-hr qobuz-connect[3232]: 20251214 20:28:00.300 [3232.3232] INFO SampleApp: shat down connection on UNIX socket Dec 14 20:28:00 volumio-hr volumio[1216]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 20:28:00 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:28:00 volumio-hr systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 20:28:00 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 20:28:00 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 20:28:00 volumio-hr sudo[3342]: pam_unix(sudo:session): session closed for user root Dec 14 20:28:00 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 20:28:00 volumio-hr sudo[3284]: pam_unix(sudo:session): session closed for user root Dec 14 20:28:00 volumio-hr volumio[1216]: info: Executing endpoint qc_getconfig Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 20:28:00 volumio-hr qobuz-connect[3346]: 20251214 20:28:00.585 [3346.3346] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 20:28:00 volumio-hr qobuz-connect[3346]: 20251214 20:28:00.589 [3346.3346] INFO VolumeManager: [0x1f55348]: Setting new playback volume: 75 Dec 14 20:28:00 volumio-hr qobuz-connect[3346]: 20251214 20:28:00.589 [3346.3346] INFO VolumeManager: [0x1f55348]: Setting new mute state: 0 Dec 14 20:28:00 volumio-hr qobuz-connect[3346]: 20251214 20:28:00.589 [3346.3346] INFO QobuzConnect: [0x1f55d18]: Client initialized! Dec 14 20:28:00 volumio-hr qobuz-connect[3346]: 20251214 20:28:00.589 [3346.3346] INFO SampleApp: Starting Avahi advertising, name: Volumio HR, service name: _qobuz-connect._tcp Dec 14 20:28:00 volumio-hr qobuz-connect[3346]: 20251214 20:28:00.623 [3346.3346] INFO LocalConfigManager: [0x1f54c28]: Starting Local Configuration server Dec 14 20:28:00 volumio-hr qobuz-connect[3346]: 20251214 20:28:00.623 [3346.3346] INFO SampleApp: Starting Local configuration server Dec 14 20:28:00 volumio-hr qobuz-connect[3346]: 20251214 20:28:00.623 [3346.3346] INFO SampleApp: Connected to UNIX socket client 0x1f3f818 Dec 14 20:28:00 volumio-hr volumio[1216]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 20:28:00 volumio-hr volumio[1216]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 20:28:00 volumio-hr volumio[1216]: info: Executing endpoint tc_getconfig Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 20:28:00 volumio-hr vtcs[3345]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 20:28:00 volumio-hr vtcs[3345]: STARTED TidalConnect services. Dec 14 20:28:00 volumio-hr qobuz-connect[3346]: 20251214 20:28:00.735 [3346.3346] INFO SampleApp: Playback volume changed: 75 Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:28:00 volumio-hr volumio[1216]: info: Executing endpoint tc_connect Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 20:28:00 volumio-hr volumio[1216]: info: Connecting to TidalConnect Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::servicePushState Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreStateMachine::pushState Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioPushState Dec 14 20:28:00 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Dec 14 20:28:00 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:28:00 volumio-hr volumio[1216]: info: CorePlayQueue::getTrack 0 Dec 14 20:28:00 volumio-hr volumio[1216]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 14 20:28:00 volumio-hr volumio[1216]: verbose: CURRENT POSITION 0 Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreStateMachine::syncState stateService stop Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreStateMachine::syncState currentStatus stop Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreStateMachine::pushState Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioPushState Dec 14 20:28:00 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Dec 14 20:28:00 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:28:00 volumio-hr volumio[1216]: info: No code Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreStateMachine::pushState Dec 14 20:28:00 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioPushState Dec 14 20:28:01 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Dec 14 20:28:01 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreCommandRouter::servicePushState Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreStateMachine::pushState Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioPushState Dec 14 20:28:01 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Dec 14 20:28:01 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:28:01 volumio-hr volumio[1216]: info: CorePlayQueue::getTrack 0 Dec 14 20:28:01 volumio-hr volumio[1216]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 14 20:28:01 volumio-hr volumio[1216]: verbose: CURRENT POSITION 0 Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreStateMachine::syncState stateService stop Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreStateMachine::syncState currentStatus stop Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreStateMachine::pushState Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioPushState Dec 14 20:28:01 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Dec 14 20:28:01 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:28:01 volumio-hr volumio[1216]: info: No code Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreStateMachine::pushState Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioPushState Dec 14 20:28:01 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Dec 14 20:28:01 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output Dec 14 20:28:01 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 20:28:01 volumio-hr sudo[3368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 20:28:01 volumio-hr sudo[3368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:28:01 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 20:28:01 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 20:28:01 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 20:28:01 volumio-hr volumio[1216]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.BQgRxyczNtPJGyTEgPH7EBt4Qld2.2f17f0648c10c9dd34cab8f534e41450.state.status' Dec 14 20:28:01 volumio-hr volumio[1216]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.BQgRxyczNtPJGyTEgPH7EBt4Qld2.2f17f0648c10c9dd34cab8f534e41450.state.status' Dec 14 20:28:01 volumio-hr volumio[1216]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.BQgRxyczNtPJGyTEgPH7EBt4Qld2.2f17f0648c10c9dd34cab8f534e41450.state.status' Dec 14 20:28:01 volumio-hr volumio[1216]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.BQgRxyczNtPJGyTEgPH7EBt4Qld2.2f17f0648c10c9dd34cab8f534e41450.state.status' Dec 14 20:28:01 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 20:28:01 volumio-hr mpd_monitor.sh[3370]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 20:28:01 volumio-hr sudo[3368]: pam_unix(sudo:session): session closed for user root Dec 14 20:28:01 volumio-hr volumio[1216]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.BQgRxyczNtPJGyTEgPH7EBt4Qld2.2f17f0648c10c9dd34cab8f534e41450.state.status' Dec 14 20:28:01 volumio-hr volumio[1216]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.BQgRxyczNtPJGyTEgPH7EBt4Qld2.2f17f0648c10c9dd34cab8f534e41450.state.status' Dec 14 20:28:01 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 20:28:01 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 20:28:01 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 20:28:01 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 20:28:01 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 20:28:01 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 20:28:01 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 20:28:02 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 20:28:02 volumio-hr volumio[1216]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] Dec 14 20:28:02 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy Dec 14 20:28:02 volumio-hr volumio[1216]: aaaaaaaaaaa 800,480 Dec 14 20:28:02 volumio-hr volumio[1216]: info: PeppySpectrum ---800480 Dec 14 20:28:02 volumio-hr volumio[1216]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 14 20:28:02 volumio-hr volumio[1216]: info: Successfully started MPD Monitor Dec 14 20:28:02 volumio-hr volumio[1216]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 20:28:02 volumio-hr volumio[1216]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 20:28:02 volumio-hr volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 20:28:02 volumio-hr volumio[1216]: info: CoreCommandRouter::Close All Modals sent Dec 14 20:28:02 volumio-hr sudo[3383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 20:28:02 volumio-hr sudo[3383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:28:02 volumio-hr sudo[3383]: pam_unix(sudo:session): session closed for user root Dec 14 20:28:02 volumio-hr sudo[3387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 20:28:02 volumio-hr sudo[3387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:28:03 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 20:28:03 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 20:28:03 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 20:28:03 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 20:28:03 volumio-hr mpd_monitor.sh[3389]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 20:28:03 volumio-hr sudo[3387]: pam_unix(sudo:session): session closed for user root Dec 14 20:28:03 volumio-hr volumio[1216]: info: Successfully started MPD Monitor Dec 14 20:28:03 volumio-hr volumio[1216]: info: TidalConnect service started! Dec 14 20:28:05 volumio-hr volumio[1216]: info: TidalConnect service started! Dec 14 20:28:14 volumio-hr volumio[1216]: info: CALLMETHOD: user_interface peppyspectrum delayspectrum [object Object] Dec 14 20:28:14 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , delayspectrum Dec 14 20:28:14 volumio-hr volumio[1216]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 14 20:28:14 volumio-hr volumio[1216]: info: PeppySpectrum -----------------------spectrum Dec 14 20:28:14 volumio-hr volumio[1216]: info: PeppySpectrum -----------------------$basefolder Dec 14 20:28:14 volumio-hr volumio[1216]: info: PeppySpectrum -----------------------screensize800x480 Dec 14 20:28:14 volumio-hr volumio[1216]: info: PeppySpectrum -----------------------screenwidth Dec 14 20:28:14 volumio-hr volumio[1216]: info: PeppySpectrum -----------------------screenheight Dec 14 20:28:14 volumio-hr volumio[1216]: info: PeppySpectrum -----------------------spectrumsize30 Dec 14 20:28:14 volumio-hr volumio[1216]: error: PeppySpectrum ---Error writing config null Dec 14 20:28:14 volumio-hr sudo[3409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Dec 14 20:28:14 volumio-hr sudo[3409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 20:28:14 volumio-hr volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 20:28:14 volumio-hr volumio[1216]: info: CoreCommandRouter::Close All Modals sent Dec 14 20:28:14 volumio-hr systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 14 20:28:14 volumio-hr sudo[3409]: pam_unix(sudo:session): session closed for user root Dec 14 20:28:14 volumio-hr volumio[1216]: info: peppyspectrum Daemon Started Dec 14 20:28:18 volumio-hr volumio[1216]: info: CALLMETHOD: user_interface peppyspectrum updatelist Dec 14 20:28:18 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Dec 14 20:28:18 volumio-hr volumio[1216]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Dec 14 20:28:19 volumio-hr volumio[1216]: --2025-12-14 20:28:18-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Dec 14 20:28:19 volumio-hr volumio[1216]: Resolving github.com (github.com)... 140.82.121.3 Dec 14 20:28:19 volumio-hr volumio[1216]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 14 20:28:19 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 302 Found Dec 14 20:28:19 volumio-hr volumio[1216]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Dec 14 20:28:19 volumio-hr volumio[1216]: --2025-12-14 20:28:18-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Dec 14 20:28:19 volumio-hr volumio[1216]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.109.133, 185.199.111.133, 185.199.108.133, ... Dec 14 20:28:19 volumio-hr volumio[1216]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.109.133|:443... connected. Dec 14 20:28:19 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 200 OK Dec 14 20:28:19 volumio-hr volumio[1216]: Length: 471 [text/plain] Dec 14 20:28:19 volumio-hr volumio[1216]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Dec 14 20:28:19 volumio-hr volumio[1216]: 0K 100% 6.82M=0s Dec 14 20:28:19 volumio-hr volumio[1216]: 2025-12-14 20:28:19 (6.82 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Dec 14 20:28:19 volumio-hr volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 20:28:19 volumio-hr volumio[1216]: info: CoreCommandRouter::Close All Modals sent Dec 14 20:28:21 volumio-hr volumio[1216]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Dec 14 20:28:21 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Dec 14 20:28:22 volumio-hr volumio[1216]: --2025-12-14 20:28:21-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Dec 14 20:28:22 volumio-hr volumio[1216]: Resolving github.com (github.com)... 140.82.121.3 Dec 14 20:28:22 volumio-hr volumio[1216]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 14 20:28:22 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 404 Not Found Dec 14 20:28:22 volumio-hr volumio[1216]: 2025-12-14 20:28:22 ERROR 404: Not Found. Dec 14 20:28:22 volumio-hr volumio[1216]: --2025-12-14 20:28:22-- http://a/ Dec 14 20:28:22 volumio-hr volumio[1216]: Resolving a (a)... failed: Device or resource busy. Dec 14 20:28:22 volumio-hr volumio[1216]: wget: unable to resolve host address ‘a’ Dec 14 20:28:22 volumio-hr volumio[1216]: --2025-12-14 20:28:22-- http://packet.zip/ Dec 14 20:28:22 volumio-hr volumio[1216]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 14 20:28:22 volumio-hr volumio[1216]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 14 20:28:22 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 302 Found Dec 14 20:28:22 volumio-hr volumio[1216]: Location: http://www.packet.zip/ [following] Dec 14 20:28:22 volumio-hr volumio[1216]: --2025-12-14 20:28:22-- http://www.packet.zip/ Dec 14 20:28:22 volumio-hr volumio[1216]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 14 20:28:22 volumio-hr volumio[1216]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 14 20:28:22 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 403 Forbidden Dec 14 20:28:22 volumio-hr volumio[1216]: 2025-12-14 20:28:22 ERROR 403: Forbidden. Dec 14 20:28:22 volumio-hr volumio[1216]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Dec 14 20:28:22 volumio-hr volumio[1216]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 14 20:28:22 volumio-hr volumio[1216]: /bin/rm: cannot remove 'a': No such file or directory Dec 14 20:28:22 volumio-hr volumio[1216]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 14 20:28:22 volumio-hr volumio[1216]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 20:28:22 volumio-hr volumio[1216]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 14 20:28:22 volumio-hr volumio[1216]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 14 20:28:22 volumio-hr volumio[1216]: /bin/rm: cannot remove 'a': No such file or directory Dec 14 20:28:22 volumio-hr volumio[1216]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 14 20:28:22 volumio-hr volumio[1216]: at checkExecSyncError (node:child_process:887:11) Dec 14 20:28:22 volumio-hr volumio[1216]: at execSync (node:child_process:959:15) Dec 14 20:28:22 volumio-hr volumio[1216]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Dec 14 20:28:22 volumio-hr volumio[1216]: at new Promise () Dec 14 20:28:22 volumio-hr volumio[1216]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Dec 14 20:28:22 volumio-hr volumio[1216]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 14 20:28:22 volumio-hr volumio[1216]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 14 20:28:22 volumio-hr volumio[1216]: at Socket.emit (node:events:514:28) Dec 14 20:28:22 volumio-hr volumio[1216]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 14 20:28:22 volumio-hr volumio[1216]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 14 20:28:22 volumio-hr volumio[1216]: status: 1, Dec 14 20:28:22 volumio-hr volumio[1216]: signal: null, Dec 14 20:28:22 volumio-hr volumio[1216]: output: [ Dec 14 20:28:22 volumio-hr volumio[1216]: null, Dec 14 20:28:22 volumio-hr volumio[1216]: , Dec 14 20:28:22 volumio-hr volumio[1216]: Dec 14 20:28:22 volumio-hr volumio[1216]: ], Dec 14 20:28:22 volumio-hr volumio[1216]: pid: 3435, Dec 14 20:28:22 volumio-hr volumio[1216]: stdout: , Dec 14 20:28:22 volumio-hr volumio[1216]: stderr: Dec 14 20:28:22 volumio-hr volumio[1216]: } Dec 14 20:28:22 volumio-hr volumio[1216]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 20:28:23 volumio-hr sudo[3454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 20:27' Dec 14 20:28:23 volumio-hr sudo[3454]: 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"