-- Logs begin at Thu 2019-02-14 02:11:59 PST, end at Mon 2025-12-08 16:25:48 PST. -- Dec 08 16:25:03 minidsp-shd systemd-timedated[863]: Changed local time to Mon Dec 8 16:25:03 2025 Dec 08 16:25:03 minidsp-shd sudo[861]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:03 minidsp-shd volumio-time-update[516]: volumio-time-update-util: System time updated successfully. Dec 08 16:25:03 minidsp-shd systemd[1]: Started Volumio Time Update Utility. Dec 08 16:25:03 minidsp-shd systemd[1]: Starting Daily apt download activities... Dec 08 16:25:03 minidsp-shd nmbd[642]: [2025/12/08 16:25:03.179148, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 08 16:25:03 minidsp-shd systemd[1]: Started Samba NMB Daemon. Dec 08 16:25:03 minidsp-shd nmbd[642]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 08 16:25:03 minidsp-shd systemd[1]: Starting Samba Winbind Daemon... Dec 08 16:25:03 minidsp-shd volumio[716]: info: MYVOLUMIO Environment detected Dec 08 16:25:03 minidsp-shd volumio[716]: info: Plugin folders cleanup Dec 08 16:25:03 minidsp-shd volumio[716]: info: Scanning into folder /volumio/app/plugins/ Dec 08 16:25:03 minidsp-shd volumio[716]: info: Scanning category audio_interface Dec 08 16:25:03 minidsp-shd volumio[716]: info: Scanning category miscellanea Dec 08 16:25:03 minidsp-shd volumio[716]: info: Scanning category music_service Dec 08 16:25:03 minidsp-shd volumio[716]: info: Scanning category plugins.json Dec 08 16:25:03 minidsp-shd volumio[716]: info: Scanning category system_controller Dec 08 16:25:03 minidsp-shd volumio[716]: info: Scanning category user_interface Dec 08 16:25:03 minidsp-shd volumio[716]: info: Scanning into folder /data/plugins/ Dec 08 16:25:03 minidsp-shd volumio[716]: info: Scanning category music_service Dec 08 16:25:03 minidsp-shd volumio[716]: info: Plugin folders cleanup completed Dec 08 16:25:03 minidsp-shd ntpd[854]: Soliciting pool server 167.248.62.201 Dec 08 16:25:03 minidsp-shd volumio[716]: info: ------------------------------------------- Dec 08 16:25:03 minidsp-shd volumio[716]: info: ----- Core plugins startup ---- Dec 08 16:25:03 minidsp-shd volumio[716]: info: ------------------------------------------- Dec 08 16:25:03 minidsp-shd volumio[716]: info: Loading plugins from folder /volumio/app/plugins/ Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin bluetooth to MyMusic Plugins Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin multiroom to MyMusic Plugins Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin upnp to MyMusic Plugins Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin metavolumio to MyMusic Plugins Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin ms_surface_dial to MyMusic Plugins Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin cd_controller to MyMusic Plugins Dec 08 16:25:03 minidsp-shd winbindd[868]: [2025/12/08 16:25:03.832982, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 08 16:25:03 minidsp-shd winbindd[868]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin raat to MyMusic Plugins Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 08 16:25:03 minidsp-shd volumio[716]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 08 16:25:03 minidsp-shd winbindd[868]: [2025/12/08 16:25:03.865218, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 08 16:25:03 minidsp-shd volumio[716]: info: Loading plugins from folder /data/plugins/ Dec 08 16:25:03 minidsp-shd systemd[1]: Started Samba Winbind Daemon. Dec 08 16:25:03 minidsp-shd winbindd[868]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 08 16:25:03 minidsp-shd volumio[716]: info: Loading plugin "system"... Dec 08 16:25:03 minidsp-shd systemd[1]: Starting Samba SMB Daemon... Dec 08 16:25:03 minidsp-shd ntpd[854]: Soliciting pool server 155.248.196.28 Dec 08 16:25:04 minidsp-shd volumio[716]: info: Loading plugin "appearance"... Dec 08 16:25:04 minidsp-shd volumio-remote-updater[536]: [2025-12-08 16:25:04] [connect] Successful connection Dec 08 16:25:04 minidsp-shd systemd[1]: apt-daily.service: Succeeded. Dec 08 16:25:04 minidsp-shd systemd[1]: Started Daily apt download activities. Dec 08 16:25:04 minidsp-shd ntpd[854]: Soliciting pool server 162.244.81.139 Dec 08 16:25:04 minidsp-shd ntpd[854]: Soliciting pool server 69.176.84.38 Dec 08 16:25:04 minidsp-shd systemd[1]: Starting Daily apt upgrade and clean activities... Dec 08 16:25:04 minidsp-shd ntpd[854]: Soliciting pool server 162.159.200.1 Dec 08 16:25:04 minidsp-shd smbd[895]: [2025/12/08 16:25:04.988729, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 08 16:25:04 minidsp-shd systemd[1]: Started Samba SMB Daemon. Dec 08 16:25:04 minidsp-shd systemd[1]: Reached target Multi-User System. Dec 08 16:25:04 minidsp-shd smbd[895]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 08 16:25:05 minidsp-shd systemd[1]: Reached target Graphical Interface. Dec 08 16:25:05 minidsp-shd systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 08 16:25:05 minidsp-shd systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 08 16:25:05 minidsp-shd systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 08 16:25:05 minidsp-shd ntpd[854]: Soliciting pool server 45.79.111.167 Dec 08 16:25:05 minidsp-shd ntpd[854]: Soliciting pool server 172.233.157.223 Dec 08 16:25:05 minidsp-shd ntpd[854]: Soliciting pool server 23.186.168.128 Dec 08 16:25:05 minidsp-shd ntpd[854]: Soliciting pool server 172.104.28.175 Dec 08 16:25:06 minidsp-shd systemd[1]: apt-daily-upgrade.service: Succeeded. Dec 08 16:25:06 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. Dec 08 16:25:06 minidsp-shd systemd[1]: Startup finished in 5.693s (kernel) + 19.331s (userspace) = 25.024s. Dec 08 16:25:06 minidsp-shd ntpd[854]: Soliciting pool server 66.244.16.123 Dec 08 16:25:06 minidsp-shd ntpd[854]: Soliciting pool server 23.150.40.242 Dec 08 16:25:06 minidsp-shd ntpd[854]: Soliciting pool server 99.28.14.242 Dec 08 16:25:07 minidsp-shd volumio[716]: info: Loading plugin "network"... Dec 08 16:25:07 minidsp-shd volumio[716]: info: Refreshing Cached IP Addresses Dec 08 16:25:07 minidsp-shd sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 08 16:25:07 minidsp-shd sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:07 minidsp-shd sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 08 16:25:07 minidsp-shd sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:07 minidsp-shd sudo[981]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:07 minidsp-shd volumio[716]: info: Loading plugin "services"... Dec 08 16:25:07 minidsp-shd volumio[716]: info: Loading plugin "alsa_controller"... Dec 08 16:25:07 minidsp-shd sudo[983]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:07 minidsp-shd sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 08 16:25:07 minidsp-shd sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:07 minidsp-shd sudo[992]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:07 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 16:25:07 minidsp-shd volumio[716]: info: Loading plugin "wizard"... Dec 08 16:25:07 minidsp-shd volumio[716]: info: Loading plugin "networkfs"... Dec 08 16:25:07 minidsp-shd volumio[716]: info: Starting Udev Watcher for removable devices Dec 08 16:25:07 minidsp-shd sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=myprost8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.76/music /mnt/NAS/DiskStation Dec 08 16:25:07 minidsp-shd sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:07 minidsp-shd kernel: FS-Cache: Loaded Dec 08 16:25:07 minidsp-shd volumio[716]: info: Ignoring mount for partition: BOOT Dec 08 16:25:07 minidsp-shd volumio[716]: info: Ignoring mount for partition: volumio Dec 08 16:25:07 minidsp-shd volumio[716]: info: Ignoring mount for partition: volumio_data Dec 08 16:25:07 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 08 16:25:07 minidsp-shd volumio[716]: info: Loading plugin "volumio_command_line_client"... Dec 08 16:25:07 minidsp-shd volumio[716]: info: Loading plugin "upnp"... Dec 08 16:25:07 minidsp-shd volumio[716]: info: [1765239907617] Starting Upmpd Daemon Dec 08 16:25:07 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 08 16:25:07 minidsp-shd volumio[716]: info: Loading plugin "my_music"... Dec 08 16:25:07 minidsp-shd volumio[716]: info: Loading plugin "mpd"... Dec 08 16:25:07 minidsp-shd kernel: FS-Cache: Netfs 'cifs' registered for caching Dec 08 16:25:07 minidsp-shd kernel: Key type cifs.spnego registered Dec 08 16:25:07 minidsp-shd kernel: Key type cifs.idmap registered Dec 08 16:25:07 minidsp-shd kernel: CIFS: Attempting to mount //192.168.1.76/music Dec 08 16:25:07 minidsp-shd kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Dec 08 16:25:07 minidsp-shd ntpd[854]: Soliciting pool server 45.63.54.13 Dec 08 16:25:07 minidsp-shd ntpd[854]: Soliciting pool server 23.186.168.133 Dec 08 16:25:08 minidsp-shd volumio[716]: info: Loading plugin "upnp_browser"... Dec 08 16:25:08 minidsp-shd ntpd[854]: Soliciting pool server 66.228.58.20 Dec 08 16:25:08 minidsp-shd ntpd[854]: Soliciting pool server 2607:f1c0:f04e:fd00::1 Dec 08 16:25:09 minidsp-shd volumio[716]: info: Plugin bluetooth is not enabled Dec 08 16:25:09 minidsp-shd volumio[716]: info: Loading plugin "alarm-clock"... Dec 08 16:25:09 minidsp-shd sudo[1011]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:09 minidsp-shd volumio[716]: info: Loading plugin "manifestui"... Dec 08 16:25:09 minidsp-shd volumio[716]: info: Loading plugin "metavolumio"... Dec 08 16:25:13 minidsp-shd volumio[716]: info: Loading plugin "airplay_emulation"... Dec 08 16:25:13 minidsp-shd volumio[716]: info: Starting Shairport Sync Dec 08 16:25:13 minidsp-shd volumio[716]: info: Loading plugin "cd_controller"... Dec 08 16:25:14 minidsp-shd volumio[716]: info: Loading plugin "last_100"... Dec 08 16:25:14 minidsp-shd volumio[716]: info: Loading plugin "qobuzconnect"... Dec 08 16:25:14 minidsp-shd volumio[716]: info: Loading plugin "raat"... Dec 08 16:25:14 minidsp-shd volumio[716]: info: RAAT Plugin loaded Dec 08 16:25:14 minidsp-shd volumio[716]: info: Adding restartRAATSocket REST API Endpoint Dec 08 16:25:14 minidsp-shd volumio[716]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Dec 08 16:25:14 minidsp-shd volumio[716]: info: Loading plugin "streaming_services"... Dec 08 16:25:17 minidsp-shd volumio[716]: info: Starting Streaming Service Transparent Proxy Dec 08 16:25:17 minidsp-shd volumio[716]: info: Loading plugin "tidalconnect"... Dec 08 16:25:17 minidsp-shd volumio[716]: info: Loading plugin "webradio"... Dec 08 16:25:17 minidsp-shd volumio[716]: info: Loading plugin "i2s_dacs"... Dec 08 16:25:17 minidsp-shd volumio[716]: info: I2S DAC not set, start Auto-detection Dec 08 16:25:17 minidsp-shd volumio[716]: info: Loading plugin "volumiodiscovery"... Dec 08 16:25:18 minidsp-shd volumio[716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 08 16:25:18 minidsp-shd volumio[716]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 08 16:25:18 minidsp-shd node[716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 08 16:25:18 minidsp-shd volumio[716]: *** WARNING *** For more information see Dec 08 16:25:18 minidsp-shd volumio[716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 08 16:25:18 minidsp-shd volumio[716]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 08 16:25:18 minidsp-shd volumio[716]: *** WARNING *** For more information see Dec 08 16:25:18 minidsp-shd node[716]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 08 16:25:18 minidsp-shd node[716]: *** WARNING *** For more information see Dec 08 16:25:18 minidsp-shd node[716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 08 16:25:18 minidsp-shd node[716]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 08 16:25:18 minidsp-shd node[716]: *** WARNING *** For more information see Dec 08 16:25:18 minidsp-shd volumio[716]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 08 16:25:18 minidsp-shd volumio[716]: info: Discovery: Started advertising with name: miniDSP SHD Dec 08 16:25:18 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 08 16:25:18 minidsp-shd volumio[716]: info: Loading plugin "spop"... Dec 08 16:25:19 minidsp-shd volumio[716]: STREAMING PROXY: Starting server on port 3245 Dec 08 16:25:19 minidsp-shd volumio[716]: Node JS runtime: 14 Dec 08 16:25:20 minidsp-shd volumio-remote-updater[536]: [2025-12-08 16:25:20] [connect] Successful connection Dec 08 16:25:20 minidsp-shd volumio[716]: info: Plugin multiroom is not enabled Dec 08 16:25:20 minidsp-shd volumio[716]: info: Loading plugin "outputs"... Dec 08 16:25:20 minidsp-shd volumio[716]: info: Loading plugin "albumart"... Dec 08 16:25:20 minidsp-shd volumio[716]: info: Loading plugin "ms_surface_dial"... Dec 08 16:25:21 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. Dec 08 16:25:21 minidsp-shd volumio[716]: Forking 3 albumart workers Dec 08 16:25:22 minidsp-shd volumio[716]: info: Applying required configuration parameters for plugin ms_surface_dial Dec 08 16:25:22 minidsp-shd volumio[716]: info: Plugin example_plugin is not enabled Dec 08 16:25:22 minidsp-shd volumio[716]: info: Loading plugin "hi_res_audio"... Dec 08 16:25:23 minidsp-shd volumio[716]: Starting albumart workers Dec 08 16:25:23 minidsp-shd volumio[716]: Starting albumart workers Dec 08 16:25:23 minidsp-shd volumio[716]: Starting albumart workers Dec 08 16:25:25 minidsp-shd volumio[716]: info: Applying required configuration parameters for plugin hi_res_audio Dec 08 16:25:25 minidsp-shd volumio[716]: info: Loading plugin "inputs"... Dec 08 16:25:27 minidsp-shd volumio[716]: info: Loading plugin "qobuz"... Dec 08 16:25:29 minidsp-shd volumio[716]: info: Loading plugin "tidal"... Dec 08 16:25:32 minidsp-shd volumio[716]: info: Loading plugin "oem_helper"... Dec 08 16:25:33 minidsp-shd systemd[1]: systemd-timedated.service: Succeeded. Dec 08 16:25:34 minidsp-shd volumio[716]: info: Applying required configuration parameters for plugin oem_helper Dec 08 16:25:34 minidsp-shd volumio[716]: info: Loading plugin "updater_comm"... Dec 08 16:25:34 minidsp-shd volumio[716]: info: Plugin mpdemulation is not enabled Dec 08 16:25:34 minidsp-shd volumio[716]: info: Loading plugin "rest_api"... Dec 08 16:25:34 minidsp-shd volumio[716]: info: Loading plugin "websocket"... Dec 08 16:25:34 minidsp-shd volumio[716]: info: Starting Socket.io Server version 2.3.0 Dec 08 16:25:34 minidsp-shd volumio[716]: info: Loading i18n strings for locale en Dec 08 16:25:34 minidsp-shd volumio[716]: Updating browse sources language Dec 08 16:25:34 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::initPlayerControls Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 08 16:25:35 minidsp-shd volumio[716]: Express server listening on port 3000 Dec 08 16:25:35 minidsp-shd volumio[716]: [Metrics] WebUI: 34s 639.82ms Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreStateMachine::resetVolumioState Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreStateMachine::getcurrentVolume Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioRetrievevolume Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:35 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:25:35 minidsp-shd sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 08 16:25:35 minidsp-shd sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Dec 08 16:25:35 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Dec 08 16:25:35 minidsp-shd sudo[1140]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:35 minidsp-shd sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 08 16:25:35 minidsp-shd sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:35 minidsp-shd volumio[716]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 08 16:25:35 minidsp-shd volumio[716]: info: Completed loading Core Plugins Dec 08 16:25:35 minidsp-shd volumio[716]: info: Preparing to generate the ALSA configuration file Dec 08 16:25:35 minidsp-shd sudo[1142]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:35 minidsp-shd volumio-remote-updater[536]: [2025-12-08 16:25:35] [connect] Successful connection Dec 08 16:25:35 minidsp-shd volumio[716]: info: Volumio Network Manager: Network status updated: 1 Dec 08 16:25:35 minidsp-shd volumio[716]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 08 16:25:35 minidsp-shd volumio[716]: wlan0 Interface doesn't support scanning. Dec 08 16:25:35 minidsp-shd volumio[716]: info: Cannot use regular scanning, forcing with ap-force Dec 08 16:25:35 minidsp-shd sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 08 16:25:35 minidsp-shd sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:35 minidsp-shd sudo[1148]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:35 minidsp-shd volumio[716]: command failed: No such device (-19) Dec 08 16:25:35 minidsp-shd volumio[716]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 08 16:25:35 minidsp-shd volumio[716]: command failed: No such device (-19) Dec 08 16:25:35 minidsp-shd volumio[716]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 08 16:25:35 minidsp-shd volumio-remote-updater[536]: [2025-12-08 16:25:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765239935 101 Dec 08 16:25:35 minidsp-shd volumio[716]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Dec 08 16:25:36 minidsp-shd volumio[716]: info: Asound.conf file unchanged, so no further update is needed Dec 08 16:25:36 minidsp-shd volumio[716]: info: Output device has changed, restarting MPD Dec 08 16:25:36 minidsp-shd sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 08 16:25:36 minidsp-shd sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:36 minidsp-shd volumio[716]: info: Output device has changed, restarting Shairport Sync Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:25:36 minidsp-shd sudo[1158]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:36 minidsp-shd sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 08 16:25:36 minidsp-shd sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:36 minidsp-shd systemd[1]: Listening on mpd.socket. Dec 08 16:25:36 minidsp-shd systemd[1]: Starting Music Player Daemon... Dec 08 16:25:36 minidsp-shd volumio[716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 08 16:25:36 minidsp-shd volumio[716]: info: ___________ START PLUGINS ___________ Dec 08 16:25:36 minidsp-shd volumio[716]: info: ControllerMpd::onStart: Initializing MPD Dec 08 16:25:36 minidsp-shd volumio[716]: info: Creating MPD Configuration file Dec 08 16:25:36 minidsp-shd sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 08 16:25:36 minidsp-shd sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 08 16:25:36 minidsp-shd sudo[1168]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:36 minidsp-shd volumio[716]: info: [1765239936455] CoreMusicLibrary::Adding element Media Servers Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:25:36 minidsp-shd sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 08 16:25:36 minidsp-shd sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:36 minidsp-shd sudo[1166]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 08 16:25:36 minidsp-shd sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:36 minidsp-shd sudo[1166]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:36 minidsp-shd systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 08 16:25:36 minidsp-shd systemd[1]: mpd.service: Succeeded. Dec 08 16:25:36 minidsp-shd volumio[716]: info: Adding METAVOLUMIO REST API Endpoints Dec 08 16:25:36 minidsp-shd volumio[716]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 08 16:25:36 minidsp-shd volumio[716]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 08 16:25:36 minidsp-shd volumio[716]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 08 16:25:36 minidsp-shd systemd[1]: Stopped Music Player Daemon. Dec 08 16:25:36 minidsp-shd volumio[716]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:25:36 minidsp-shd systemd[1]: Starting Music Player Daemon... Dec 08 16:25:36 minidsp-shd volumio[716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:25:36 minidsp-shd volumio[716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 08 16:25:36 minidsp-shd volumio[716]: info: Preparing CD Folders Dec 08 16:25:36 minidsp-shd volumio[716]: info: Adding CD REST API Endpoints Dec 08 16:25:36 minidsp-shd sudo[1173]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 08 16:25:36 minidsp-shd volumio[716]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 08 16:25:36 minidsp-shd volumio[716]: info: Starting UDEV Watcher for CD Dec 08 16:25:36 minidsp-shd sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:36 minidsp-shd volumio[716]: info: Detecting CD presence with UDEV Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 08 16:25:36 minidsp-shd sudo[1173]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 08 16:25:36 minidsp-shd sudo[1173]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 08 16:25:36 minidsp-shd volumio[716]: info: [1765239936788] CoreMusicLibrary::Adding element Last_100 Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:25:36 minidsp-shd volumio[716]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:25:36 minidsp-shd volumio[716]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 08 16:25:36 minidsp-shd volumio[716]: info: Starting RAAT Plugin Dec 08 16:25:36 minidsp-shd sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 08 16:25:36 minidsp-shd sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:36 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Dec 08 16:25:36 minidsp-shd volumio[716]: info: Additional UI Settings Added for plugin music_service/raat Dec 08 16:25:36 minidsp-shd volumio[716]: info: Streaming services startup Dec 08 16:25:36 minidsp-shd volumio[716]: info: Starting Streaming Daemon Dec 08 16:25:36 minidsp-shd sudo[1186]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:37 minidsp-shd sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 08 16:25:37 minidsp-shd sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:37 minidsp-shd sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 08 16:25:37 minidsp-shd sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:37 minidsp-shd sudo[1192]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:37 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 08 16:25:37 minidsp-shd volumio[716]: info: [1765239937166] CoreMusicLibrary::Adding element Webradio Dec 08 16:25:37 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:25:37 minidsp-shd systemd[1]: Started Volumio Qobuz Connect Service. Dec 08 16:25:37 minidsp-shd sudo[1191]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:37 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 16:25:37 minidsp-shd volumio[716]: info: Initializing BBC Radios Dec 08 16:25:37 minidsp-shd sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 08 16:25:37 minidsp-shd sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:37 minidsp-shd sudo[1200]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:37 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 16:25:37 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:25:37 minidsp-shd volumio[716]: info: Creating Spotify config file Dec 08 16:25:37 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:37 minidsp-shd dbus-daemon[506]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.10' (uid=1000 pid=716 comm="/usr/bin/node /volumio/index.js ") Dec 08 16:25:37 minidsp-shd volumio[716]: error: Hi Res Audio Failed Login: Missing Login Data Dec 08 16:25:37 minidsp-shd volumio[716]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 08 16:25:37 minidsp-shd systemd[1]: Starting Bluetooth service... Dec 08 16:25:37 minidsp-shd volumio[716]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 08 16:25:37 minidsp-shd volumio[716]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 08 16:25:37 minidsp-shd volumio[716]: info: Refreshing TIDAL token Dec 08 16:25:37 minidsp-shd bluetoothd[1215]: Bluetooth daemon 5.23 Dec 08 16:25:37 minidsp-shd dbus-daemon[506]: [system] Successfully activated service 'org.bluez' Dec 08 16:25:37 minidsp-shd systemd[1]: Started Bluetooth service. Dec 08 16:25:37 minidsp-shd bluetoothd[1215]: Starting SDP server Dec 08 16:25:37 minidsp-shd bluetoothd[1215]: kernel lacks bnep-protocol support Dec 08 16:25:37 minidsp-shd bluetoothd[1215]: System does not support network plugin Dec 08 16:25:37 minidsp-shd dbus-daemon[506]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.12' (uid=0 pid=1215 comm="/usr/local/libexec/bluetooth/bluetoothd ") Dec 08 16:25:37 minidsp-shd bluetoothd[1215]: Bluetooth management interface 1.14 initialized Dec 08 16:25:37 minidsp-shd systemd[1]: Starting Hostname Service... Dec 08 16:25:37 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 16:25:37 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:25:37 minidsp-shd volumio[716]: info: Volumio Calling Home Dec 08 16:25:38 minidsp-shd volumio[716]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 08 16:25:38 minidsp-shd volumio[716]: info: Stopping AccessToken refresher cron for QOBUZ Dec 08 16:25:38 minidsp-shd dbus-daemon[506]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 08 16:25:38 minidsp-shd systemd[1]: Started Hostname Service. Dec 08 16:25:38 minidsp-shd volumio[716]: info: AccessToken refresher cron started for QOBUZ Dec 08 16:25:38 minidsp-shd volumio[716]: info: Adding QOBUZ REST API Endpoints Dec 08 16:25:38 minidsp-shd volumio[716]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 08 16:25:38 minidsp-shd volumio[716]: info: Setting Device type: NanoPi NEO 3 Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreStateMachine::setRepeat false single undefined Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:38 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:38 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreStateMachine::setRandom null Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:38 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:38 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:38 minidsp-shd volumio[716]: info: Serial port opened successfully Dec 08 16:25:38 minidsp-shd volumio[716]: error: Cannot start Volumio Streaming Daemon Dec 08 16:25:38 minidsp-shd volumio[716]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 08 16:25:38 minidsp-shd volumio[716]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 08 16:25:38 minidsp-shd volumio[716]: info: RAAT Albumart path created successfully Dec 08 16:25:38 minidsp-shd volumio[716]: info: MPD Permissions set Dec 08 16:25:38 minidsp-shd volumio[716]: info: MPD Permissions set Dec 08 16:25:38 minidsp-shd volumio[716]: info: msSurfaceDial volumioupdatevolume callback: {"vol":88,"dbVolume":-15.5,"disableVolumeControl":false} Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:38 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:38 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Dec 08 16:25:38 minidsp-shd volumio[716]: info: Ignoring ROON Volume update because of undefined data Dec 08 16:25:38 minidsp-shd volumio[716]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Dec 08 16:25:38 minidsp-shd volumio[716]: info: [msSurfaceDial init()] check /org/bluez Dec 08 16:25:38 minidsp-shd volumio[716]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Dec 08 16:25:38 minidsp-shd sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 08 16:25:38 minidsp-shd sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:38 minidsp-shd volumio[716]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Dec 08 16:25:38 minidsp-shd volumio[716]: info: CoreCommandRouter::Reload Ui Dec 08 16:25:38 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Dec 08 16:25:39 minidsp-shd sudo[1246]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:39 minidsp-shd volumio[716]: info: Executing endpoint qc_getconfig Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 08 16:25:39 minidsp-shd qobuz-connect[1201]: 20251208 16:25:39.217 [1201.1201] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 08 16:25:39 minidsp-shd volumio[716]: info: Volumio called home Dec 08 16:25:39 minidsp-shd volumio[716]: info: Spotify config file written Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Dec 08 16:25:39 minidsp-shd volumio[716]: info: updateDSP function in raat called! Dec 08 16:25:39 minidsp-shd volumio[716]: info: Updating RAAT Signal Path Dec 08 16:25:39 minidsp-shd volumio[716]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Dec 08 16:25:39 minidsp-shd volumio[716]: info: Reconfiguring and Restarting RAAT Plugin Dec 08 16:25:39 minidsp-shd sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 08 16:25:39 minidsp-shd sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:39 minidsp-shd qobuz-connect[1201]: 20251208 16:25:39.394 [1201.1201] INFO VolumeManager: [0x1ae2958]: Setting new playback volume: 75 Dec 08 16:25:39 minidsp-shd qobuz-connect[1201]: 20251208 16:25:39.394 [1201.1201] INFO VolumeManager: [0x1ae2958]: Setting new mute state: 0 Dec 08 16:25:39 minidsp-shd qobuz-connect[1201]: 20251208 16:25:39.395 [1201.1201] INFO QobuzConnect: [0x1ae3328]: Client initialized! Dec 08 16:25:39 minidsp-shd qobuz-connect[1201]: 20251208 16:25:39.395 [1201.1201] INFO SampleApp: Starting Avahi advertising, name: miniDSP SHD, service name: _qobuz-connect._tcp Dec 08 16:25:39 minidsp-shd qobuz-connect[1201]: 20251208 16:25:39.438 [1201.1201] INFO LocalConfigManager: [0x1ae2238]: Starting Local Configuration server Dec 08 16:25:39 minidsp-shd qobuz-connect[1201]: 20251208 16:25:39.440 [1201.1201] INFO SampleApp: Starting Local configuration server Dec 08 16:25:39 minidsp-shd qobuz-connect[1201]: 20251208 16:25:39.443 [1201.1201] INFO SampleApp: Playback volume changed: 75 Dec 08 16:25:39 minidsp-shd qobuz-connect[1201]: 20251208 16:25:39.445 [1201.1201] INFO SampleApp: Connected to UNIX socket client 0x1ad7ed8 Dec 08 16:25:39 minidsp-shd systemd[1]: Started go-librespot Daemon. Dec 08 16:25:39 minidsp-shd sudo[1252]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 08 16:25:39 minidsp-shd go-librespot[1258]: go-librespot daemon starting... Dec 08 16:25:39 minidsp-shd volumio[716]: info: Not Reporting Auto name since its the default one Dec 08 16:25:39 minidsp-shd volumio[716]: info: RAAT Overriding default device vendor model Dec 08 16:25:39 minidsp-shd volumio[716]: info: Adding MINIDSP Inputs Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 08 16:25:39 minidsp-shd volumio[716]: info: [1765239939503] CoreMusicLibrary::Adding element Inputs Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 08 16:25:39 minidsp-shd volumio[716]: info: [1765239939514] CoreMusicLibrary::Adding element Presets Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:25:39 minidsp-shd volumio[716]: Cannot find translation for source Presets Dec 08 16:25:39 minidsp-shd volumio[716]: (node:716) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Dec 08 16:25:39 minidsp-shd volumio[716]: (Use `node --trace-deprecation ...` to show where the warning was created) Dec 08 16:25:39 minidsp-shd volumio[716]: info: Discovery: adding e46f0793-b14c-4dba-99fd-edf35918d7e7 Dec 08 16:25:39 minidsp-shd volumio[716]: info: Discovery: Found device miniDSP SHD Dec 08 16:25:39 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:39 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:39 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:39-08:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:25:39 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:39-08:00" level=debug msg="app state loaded" Dec 08 16:25:39 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:39-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:25:40 minidsp-shd volumio[716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 08 16:25:40 minidsp-shd volumio[716]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 08 16:25:40 minidsp-shd volumio[716]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 08 16:25:40 minidsp-shd volumio[716]: info: Upmpdcli Daemon Started Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: No need to fix Spotify hosts Dec 08 16:25:40 minidsp-shd volumio[716]: info: Access Token successfully retrieved Dec 08 16:25:40 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:40-08:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 08 16:25:40 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:40-08:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 08 16:25:40 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:40-08:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 08 16:25:40 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:40-08:00" level=info msg="zeroconf server listening on port 35703" Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:40 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 08 16:25:40 minidsp-shd volumio[716]: info: Enabling external Volume Control Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:40 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:40-08:00" level=debug msg="obtained new client token: AABeB87F5gBiFKaOFhhSEI8DtLqIH+rEDmupDyTTvnbjCL4JxWFcxSh6RyefrYKeThEa2B8RvLU+iDo+GONSMDlw+kLD8OSldMhaXNykHhOygh2RUsW8qQyq626/3iujnaFb5+sFOwPL4XRQ6hC7kM2tkuTMSZ1JZWF1/5/r7UZATaxaXvko6ZJernYLxdxSnm4cWU+apq0aWH46rKR27Eo4BRBMJXpWxZcTO5eymcTLEijOW1nuNV9g7A==" Dec 08 16:25:40 minidsp-shd volumio[716]: info: TidalConnect service stoped! Dec 08 16:25:40 minidsp-shd volumio[716]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 08 16:25:40 minidsp-shd volumio[716]: SPOTIFY: BQACRBc36cJYleDUK8vXa6phXhyZ2YblJWRonTyu1AlpNs5Y2DIgeqmuee3ugZH3XUvMW02VYNwZArDNktRZPKSNbb3BMpWxEJ1c7y53BOA5ivgwkXYj7ou-Ar0dFOlkcCituqnt0mHfLmGuz1tMefnLFEGKPb6jP2dGXTV5uneLnXTVBu48W5DM7aQXwxC0AbFWNZdw18nS1F9ZZO2W4HTCOYelR9r4OBjSxdVpzhFq940eDLDFI06fOJ3Qug8R5sT6W5TupWXRa9SBg1nV3Q5IRMuyfB110wJY Dec 08 16:25:40 minidsp-shd volumio[716]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 08 16:25:40 minidsp-shd volumio[716]: info: New Spotify access token = BQACRBc36cJYleDUK8vXa6phXhyZ2YblJWRonTyu1AlpNs5Y2DIgeqmuee3ugZH3XUvMW02VYNwZArDNktRZPKSNbb3BMpWxEJ1c7y53BOA5ivgwkXYj7ou-Ar0dFOlkcCituqnt0mHfLmGuz1tMefnLFEGKPb6jP2dGXTV5uneLnXTVBu48W5DM7aQXwxC0AbFWNZdw18nS1F9ZZO2W4HTCOYelR9r4OBjSxdVpzhFq940eDLDFI06fOJ3Qug8R5sT6W5TupWXRa9SBg1nV3Q5IRMuyfB110wJY Dec 08 16:25:40 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:40-08:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 16:25:40 minidsp-shd volumio[716]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::volumiosetSourceActiveno-source Dec 08 16:25:40 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:25:40 minidsp-shd volumio[716]: Cannot find translation for source Presets Dec 08 16:25:41 minidsp-shd volumio[716]: info: Starting Shairport Sync Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="completed keyexchange" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="completed challenge" Dec 08 16:25:41 minidsp-shd volumio[716]: info: Starting Shairport Sync Dec 08 16:25:41 minidsp-shd sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 08 16:25:41 minidsp-shd sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:41 minidsp-shd volumio[716]: info: Starting Shairport Sync Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=info msg="authenticated AP" username="pl******ad" Dec 08 16:25:41 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 08 16:25:41 minidsp-shd sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 08 16:25:41 minidsp-shd sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:41 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 08 16:25:41 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Dec 08 16:25:41 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 08 16:25:41 minidsp-shd sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 08 16:25:41 minidsp-shd sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:41 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 08 16:25:41 minidsp-shd sudo[1286]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:41 minidsp-shd sudo[1284]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=info msg="authenticated Login5" username="pl******ad" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="initializing zeroconf session" username="pl******ad" Dec 08 16:25:41 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 08 16:25:41 minidsp-shd systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 08 16:25:41 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Dec 08 16:25:41 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="dealer connection opened" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=trace msg="starting accesspoint recv loop" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=trace msg="starting dealer recv loop" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=trace msg="received accesspoint ping" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="received connection id: YTBlYmQxMmItOWM2...OTM1QkI3MUZEOA==" Dec 08 16:25:41 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:41 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 08 16:25:41 minidsp-shd sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 08 16:25:41 minidsp-shd sudo[1289]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:41 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:41 minidsp-shd sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:41 minidsp-shd volumio[716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 08 16:25:41 minidsp-shd systemd[1]: Started RAAT DAEMON. Dec 08 16:25:41 minidsp-shd sudo[1296]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="put connect state because NEW_DEVICE" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="update volume requested to 57670/65535" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=trace msg="received accesspoint pong ack" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 08 16:25:41 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:41-08:00" level=trace msg="emitting websocket event: volume" Dec 08 16:25:42 minidsp-shd volumio[716]: info: Reloading queue from file Dec 08 16:25:42 minidsp-shd volumio[716]: info: Raat Daemon started successfully Dec 08 16:25:42 minidsp-shd volumio[716]: info: Shairport-Sync Started Dec 08 16:25:42 minidsp-shd volumio[716]: Error adding Membership: Error: addMembership EINVAL Dec 08 16:25:42 minidsp-shd volumio[716]: info: Shairport-Sync Started Dec 08 16:25:42 minidsp-shd volumio[716]: info: Shairport-Sync Started Dec 08 16:25:42 minidsp-shd volumio[716]: info: Successfully retrieved User Session From TIDAL Dec 08 16:25:42 minidsp-shd volumio[716]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 08 16:25:42 minidsp-shd volumio[716]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 08 16:25:42 minidsp-shd volumio[716]: info: msSurfaceDial volumioupdatevolume callback: {"vol":88,"dbVolume":-15.5,"mute":false,"disableVolumeControl":false} Dec 08 16:25:42 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:42 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:42 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 16:25:42 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:42 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:42 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:42 minidsp-shd volumio[716]: error: Help! Some callbacks for volumioPushState are crashing! Dec 08 16:25:42 minidsp-shd volumio[716]: error: Cannot read property 'sendVolumeMute' of undefined Dec 08 16:25:42 minidsp-shd sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 08 16:25:42 minidsp-shd sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:42 minidsp-shd volumio[716]: info: Executing endpoint restartRAATSocket Dec 08 16:25:42 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 08 16:25:42 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Dec 08 16:25:42 minidsp-shd sudo[1317]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:43 minidsp-shd volumio[716]: info: msSurfaceDial volumioupdatevolume callback: {"vol":88,"dbVolume":-15.5,"mute":false,"disableVolumeControl":false} Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:43 minidsp-shd volumio[716]: error: Help! Some callbacks for volumioPushState are crashing! Dec 08 16:25:43 minidsp-shd volumio[716]: error: Cannot read property 'sendVolumeMute' of undefined Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Dec 08 16:25:43 minidsp-shd volumio[716]: info: Successfully retrieved User Subscription From TIDAL Dec 08 16:25:43 minidsp-shd volumio[716]: info: Adding TIDAL to Browse Sources Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 08 16:25:43 minidsp-shd volumio[716]: info: [1765239943072] CoreMusicLibrary::Adding element TIDAL Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:25:43 minidsp-shd volumio[716]: Cannot find translation for source Presets Dec 08 16:25:43 minidsp-shd volumio[716]: Cannot find translation for source TIDAL Dec 08 16:25:43 minidsp-shd volumio[716]: info: Adding TIDAL REST API Endpoints Dec 08 16:25:43 minidsp-shd volumio[716]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 08 16:25:43 minidsp-shd volumio[716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 08 16:25:43 minidsp-shd volumio[716]: SPOTIFY: User informations: {"country":"US","display_name":"plbankhead","email":"paul@bankheads.net","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/plbankhead"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/plbankhead","id":"plbankhead","images":[],"product":"premium","type":"user","uri":"spotify:user:plbankhead"} Dec 08 16:25:43 minidsp-shd volumio[716]: info: Spotify Successfully logged in Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 08 16:25:43 minidsp-shd volumio[716]: info: [1765239943283] CoreMusicLibrary::Adding element Spotify Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:25:43 minidsp-shd volumio[716]: Cannot find translation for source Presets Dec 08 16:25:43 minidsp-shd volumio[716]: Cannot find translation for source TIDAL Dec 08 16:25:43 minidsp-shd volumio[716]: Cannot find translation for source Spotify Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:43 minidsp-shd volumio[716]: info: Executing endpoint tc_getconfig Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 08 16:25:43 minidsp-shd vtcs[1319]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 08 16:25:43 minidsp-shd vtcs[1319]: STARTED TidalConnect services. Dec 08 16:25:43 minidsp-shd volumio[716]: info: Executing endpoint tc_connect Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 08 16:25:43 minidsp-shd volumio[716]: info: Connecting to TidalConnect Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::servicePushState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:43 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:43 minidsp-shd volumio[716]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::servicePushState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:43 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:43 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:43 minidsp-shd volumio[716]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect Dec 08 16:25:43 minidsp-shd volumio[716]: info: go-librespot daemon successfully initialized Dec 08 16:25:44 minidsp-shd mpd[1181]: Dec 08 16:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 08 16:25:44 minidsp-shd systemd[1]: Started Music Player Daemon. Dec 08 16:25:44 minidsp-shd volumio[716]: Upnp client error: Error: This socket has been ended by the other party Dec 08 16:25:44 minidsp-shd sudo[1170]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:44 minidsp-shd sudo[1160]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:44 minidsp-shd volumio[716]: error: MPD error: The expression evaluated to a falsy value: Dec 08 16:25:44 minidsp-shd volumio[716]: assert.ok(self.idling) Dec 08 16:25:44 minidsp-shd volumio[716]: error: The expression evaluated to a falsy value: Dec 08 16:25:44 minidsp-shd volumio[716]: assert.ok(self.idling) Dec 08 16:25:44 minidsp-shd volumio[716]: error: updateQueue error: null Dec 08 16:25:44 minidsp-shd volumio[716]: info: MPD running with PID1181 Dec 08 16:25:44 minidsp-shd volumio[716]: ,establishing connection Dec 08 16:25:44 minidsp-shd volumio[716]: error: updateQueue error: null Dec 08 16:25:44 minidsp-shd volumio[1248]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 08 16:25:45 minidsp-shd volumio[716]: info: TidalConnect service started! Dec 08 16:25:45 minidsp-shd volumio[716]: info: Completed starting Core Plugins Dec 08 16:25:45 minidsp-shd volumio[716]: info: ------------------------------------------- Dec 08 16:25:45 minidsp-shd volumio[716]: info: ----- MyVolumio plugins startup ---- Dec 08 16:25:45 minidsp-shd volumio[716]: info: ------------------------------------------- Dec 08 16:25:45 minidsp-shd volumio[716]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="dealer connection closed" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="dealer recv loop stopped" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="obtained new client token: AADPdc3fES5uiIN4YxcV5qtIs+UMBz4rdzbVw+6+5+5X7welDUTNxB915DiIJWVQcpMeluD1v5H3PJSopE/h/PQN9UO5G3Y6OVGEaHTv6LN/wAB0VavbdX3C5/6bJ2yet0Nx7h6JjfF754yRPH1FqNZRcvSkym0O1G3iXEY/j/mx3HsEE6F18pBMQ7B1wUyDh5JL7K7syXqE3n3omRjPfbR9Hgz6Jz654cSRX1ocDiid8/ZopfCwHuU=" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="completed keyexchange" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="completed challenge" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=info msg="authenticated AP" username="0n*********************b5" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=info msg="authenticated Login5" username="0n*********************b5" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=info msg="accepted zeroconf from Pixel 8a" username="0n*********************b5" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="dealer connection opened" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=trace msg="starting accesspoint recv loop" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=trace msg="starting dealer recv loop" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=trace msg="received accesspoint ping" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="received connection id: ZTVlNWQ3OWMtNWE3...NkVGMzBBMDFEQQ==" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=trace msg="received accesspoint pong ack" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="put connect state because NEW_DEVICE" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="update volume requested to 57670/65535" Dec 08 16:25:46 minidsp-shd volumio[716]: info: Initializing connection to go-librespot Websocket Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="new websocket client" Dec 08 16:25:46 minidsp-shd volumio[716]: info: Connection to go-librespot Websocket established Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=trace msg="emitting websocket event: volume" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="handling transfer player command from 1294302307cbabff9340995ea5fa2f601ac2c5fd" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="resolved context of track" uri="spotify:search:high+hopes" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:search:high+hopes" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:5a4MgIUSf9K8wXLSm6xPEx" Dec 08 16:25:46 minidsp-shd volumio[716]: SPOTIFY: received: {"type":"volume","data":{"value":88,"max":100}} Dec 08 16:25:46 minidsp-shd volumio[716]: SPOTIFY: RECEIVED SPOTIFY VOLUME 88 Dec 08 16:25:46 minidsp-shd volumio[716]: info: Setting Volumio Volume from Spotify: 88 Dec 08 16:25:46 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Dec 08 16:25:46 minidsp-shd volumio[716]: info: msSurfaceDial volumioupdatevolume callback: {"vol":88,"dbVolume":-15.5,"mute":false,"disableVolumeControl":false} Dec 08 16:25:46 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:46 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:46 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:46 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:46 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:46 minidsp-shd volumio[716]: info: msSurfaceDial volumioupdatevolume callback: {"vol":88,"dbVolume":-15.5,"mute":false,"disableVolumeControl":false} Dec 08 16:25:46 minidsp-shd volumio[716]: info: CoreStateMachine::pushState Dec 08 16:25:46 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:46 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioPushState Dec 08 16:25:46 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:46 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=trace msg="emitting websocket event: will_play" Dec 08 16:25:46 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Dec 08 16:25:46 minidsp-shd volumio[716]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:search:high+hopes","uri":"spotify:track:5a4MgIUSf9K8wXLSm6xPEx","play_origin":"search"}} Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 391" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1362" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="selected format OGG_VORBIS_320 (397a45d6e2fc458a8ce0caffc28b1bcdfa5e2341)" uri="spotify:track:5a4MgIUSf9K8wXLSm6xPEx" Dec 08 16:25:46 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:46-08:00" level=debug msg="requested aes key for file 397a45d6e2fc458a8ce0caffc28b1bcdfa5e2341, gid: 5a4MgIUSf9K8wXLSm6xPEx" Dec 08 16:25:47 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:47 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:47 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:47 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:25:47 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 08 16:25:47 minidsp-shd volumio[716]: info: Not Reporting Auto name since its the default one Dec 08 16:25:47 minidsp-shd volumio[716]: info: RAAT Overriding default device vendor model Dec 08 16:25:47 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=trace msg="found 2 cdn urls" uri="spotify:track:5a4MgIUSf9K8wXLSm6xPEx" Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1127" Dec 08 16:25:47 minidsp-shd sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 08 16:25:47 minidsp-shd sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:47 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Dec 08 16:25:47 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 08 16:25:47 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Dec 08 16:25:47 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Dec 08 16:25:47 minidsp-shd systemd[1]: Started RAAT DAEMON. Dec 08 16:25:47 minidsp-shd sudo[1365]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:47 minidsp-shd volumio[716]: info: Raat Daemon started successfully Dec 08 16:25:47 minidsp-shd volumio[716]: info: Executing endpoint restartRAATSocket Dec 08 16:25:47 minidsp-shd volumio[716]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 08 16:25:47 minidsp-shd volumio[1248]: ..................................++++ Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="fetched first chunk of 38, total size is 19426857 bytes" uri="spotify:track:5a4MgIUSf9K8wXLSm6xPEx" Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="created new output device" Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=info msg="loaded track \"High Hopes\" (paused: true, position: 0ms, duration: 511013ms, prefetched: false)" uri="spotify:track:5a4MgIUSf9K8wXLSm6xPEx" Dec 08 16:25:47 minidsp-shd volumio[716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=trace msg="emitting websocket event: metadata" Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=trace msg="emitting websocket event: active" Dec 08 16:25:47 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:47 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="sending successful reply for dealer request" Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=trace msg="emitting websocket event: paused" Dec 08 16:25:47 minidsp-shd volumio[716]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5a4MgIUSf9K8wXLSm6xPEx","name":"High Hopes","artist_names":["Pink Floyd"],"album_name":"The Division Bell","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f508f0949710aa33a79cf6b2","position":0,"duration":511013,"release_date":"year:1994","track_number":11,"disc_number":1}} Dec 08 16:25:47 minidsp-shd volumio[716]: SPOTIFY: received: {"type":"active","data":null} Dec 08 16:25:47 minidsp-shd volumio[716]: info: Aligning Spotify Volume to Volumio Volume Dec 08 16:25:47 minidsp-shd volumio[716]: info: CoreCommandRouter::volumioGetState Dec 08 16:25:47 minidsp-shd volumio[716]: info: CorePlayQueue::getTrack 0 Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="fetched chunk 2/37, size: 524288" uri="spotify:track:5a4MgIUSf9K8wXLSm6xPEx" Dec 08 16:25:47 minidsp-shd volumio[716]: info: Setting Spotify Volume from Volumio: 88 Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="fetched chunk 3/37, size: 524288" uri="spotify:track:5a4MgIUSf9K8wXLSm6xPEx" Dec 08 16:25:47 minidsp-shd sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 08 16:25:47 minidsp-shd sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:47 minidsp-shd volumio[716]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:search:high+hopes","uri":"spotify:track:5a4MgIUSf9K8wXLSm6xPEx","play_origin":"search"}} Dec 08 16:25:47 minidsp-shd volumio[716]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 16:25:47 minidsp-shd go-librespot[1258]: time="2025-12-08T16:25:47-08:00" level=debug msg="fetched chunk 1/37, size: 524288" uri="spotify:track:5a4MgIUSf9K8wXLSm6xPEx" Dec 08 16:25:47 minidsp-shd sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 08 16:25:47 minidsp-shd volumio[716]: TypeError: Cannot read property 'service' of undefined Dec 08 16:25:47 minidsp-shd volumio[716]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 08 16:25:47 minidsp-shd volumio[716]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Dec 08 16:25:47 minidsp-shd volumio[716]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 08 16:25:47 minidsp-shd volumio[716]: at WebSocket.emit (events.js:400:28) Dec 08 16:25:47 minidsp-shd volumio[716]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 08 16:25:47 minidsp-shd volumio[716]: at Receiver.emit (events.js:400:28) Dec 08 16:25:47 minidsp-shd volumio[716]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 08 16:25:47 minidsp-shd volumio[716]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 08 16:25:47 minidsp-shd volumio[716]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 08 16:25:47 minidsp-shd volumio[716]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Dec 08 16:25:47 minidsp-shd volumio[716]: at writeOrBuffer (internal/streams/writable.js:358:12) Dec 08 16:25:47 minidsp-shd volumio[716]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Dec 08 16:25:47 minidsp-shd volumio[716]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Dec 08 16:25:47 minidsp-shd volumio[716]: at Socket.emit (events.js:400:28) Dec 08 16:25:47 minidsp-shd volumio[716]: at addChunk (internal/streams/readable.js:293:12) Dec 08 16:25:47 minidsp-shd volumio[716]: at readableAddChunk (internal/streams/readable.js:267:9) Dec 08 16:25:47 minidsp-shd volumio[716]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 16:25:47 minidsp-shd sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 16:25:47 minidsp-shd systemd[1]: Started MPD Monitor Service. Dec 08 16:25:47 minidsp-shd sudo[1381]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:47 minidsp-shd mpd_monitor.sh[1387]: MPD Monitor Service: Starting MPD Monitor Service Dec 08 16:25:47 minidsp-shd systemd[1]: Stopping MPD Monitor Service... Dec 08 16:25:47 minidsp-shd systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 08 16:25:47 minidsp-shd systemd[1]: mpd_monitor.service: Succeeded. Dec 08 16:25:47 minidsp-shd systemd[1]: Stopped MPD Monitor Service. Dec 08 16:25:47 minidsp-shd systemd[1]: Started MPD Monitor Service. Dec 08 16:25:47 minidsp-shd sudo[1383]: pam_unix(sudo:session): session closed for user root Dec 08 16:25:47 minidsp-shd mpd_monitor.sh[1396]: MPD Monitor Service: Starting MPD Monitor Service Dec 08 16:25:48 minidsp-shd sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-08 16:24 Dec 08 16:25:48 minidsp-shd sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 18 Nov 2025 04:27:40 PM CET" VOLUMIO_VERSION="3.877" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="861d146b0ca5e28d2e157bf87e6b15ea"