Dec 14 18:11:03 volumio-hr volumio[1215]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/bookworm/armhf Dec 14 18:11:05 volumio-hr volumio[1215]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/bookworm/armhf Dec 14 18:11:05 volumio-hr volumio[1215]: info: Folder /tmp/plugins removed Dec 14 18:11:06 volumio-hr volumio[1215]: info: Check plugin dependencies Dec 14 18:11:07 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 18:11:07 volumio-hr volumio[1215]: info: Checking if plugin already exists Dec 14 18:11:07 volumio-hr volumio[1215]: info: Rename folder Dec 14 18:11:07 volumio-hr volumio[1215]: info: Folder /tmp/downloaded_plugin.zip removed Dec 14 18:11:07 volumio-hr volumio[1215]: info: Move to category Dec 14 18:11:08 volumio-hr volumio[1215]: info: Checking if install.sh is present Dec 14 18:11:08 volumio-hr volumio[1215]: info: Executing install.sh Dec 14 18:11:08 volumio-hr sudo[2841]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppyspectrum/install.sh Dec 14 18:11:08 volumio-hr sudo[2841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:08 volumio-hr volumio[1215]: info: Installing peppyalsa plugin dependencies Dec 14 18:11:08 volumio-hr sudo[2845]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/usermod -aG render volumio Dec 14 18:11:08 volumio-hr sudo[2845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:11:08 volumio-hr sudo[2845]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:08 volumio-hr sudo[2850]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Dec 14 18:11:08 volumio-hr sudo[2850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:11:09 volumio-hr volumio[1215]: info: Get:1 http://raspbian.raspberrypi.com/raspbian bookworm InRelease [15.0 kB] Dec 14 18:11:09 volumio-hr volumio[1215]: info: Hit:2 http://archive.raspberrypi.com/debian bookworm InRelease Dec 14 18:11:10 volumio-hr volumio[1215]: info: Hit:3 https://deb.nodesource.com/node_20.x nodistro InRelease Dec 14 18:11:13 volumio-hr volumio[1215]: info: Fetched 15.0 kB in 4s (3828 B/s) Dec 14 18:11:35 volumio-hr volumio[1215]: info: Reading package lists... Dec 14 18:11:35 volumio-hr sudo[2850]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:35 volumio-hr sudo[3059]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chown -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Dec 14 18:11:35 volumio-hr sudo[3059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:11:35 volumio-hr sudo[3059]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:35 volumio-hr sudo[3061]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chgrp -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Dec 14 18:11:35 volumio-hr sudo[3061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:11:35 volumio-hr sudo[3061]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:35 volumio-hr volumio[1215]: info: installing apt packages Dec 14 18:11:35 volumio-hr sudo[3063]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install python3-pygame python3-pillow libfftw3-dev Dec 14 18:11:35 volumio-hr sudo[3063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:11:36 volumio-hr volumio[1215]: info: Reading package lists... Dec 14 18:11:40 volumio-hr volumio[1215]: info: Building dependency tree... Dec 14 18:11:40 volumio-hr volumio[1215]: info: Reading state information... Dec 14 18:11:48 volumio-hr volumio[1215]: info: python3-pygame is already the newest version (2.1.2+dfsg-5). Dec 14 18:11:48 volumio-hr sudo[3063]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:48 volumio-hr volumio[1215]: info: python3-pil is already the newest version (9.4.0-1.1+deb12u1). Dec 14 18:11:48 volumio-hr volumio[1215]: info: libfftw3-dev is already the newest version (3.3.10-1). Dec 14 18:11:48 volumio-hr volumio[1215]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Dec 14 18:11:48 volumio-hr sudo[3103]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppyspectrum/startpeppyspectrum.sh Dec 14 18:11:48 volumio-hr sudo[3103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:11:48 volumio-hr sudo[3103]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:48 volumio-hr volumio[1215]: 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:11:48 volumio-hr sudo[3105]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Dec 14 18:11:48 volumio-hr sudo[3105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:11:48 volumio-hr volumio[1215]: 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:11:48 volumio-hr systemd[1]: Reloading. Dec 14 18:11:49 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:11:49 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:11:49 volumio-hr sudo[3105]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:49 volumio-hr sudo[2841]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:49 volumio-hr volumio[1215]: info: Install script completed Dec 14 18:11:49 volumio-hr volumio[1215]: info: Adding reference to registry Dec 14 18:11:49 volumio-hr volumio[1215]: info: Done installing plugin. Dec 14 18:11:49 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 18:11:49 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 18:11:49 volumio-hr volumio[1215]: info: Folder /tmp/plugins removed Dec 14 18:11:49 volumio-hr volumio[1215]: info: Folder /tmp/downloaded_plugin.zip removed Dec 14 18:11:50 volumio-hr volumio[1215]: Plugin install end detected on script Dec 14 18:11:50 volumio-hr volumio[1215]: info: Folder /data/temp removed Dec 14 18:11:52 volumio-hr volumio[1215]: info: Enabling plugin peppyspectrum Dec 14 18:11:52 volumio-hr volumio[1215]: info: Loading plugin "peppyspectrum"... Dec 14 18:11:52 volumio-hr volumio[1215]: info: Preparing to generate the ALSA configuration file Dec 14 18:11:52 volumio-hr volumio[1215]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 18:11:52 volumio-hr volumio[1215]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:11:52 volumio-hr volumio[1215]: info: Reading ALSA contributions from plugins. Dec 14 18:11:52 volumio-hr volumio[1215]: info: Asound.conf file written Dec 14 18:11:52 volumio-hr sudo[3126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 14 18:11:52 volumio-hr sudo[3126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:52 volumio-hr sudo[3126]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:52 volumio-hr volumio[1215]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 14 18:11:52 volumio-hr volumio[1215]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 14 18:11:52 volumio-hr volumio[1215]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 14 18:11:52 volumio-hr volumio[1215]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Dec 14 18:11:52 volumio-hr volumio[1215]: Hardware is initialized using a generic method Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #8 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #9 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #10 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #11 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #12 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #13 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #14 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #22 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #23 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #24 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #25 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #26 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #27 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: /usr/sbin/alsactl: set_control:1339: failed to obtain info for control #28 (No such file or directory) Dec 14 18:11:52 volumio-hr volumio[1215]: info: Output device has changed, restarting MPD Dec 14 18:11:52 volumio-hr volumio[1215]: info: Output device has changed, restarting Shairport Sync Dec 14 18:11:52 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:52 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:11:53 volumio-hr sudo[3132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:11:53 volumio-hr sudo[3132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:53 volumio-hr sudo[3132]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:53 volumio-hr volumio[1215]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 18:11:53 volumio-hr volumio[1215]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 18:11:53 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:53 volumio-hr sudo[3134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:11:53 volumio-hr sudo[3134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:53 volumio-hr volumio[1215]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:11:53 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:11:53 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:53 volumio-hr vtcs[2686]: [2025-12-14 18:11:53.122] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 18:11:53 volumio-hr vtcs[2686]: [2025-12-14 18:11:53.122] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 18:11:53 volumio-hr volumio[1215]: info: PLUGIN START: peppyspectrum Dec 14 18:11:53 volumio-hr systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 18:11:53 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 18:11:53 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 18:11:53 volumio-hr systemd[1]: mpd.service: Consumed 2.114s CPU time. Dec 14 18:11:53 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 14 18:11:53 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 18:11:53 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 18:11:53 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 18:11:53 volumio-hr sudo[3144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:11:53 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 18:11:53 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 18:11:53 volumio-hr sudo[3144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:53 volumio-hr sudo[3147]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 14 18:11:53 volumio-hr sudo[3147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:53 volumio-hr volumio[1215]: info: Preparing to generate the ALSA configuration file Dec 14 18:11:53 volumio-hr systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 18:11:53 volumio-hr systemd[1]: vtcs.service: Deactivated successfully. Dec 14 18:11:53 volumio-hr sudo[3148]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 18:11:53 volumio-hr sudo[3148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:11:53 volumio-hr systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 18:11:53 volumio-hr sudo[3148]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:53 volumio-hr sudo[3147]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:53 volumio-hr sudo[3144]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:53 volumio-hr volumio[1215]: info: Done. Dec 14 18:11:53 volumio-hr volumio[1215]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 18:11:53 volumio-hr volumio[1215]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:11:53 volumio-hr sudo[3155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:11:53 volumio-hr sudo[3155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:53 volumio-hr volumio[1215]: info: Reading ALSA contributions from plugins. Dec 14 18:11:53 volumio-hr volumio[1215]: info: snd_dummy loaded Dec 14 18:11:53 volumio-hr sudo[3161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 18:11:53 volumio-hr sudo[3161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:54 volumio-hr volumio[1215]: info: MPD Permissions set Dec 14 18:11:54 volumio-hr sudo[3155]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:54 volumio-hr volumio[1215]: 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:11:54 volumio-hr sudo[3161]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr sudo[3165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 18:11:54 volumio-hr sudo[3165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:54 volumio-hr volumio[1215]: info: Starting Shairport Sync Dec 14 18:11:54 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 18:11:54 volumio-hr qobuz-connect[2668]: 20251214 18:11:54.502 [2668.2668] INFO SampleApp: Stopping Local configuration server Dec 14 18:11:54 volumio-hr volumio[1215]: info: Asound.conf file unchanged, so no further update is needed Dec 14 18:11:54 volumio-hr volumio[1215]: info: Output device has changed, restarting MPD Dec 14 18:11:54 volumio-hr sudo[3173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 18:11:54 volumio-hr sudo[3173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:54 volumio-hr volumio[1215]: info: Output device has changed, restarting Shairport Sync Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:11:54 volumio-hr sudo[3175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:11:54 volumio-hr sudo[3175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:54 volumio-hr volumio[1215]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 18:11:54 volumio-hr volumio[1215]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:54 volumio-hr sudo[3175]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:54 volumio-hr volumio[1215]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:11:54 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:11:54 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:54 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 18:11:54 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 18:11:54 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:11:54 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.820s CPU time. Dec 14 18:11:54 volumio-hr sudo[3178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:11:54 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:11:54 volumio-hr sudo[3178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:54 volumio-hr sudo[3173]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:54 volumio-hr mpd[3152]: 2025-12-14T18:11:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 18:11:55 volumio-hr systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 18:11:55 volumio-hr sudo[3134]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:55 volumio-hr sudo[3209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:11:55 volumio-hr sudo[3209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:55 volumio-hr sudo[3188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:11:55 volumio-hr systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 18:11:55 volumio-hr sudo[3188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:55 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 18:11:55 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 18:11:55 volumio-hr systemd[1]: mpd.service: Consumed 1.528s CPU time. Dec 14 18:11:55 volumio-hr sudo[3209]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:55 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 18:11:55 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 18:11:55 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 18:11:55 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 18:11:55 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 18:11:55 volumio-hr sudo[3188]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:55 volumio-hr sudo[3219]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 18:11:55 volumio-hr sudo[3219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:11:55 volumio-hr sudo[3219]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:55 volumio-hr sudo[3221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 18:11:55 volumio-hr sudo[3221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:55 volumio-hr volumio[1215]: info: MPD Permissions set Dec 14 18:11:55 volumio-hr sudo[3221]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:55 volumio-hr volumio[1215]: info: Shairport-Sync Started Dec 14 18:11:55 volumio-hr qobuz-connect[2668]: 20251214 18:11:55.689 [2668.2668] INFO SampleApp: shat down connection on UNIX socket Dec 14 18:11:55 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 18:11:55 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:11:55 volumio-hr sudo[3226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 18:11:55 volumio-hr sudo[3226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:55 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:11:55 volumio-hr sudo[3165]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:55 volumio-hr volumio[1215]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 18:11:55 volumio-hr volumio[1215]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:11:55 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:11:55 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:55 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:55 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:55 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:55 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:55 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:55 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:55 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:55 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:55 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 18:11:55 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 18:11:55 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:11:55 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:11:56 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:11:56 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:11:56 volumio-hr sudo[3226]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:56 volumio-hr volumio[1215]: info: Executing endpoint qc_getconfig Dec 14 18:11:56 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 18:11:56 volumio-hr volumio[1215]: info: Executing endpoint qc_getconfig Dec 14 18:11:56 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 18:11:56 volumio-hr qobuz-connect[3234]: 20251214 18:11:56.500 [3234.3234] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 18:11:56 volumio-hr volumio[1215]: info: Starting Shairport Sync Dec 14 18:11:56 volumio-hr qobuz-connect[3234]: 20251214 18:11:56.504 [3234.3234] INFO VolumeManager: [0x159d348]: Setting new playback volume: 75 Dec 14 18:11:56 volumio-hr qobuz-connect[3234]: 20251214 18:11:56.504 [3234.3234] INFO VolumeManager: [0x159d348]: Setting new mute state: 0 Dec 14 18:11:56 volumio-hr qobuz-connect[3234]: 20251214 18:11:56.504 [3234.3234] INFO QobuzConnect: [0x159dd18]: Client initialized! Dec 14 18:11:56 volumio-hr qobuz-connect[3234]: 20251214 18:11:56.504 [3234.3234] INFO SampleApp: Starting Avahi advertising, name: Volumio HR, service name: _qobuz-connect._tcp Dec 14 18:11:56 volumio-hr qobuz-connect[3234]: 20251214 18:11:56.524 [3234.3234] INFO LocalConfigManager: [0x159cc28]: Starting Local Configuration server Dec 14 18:11:56 volumio-hr qobuz-connect[3234]: 20251214 18:11:56.524 [3234.3234] INFO SampleApp: Starting Local configuration server Dec 14 18:11:56 volumio-hr qobuz-connect[3234]: 20251214 18:11:56.525 [3234.3234] INFO SampleApp: Connected to UNIX socket client 0x1587818 Dec 14 18:11:56 volumio-hr sudo[3242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 18:11:56 volumio-hr sudo[3242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:56 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 18:11:56 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 18:11:56 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:11:56 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.269s CPU time. Dec 14 18:11:56 volumio-hr volumio[1215]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 18:11:56 volumio-hr volumio[1215]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 18:11:56 volumio-hr qobuz-connect[3234]: 20251214 18:11:56.649 [3234.3234] INFO SampleApp: Playback volume changed: 75 Dec 14 18:11:56 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:11:56 volumio-hr volumio[1215]: info: TidalConnect service stoped! Dec 14 18:11:56 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:11:56 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:56 volumio-hr sudo[3242]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:56 volumio-hr volumio[1215]: info: Shairport-Sync Started Dec 14 18:11:57 volumio-hr volumio[1215]: info: TidalConnect service stoped! Dec 14 18:11:57 volumio-hr mpd[3225]: 2025-12-14T18:11:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 18:11:57 volumio-hr systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 18:11:57 volumio-hr sudo[3178]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:57 volumio-hr volumio[1215]: error: updateQueue error: null Dec 14 18:11:57 volumio-hr volumio[1215]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:11:57 volumio-hr volumio[1215]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:11:57 volumio-hr volumio[1215]: error: updateQueue error: null Dec 14 18:11:57 volumio-hr sudo[3290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 18:11:57 volumio-hr sudo[3290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:57 volumio-hr systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 18:11:57 volumio-hr sudo[3290]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:57 volumio-hr volumio[1215]: info: Executing endpoint tc_getconfig Dec 14 18:11:57 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 18:11:57 volumio-hr vtcs[3293]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 18:11:58 volumio-hr vtcs[3293]: STARTED TidalConnect services. Dec 14 18:11:58 volumio-hr volumio[1215]: info: Executing endpoint tc_connect Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 18:11:58 volumio-hr volumio[1215]: info: Connecting to TidalConnect Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreCommandRouter::servicePushState Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreStateMachine::pushState Dec 14 18:11:58 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioPushState Dec 14 18:11:58 volumio-hr volumio[1215]: info: MRS: Pushing multiroomSync output update for this device Dec 14 18:11:58 volumio-hr volumio[1215]: info: MRS: Pushing multiroomSync output Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:11:58 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:58 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:58 volumio-hr volumio[1215]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreCommandRouter::servicePushState Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreStateMachine::pushState Dec 14 18:11:58 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioPushState Dec 14 18:11:58 volumio-hr volumio[1215]: info: MRS: Pushing multiroomSync output update for this device Dec 14 18:11:58 volumio-hr volumio[1215]: info: MRS: Pushing multiroomSync output Dec 14 18:11:58 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:11:58 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:58 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:11:58 volumio-hr volumio[1215]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 18:11:58 volumio-hr volumio[1215]: info: PeppySpectrum ---peppyspectrum status play Dec 14 18:11:58 volumio-hr volumio[1215]: info: PeppySpectrum ---peppyspectrum status play Dec 14 18:11:58 volumio-hr sudo[3310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 14 18:11:58 volumio-hr sudo[3310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:58 volumio-hr volumio[1215]: info: TidalConnect service stoped! Dec 14 18:11:58 volumio-hr volumio[1215]: info: TidalConnect service stoped! Dec 14 18:11:58 volumio-hr sudo[3313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 14 18:11:58 volumio-hr sudo[3313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:58 volumio-hr systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 14 18:11:58 volumio-hr sudo[3310]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:58 volumio-hr sudo[3320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:11:58 volumio-hr sudo[3313]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:59 volumio-hr volumio[1215]: info: peppyspectrum Daemon Started Dec 14 18:11:59 volumio-hr sudo[3320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:59 volumio-hr volumio[1215]: info: peppyspectrum Daemon Started Dec 14 18:11:59 volumio-hr volumio[1215]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:11:59 volumio-hr volumio[1215]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:11:59 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 18:11:59 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 18:11:59 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 18:11:59 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 18:11:59 volumio-hr mpd_monitor.sh[3328]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:11:59 volumio-hr sudo[3320]: pam_unix(sudo:session): session closed for user root Dec 14 18:11:59 volumio-hr volumio[1215]: info: Successfully started MPD Monitor Dec 14 18:11:59 volumio-hr sudo[3333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 18:11:59 volumio-hr sudo[3333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:11:59 volumio-hr sudo[3333]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:00 volumio-hr systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 14 18:12:00 volumio-hr sudo[3337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:12:00 volumio-hr sudo[3337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:00 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 18:12:00 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 18:12:00 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 18:12:00 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 18:12:00 volumio-hr mpd_monitor.sh[3339]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:12:00 volumio-hr sudo[3337]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:00 volumio-hr volumio[1215]: info: Successfully started MPD Monitor Dec 14 18:12:00 volumio-hr volumio[1215]: info: TidalConnect service started! Dec 14 18:12:02 volumio-hr volumio[1215]: info: TidalConnect service started! Dec 14 18:12:10 volumio-hr volumio[1215]: info: Enabling plugin peppyspectrum Dec 14 18:12:10 volumio-hr volumio[1215]: info: Loading plugin "peppyspectrum"... Dec 14 18:12:10 volumio-hr volumio[1215]: info: Preparing to generate the ALSA configuration file Dec 14 18:12:10 volumio-hr volumio[1215]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 18:12:10 volumio-hr volumio[1215]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:12:10 volumio-hr volumio[1215]: info: Reading ALSA contributions from plugins. Dec 14 18:12:10 volumio-hr volumio[1215]: info: Asound.conf file unchanged, so no further update is needed Dec 14 18:12:10 volumio-hr volumio[1215]: info: Output device has changed, restarting MPD Dec 14 18:12:10 volumio-hr volumio[1215]: info: Output device has changed, restarting Shairport Sync Dec 14 18:12:10 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:10 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:12:10 volumio-hr sudo[3358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:12:10 volumio-hr sudo[3358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:10 volumio-hr volumio[1215]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 18:12:10 volumio-hr volumio[1215]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 18:12:10 volumio-hr sudo[3358]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:10 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:10 volumio-hr sudo[3362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:12:10 volumio-hr sudo[3362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:10 volumio-hr volumio[1215]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:12:10 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:12:10 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:10 volumio-hr vtcs[3293]: [2025-12-14 18:12:10.779] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 18:12:10 volumio-hr vtcs[3293]: [2025-12-14 18:12:10.779] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 18:12:10 volumio-hr volumio[1215]: info: PLUGIN START: peppyspectrum Dec 14 18:12:10 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 14 18:12:10 volumio-hr systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 18:12:10 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 18:12:10 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 18:12:10 volumio-hr systemd[1]: mpd.service: Consumed 1.573s CPU time. Dec 14 18:12:10 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 18:12:10 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 18:12:10 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 18:12:10 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 18:12:10 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 18:12:10 volumio-hr volumio[1215]: info: Preparing to generate the ALSA configuration file Dec 14 18:12:10 volumio-hr sudo[3370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:12:10 volumio-hr sudo[3370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:11 volumio-hr sudo[3372]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 14 18:12:11 volumio-hr sudo[3372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:11 volumio-hr volumio[1215]: info: Done. Dec 14 18:12:11 volumio-hr sudo[3374]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 18:12:11 volumio-hr sudo[3374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:12:11 volumio-hr sudo[3374]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:11 volumio-hr sudo[3372]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:11 volumio-hr sudo[3378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:12:11 volumio-hr sudo[3378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:11 volumio-hr systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 18:12:11 volumio-hr systemd[1]: vtcs.service: Killing process 3297 (vtcs) with signal SIGKILL. Dec 14 18:12:11 volumio-hr systemd[1]: vtcs.service: Deactivated successfully. Dec 14 18:12:11 volumio-hr systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 18:12:11 volumio-hr sudo[3370]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:11 volumio-hr volumio[1215]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 18:12:11 volumio-hr volumio[1215]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 18:12:11 volumio-hr volumio[1215]: info: Reading ALSA contributions from plugins. Dec 14 18:12:11 volumio-hr sudo[3378]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:11 volumio-hr volumio[1215]: info: snd_dummy loaded Dec 14 18:12:11 volumio-hr volumio[1215]: info: MPD Permissions set Dec 14 18:12:11 volumio-hr sudo[3388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 18:12:11 volumio-hr sudo[3388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:11 volumio-hr volumio[1215]: 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:12:11 volumio-hr sudo[3388]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:11 volumio-hr sudo[3391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 18:12:11 volumio-hr sudo[3391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:12:11 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:11 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 18:12:11 volumio-hr qobuz-connect[3234]: 20251214 18:12:11.804 [3234.3234] INFO SampleApp: Stopping Local configuration server Dec 14 18:12:11 volumio-hr volumio[1215]: info: Starting Shairport Sync Dec 14 18:12:12 volumio-hr volumio[1215]: info: Asound.conf file unchanged, so no further update is needed Dec 14 18:12:12 volumio-hr volumio[1215]: info: Output device has changed, restarting MPD Dec 14 18:12:12 volumio-hr volumio[1215]: info: Output device has changed, restarting Shairport Sync Dec 14 18:12:12 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:12 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:12:12 volumio-hr sudo[3399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 18:12:12 volumio-hr sudo[3399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:12 volumio-hr sudo[3401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 18:12:12 volumio-hr sudo[3403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 18:12:12 volumio-hr sudo[3403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:12 volumio-hr sudo[3401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:12 volumio-hr volumio[1215]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 18:12:12 volumio-hr volumio[1215]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 18:12:12 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:12 volumio-hr sudo[3401]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:12 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 18:12:12 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 18:12:12 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:12:12 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.404s CPU time. Dec 14 18:12:12 volumio-hr volumio[1215]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:12:12 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:12:12 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:12 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:12:12 volumio-hr sudo[3399]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:12 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Dec 14 18:12:12 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 18:12:12 volumio-hr systemd[1]: mpd.service: Consumed 1.318s CPU time. Dec 14 18:12:12 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Dec 14 18:12:12 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 18:12:12 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 18:12:12 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 18:12:12 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 18:12:12 volumio-hr qobuz-connect[3234]: 20251214 18:12:12.548 [3234.3234] INFO SampleApp: shat down connection on UNIX socket Dec 14 18:12:12 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 18:12:12 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:12:12 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:12:12 volumio-hr sudo[3391]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:12 volumio-hr sudo[3416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:12:12 volumio-hr sudo[3416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:12 volumio-hr sudo[3437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 18:12:12 volumio-hr sudo[3437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:12 volumio-hr sudo[3434]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 18:12:12 volumio-hr sudo[3434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 18:12:12 volumio-hr sudo[3434]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:12 volumio-hr sudo[3437]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:12 volumio-hr volumio[1215]: info: MPD Permissions set Dec 14 18:12:12 volumio-hr volumio[1215]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 18:12:12 volumio-hr volumio[1215]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:12:12 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:12:12 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:12 volumio-hr volumio[1215]: info: Shairport-Sync Started Dec 14 18:12:12 volumio-hr sudo[3416]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:12 volumio-hr volumio[1215]: info: Executing endpoint qc_getconfig Dec 14 18:12:12 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 18:12:12 volumio-hr sudo[3447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 18:12:12 volumio-hr sudo[3447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.061 [3439.3439] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.065 [3439.3439] INFO VolumeManager: [0xfa2348]: Setting new playback volume: 75 Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.066 [3439.3439] INFO VolumeManager: [0xfa2348]: Setting new mute state: 0 Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.066 [3439.3439] INFO QobuzConnect: [0xfa2d18]: Client initialized! Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.066 [3439.3439] INFO SampleApp: Starting Avahi advertising, name: Volumio HR, service name: _qobuz-connect._tcp Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.092 [3439.3439] INFO LocalConfigManager: [0xfa1c28]: Starting Local Configuration server Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.092 [3439.3439] INFO SampleApp: Starting Local configuration server Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.093 [3439.3439] INFO SampleApp: Connected to UNIX socket client 0xf8c818 Dec 14 18:12:13 volumio-hr volumio[1215]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 18:12:13 volumio-hr volumio[1215]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.211 [3439.3439] INFO SampleApp: Playback volume changed: 75 Dec 14 18:12:13 volumio-hr sudo[3447]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:13 volumio-hr sudo[3456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 18:12:13 volumio-hr sudo[3456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:12:13 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:13 volumio-hr volumio[1215]: info: Starting Shairport Sync Dec 14 18:12:13 volumio-hr qobuz-connect[3439]: 20251214 18:12:13.577 [3439.3439] INFO SampleApp: Stopping Local configuration server Dec 14 18:12:13 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 18:12:13 volumio-hr sudo[3464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 18:12:13 volumio-hr sudo[3464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:13 volumio-hr volumio[1215]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 18:12:14 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 18:12:14 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 18:12:14 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:12:14 volumio-hr systemd[1]: shairport-sync.service: Consumed 1.212s CPU time. Dec 14 18:12:14 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 18:12:14 volumio-hr sudo[3464]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:14 volumio-hr volumio[1215]: info: Shairport-Sync Started Dec 14 18:12:14 volumio-hr volumio[1215]: (node:1215) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 18:12:14 volumio-hr volumio[1215]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 14 18:12:14 volumio-hr volumio[1215]: (node:1215) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 18:12:14 volumio-hr volumio[1215]: (node:1215) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 18:12:14 volumio-hr volumio[1215]: (node:1215) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 18:12:14 volumio-hr volumio[1215]: (node:1215) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 18:12:14 volumio-hr volumio[1215]: info: TidalConnect service stoped! Dec 14 18:12:14 volumio-hr mpd[3444]: 2025-12-14T18:12:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 18:12:14 volumio-hr systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 18:12:14 volumio-hr volumio[1215]: info: TidalConnect service stoped! Dec 14 18:12:14 volumio-hr sudo[3403]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:14 volumio-hr sudo[3362]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:14 volumio-hr volumio[1215]: error: MPD error: The expression evaluated to a falsy value: Dec 14 18:12:14 volumio-hr volumio[1215]: assert.ok(self.idling) Dec 14 18:12:14 volumio-hr volumio[1215]: error: The expression evaluated to a falsy value: Dec 14 18:12:14 volumio-hr volumio[1215]: assert.ok(self.idling) Dec 14 18:12:14 volumio-hr volumio[1215]: error: updateQueue error: null Dec 14 18:12:14 volumio-hr volumio[1215]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:12:14 volumio-hr volumio[1215]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:12:14 volumio-hr sudo[3492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 18:12:14 volumio-hr sudo[3492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:15 volumio-hr systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 18:12:15 volumio-hr sudo[3492]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:15 volumio-hr qobuz-connect[3439]: 20251214 18:12:15.107 [3439.3439] INFO SampleApp: shat down connection on UNIX socket Dec 14 18:12:15 volumio-hr volumio[1215]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 18:12:15 volumio-hr volumio[1215]: info: QobuzConnect: setDeactiveState invoked Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:12:15 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:15 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 18:12:15 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:12:15 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 18:12:15 volumio-hr sudo[3456]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:15 volumio-hr volumio[1215]: info: Executing endpoint qc_getconfig Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 18:12:15 volumio-hr qobuz-connect[3497]: 20251214 18:12:15.330 [3497.3497] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 18:12:15 volumio-hr qobuz-connect[3497]: 20251214 18:12:15.334 [3497.3497] INFO VolumeManager: [0x1605348]: Setting new playback volume: 75 Dec 14 18:12:15 volumio-hr qobuz-connect[3497]: 20251214 18:12:15.334 [3497.3497] INFO VolumeManager: [0x1605348]: Setting new mute state: 0 Dec 14 18:12:15 volumio-hr qobuz-connect[3497]: 20251214 18:12:15.334 [3497.3497] INFO QobuzConnect: [0x1605d18]: Client initialized! Dec 14 18:12:15 volumio-hr qobuz-connect[3497]: 20251214 18:12:15.334 [3497.3497] INFO SampleApp: Starting Avahi advertising, name: Volumio HR, service name: _qobuz-connect._tcp Dec 14 18:12:15 volumio-hr qobuz-connect[3497]: 20251214 18:12:15.343 [3497.3497] INFO LocalConfigManager: [0x1604c28]: Starting Local Configuration server Dec 14 18:12:15 volumio-hr qobuz-connect[3497]: 20251214 18:12:15.343 [3497.3497] INFO SampleApp: Starting Local configuration server Dec 14 18:12:15 volumio-hr qobuz-connect[3497]: 20251214 18:12:15.344 [3497.3497] INFO SampleApp: Connected to UNIX socket client 0x15ef818 Dec 14 18:12:15 volumio-hr volumio[1215]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 18:12:15 volumio-hr volumio[1215]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 18:12:15 volumio-hr volumio[1215]: info: Executing endpoint tc_getconfig Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 18:12:15 volumio-hr vtcs[3495]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 18:12:15 volumio-hr vtcs[3495]: STARTED TidalConnect services. Dec 14 18:12:15 volumio-hr volumio[1215]: info: Executing endpoint tc_connect Dec 14 18:12:15 volumio-hr qobuz-connect[3497]: 20251214 18:12:15.480 [3497.3497] INFO SampleApp: Playback volume changed: 75 Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 18:12:15 volumio-hr volumio[1215]: info: Connecting to TidalConnect Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:12:15 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::servicePushState Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreStateMachine::pushState Dec 14 18:12:15 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioPushState Dec 14 18:12:15 volumio-hr volumio[1215]: info: MRS: Pushing multiroomSync output update for this device Dec 14 18:12:15 volumio-hr volumio[1215]: info: MRS: Pushing multiroomSync output Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:12:15 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:15 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:15 volumio-hr volumio[1215]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::servicePushState Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreStateMachine::pushState Dec 14 18:12:15 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioPushState Dec 14 18:12:15 volumio-hr volumio[1215]: info: MRS: Pushing multiroomSync output update for this device Dec 14 18:12:15 volumio-hr volumio[1215]: info: MRS: Pushing multiroomSync output Dec 14 18:12:15 volumio-hr volumio[1215]: info: CoreCommandRouter::volumioGetState Dec 14 18:12:15 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:15 volumio-hr volumio[1215]: info: CorePlayQueue::getTrack 0 Dec 14 18:12:15 volumio-hr volumio[1215]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 14 18:12:16 volumio-hr volumio[1215]: info: PeppySpectrum ---peppyspectrum status play Dec 14 18:12:16 volumio-hr volumio[1215]: info: PeppySpectrum ---peppyspectrum status play Dec 14 18:12:16 volumio-hr volumio[1215]: info: PeppySpectrum ---peppyspectrum status play Dec 14 18:12:16 volumio-hr sudo[3518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 14 18:12:16 volumio-hr volumio[1215]: info: PeppySpectrum ---peppyspectrum status play Dec 14 18:12:16 volumio-hr sudo[3520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 14 18:12:16 volumio-hr sudo[3520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:16 volumio-hr sudo[3518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:16 volumio-hr volumio[1215]: info: TidalConnect service stoped! Dec 14 18:12:16 volumio-hr sudo[3522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 14 18:12:16 volumio-hr sudo[3522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:16 volumio-hr volumio[1215]: info: TidalConnect service stoped! Dec 14 18:12:16 volumio-hr sudo[3525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 14 18:12:16 volumio-hr sudo[3525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:16 volumio-hr systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 14 18:12:16 volumio-hr sudo[3518]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:16 volumio-hr sudo[3520]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:16 volumio-hr volumio[1215]: info: peppyspectrum Daemon Started Dec 14 18:12:16 volumio-hr volumio[1215]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:12:16 volumio-hr volumio[1215]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 18:12:16 volumio-hr sudo[3525]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:16 volumio-hr volumio[1215]: info: peppyspectrum Daemon Started Dec 14 18:12:16 volumio-hr sudo[3522]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:16 volumio-hr volumio[1215]: info: peppyspectrum Daemon Started Dec 14 18:12:16 volumio-hr volumio[1215]: info: peppyspectrum Daemon Started Dec 14 18:12:16 volumio-hr sudo[3540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 18:12:16 volumio-hr sudo[3540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:16 volumio-hr sudo[3540]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:17 volumio-hr systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 14 18:12:17 volumio-hr sudo[3559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:12:17 volumio-hr sudo[3559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:17 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 18:12:17 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 18:12:17 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 18:12:17 volumio-hr sudo[3562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 18:12:17 volumio-hr sudo[3562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 18:12:17 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 18:12:17 volumio-hr sudo[3559]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:17 volumio-hr mpd_monitor.sh[3563]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:12:17 volumio-hr volumio[1215]: info: Successfully started MPD Monitor Dec 14 18:12:17 volumio-hr systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 18:12:17 volumio-hr systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 18:12:17 volumio-hr systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 18:12:17 volumio-hr systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 18:12:17 volumio-hr mpd_monitor.sh[3568]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 18:12:17 volumio-hr sudo[3562]: pam_unix(sudo:session): session closed for user root Dec 14 18:12:17 volumio-hr volumio[1215]: info: Successfully started MPD Monitor Dec 14 18:12:18 volumio-hr volumio[1215]: info: TidalConnect service started! Dec 14 18:12:19 volumio-hr volumio[1215]: info: TidalConnect service started! Dec 14 18:12:24 volumio-hr volumio[1215]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] Dec 14 18:12:24 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy Dec 14 18:12:24 volumio-hr volumio[1215]: aaaaaaaaaaa 800,480 Dec 14 18:12:24 volumio-hr volumio[1215]: info: PeppySpectrum ---800480 Dec 14 18:12:24 volumio-hr volumio[1215]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 14 18:12:25 volumio-hr volumio[1215]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 18:12:25 volumio-hr volumio[1215]: info: CoreCommandRouter::Close All Modals sent Dec 14 18:12:40 volumio-hr volumio[1215]: info: CALLMETHOD: user_interface peppyspectrum updatelist Dec 14 18:12:40 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Dec 14 18:12:40 volumio-hr volumio[1215]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Dec 14 18:12:41 volumio-hr volumio[1215]: --2025-12-14 18:12:40-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Dec 14 18:12:41 volumio-hr volumio[1215]: Resolving github.com (github.com)... 140.82.121.3 Dec 14 18:12:41 volumio-hr volumio[1215]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 14 18:12:41 volumio-hr volumio[1215]: HTTP request sent, awaiting response... 302 Found Dec 14 18:12:41 volumio-hr volumio[1215]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Dec 14 18:12:41 volumio-hr volumio[1215]: --2025-12-14 18:12:41-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Dec 14 18:12:41 volumio-hr volumio[1215]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.110.133, 185.199.111.133, 185.199.109.133, ... Dec 14 18:12:41 volumio-hr volumio[1215]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.110.133|:443... connected. Dec 14 18:12:41 volumio-hr volumio[1215]: HTTP request sent, awaiting response... 200 OK Dec 14 18:12:41 volumio-hr volumio[1215]: Length: 471 [text/plain] Dec 14 18:12:41 volumio-hr volumio[1215]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Dec 14 18:12:41 volumio-hr volumio[1215]: 0K 100% 7.69M=0s Dec 14 18:12:41 volumio-hr volumio[1215]: 2025-12-14 18:12:41 (7.69 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Dec 14 18:12:41 volumio-hr volumio[1215]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 18:12:41 volumio-hr volumio[1215]: info: CoreCommandRouter::Close All Modals sent Dec 14 18:12:42 volumio-hr volumio[1215]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Dec 14 18:12:42 volumio-hr volumio[1215]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Dec 14 18:12:44 volumio-hr volumio[1215]: --2025-12-14 18:12:42-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Dec 14 18:12:44 volumio-hr volumio[1215]: Resolving github.com (github.com)... 140.82.121.4 Dec 14 18:12:44 volumio-hr volumio[1215]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 14 18:12:44 volumio-hr volumio[1215]: HTTP request sent, awaiting response... 404 Not Found Dec 14 18:12:44 volumio-hr volumio[1215]: 2025-12-14 18:12:43 ERROR 404: Not Found. Dec 14 18:12:44 volumio-hr volumio[1215]: --2025-12-14 18:12:43-- http://a/ Dec 14 18:12:44 volumio-hr volumio[1215]: Resolving a (a)... failed: Device or resource busy. Dec 14 18:12:44 volumio-hr volumio[1215]: wget: unable to resolve host address ‘a’ Dec 14 18:12:44 volumio-hr volumio[1215]: --2025-12-14 18:12:43-- http://packet.zip/ Dec 14 18:12:44 volumio-hr volumio[1215]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 14 18:12:44 volumio-hr volumio[1215]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 14 18:12:44 volumio-hr volumio[1215]: HTTP request sent, awaiting response... 302 Found Dec 14 18:12:44 volumio-hr volumio[1215]: Location: http://www.packet.zip/ [following] Dec 14 18:12:44 volumio-hr volumio[1215]: --2025-12-14 18:12:43-- http://www.packet.zip/ Dec 14 18:12:44 volumio-hr volumio[1215]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 14 18:12:44 volumio-hr volumio[1215]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 14 18:12:44 volumio-hr volumio[1215]: HTTP request sent, awaiting response... 403 Forbidden Dec 14 18:12:44 volumio-hr volumio[1215]: 2025-12-14 18:12:44 ERROR 403: Forbidden. Dec 14 18:12:44 volumio-hr volumio[1215]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Dec 14 18:12:44 volumio-hr volumio[1215]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 14 18:12:44 volumio-hr volumio[1215]: /bin/rm: cannot remove 'a': No such file or directory Dec 14 18:12:44 volumio-hr volumio[1215]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 14 18:12:44 volumio-hr volumio[1215]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 18:12:44 volumio-hr volumio[1215]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 14 18:12:44 volumio-hr volumio[1215]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 14 18:12:44 volumio-hr volumio[1215]: /bin/rm: cannot remove 'a': No such file or directory Dec 14 18:12:44 volumio-hr volumio[1215]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 14 18:12:44 volumio-hr volumio[1215]: at checkExecSyncError (node:child_process:887:11) Dec 14 18:12:44 volumio-hr volumio[1215]: at execSync (node:child_process:959:15) Dec 14 18:12:44 volumio-hr volumio[1215]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Dec 14 18:12:44 volumio-hr volumio[1215]: at new Promise () Dec 14 18:12:44 volumio-hr volumio[1215]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Dec 14 18:12:44 volumio-hr volumio[1215]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 14 18:12:44 volumio-hr volumio[1215]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 14 18:12:44 volumio-hr volumio[1215]: at Socket.emit (node:events:514:28) Dec 14 18:12:44 volumio-hr volumio[1215]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 14 18:12:44 volumio-hr volumio[1215]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 14 18:12:44 volumio-hr volumio[1215]: status: 1, Dec 14 18:12:44 volumio-hr volumio[1215]: signal: null, Dec 14 18:12:44 volumio-hr volumio[1215]: output: [ Dec 14 18:12:44 volumio-hr volumio[1215]: null, Dec 14 18:12:44 volumio-hr volumio[1215]: , Dec 14 18:12:44 volumio-hr volumio[1215]: Dec 14 18:12:44 volumio-hr volumio[1215]: ], Dec 14 18:12:44 volumio-hr volumio[1215]: pid: 3612, Dec 14 18:12:44 volumio-hr volumio[1215]: stdout: , Dec 14 18:12:44 volumio-hr volumio[1215]: stderr: Dec 14 18:12:44 volumio-hr volumio[1215]: } Dec 14 18:12:44 volumio-hr volumio[1215]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 18:12:44 volumio-hr sudo[3628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 18:11' Dec 14 18:12:44 volumio-hr sudo[3628]: 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"