Dec 14 18:02:03 volumio-hr volumio[1216]: info: Reading package lists... Dec 14 18:02:04 volumio-hr sudo[2448]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:04 volumio-hr sudo[2673]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chown -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Dec 14 18:02:04 volumio-hr sudo[2673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:02:04 volumio-hr sudo[2673]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:04 volumio-hr sudo[2675]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chgrp -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Dec 14 18:02:04 volumio-hr sudo[2675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:02:04 volumio-hr sudo[2675]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:04 volumio-hr volumio[1216]: info: installing apt packages Dec 14 18:02:04 volumio-hr sudo[2677]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install python3-pygame python3-pillow libfftw3-dev Dec 14 18:02:04 volumio-hr sudo[2677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:02:05 volumio-hr volumio[1216]: info: Reading package lists... Dec 14 18:02:08 volumio-hr volumio[1216]: info: Building dependency tree... Dec 14 18:02:08 volumio-hr volumio[1216]: info: Reading state information... Dec 14 18:02:15 volumio-hr volumio[1216]: info: python3-pygame is already the newest version (2.1.2+dfsg-5). Dec 14 18:02:15 volumio-hr volumio[1216]: info: libfftw3-dev is already the newest version (3.3.10-1). Dec 14 18:02:15 volumio-hr volumio[1216]: info: The following additional packages will be installed: Dec 14 18:02:15 volumio-hr volumio[1216]: info: bzip2 file libimagequant0 libraqm0 mailcap mime-support python3-olefile Dec 14 18:02:15 volumio-hr volumio[1216]: info: Suggested packages: Dec 14 18:02:15 volumio-hr volumio[1216]: info: bzip2-doc python-pil-doc Dec 14 18:02:15 volumio-hr volumio[1216]: info: The following NEW packages will be installed: Dec 14 18:02:15 volumio-hr volumio[1216]: info: bzip2 file libimagequant0 libraqm0 mailcap mime-support python3-olefile Dec 14 18:02:15 volumio-hr volumio[1216]: info: python3-pil Dec 14 18:02:16 volumio-hr volumio[1216]: info: 0 upgraded, 8 newly installed, 0 to remove and 2 not upgraded. Dec 14 18:02:16 volumio-hr volumio[1216]: info: Need to get 638 kB of archives. Dec 14 18:02:16 volumio-hr volumio[1216]: info: After this operation, 2248 kB of additional disk space will be used. Dec 14 18:02:16 volumio-hr volumio[1216]: info: Get:1 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf bzip2 armhf 1.0.8-5+b2 [47.4 kB] Dec 14 18:02:16 volumio-hr volumio[1216]: info: Get:2 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf file armhf 1:5.44-3 [41.5 kB] Dec 14 18:02:16 volumio-hr volumio[1216]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf mailcap all 3.70+nmu1 [32.0 kB] Dec 14 18:02:16 volumio-hr volumio[1216]: info: Get:4 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf mime-support all 3.66 [10.9 kB] Dec 14 18:02:16 volumio-hr volumio[1216]: info: Get:5 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libimagequant0 armhf 2.17.0-1 [28.0 kB] Dec 14 18:02:17 volumio-hr volumio[1216]: info: Get:6 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libraqm0 armhf 0.7.0-4.1 [9176 B] Dec 14 18:02:17 volumio-hr volumio[1216]: info: Get:7 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf python3-olefile all 0.46-3 [36.1 kB] Dec 14 18:02:17 volumio-hr volumio[1216]: info: Get:8 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pil armhf 9.4.0-1.1+deb12u1 [433 kB] Dec 14 18:02:23 volumio-hr volumio[1216]: info: Fetched 638 kB in 2s (316 kB/s) Dec 14 18:02:23 volumio-hr volumio[1216]: info: Selecting previously unselected package bzip2. Dec 14 18:02:24 volumio-hr volumio[1216]: [620B blob data] Dec 14 18:02:24 volumio-hr volumio[1216]: info: Preparing to unpack .../0-bzip2_1.0.8-5+b2_armhf.deb ... Dec 14 18:02:25 volumio-hr volumio[1216]: info: Unpacking bzip2 (1.0.8-5+b2) ... Dec 14 18:02:25 volumio-hr volumio[1216]: info: Selecting previously unselected package file. Dec 14 18:02:25 volumio-hr volumio[1216]: info: Preparing to unpack .../1-file_1%3a5.44-3_armhf.deb ... Dec 14 18:02:25 volumio-hr volumio[1216]: info: Unpacking file (1:5.44-3) ... Dec 14 18:02:25 volumio-hr volumio[1216]: info: Selecting previously unselected package mailcap. Dec 14 18:02:25 volumio-hr volumio[1216]: info: Preparing to unpack .../2-mailcap_3.70+nmu1_all.deb ... Dec 14 18:02:25 volumio-hr volumio[1216]: info: Unpacking mailcap (3.70+nmu1) ... Dec 14 18:02:25 volumio-hr volumio[1216]: info: Selecting previously unselected package mime-support. Dec 14 18:02:25 volumio-hr volumio[1216]: info: Preparing to unpack .../3-mime-support_3.66_all.deb ... Dec 14 18:02:25 volumio-hr volumio[1216]: info: Unpacking mime-support (3.66) ... Dec 14 18:02:26 volumio-hr volumio[1216]: info: Selecting previously unselected package libimagequant0:armhf. Dec 14 18:02:26 volumio-hr volumio[1216]: info: Preparing to unpack .../4-libimagequant0_2.17.0-1_armhf.deb ... Dec 14 18:02:26 volumio-hr volumio[1216]: info: Unpacking libimagequant0:armhf (2.17.0-1) ... Dec 14 18:02:26 volumio-hr volumio[1216]: info: Selecting previously unselected package libraqm0:armhf. Dec 14 18:02:26 volumio-hr volumio[1216]: info: Preparing to unpack .../5-libraqm0_0.7.0-4.1_armhf.deb ... Dec 14 18:02:26 volumio-hr volumio[1216]: info: Unpacking libraqm0:armhf (0.7.0-4.1) ... Dec 14 18:02:26 volumio-hr volumio[1216]: info: Selecting previously unselected package python3-olefile. Dec 14 18:02:26 volumio-hr volumio[1216]: info: Preparing to unpack .../6-python3-olefile_0.46-3_all.deb ... Dec 14 18:02:26 volumio-hr volumio[1216]: info: Unpacking python3-olefile (0.46-3) ... Dec 14 18:02:26 volumio-hr volumio[1216]: info: Selecting previously unselected package python3-pil:armhf. Dec 14 18:02:27 volumio-hr volumio[1216]: info: Preparing to unpack .../7-python3-pil_9.4.0-1.1+deb12u1_armhf.deb ... Dec 14 18:02:27 volumio-hr volumio[1216]: info: Unpacking python3-pil:armhf (9.4.0-1.1+deb12u1) ... Dec 14 18:02:27 volumio-hr volumio[1216]: info: Setting up python3-olefile (0.46-3) ... Dec 14 18:02:28 volumio-hr volumio[1216]: info: Setting up file (1:5.44-3) ... Dec 14 18:02:28 volumio-hr volumio[1216]: info: Setting up bzip2 (1.0.8-5+b2) ... Dec 14 18:02:28 volumio-hr volumio[1216]: info: Setting up libraqm0:armhf (0.7.0-4.1) ... Dec 14 18:02:28 volumio-hr volumio[1216]: info: Setting up libimagequant0:armhf (2.17.0-1) ... Dec 14 18:02:28 volumio-hr volumio[1216]: info: Setting up mailcap (3.70+nmu1) ... Dec 14 18:02:28 volumio-hr volumio[1216]: info: Setting up mime-support (3.66) ... Dec 14 18:02:28 volumio-hr volumio[1216]: info: Setting up python3-pil:armhf (9.4.0-1.1+deb12u1) ... Dec 14 18:02:29 volumio-hr volumio[1216]: info: Processing triggers for man-db (2.11.2-2) ... Dec 14 18:02:30 volumio-hr volumio[1216]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Dec 14 18:02:32 volumio-hr volumio[1216]: info: Tunnel connection is inactive, restarting it Dec 14 18:02:32 volumio-hr volumio[1216]: info: Starting Tunnel 1 Dec 14 18:02:32 volumio-hr volumio[1216]: info: Starting Tunnel Connection Checker Dec 14 18:02:34 volumio-hr sudo[2870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 14 18:02:34 volumio-hr sudo[2870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:34 volumio-hr autossh[2163]: received signal to exit (15) Dec 14 18:02:34 volumio-hr systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Dec 14 18:02:34 volumio-hr systemd[1]: sshtunnel.service: Deactivated successfully. Dec 14 18:02:34 volumio-hr systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 14 18:02:34 volumio-hr sudo[2677]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:34 volumio-hr systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 14 18:02:34 volumio-hr sudo[2870]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:34 volumio-hr volumio[1216]: info: Remote SSH Started Dec 14 18:02:34 volumio-hr autossh[2874]: port set to 0, monitoring disabled Dec 14 18:02:34 volumio-hr autossh[2874]: starting ssh (count 1) Dec 14 18:02:34 volumio-hr autossh[2874]: ssh child pid is 2877 Dec 14 18:02:34 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: 9 Dec 14 18:02:34 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 18:02:34 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 18:02:34 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:34 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 18:02:34 volumio-hr volumiossh-tunnel[2877]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 14 18:02:34 volumio-hr sudo[2881]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppyspectrum/startpeppyspectrum.sh Dec 14 18:02:34 volumio-hr sudo[2881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:02:34 volumio-hr sudo[2881]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:34 volumio-hr sudo[2883]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Dec 14 18:02:34 volumio-hr sudo[2883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:02:34 volumio-hr systemd[1]: Reloading. Dec 14 18:02:35 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 18:02:35 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 18:02:35 volumio-hr sudo[2883]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:35 volumio-hr sudo[2439]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:35 volumio-hr volumio[1216]: info: Install script completed Dec 14 18:02:35 volumio-hr volumio[1216]: info: Adding reference to registry Dec 14 18:02:36 volumio-hr volumio[1216]: info: Done installing plugin. Dec 14 18:02:36 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 18:02:36 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 18:02:36 volumio-hr volumio[1216]: Plugin install end detected on script Dec 14 18:02:36 volumio-hr volumio[1216]: info: Folder /tmp/plugins removed Dec 14 18:02:36 volumio-hr volumio[1216]: info: Folder /tmp/downloaded_plugin.zip removed Dec 14 18:02:36 volumio-hr volumio[1216]: info: Folder /data/temp removed Dec 14 18:02:38 volumio-hr volumio[1216]: info: Enabling plugin peppyspectrum Dec 14 18:02:38 volumio-hr volumio[1216]: info: Loading plugin "peppyspectrum"... Dec 14 18:02:39 volumio-hr volumio[1216]: info: Preparing to generate the ALSA configuration file Dec 14 18:02:39 volumio-hr volumio[1216]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 18:02:39 volumio-hr volumio[1216]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:02:39 volumio-hr volumio[1216]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:02:39 volumio-hr volumio[1216]: info: Reading ALSA contributions from plugins. Dec 14 18:02:39 volumio-hr volumio[1216]: info: Enabling plugin peppyspectrum Dec 14 18:02:39 volumio-hr volumio[1216]: info: Loading plugin "peppyspectrum"... Dec 14 18:02:39 volumio-hr volumio[1216]: info: Asound.conf file written Dec 14 18:02:40 volumio-hr sudo[2904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 14 18:02:40 volumio-hr sudo[2904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:40 volumio-hr sudo[2904]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:40 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 18:02:40 volumio-hr volumio[1216]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 14 18:02:40 volumio-hr volumio[1216]: No state is present for card Loopback Dec 14 18:02:40 volumio-hr volumio[1216]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 14 18:02:40 volumio-hr volumio[1216]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Dec 14 18:02:40 volumio-hr volumio[1216]: Hardware is initialized using a generic method Dec 14 18:02:40 volumio-hr volumio[1216]: No state is present for card Loopback Dec 14 18:02:40 volumio-hr volumio[1216]: info: Output device has changed, restarting MPD Dec 14 18:02:40 volumio-hr volumio[1216]: info: Output device has changed, restarting Shairport Sync Dec 14 18:02:40 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:40 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:02:40 volumio-hr sudo[2910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:02:40 volumio-hr sudo[2910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:40 volumio-hr sudo[2910]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:40 volumio-hr sudo[2914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:02:40 volumio-hr sudo[2914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:40 volumio-hr volumio[1216]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 18:02:40 volumio-hr volumio[1216]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 18:02:40 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:40 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:02:40 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:40 volumio-hr vtcs[2300]: [2025-12-14 18:02:40.470] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 18:02:40 volumio-hr vtcs[2300]: [2025-12-14 18:02:40.495] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 14 18:02:40 volumio-hr vtcs[2300]: [2025-12-14 18:02:40.495] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 18:02:40 volumio-hr systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 18:02:40 volumio-hr volumio[1216]: info: Preparing to generate the ALSA configuration file Dec 14 18:02:40 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 18:02:40 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 18:02:40 volumio-hr systemd[1]: mpd.service: Consumed 1.656s CPU time. Dec 14 18:02:40 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 18:02:40 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 18:02:40 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 18:02:40 volumio-hr volumio[1216]: info: PLUGIN START: peppyspectrum Dec 14 18:02:40 volumio-hr sudo[2922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:02:40 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 18:02:40 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 18:02:40 volumio-hr sudo[2922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:40 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 14 18:02:40 volumio-hr sudo[2924]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 18:02:40 volumio-hr sudo[2924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:02:40 volumio-hr sudo[2924]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:40 volumio-hr systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 18:02:40 volumio-hr systemd[1]: vtcs.service: Deactivated successfully. Dec 14 18:02:40 volumio-hr systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 18:02:40 volumio-hr sudo[2929]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 14 18:02:40 volumio-hr sudo[2929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:40 volumio-hr sudo[2929]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:40 volumio-hr sudo[2922]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:40 volumio-hr volumio[1216]: info: Done. Dec 14 18:02:41 volumio-hr sudo[2933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:02:41 volumio-hr sudo[2933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:41 volumio-hr volumio[1216]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 18:02:41 volumio-hr volumio[1216]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:02:41 volumio-hr volumio[1216]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:02:41 volumio-hr volumio[1216]: info: Reading ALSA contributions from plugins. Dec 14 18:02:41 volumio-hr sudo[2933]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:41 volumio-hr sudo[2940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 18:02:41 volumio-hr sudo[2940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:41 volumio-hr sudo[2940]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:41 volumio-hr volumio[1216]: info: snd_dummy loaded Dec 14 18:02:41 volumio-hr volumio[1216]: info: MPD Permissions set Dec 14 18:02:41 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: 9 Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:41 volumio-hr sudo[2943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:41 volumio-hr sudo[2943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:41 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:02:41 volumio-hr qobuz-connect[2230]: 20251214 18:02:41.727 [2230.2230] INFO SampleApp: Stopping Local configuration server Dec 14 18:02:41 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:42 volumio-hr volumio[1216]: info: Starting Shairport Sync Dec 14 18:02:42 volumio-hr sudo[2951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 18:02:42 volumio-hr volumio[1216]: info: Asound.conf file unchanged, so no further update is needed Dec 14 18:02:42 volumio-hr volumio[1216]: info: Output device has changed, restarting MPD Dec 14 18:02:42 volumio-hr sudo[2951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:42 volumio-hr mpd[2928]: 2025-12-14T18:02:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 18:02:42 volumio-hr systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 18:02:42 volumio-hr volumio[1216]: info: Output device has changed, restarting Shairport Sync Dec 14 18:02:42 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:42 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:02:42 volumio-hr sudo[2914]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:42 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 18:02:42 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 18:02:42 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:02:42 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.505s CPU time. Dec 14 18:02:42 volumio-hr sudo[2954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:02:42 volumio-hr sudo[2954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:42 volumio-hr sudo[2958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:02:42 volumio-hr sudo[2958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:42 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:02:42 volumio-hr sudo[2954]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:42 volumio-hr sudo[2951]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:42 volumio-hr volumio[1216]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 18:02:42 volumio-hr volumio[1216]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 18:02:42 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:42 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:02:42 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:42 volumio-hr volumio[1216]: info: Preparing to generate the ALSA configuration file Dec 14 18:02:42 volumio-hr systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 18:02:42 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 18:02:42 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 18:02:42 volumio-hr systemd[1]: mpd.service: Consumed 1.479s CPU time. Dec 14 18:02:42 volumio-hr volumio[1216]: info: PLUGIN START: peppyspectrum Dec 14 18:02:42 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 18:02:42 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 18:02:42 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 14 18:02:42 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 18:02:42 volumio-hr sudo[2983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:02:42 volumio-hr volumio[1216]: info: Done. Dec 14 18:02:42 volumio-hr sudo[2983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:42 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 18:02:42 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 18:02:42 volumio-hr sudo[2986]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 14 18:02:42 volumio-hr sudo[2986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:42 volumio-hr sudo[2988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:02:42 volumio-hr sudo[2988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:42 volumio-hr volumio[1216]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 18:02:42 volumio-hr volumio[1216]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:02:42 volumio-hr volumio[1216]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:02:42 volumio-hr volumio[1216]: info: Reading ALSA contributions from plugins. Dec 14 18:02:42 volumio-hr sudo[2994]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 18:02:42 volumio-hr sudo[2994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:02:42 volumio-hr volumio[1216]: info: MPD Permissions set Dec 14 18:02:42 volumio-hr volumio[1216]: info: Shairport-Sync Started Dec 14 18:02:42 volumio-hr sudo[2994]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:42 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: 10 Dec 14 18:02:42 volumio-hr sudo[2986]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:42 volumio-hr sudo[2983]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:42 volumio-hr sudo[2997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 18:02:42 volumio-hr sudo[2997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:43 volumio-hr sudo[2988]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr sudo[2997]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr sudo[3005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 18:02:43 volumio-hr volumio[1216]: info: snd_dummy loaded Dec 14 18:02:43 volumio-hr sudo[3005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:43 volumio-hr qobuz-connect[2230]: 20251214 18:02:43.581 [2230.2230] INFO SampleApp: shat down connection on UNIX socket Dec 14 18:02:43 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 18:02:43 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:02:43 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:02:43 volumio-hr sudo[3005]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:43 volumio-hr volumio[1216]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 18:02:43 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:43 volumio-hr volumio[1216]: info: Starting Shairport Sync Dec 14 18:02:43 volumio-hr sudo[2943]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:43 volumio-hr volumio[1216]: info: Asound.conf file unchanged, so no further update is needed Dec 14 18:02:43 volumio-hr volumio[1216]: info: Output device has changed, restarting MPD Dec 14 18:02:43 volumio-hr volumio[1216]: info: Output device has changed, restarting Shairport Sync Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:43 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:02:43 volumio-hr sudo[3015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:02:43 volumio-hr sudo[3015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:43 volumio-hr sudo[3013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 18:02:43 volumio-hr sudo[3013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:43 volumio-hr sudo[3015]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:44 volumio-hr volumio[1216]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 18:02:44 volumio-hr volumio[1216]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 18:02:44 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:44 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 18:02:44 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 18:02:44 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:02:44 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.205s CPU time. Dec 14 18:02:44 volumio-hr sudo[3017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:02:44 volumio-hr sudo[3017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:44 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:02:44 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:44 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:02:44 volumio-hr sudo[3013]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:44 volumio-hr volumio[1216]: info: Preparing to generate the ALSA configuration file Dec 14 18:02:44 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 18:02:44 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 18:02:44 volumio-hr systemd[1]: mpd.service: Consumed 1.196s CPU time. Dec 14 18:02:44 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 18:02:44 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 18:02:44 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 18:02:44 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 18:02:44 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 18:02:44 volumio-hr sudo[3029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:02:44 volumio-hr sudo[3029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:44 volumio-hr sudo[3044]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 18:02:44 volumio-hr sudo[3044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:02:44 volumio-hr sudo[3044]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:44 volumio-hr sudo[3029]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:44 volumio-hr sudo[3051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:02:44 volumio-hr sudo[3051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:44 volumio-hr sudo[3064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 18:02:44 volumio-hr volumio[1216]: info: Executing endpoint qc_getconfig Dec 14 18:02:44 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 18:02:44 volumio-hr sudo[3064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:44 volumio-hr sudo[3051]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:44 volumio-hr qobuz-connect[3011]: 20251214 18:02:44.741 [3011.3011] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 18:02:44 volumio-hr qobuz-connect[3011]: 20251214 18:02:44.745 [3011.3011] INFO VolumeManager: [0x1658348]: Setting new playback volume: 75 Dec 14 18:02:44 volumio-hr qobuz-connect[3011]: 20251214 18:02:44.746 [3011.3011] INFO VolumeManager: [0x1658348]: Setting new mute state: 0 Dec 14 18:02:44 volumio-hr qobuz-connect[3011]: 20251214 18:02:44.746 [3011.3011] INFO QobuzConnect: [0x1658d18]: Client initialized! Dec 14 18:02:44 volumio-hr qobuz-connect[3011]: 20251214 18:02:44.746 [3011.3011] INFO SampleApp: Starting Avahi advertising, name: Volumio HR, service name: _qobuz-connect._tcp Dec 14 18:02:44 volumio-hr qobuz-connect[3011]: 20251214 18:02:44.760 [3011.3011] INFO LocalConfigManager: [0x1657c28]: Starting Local Configuration server Dec 14 18:02:44 volumio-hr qobuz-connect[3011]: 20251214 18:02:44.760 [3011.3011] INFO SampleApp: Starting Local configuration server Dec 14 18:02:44 volumio-hr qobuz-connect[3011]: 20251214 18:02:44.761 [3011.3011] INFO SampleApp: Connected to UNIX socket client 0x1642818 Dec 14 18:02:44 volumio-hr sudo[3064]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:44 volumio-hr volumio[1216]: error: MPD error: Error: read ECONNRESET Dec 14 18:02:44 volumio-hr volumio[1216]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 18:02:44 volumio-hr volumio[1216]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:02:44 volumio-hr volumio[1216]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:02:44 volumio-hr volumio[1216]: info: Reading ALSA contributions from plugins. Dec 14 18:02:44 volumio-hr qobuz-connect[3011]: 20251214 18:02:44.891 [3011.3011] INFO SampleApp: Playback volume changed: 75 Dec 14 18:02:44 volumio-hr volumio[1216]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 18:02:44 volumio-hr volumio[1216]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 18:02:44 volumio-hr sudo[3083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 18:02:44 volumio-hr sudo[3083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:45 volumio-hr volumio[1216]: info: MPD Permissions set Dec 14 18:02:45 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 18:02:45 volumio-hr volumio[1216]: info: Shairport-Sync Started Dec 14 18:02:45 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 18:02:45 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 18:02:45 volumio-hr qobuz-connect[3011]: 20251214 18:02:45.212 [3011.3011] INFO SampleApp: Stopping Local configuration server Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:45 volumio-hr volumio[1216]: info: Starting Shairport Sync Dec 14 18:02:45 volumio-hr sudo[3099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 18:02:45 volumio-hr sudo[3099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:45 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 18:02:45 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 18:02:45 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:02:45 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.249s CPU time. Dec 14 18:02:45 volumio-hr volumio[1216]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:02:45 volumio-hr volumio[1216]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:02:45 volumio-hr volumio[1216]: info: Asound.conf file unchanged, so no further update is needed Dec 14 18:02:45 volumio-hr volumio[1216]: info: Output device has changed, restarting MPD Dec 14 18:02:45 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:02:45 volumio-hr sudo[3099]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:45 volumio-hr mpd[3057]: 2025-12-14T18:02:45 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 18:02:45 volumio-hr sudo[3103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:02:46 volumio-hr sudo[3103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:46 volumio-hr systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 18:02:46 volumio-hr sudo[3017]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:46 volumio-hr volumio[1216]: info: Output device has changed, restarting Shairport Sync Dec 14 18:02:46 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:46 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:02:46 volumio-hr sudo[3121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:02:46 volumio-hr sudo[3121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:46 volumio-hr sudo[2958]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:46 volumio-hr sudo[3103]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:46 volumio-hr systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 18:02:46 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 18:02:46 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 18:02:46 volumio-hr systemd[1]: mpd.service: Consumed 1.527s CPU time. Dec 14 18:02:46 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 18:02:46 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 18:02:46 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 18:02:46 volumio-hr volumio[1216]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 18:02:46 volumio-hr volumio[1216]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 18:02:46 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:46 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:02:46 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:46 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 18:02:46 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 18:02:46 volumio-hr sudo[3132]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 18:02:46 volumio-hr sudo[3132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:02:46 volumio-hr sudo[3132]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:46 volumio-hr sudo[3133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:02:46 volumio-hr sudo[3133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:46 volumio-hr sudo[3139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:02:46 volumio-hr sudo[3139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:46 volumio-hr volumio[1216]: info: MPD Permissions set Dec 14 18:02:46 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 18:02:46 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 18:02:46 volumio-hr sudo[3133]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:46 volumio-hr sudo[3148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 18:02:46 volumio-hr sudo[3148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:46 volumio-hr sudo[3150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:02:46 volumio-hr sudo[3150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:46 volumio-hr sudo[3139]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:46 volumio-hr qobuz-connect[3011]: 20251214 18:02:46.791 [3011.3011] INFO SampleApp: shat down connection on UNIX socket Dec 14 18:02:46 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 18:02:46 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:02:46 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:02:46 volumio-hr sudo[3083]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:46 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 18:02:46 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 18:02:46 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 18:02:47 volumio-hr volumio[1216]: info: Shairport-Sync Started Dec 14 18:02:47 volumio-hr sudo[3148]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:47 volumio-hr sudo[3161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 18:02:47 volumio-hr sudo[3161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:47 volumio-hr sudo[3162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 18:02:47 volumio-hr sudo[3162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:47 volumio-hr volumio[1216]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 18:02:47 volumio-hr volumio[1216]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:47 volumio-hr volumio[1216]: info: Executing endpoint qc_getconfig Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 18:02:47 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 18:02:47 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 18:02:47 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:02:47 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:02:47 volumio-hr systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:47 volumio-hr sudo[3161]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:47 volumio-hr sudo[3162]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:02:47 volumio-hr volumio[1216]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:02:47 volumio-hr volumio[1216]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:02:47 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 18:02:47 volumio-hr volumio[1216]: info: Executing endpoint qc_getconfig Dec 14 18:02:47 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 18:02:47 volumio-hr qobuz-connect[3172]: 20251214 18:02:47.875 [3172.3172] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 18:02:47 volumio-hr qobuz-connect[3172]: 20251214 18:02:47.879 [3172.3172] INFO VolumeManager: [0x4ce348]: Setting new playback volume: 75 Dec 14 18:02:47 volumio-hr qobuz-connect[3172]: 20251214 18:02:47.879 [3172.3172] INFO VolumeManager: [0x4ce348]: Setting new mute state: 0 Dec 14 18:02:47 volumio-hr qobuz-connect[3172]: 20251214 18:02:47.879 [3172.3172] INFO QobuzConnect: [0x4ced18]: Client initialized! Dec 14 18:02:47 volumio-hr qobuz-connect[3172]: 20251214 18:02:47.879 [3172.3172] INFO SampleApp: Starting Avahi advertising, name: Volumio HR, service name: _qobuz-connect._tcp Dec 14 18:02:47 volumio-hr qobuz-connect[3172]: 20251214 18:02:47.889 [3172.3172] INFO LocalConfigManager: [0x4cdc28]: Starting Local Configuration server Dec 14 18:02:47 volumio-hr qobuz-connect[3172]: 20251214 18:02:47.889 [3172.3172] INFO SampleApp: Starting Local configuration server Dec 14 18:02:47 volumio-hr qobuz-connect[3172]: 20251214 18:02:47.889 [3172.3172] INFO SampleApp: Connected to UNIX socket client 0x4b8818 Dec 14 18:02:47 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 18:02:47 volumio-hr volumio[1216]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 18:02:47 volumio-hr volumio[1216]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 18:02:48 volumio-hr mpd[3141]: 2025-12-14T18:02:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 18:02:48 volumio-hr volumio[1216]: info: Executing endpoint tc_getconfig Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 18:02:48 volumio-hr vtcs[3175]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 18:02:48 volumio-hr vtcs[3175]: STARTED TidalConnect services. Dec 14 18:02:48 volumio-hr qobuz-connect[3172]: 20251214 18:02:48.025 [3172.3172] INFO SampleApp: Playback volume changed: 75 Dec 14 18:02:48 volumio-hr systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 18:02:48 volumio-hr sudo[3121]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:48 volumio-hr volumio[1216]: info: Starting Shairport Sync Dec 14 18:02:48 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 18:02:48 volumio-hr mpd_monitor.sh[3201]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:02:48 volumio-hr sudo[3150]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:48 volumio-hr sudo[3205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 18:02:48 volumio-hr sudo[3205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:48 volumio-hr sudo[3205]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:48 volumio-hr volumio[1216]: error: MPD error: The expression evaluated to a falsy value: Dec 14 18:02:48 volumio-hr volumio[1216]: assert.ok(self.idling) Dec 14 18:02:48 volumio-hr volumio[1216]: error: The expression evaluated to a falsy value: Dec 14 18:02:48 volumio-hr volumio[1216]: assert.ok(self.idling) Dec 14 18:02:48 volumio-hr volumio[1216]: info: Successfully started MPD Monitor Dec 14 18:02:48 volumio-hr sudo[3203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 18:02:48 volumio-hr sudo[3203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:48 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 18:02:48 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 18:02:48 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:02:48 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.318s CPU time. Dec 14 18:02:48 volumio-hr volumio[1216]: error: updateQueue error: null Dec 14 18:02:48 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:02:48 volumio-hr sudo[3203]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:48 volumio-hr volumio[1216]: info: Executing endpoint tc_connect Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 18:02:48 volumio-hr volumio[1216]: info: Connecting to TidalConnect Dec 14 18:02:48 volumio-hr volumio[1216]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:02:48 volumio-hr volumio[1216]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:02:48 volumio-hr volumio[1216]: info: Shairport-Sync Started Dec 14 18:02:48 volumio-hr volumio[1216]: error: updateQueue error: null Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreCommandRouter::servicePushState Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreStateMachine::pushState Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioPushState Dec 14 18:02:48 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Dec 14 18:02:48 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:48 volumio-hr volumio[1216]: info: CorePlayQueue::getTrack 0 Dec 14 18:02:48 volumio-hr volumio[1216]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreCommandRouter::servicePushState Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreStateMachine::pushState Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioPushState Dec 14 18:02:48 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output update for this device Dec 14 18:02:48 volumio-hr volumio[1216]: info: MRS: Pushing multiroomSync output Dec 14 18:02:48 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:48 volumio-hr volumio[1216]: info: CorePlayQueue::getTrack 0 Dec 14 18:02:48 volumio-hr volumio[1216]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Dec 14 18:02:49 volumio-hr volumio[1216]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 14 18:02:49 volumio-hr volumio[1216]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 14 18:02:49 volumio-hr sudo[3240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 18:02:49 volumio-hr sudo[3240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:49 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 18:02:49 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 18:02:49 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 18:02:49 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 18:02:49 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 18:02:49 volumio-hr volumio[1216]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 18:02:49 volumio-hr sudo[3240]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:49 volumio-hr sudo[3246]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 18:02:49 volumio-hr sudo[3246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:49 volumio-hr sudo[3244]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 18:02:49 volumio-hr sudo[3244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:49 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 18:02:49 volumio-hr sudo[3246]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:49 volumio-hr sudo[3244]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:49 volumio-hr volumio[1216]: info: TidalConnect service stoped! Dec 14 18:02:49 volumio-hr volumio[1216]: verbose: New Socket.io Connection to 192.168.1.171 from 192.168.1.141 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 14 18:02:49 volumio-hr sudo[3250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:02:49 volumio-hr sudo[3250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:49 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 18:02:49 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 18:02:49 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 18:02:49 volumio-hr volumio[1216]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:02:49 volumio-hr volumio[1216]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:02:50 volumio-hr volumio[1216]: error: updateQueue error: null Dec 14 18:02:50 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 18:02:50 volumio-hr mpd_monitor.sh[3260]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:02:50 volumio-hr sudo[3250]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:50 volumio-hr volumio[1216]: info: Successfully started MPD Monitor Dec 14 18:02:50 volumio-hr sudo[3262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:02:50 volumio-hr sudo[3262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 18:02:50 volumio-hr sudo[3267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 18:02:50 volumio-hr sudo[3267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 18:02:50 volumio-hr volumio[1216]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 14 18:02:50 volumio-hr volumio[1216]: info: Received Get System Info Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:02:50 volumio-hr volumio[1216]: info: Discovery: Getting this device information Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:02:50 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 18:02:50 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 18:02:50 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:50 volumio-hr volumio[1216]: info: Listing playlists Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 18:02:50 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 18:02:50 volumio-hr sudo[3267]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:50 volumio-hr mpd_monitor.sh[3270]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:02:50 volumio-hr sudo[3262]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 18:02:50 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 14 18:02:50 volumio-hr volumio[1216]: info: Successfully started MPD Monitor Dec 14 18:02:50 volumio-hr volumio[1216]: info: TidalConnect service started! Dec 14 18:02:51 volumio-hr volumio[1216]: info: TidalConnect service started! Dec 14 18:02:51 volumio-hr sudo[3276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:02:51 volumio-hr sudo[3276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:02:51 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 18:02:51 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 18:02:51 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 18:02:51 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 18:02:51 volumio-hr mpd_monitor.sh[3278]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:02:51 volumio-hr sudo[3276]: pam_unix(sudo:session): session closed for user root Dec 14 18:02:51 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 18:02:51 volumio-hr volumio[1216]: info: Received Get System Info Dec 14 18:02:51 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:02:51 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:02:51 volumio-hr volumio[1216]: info: Discovery: Getting this device information Dec 14 18:02:51 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:51 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:02:51 volumio-hr volumio[1216]: info: Successfully started MPD Monitor Dec 14 18:02:52 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 14 18:02:52 volumio-hr volumio[1216]: info: TidalConnect service started! Dec 14 18:02:52 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 18:02:52 volumio-hr volumio[1216]: info: Received Get System Info Dec 14 18:02:52 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 18:02:52 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 18:02:52 volumio-hr volumio[1216]: info: Discovery: Getting this device information Dec 14 18:02:52 volumio-hr volumio[1216]: info: CoreCommandRouter::volumioGetState Dec 14 18:02:52 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 18:02:53 volumio-hr volumio[1216]: info: TidalConnect service started! Dec 14 18:02:54 volumio-hr volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 18:02:59 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 14 18:03:01 volumio-hr volumio[1216]: info: CALLMETHOD: user_interface peppyspectrum updatelist Dec 14 18:03:01 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Dec 14 18:03:01 volumio-hr volumio[1216]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Dec 14 18:03:02 volumio-hr volumio[1216]: --2025-12-14 18:03:01-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Dec 14 18:03:02 volumio-hr volumio[1216]: Resolving github.com (github.com)... 140.82.121.4 Dec 14 18:03:02 volumio-hr volumio[1216]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 14 18:03:02 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 302 Found Dec 14 18:03:02 volumio-hr volumio[1216]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Dec 14 18:03:02 volumio-hr volumio[1216]: --2025-12-14 18:03:02-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Dec 14 18:03:02 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 18:03:02 volumio-hr volumio[1216]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.109.133|:443... connected. Dec 14 18:03:02 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 200 OK Dec 14 18:03:02 volumio-hr volumio[1216]: Length: 471 [text/plain] Dec 14 18:03:02 volumio-hr volumio[1216]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Dec 14 18:03:02 volumio-hr volumio[1216]: 0K 100% 7.57M=0s Dec 14 18:03:02 volumio-hr volumio[1216]: 2025-12-14 18:03:02 (7.57 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Dec 14 18:03:02 volumio-hr volumio[1216]: info: CALLMETHOD: user_interface peppyspectrum updatelist Dec 14 18:03:02 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Dec 14 18:03:02 volumio-hr volumio[1216]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Dec 14 18:03:03 volumio-hr volumio[1216]: --2025-12-14 18:03:02-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Dec 14 18:03:03 volumio-hr volumio[1216]: Resolving github.com (github.com)... 140.82.121.4 Dec 14 18:03:03 volumio-hr volumio[1216]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 14 18:03:03 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 302 Found Dec 14 18:03:03 volumio-hr volumio[1216]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Dec 14 18:03:03 volumio-hr volumio[1216]: --2025-12-14 18:03:03-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Dec 14 18:03:03 volumio-hr volumio[1216]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.109.133, 185.199.110.133, 185.199.111.133, ... Dec 14 18:03:03 volumio-hr volumio[1216]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.109.133|:443... connected. Dec 14 18:03:03 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 200 OK Dec 14 18:03:03 volumio-hr volumio[1216]: Length: 471 [text/plain] Dec 14 18:03:03 volumio-hr volumio[1216]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Dec 14 18:03:03 volumio-hr volumio[1216]: 0K 100% 9.21M=0s Dec 14 18:03:03 volumio-hr volumio[1216]: 2025-12-14 18:03:03 (9.21 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Dec 14 18:03:03 volumio-hr volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 18:03:03 volumio-hr volumio[1216]: info: CoreCommandRouter::Close All Modals sent Dec 14 18:03:03 volumio-hr volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 18:03:03 volumio-hr volumio[1216]: info: CoreCommandRouter::Close All Modals sent Dec 14 18:03:04 volumio-hr volumio[1216]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Dec 14 18:03:04 volumio-hr volumio[1216]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Dec 14 18:03:06 volumio-hr volumio[1216]: --2025-12-14 18:03:04-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Dec 14 18:03:06 volumio-hr volumio[1216]: Resolving github.com (github.com)... 140.82.121.4 Dec 14 18:03:06 volumio-hr volumio[1216]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 14 18:03:06 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 404 Not Found Dec 14 18:03:06 volumio-hr volumio[1216]: 2025-12-14 18:03:05 ERROR 404: Not Found. Dec 14 18:03:06 volumio-hr volumio[1216]: --2025-12-14 18:03:05-- http://a/ Dec 14 18:03:06 volumio-hr volumio[1216]: Resolving a (a)... failed: Device or resource busy. Dec 14 18:03:06 volumio-hr volumio[1216]: wget: unable to resolve host address ‘a’ Dec 14 18:03:06 volumio-hr volumio[1216]: --2025-12-14 18:03:05-- http://packet.zip/ Dec 14 18:03:06 volumio-hr volumio[1216]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 14 18:03:06 volumio-hr volumio[1216]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 14 18:03:06 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 302 Found Dec 14 18:03:06 volumio-hr volumio[1216]: Location: http://www.packet.zip/ [following] Dec 14 18:03:06 volumio-hr volumio[1216]: --2025-12-14 18:03:05-- http://www.packet.zip/ Dec 14 18:03:06 volumio-hr volumio[1216]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 14 18:03:06 volumio-hr volumio[1216]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 14 18:03:06 volumio-hr volumio[1216]: HTTP request sent, awaiting response... 403 Forbidden Dec 14 18:03:06 volumio-hr volumio[1216]: 2025-12-14 18:03:06 ERROR 403: Forbidden. Dec 14 18:03:06 volumio-hr volumio[1216]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Dec 14 18:03:06 volumio-hr volumio[1216]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 14 18:03:06 volumio-hr volumio[1216]: /bin/rm: cannot remove 'a': No such file or directory Dec 14 18:03:06 volumio-hr volumio[1216]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 14 18:03:06 volumio-hr volumio[1216]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 18:03:06 volumio-hr volumio[1216]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 14 18:03:06 volumio-hr volumio[1216]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 14 18:03:06 volumio-hr volumio[1216]: /bin/rm: cannot remove 'a': No such file or directory Dec 14 18:03:06 volumio-hr volumio[1216]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 14 18:03:06 volumio-hr volumio[1216]: at checkExecSyncError (node:child_process:887:11) Dec 14 18:03:06 volumio-hr volumio[1216]: at execSync (node:child_process:959:15) Dec 14 18:03:06 volumio-hr volumio[1216]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Dec 14 18:03:06 volumio-hr volumio[1216]: at new Promise () Dec 14 18:03:06 volumio-hr volumio[1216]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Dec 14 18:03:06 volumio-hr volumio[1216]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 14 18:03:06 volumio-hr volumio[1216]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 14 18:03:06 volumio-hr volumio[1216]: at Socket.emit (node:events:514:28) Dec 14 18:03:06 volumio-hr volumio[1216]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 14 18:03:06 volumio-hr volumio[1216]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 14 18:03:06 volumio-hr volumio[1216]: status: 1, Dec 14 18:03:06 volumio-hr volumio[1216]: signal: null, Dec 14 18:03:06 volumio-hr volumio[1216]: output: [ Dec 14 18:03:06 volumio-hr volumio[1216]: null, Dec 14 18:03:06 volumio-hr volumio[1216]: , Dec 14 18:03:06 volumio-hr volumio[1216]: Dec 14 18:03:06 volumio-hr volumio[1216]: ], Dec 14 18:03:06 volumio-hr volumio[1216]: pid: 3322, Dec 14 18:03:06 volumio-hr volumio[1216]: stdout: , Dec 14 18:03:06 volumio-hr volumio[1216]: stderr: Dec 14 18:03:06 volumio-hr volumio[1216]: } Dec 14 18:03:06 volumio-hr volumio[1216]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 18:03:09 volumio-hr sudo[3338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 18:02' Dec 14 18:03:09 volumio-hr sudo[3338]: 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"