-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sun 2026-08-30 09:44:56 CST. -- Aug 30 09:44:03 minidsp-shd systemd-timedated[901]: Changed local time to Sun Aug 30 09:44:03 2026 Aug 30 09:44:03 minidsp-shd systemd[1]: Starting Daily apt download activities... Aug 30 09:44:03 minidsp-shd sudo[899]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:03 minidsp-shd volumio-time-update[521]: volumio-time-update-util: System time updated successfully. Aug 30 09:44:03 minidsp-shd systemd[1]: Started Volumio Time Update Utility. Aug 30 09:44:03 minidsp-shd ntpd[877]: Soliciting pool server 172.104.182.184 Aug 30 09:44:03 minidsp-shd ntpd[877]: Soliciting pool server 51.79.177.199 Aug 30 09:44:03 minidsp-shd ntpd[877]: Soliciting pool server 202.91.32.240 Aug 30 09:44:03 minidsp-shd nmbd[711]: [2026/08/30 09:44:03.327624, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 30 09:44:03 minidsp-shd nmbd[711]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 30 09:44:03 minidsp-shd systemd[1]: Started Samba NMB Daemon. Aug 30 09:44:03 minidsp-shd systemd[1]: Starting Samba Winbind Daemon... Aug 30 09:44:03 minidsp-shd volumio[757]: info: Plugin folders cleanup Aug 30 09:44:03 minidsp-shd volumio[757]: info: Scanning into folder /volumio/app/plugins/ Aug 30 09:44:03 minidsp-shd volumio[757]: info: Scanning category audio_interface Aug 30 09:44:03 minidsp-shd volumio[757]: info: Scanning category miscellanea Aug 30 09:44:03 minidsp-shd volumio[757]: info: Scanning category music_service Aug 30 09:44:03 minidsp-shd volumio[757]: info: Scanning category plugins.json Aug 30 09:44:03 minidsp-shd volumio[757]: info: Scanning category system_controller Aug 30 09:44:03 minidsp-shd volumio[757]: info: Scanning category user_interface Aug 30 09:44:03 minidsp-shd volumio[757]: info: Scanning into folder /data/plugins/ Aug 30 09:44:03 minidsp-shd volumio[757]: info: Scanning category music_service Aug 30 09:44:03 minidsp-shd volumio[757]: info: Plugin folders cleanup completed Aug 30 09:44:03 minidsp-shd volumio[757]: info: ------------------------------------------- Aug 30 09:44:03 minidsp-shd volumio[757]: info: ----- Core plugins startup ---- Aug 30 09:44:03 minidsp-shd volumio[757]: info: ------------------------------------------- Aug 30 09:44:03 minidsp-shd volumio[757]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin upnp to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin ms_surface_dial to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin raat to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 09:44:03 minidsp-shd volumio[757]: info: Loading plugins from folder /data/plugins/ Aug 30 09:44:03 minidsp-shd volumio[757]: info: Loading plugin "system"... Aug 30 09:44:03 minidsp-shd volumio[757]: info: Loading plugin "appearance"... Aug 30 09:44:04 minidsp-shd winbindd[912]: [2026/08/30 09:44:04.015990, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 30 09:44:04 minidsp-shd winbindd[912]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 30 09:44:04 minidsp-shd systemd[1]: Started Samba Winbind Daemon. Aug 30 09:44:04 minidsp-shd winbindd[912]: [2026/08/30 09:44:04.056561, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 30 09:44:04 minidsp-shd winbindd[912]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 30 09:44:04 minidsp-shd systemd[1]: Starting Samba SMB Daemon... Aug 30 09:44:04 minidsp-shd ntpd[877]: Soliciting pool server 142.91.103.26 Aug 30 09:44:04 minidsp-shd ntpd[877]: Soliciting pool server 167.71.195.165 Aug 30 09:44:04 minidsp-shd ntpd[877]: Soliciting pool server 51.79.249.229 Aug 30 09:44:04 minidsp-shd ntpd[877]: Soliciting pool server 142.91.99.46 Aug 30 09:44:04 minidsp-shd volumio-remote-updater[542]: [2026-08-30 09:44:04] [connect] Successful connection Aug 30 09:44:04 minidsp-shd systemd[1]: apt-daily.service: Succeeded. Aug 30 09:44:04 minidsp-shd systemd[1]: Started Daily apt download activities. Aug 30 09:44:04 minidsp-shd systemd[1]: Starting Daily apt upgrade and clean activities... Aug 30 09:44:05 minidsp-shd smbd[944]: [2026/08/30 09:44:05.156399, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 30 09:44:05 minidsp-shd systemd[1]: Started Samba SMB Daemon. Aug 30 09:44:05 minidsp-shd smbd[944]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 30 09:44:05 minidsp-shd systemd[1]: Reached target Multi-User System. Aug 30 09:44:05 minidsp-shd systemd[1]: Reached target Graphical Interface. Aug 30 09:44:05 minidsp-shd systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 30 09:44:05 minidsp-shd ntpd[877]: Soliciting pool server 5.223.50.207 Aug 30 09:44:05 minidsp-shd ntpd[877]: Soliciting pool server 45.77.171.102 Aug 30 09:44:05 minidsp-shd systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 30 09:44:05 minidsp-shd systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 30 09:44:06 minidsp-shd systemd[1]: apt-daily-upgrade.service: Succeeded. Aug 30 09:44:06 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. Aug 30 09:44:06 minidsp-shd systemd[1]: Startup finished in 5.794s (kernel) + 19.367s (userspace) = 25.162s. Aug 30 09:44:06 minidsp-shd ntpd[877]: Soliciting pool server 23.106.249.200 Aug 30 09:44:06 minidsp-shd ntpd[877]: Soliciting pool server 2402:a7c0:3003:112:1c00:4bff:fe00:8c9 Aug 30 09:44:06 minidsp-shd volumio[757]: info: Loading plugin "network"... Aug 30 09:44:06 minidsp-shd volumio[757]: info: Refreshing Cached IP Addresses Aug 30 09:44:06 minidsp-shd sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 09:44:06 minidsp-shd sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:06 minidsp-shd sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 09:44:06 minidsp-shd sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:06 minidsp-shd sudo[1020]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:06 minidsp-shd volumio[757]: info: Loading plugin "services"... Aug 30 09:44:07 minidsp-shd volumio[757]: info: Loading plugin "alsa_controller"... Aug 30 09:44:07 minidsp-shd sudo[1022]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:07 minidsp-shd sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 09:44:07 minidsp-shd sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:07 minidsp-shd sudo[1032]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:07 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 09:44:07 minidsp-shd volumio[757]: info: Loading plugin "wizard"... Aug 30 09:44:07 minidsp-shd volumio[757]: info: Loading plugin "networkfs"... Aug 30 09:44:07 minidsp-shd volumio[757]: info: Starting Udev Watcher for removable devices Aug 30 09:44:07 minidsp-shd volumio[757]: info: Ignoring mount for partition: BOOT Aug 30 09:44:07 minidsp-shd volumio[757]: info: Ignoring mount for partition: volumio Aug 30 09:44:07 minidsp-shd volumio[757]: info: Ignoring mount for partition: volumio_data Aug 30 09:44:07 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 09:44:07 minidsp-shd volumio[757]: info: Loading plugin "volumio_command_line_client"... Aug 30 09:44:07 minidsp-shd volumio[757]: info: Loading plugin "upnp"... Aug 30 09:44:07 minidsp-shd volumio[757]: info: [1788054247392] Starting Upmpd Daemon Aug 30 09:44:07 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 09:44:07 minidsp-shd volumio[757]: info: Loading plugin "my_music"... Aug 30 09:44:07 minidsp-shd volumio[757]: info: Loading plugin "mpd"... Aug 30 09:44:08 minidsp-shd volumio[757]: info: Loading plugin "upnp_browser"... Aug 30 09:44:08 minidsp-shd volumio[757]: info: Starting UPNP Browser Aug 30 09:44:08 minidsp-shd volumio[757]: info: Loading plugin "bluetooth"... Aug 30 09:44:10 minidsp-shd volumio[757]: info: [1788054250350] Starting BluetoothController Aug 30 09:44:10 minidsp-shd ntpd[877]: receive: Unexpected origin timestamp 0xee3e0969.3c5116e2 does not match aorg 0000000000.00000000 from server@51.79.249.229 xmt 0xee3e096a.56b8917a Aug 30 09:44:10 minidsp-shd ntpd[877]: receive: Unexpected origin timestamp 0xee3e0969.3c4a36e8 does not match aorg 0000000000.00000000 from server@142.91.99.46 xmt 0xee3e096a.58a95586 Aug 30 09:44:10 minidsp-shd ntpd[877]: receive: Unexpected origin timestamp 0xee3e0969.3c4eb75f does not match aorg 0000000000.00000000 from server@142.91.103.26 xmt 0xee3e096a.58d5c1ac Aug 30 09:44:10 minidsp-shd ntpd[877]: receive: Unexpected origin timestamp 0xee3e0969.3c5358e3 does not match aorg 0000000000.00000000 from server@202.91.32.240 xmt 0xee3e096a.63d1c998 Aug 30 09:44:10 minidsp-shd ntpd[877]: receive: Unexpected origin timestamp 0xee3e0969.3c391ec7 does not match aorg 0000000000.00000000 from server@45.77.171.102 xmt 0xee3e096a.70ccc884 Aug 30 09:44:10 minidsp-shd volumio[757]: info: Loading plugin "alarm-clock"... Aug 30 09:44:10 minidsp-shd volumio[757]: info: Loading plugin "manifestui"... Aug 30 09:44:11 minidsp-shd volumio[757]: info: Loading plugin "metavolumio"... Aug 30 09:44:13 minidsp-shd volumio[757]: info: Loading plugin "airplay_emulation"... Aug 30 09:44:14 minidsp-shd volumio[757]: info: Starting Shairport Sync Aug 30 09:44:14 minidsp-shd volumio[757]: info: Loading plugin "cd_controller"... Aug 30 09:44:14 minidsp-shd volumio[757]: info: Loading plugin "last_100"... Aug 30 09:44:14 minidsp-shd volumio[757]: info: Loading plugin "qobuzconnect"... Aug 30 09:44:14 minidsp-shd volumio[757]: info: Loading plugin "raat"... Aug 30 09:44:15 minidsp-shd volumio[757]: info: RAAT Plugin loaded Aug 30 09:44:15 minidsp-shd volumio[757]: info: Adding restartRAATSocket REST API Endpoint Aug 30 09:44:15 minidsp-shd volumio[757]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Aug 30 09:44:15 minidsp-shd volumio[757]: info: Loading plugin "streaming_services"... Aug 30 09:44:17 minidsp-shd volumio[757]: info: Starting Streaming Service Transparent Proxy Aug 30 09:44:17 minidsp-shd volumio[757]: info: Loading plugin "tidalconnect"... Aug 30 09:44:17 minidsp-shd volumio[757]: info: Loading plugin "webradio"... Aug 30 09:44:18 minidsp-shd volumio[757]: info: Loading plugin "i2s_dacs"... Aug 30 09:44:18 minidsp-shd volumio[757]: info: I2S DAC not set, start Auto-detection Aug 30 09:44:18 minidsp-shd volumio[757]: info: Loading plugin "volumiodiscovery"... Aug 30 09:44:18 minidsp-shd volumio[757]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 09:44:18 minidsp-shd volumio[757]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 09:44:18 minidsp-shd node[757]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 09:44:18 minidsp-shd volumio[757]: *** WARNING *** For more information see Aug 30 09:44:18 minidsp-shd volumio[757]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 09:44:18 minidsp-shd volumio[757]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 09:44:18 minidsp-shd volumio[757]: *** WARNING *** For more information see Aug 30 09:44:18 minidsp-shd node[757]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 09:44:18 minidsp-shd node[757]: *** WARNING *** For more information see Aug 30 09:44:18 minidsp-shd node[757]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 09:44:18 minidsp-shd node[757]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 09:44:18 minidsp-shd node[757]: *** WARNING *** For more information see Aug 30 09:44:18 minidsp-shd volumio[757]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 09:44:18 minidsp-shd volumio[757]: info: Discovery: Started advertising with name: miniDSP SHD Aug 30 09:44:18 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 09:44:18 minidsp-shd volumio[757]: info: Loading plugin "spop"... Aug 30 09:44:19 minidsp-shd volumio[757]: STREAMING PROXY: Starting server on port 3245 Aug 30 09:44:19 minidsp-shd volumio[757]: Node JS runtime: 14 Aug 30 09:44:20 minidsp-shd volumio-remote-updater[542]: [2026-08-30 09:44:20] [connect] Successful connection Aug 30 09:44:21 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. Aug 30 09:44:21 minidsp-shd volumio[757]: info: Loading plugin "ytmusic"... Aug 30 09:44:23 minidsp-shd volumio[757]: info: Loading plugin "multiroom"... Aug 30 09:44:26 minidsp-shd volumio[757]: info: Applying required configuration parameters for plugin multiroom Aug 30 09:44:26 minidsp-shd sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 30 09:44:26 minidsp-shd sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:26 minidsp-shd sudo[1076]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:26 minidsp-shd volumio[757]: info: MRS: MultiRoom plugin initialized Aug 30 09:44:26 minidsp-shd volumio[757]: info: MRS: STOPPING SNAPCLIENT Aug 30 09:44:26 minidsp-shd volumio[757]: info: MRS: Snap server stop Aug 30 09:44:27 minidsp-shd sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 30 09:44:27 minidsp-shd sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:27 minidsp-shd volumio[757]: info: MRS: STOPPING volumioStreaming Aug 30 09:44:27 minidsp-shd sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 30 09:44:27 minidsp-shd sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:27 minidsp-shd sudo[1092]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:27 minidsp-shd sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 30 09:44:27 minidsp-shd sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:27 minidsp-shd volumio[757]: info: Loading plugin "outputs"... Aug 30 09:44:27 minidsp-shd sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 30 09:44:27 minidsp-shd sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:27 minidsp-shd volumio[757]: info: Loading plugin "albumart"... Aug 30 09:44:27 minidsp-shd sudo[1100]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:27 minidsp-shd sudo[1094]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:27 minidsp-shd sudo[1097]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:27 minidsp-shd volumio[757]: info: Loading plugin "ms_surface_dial"... Aug 30 09:44:27 minidsp-shd volumio[757]: Forking 3 albumart workers Aug 30 09:44:28 minidsp-shd volumio[757]: info: Applying required configuration parameters for plugin ms_surface_dial Aug 30 09:44:28 minidsp-shd volumio[757]: info: Plugin example_plugin is not enabled Aug 30 09:44:28 minidsp-shd volumio[757]: info: Loading plugin "hi_res_audio"... Aug 30 09:44:29 minidsp-shd volumio[757]: Starting albumart workers Aug 30 09:44:29 minidsp-shd volumio[757]: Starting albumart workers Aug 30 09:44:30 minidsp-shd volumio[757]: Starting albumart workers Aug 30 09:44:30 minidsp-shd shairport-sync[703]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 8192 Aug 30 09:44:30 minidsp-shd shairport-sync[703]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 8192 Aug 30 09:44:32 minidsp-shd volumio[757]: info: Applying required configuration parameters for plugin hi_res_audio Aug 30 09:44:32 minidsp-shd volumio[757]: info: Loading plugin "inputs"... Aug 30 09:44:33 minidsp-shd volumio[757]: info: Loading plugin "qobuz"... Aug 30 09:44:34 minidsp-shd systemd[1]: systemd-timedated.service: Succeeded. Aug 30 09:44:35 minidsp-shd volumio-remote-updater[542]: [2026-08-30 09:44:35] [connect] Successful connection Aug 30 09:44:36 minidsp-shd volumio[757]: info: Loading plugin "tidal"... Aug 30 09:44:38 minidsp-shd volumio[757]: info: Loading plugin "oem_helper"... Aug 30 09:44:40 minidsp-shd volumio[757]: info: Applying required configuration parameters for plugin oem_helper Aug 30 09:44:40 minidsp-shd volumio[757]: info: Loading plugin "updater_comm"... Aug 30 09:44:41 minidsp-shd volumio[757]: info: Plugin mpdemulation is not enabled Aug 30 09:44:41 minidsp-shd volumio[757]: info: Loading plugin "rest_api"... Aug 30 09:44:41 minidsp-shd volumio[757]: info: Loading plugin "websocket"... Aug 30 09:44:41 minidsp-shd volumio[757]: info: Starting Socket.io Server version 2.3.0 Aug 30 09:44:41 minidsp-shd volumio[757]: info: Loading i18n strings for locale zh_TW Aug 30 09:44:41 minidsp-shd volumio[757]: Updating browse sources language Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::initPlayerControls Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 09:44:41 minidsp-shd volumio[757]: Express server listening on port 3000 Aug 30 09:44:41 minidsp-shd volumio[757]: [Metrics] WebUI: 41s 123.45ms Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreStateMachine::resetVolumioState Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreStateMachine::getcurrentVolume Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreStateMachine::pushState Aug 30 09:44:41 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioPushState Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:41 minidsp-shd sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 09:44:41 minidsp-shd sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Aug 30 09:44:41 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Aug 30 09:44:41 minidsp-shd sudo[1202]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:41 minidsp-shd sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 09:44:41 minidsp-shd sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:41 minidsp-shd volumio[757]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 30 09:44:41 minidsp-shd volumio[757]: info: Completed loading Core Plugins Aug 30 09:44:41 minidsp-shd volumio[757]: info: Preparing to generate the ALSA configuration file Aug 30 09:44:41 minidsp-shd sudo[1204]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:42 minidsp-shd volumio[757]: info: Volumio Network Manager: Network status updated: 1 Aug 30 09:44:42 minidsp-shd volumio[757]: info: MRS: Removed streaming files Aug 30 09:44:42 minidsp-shd volumio[757]: info: MRS: volumioStreaming STOPPED Aug 30 09:44:42 minidsp-shd volumio[757]: info: MRS: SNAPSERVER STOPPED Aug 30 09:44:42 minidsp-shd volumio[757]: info: MRS: SNAPCLIENT STOPPED Aug 30 09:44:42 minidsp-shd volumio[757]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 30 09:44:42 minidsp-shd volumio[757]: wlan0 Interface doesn't support scanning. Aug 30 09:44:42 minidsp-shd volumio[757]: info: Cannot use regular scanning, forcing with ap-force Aug 30 09:44:42 minidsp-shd sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 30 09:44:42 minidsp-shd sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:42 minidsp-shd sudo[1210]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:42 minidsp-shd volumio[757]: command failed: No such device (-19) Aug 30 09:44:42 minidsp-shd volumio[757]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Aug 30 09:44:42 minidsp-shd volumio[757]: command failed: No such device (-19) Aug 30 09:44:42 minidsp-shd volumio[757]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 30 09:44:42 minidsp-shd volumio[757]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 30 09:44:42 minidsp-shd volumio[757]: info: Reading ALSA contributions from plugins. Aug 30 09:44:42 minidsp-shd volumio[757]: info: Reloading queue from file Aug 30 09:44:42 minidsp-shd volumio[757]: info: Setting Device type: NanoPi NEO 3 Aug 30 09:44:42 minidsp-shd volumio[757]: info: CoreStateMachine::setRepeat null single undefined Aug 30 09:44:42 minidsp-shd volumio[757]: info: CoreStateMachine::pushState Aug 30 09:44:42 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:42 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 09:44:42 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioPushState Aug 30 09:44:42 minidsp-shd volumio[757]: info: CoreStateMachine::setRandom null Aug 30 09:44:42 minidsp-shd volumio[757]: info: CoreStateMachine::pushState Aug 30 09:44:42 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:42 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioPushState Aug 30 09:44:42 minidsp-shd volumio[757]: info: Asound.conf file unchanged, so no further update is needed Aug 30 09:44:42 minidsp-shd volumio[757]: info: Output device has changed, restarting MPD Aug 30 09:44:42 minidsp-shd sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 30 09:44:42 minidsp-shd sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:42 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Aug 30 09:44:42 minidsp-shd sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 09:44:42 minidsp-shd sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:42 minidsp-shd volumio[757]: info: Output device has changed, restarting Shairport Sync Aug 30 09:44:42 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:42 minidsp-shd sudo[1220]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:42 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:42 minidsp-shd sudo[1223]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:42 minidsp-shd sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 09:44:42 minidsp-shd sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:43 minidsp-shd systemd[1]: Listening on mpd.socket. Aug 30 09:44:43 minidsp-shd volumio[757]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 09:44:43 minidsp-shd systemd[1]: Starting Music Player Daemon... Aug 30 09:44:43 minidsp-shd volumio[757]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:43 minidsp-shd volumio[757]: info: ___________ START PLUGINS ___________ Aug 30 09:44:43 minidsp-shd volumio[757]: info: ControllerMpd::onStart: Initializing MPD Aug 30 09:44:43 minidsp-shd volumio[757]: info: Creating MPD Configuration file Aug 30 09:44:43 minidsp-shd sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 09:44:43 minidsp-shd sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 09:44:43 minidsp-shd volumio[757]: info: [1788054283202] CoreMusicLibrary::Adding element Media Servers Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 09:44:43 minidsp-shd sudo[1236]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:43 minidsp-shd sudo[1232]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 09:44:43 minidsp-shd sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:43 minidsp-shd volumio[757]: info: UPNP Browser: Client initialized successfully Aug 30 09:44:43 minidsp-shd sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 09:44:43 minidsp-shd sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:43 minidsp-shd sudo[1232]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 09:44:43 minidsp-shd sudo[1232]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 09:44:43 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 30 09:44:43 minidsp-shd systemd[1]: mpd.service: Succeeded. Aug 30 09:44:43 minidsp-shd systemd[1]: Stopped Music Player Daemon. Aug 30 09:44:43 minidsp-shd systemd[1]: Starting Music Player Daemon... Aug 30 09:44:43 minidsp-shd volumio[757]: info: Adding METAVOLUMIO REST API Endpoints Aug 30 09:44:43 minidsp-shd volumio[757]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 30 09:44:43 minidsp-shd volumio[757]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 30 09:44:43 minidsp-shd volumio[757]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 30 09:44:43 minidsp-shd volumio[757]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:43 minidsp-shd sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Aug 30 09:44:43 minidsp-shd sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:43 minidsp-shd sudo[1245]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:43 minidsp-shd volumio[757]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:43 minidsp-shd sudo[1244]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 09:44:43 minidsp-shd sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:43 minidsp-shd sudo[1244]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 09:44:43 minidsp-shd sudo[1244]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:43 minidsp-shd volumio[757]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 09:44:43 minidsp-shd volumio[757]: info: Preparing CD Folders Aug 30 09:44:43 minidsp-shd volumio[757]: info: Adding CD REST API Endpoints Aug 30 09:44:43 minidsp-shd volumio[757]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 30 09:44:43 minidsp-shd volumio[757]: info: Starting UDEV Watcher for CD Aug 30 09:44:43 minidsp-shd volumio[757]: info: Detecting CD presence with UDEV Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 09:44:43 minidsp-shd volumio[757]: info: [1788054283613] CoreMusicLibrary::Adding element Last_100 Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 09:44:43 minidsp-shd volumio[757]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:43 minidsp-shd volumio[757]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 30 09:44:43 minidsp-shd volumio[757]: info: Starting RAAT Plugin Aug 30 09:44:43 minidsp-shd sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 30 09:44:43 minidsp-shd sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:43 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 30 09:44:43 minidsp-shd volumio[757]: info: Additional UI Settings Added for plugin music_service/raat Aug 30 09:44:43 minidsp-shd volumio[757]: info: Streaming services startup Aug 30 09:44:43 minidsp-shd volumio[757]: info: Starting Streaming Daemon Aug 30 09:44:43 minidsp-shd sudo[1259]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:43 minidsp-shd sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 30 09:44:43 minidsp-shd sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:43 minidsp-shd sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 09:44:43 minidsp-shd sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:43 minidsp-shd sudo[1265]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:44 minidsp-shd systemd[1]: Started Volumio Qobuz Connect Service. Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 09:44:44 minidsp-shd volumio[757]: info: [1788054284044] CoreMusicLibrary::Adding element Webradio Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 09:44:44 minidsp-shd sudo[1264]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 09:44:44 minidsp-shd sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 30 09:44:44 minidsp-shd sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:44 minidsp-shd volumio[757]: info: Initializing BBC Radios Aug 30 09:44:44 minidsp-shd sudo[1274]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:44 minidsp-shd volumio[757]: info: Creating Spotify config file Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 09:44:44 minidsp-shd volumio[757]: info: [1788054284367] CoreMusicLibrary::Adding element YouTube Music Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 09:44:44 minidsp-shd volumio[757]: Cannot find translation for source YouTube Music Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 30 09:44:44 minidsp-shd volumio[757]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:44 minidsp-shd dbus-daemon[523]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.11' (uid=1000 pid=757 comm="/usr/bin/node /volumio/index.js ") Aug 30 09:44:44 minidsp-shd volumio[757]: error: Hi Res Audio Failed Login: Missing Login Data Aug 30 09:44:44 minidsp-shd systemd[1]: Starting Bluetooth service... Aug 30 09:44:44 minidsp-shd volumio[757]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 30 09:44:44 minidsp-shd volumio[757]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 30 09:44:44 minidsp-shd volumio[757]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 30 09:44:44 minidsp-shd bluetoothd[1291]: Bluetooth daemon 5.23 Aug 30 09:44:44 minidsp-shd dbus-daemon[523]: [system] Successfully activated service 'org.bluez' Aug 30 09:44:44 minidsp-shd systemd[1]: Started Bluetooth service. Aug 30 09:44:44 minidsp-shd bluetoothd[1291]: Starting SDP server Aug 30 09:44:44 minidsp-shd bluetoothd[1291]: kernel lacks bnep-protocol support Aug 30 09:44:44 minidsp-shd bluetoothd[1291]: System does not support network plugin Aug 30 09:44:44 minidsp-shd dbus-daemon[523]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.14' (uid=0 pid=1291 comm="/usr/local/libexec/bluetooth/bluetoothd ") Aug 30 09:44:44 minidsp-shd bluetoothd[1291]: Bluetooth management interface 1.14 initialized Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 09:44:44 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:44 minidsp-shd volumio[757]: info: Volumio Calling Home Aug 30 09:44:44 minidsp-shd systemd[1]: Starting Hostname Service... Aug 30 09:44:45 minidsp-shd volumio[757]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 30 09:44:45 minidsp-shd dbus-daemon[523]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 30 09:44:45 minidsp-shd systemd[1]: Started Hostname Service. Aug 30 09:44:45 minidsp-shd volumio[757]: info: Stopping AccessToken refresher cron for QOBUZ Aug 30 09:44:45 minidsp-shd volumio[757]: info: AccessToken refresher cron started for QOBUZ Aug 30 09:44:45 minidsp-shd volumio[757]: info: Adding TIDAL REST API Endpoints Aug 30 09:44:45 minidsp-shd volumio[757]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Aug 30 09:44:45 minidsp-shd volumio[757]: info: Adding QOBUZ REST API Endpoints Aug 30 09:44:45 minidsp-shd volumio[757]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Aug 30 09:44:45 minidsp-shd volumio[757]: info: Discovery: adding daadbc84-d220-4d28-9c46-d2edc5f533bd Aug 30 09:44:45 minidsp-shd volumio[757]: info: Discovery: Found device miniDSP SHD Aug 30 09:44:45 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:45 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:45 minidsp-shd volumio[757]: info: MRS: Pushing multiroomSync output for this device Aug 30 09:44:45 minidsp-shd volumio[757]: info: MRS: Pushing multiroomSync output Aug 30 09:44:45 minidsp-shd volumio[757]: info: Adding audio output: Aug 30 09:44:45 minidsp-shd volumio[757]: info: Adding audio output: Aug 30 09:44:45 minidsp-shd volumio[757]: info: Serial port opened successfully Aug 30 09:44:45 minidsp-shd volumio[757]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Aug 30 09:44:45 minidsp-shd volumio[757]: error: Cannot start Volumio Streaming Daemon Aug 30 09:44:45 minidsp-shd volumio[757]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 09:44:45 minidsp-shd volumio[757]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 09:44:45 minidsp-shd volumio[757]: info: RAAT Albumart path created successfully Aug 30 09:44:45 minidsp-shd volumio[757]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Aug 30 09:44:45 minidsp-shd volumio[757]: Can't get device info: No such device Aug 30 09:44:45 minidsp-shd volumio[757]: info: MPD Permissions set Aug 30 09:44:45 minidsp-shd volumio[757]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Aug 30 09:44:45 minidsp-shd volumio[757]: info: MPD Permissions set Aug 30 09:44:45 minidsp-shd volumio[757]: info: Upmpdcli Daemon Started Aug 30 09:44:45 minidsp-shd volumio[757]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-35,"disableVolumeControl":false} Aug 30 09:44:45 minidsp-shd volumio[757]: info: CoreStateMachine::pushState Aug 30 09:44:45 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:45 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 09:44:45 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioPushState Aug 30 09:44:45 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:45 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:45 minidsp-shd volumio[757]: info: MRS: Pushing multiroomSync output update for this device Aug 30 09:44:45 minidsp-shd volumio[757]: info: MRS: Pushing multiroomSync output Aug 30 09:44:45 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 30 09:44:45 minidsp-shd volumio[757]: info: Ignoring ROON Volume update because of undefined data Aug 30 09:44:45 minidsp-shd volumio[757]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Aug 30 09:44:45 minidsp-shd volumio[757]: info: [msSurfaceDial init()] check /org/bluez Aug 30 09:44:45 minidsp-shd volumio[757]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Aug 30 09:44:45 minidsp-shd volumio[757]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Aug 30 09:44:45 minidsp-shd volumio[757]: info: CoreCommandRouter::Reload Ui Aug 30 09:44:45 minidsp-shd volumio[757]: info: Executing endpoint qc_getconfig Aug 30 09:44:45 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.024 [1272.1272] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 30 09:44:46 minidsp-shd volumio[757]: info: Spotify config file written Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Aug 30 09:44:46 minidsp-shd volumio[757]: info: updateDSP function in raat called! Aug 30 09:44:46 minidsp-shd volumio[757]: info: Updating RAAT Signal Path Aug 30 09:44:46 minidsp-shd volumio[757]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Aug 30 09:44:46 minidsp-shd volumio[757]: info: Reconfiguring and Restarting RAAT Plugin Aug 30 09:44:46 minidsp-shd sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 30 09:44:46 minidsp-shd sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.218 [1272.1272] INFO VolumeManager: [0xc8c150]: Setting new playback volume: 75 Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.219 [1272.1272] INFO VolumeManager: [0xc8c150]: Setting new mute state: 0 Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.221 [1272.1272] INFO AudioStreamManager: [0xc8bea8]: Setting new audio download buffer size: 1048576 Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.222 [1272.1272] INFO QobuzConnect: [0xc8ca18]: Client initialized! Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.224 [1272.1272] INFO SampleApp: Starting Avahi advertising, name: miniDSP SHD, service name: _qobuz-connect._tcp Aug 30 09:44:46 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 09:44:46 minidsp-shd sudo[1310]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.283 [1272.1272] INFO LocalConfigManager: [0xc8bbd0]: Starting Local Configuration server Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.283 [1272.1272] INFO SampleApp: Starting Local configuration server Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.285 [1272.1272] INFO SampleApp: Connected to UNIX socket client 0xc81ed8 Aug 30 09:44:46 minidsp-shd qobuz-connect[1272]: 20260830 09:44:46.286 [1272.1272] INFO SampleApp: Playback volume changed: 75 Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: Not Reporting Auto name since its the default one Aug 30 09:44:46 minidsp-shd volumio[757]: info: RAAT Overriding default device vendor model Aug 30 09:44:46 minidsp-shd volumio[757]: info: Adding MINIDSP Inputs Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 09:44:46 minidsp-shd volumio[757]: info: [1788054286305] CoreMusicLibrary::Adding element Inputs Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 09:44:46 minidsp-shd go-librespot[1313]: go-librespot daemon starting... Aug 30 09:44:46 minidsp-shd volumio[757]: Cannot find translation for source YouTube Music Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 09:44:46 minidsp-shd volumio[757]: info: [1788054286316] CoreMusicLibrary::Adding element Presets Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 09:44:46 minidsp-shd volumio[757]: Cannot find translation for source YouTube Music Aug 30 09:44:46 minidsp-shd volumio[757]: Cannot find translation for source Presets Aug 30 09:44:46 minidsp-shd volumio[757]: (node:757) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Aug 30 09:44:46 minidsp-shd volumio[757]: (Use `node --trace-deprecation ...` to show where the warning was created) Aug 30 09:44:46 minidsp-shd volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 30 09:44:46 minidsp-shd volumio[757]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 30 09:44:46 minidsp-shd volumio[757]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 30 09:44:46 minidsp-shd sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 09:44:46 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Aug 30 09:44:46 minidsp-shd sudo[1325]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Aug 30 09:44:46 minidsp-shd sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:46 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:46+08:00" level=info msg="running go-librespot 0.4.0" Aug 30 09:44:46 minidsp-shd volumiobt[1331]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Aug 30 09:44:46 minidsp-shd sudo[1334]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:46 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:46+08:00" level=debug msg="app state loaded" Aug 30 09:44:46 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:46+08:00" level=debug msg="stored credentials not found" Aug 30 09:44:46 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:46+08:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 09:44:46 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:46 minidsp-shd volumio[757]: info: No need to fix Spotify hosts Aug 30 09:44:47 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:47 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:47 minidsp-shd volumio[757]: info: Volumio called home Aug 30 09:44:47 minidsp-shd volumio[757]: info: TidalConnect service stoped! Aug 30 09:44:47 minidsp-shd volumio[757]: info: Volumio BT Module successfully started Aug 30 09:44:47 minidsp-shd volumio[757]: info: CoreCommandRouter::volumiosetSourceActiveno-source Aug 30 09:44:47 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 09:44:47 minidsp-shd volumio[757]: Cannot find translation for source YouTube Music Aug 30 09:44:47 minidsp-shd volumio[757]: Cannot find translation for source Presets Aug 30 09:44:47 minidsp-shd volumio[757]: info: Starting Shairport Sync Aug 30 09:44:47 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:47+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 30 09:44:47 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:47+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 30 09:44:47 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:47+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 30 09:44:47 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:47+08:00" level=info msg="zeroconf server listening on port 39605" Aug 30 09:44:47 minidsp-shd volumio[757]: info: Starting Shairport Sync Aug 30 09:44:47 minidsp-shd sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 09:44:47 minidsp-shd sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:47 minidsp-shd volumio[757]: info: Starting Shairport Sync Aug 30 09:44:47 minidsp-shd sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 09:44:47 minidsp-shd sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:47 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 30 09:44:47 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 09:44:47 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Aug 30 09:44:47 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 09:44:47 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 09:44:47 minidsp-shd sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 09:44:47 minidsp-shd sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:47 minidsp-shd sudo[1353]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:47 minidsp-shd sudo[1350]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:47 minidsp-shd sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Aug 30 09:44:47 minidsp-shd sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:47 minidsp-shd volumio[757]: xcb_connection_has_error() returned true Aug 30 09:44:47 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 09:44:47 minidsp-shd systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 30 09:44:47 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Aug 30 09:44:47 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 09:44:47 minidsp-shd volumiobt[1331]: pulseaudio: no process found Aug 30 09:44:47 minidsp-shd sudo[1360]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:47 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 09:44:47 minidsp-shd sudo[1356]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:48 minidsp-shd sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 30 09:44:48 minidsp-shd sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:48 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:48 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:48 minidsp-shd dbus-daemon[523]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.20' (uid=1000 pid=1370 comm="/usr/bin/pulseaudio --high-priority=true ") Aug 30 09:44:48 minidsp-shd volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 30 09:44:48 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Aug 30 09:44:48 minidsp-shd systemd[1]: Started RAAT DAEMON. Aug 30 09:44:48 minidsp-shd sudo[1366]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:48 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 30 09:44:48 minidsp-shd volumio[757]: info: Enabling external Volume Control Aug 30 09:44:48 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:48 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:48 minidsp-shd dbus-daemon[523]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Aug 30 09:44:48 minidsp-shd rtkit-daemon[1372]: Successfully called chroot. Aug 30 09:44:48 minidsp-shd rtkit-daemon[1372]: Successfully dropped privileges. Aug 30 09:44:48 minidsp-shd rtkit-daemon[1372]: Successfully limited resources. Aug 30 09:44:48 minidsp-shd rtkit-daemon[1372]: Running. Aug 30 09:44:48 minidsp-shd dbus-daemon[523]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.21' (uid=0 pid=1372 comm="/usr/lib/rtkit/rtkit-daemon ") Aug 30 09:44:48 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Aug 30 09:44:48 minidsp-shd rtkit-daemon[1372]: Canary thread running. Aug 30 09:44:48 minidsp-shd volumio[757]: info: Preparing to generate the ALSA configuration file Aug 30 09:44:48 minidsp-shd systemd[1]: Starting Authorization Manager... Aug 30 09:44:48 minidsp-shd rtkit-daemon[1372]: Watchdog thread running. Aug 30 09:44:48 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:48 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:48 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:48 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:48 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:48 minidsp-shd polkitd[1384]: started daemon version 0.105 using authority implementation `local' version `0.105' Aug 30 09:44:48 minidsp-shd dbus-daemon[523]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 30 09:44:48 minidsp-shd systemd[1]: Started Authorization Manager. Aug 30 09:44:48 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:48 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:48 minidsp-shd volumio[757]: info: Raat Daemon started successfully Aug 30 09:44:48 minidsp-shd volumio[757]: info: Shairport-Sync Started Aug 30 09:44:48 minidsp-shd volumio[757]: Error adding Membership: Error: addMembership EINVAL Aug 30 09:44:48 minidsp-shd volumio[757]: info: Shairport-Sync Started Aug 30 09:44:48 minidsp-shd volumio[757]: info: Shairport-Sync Started Aug 30 09:44:48 minidsp-shd volumio[757]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 30 09:44:48 minidsp-shd volumio[757]: info: Reading ALSA contributions from plugins. Aug 30 09:44:49 minidsp-shd volumio[757]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 30 09:44:49 minidsp-shd volumio[757]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 30 09:44:49 minidsp-shd sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 30 09:44:49 minidsp-shd sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:49 minidsp-shd volumio[757]: info: Executing endpoint restartRAATSocket Aug 30 09:44:49 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Aug 30 09:44:49 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Aug 30 09:44:49 minidsp-shd sudo[1412]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:49 minidsp-shd volumio[757]: info: MRS: Getting audio outputs on start Aug 30 09:44:49 minidsp-shd volumio[757]: info: MRS: Requesting all other devices output Aug 30 09:44:49 minidsp-shd volumio[757]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-35,"mute":false,"disableVolumeControl":false} Aug 30 09:44:49 minidsp-shd volumio[757]: info: CoreStateMachine::pushState Aug 30 09:44:49 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:49 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 09:44:49 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioPushState Aug 30 09:44:49 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:49 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:49 minidsp-shd volumio[757]: info: MRS: Pushing multiroomSync output update for this device Aug 30 09:44:49 minidsp-shd volumio[757]: info: MRS: Pushing multiroomSync output Aug 30 09:44:49 minidsp-shd pulseaudio[1370]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 30 09:44:49 minidsp-shd pulseaudio[1370]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 30 09:44:49 minidsp-shd volumiobt[1331]: Applying permissions Aug 30 09:44:49 minidsp-shd volumiobt[1331]: Setting BT discoverable and pairable Aug 30 09:44:49 minidsp-shd pulseaudio[1370]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Aug 30 09:44:49 minidsp-shd volumio[757]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 30 09:44:49 minidsp-shd volumiobt[1331]: [38B blob data] Aug 30 09:44:49 minidsp-shd volumiobt[1331]: Invalid command Aug 30 09:44:49 minidsp-shd volumiobt[1331]: [bluetooth]# agent on Aug 30 09:44:49 minidsp-shd volumiobt[1331]: [bluetooth]# discoverable on Aug 30 09:44:49 minidsp-shd volumiobt[1331]: No default controller available Aug 30 09:44:49 minidsp-shd volumiobt[1331]: [bluetooth]# pairable on Aug 30 09:44:49 minidsp-shd volumiobt[1331]: No default controller available Aug 30 09:44:49 minidsp-shd volumiobt[1331]: [bluetooth]# agent NoInputNoOutput Aug 30 09:44:49 minidsp-shd volumiobt[1331]: Failed to register agent object Aug 30 09:44:49 minidsp-shd volumiobt[1331]: [bluetooth]# default-agent Aug 30 09:44:49 minidsp-shd volumiobt[1331]: No agent is registered Aug 30 09:44:49 minidsp-shd volumiobt[1331]: [bluetooth]# quit Aug 30 09:44:49 minidsp-shd volumiobt[1331]: [61B blob data] Aug 30 09:44:49 minidsp-shd volumiobt[1331]: [62B blob data] Aug 30 09:44:49 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:49 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:50 minidsp-shd volumio[757]: info: Asound.conf file written Aug 30 09:44:50 minidsp-shd sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 30 09:44:50 minidsp-shd sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:50 minidsp-shd sudo[1423]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:50 minidsp-shd volumio[757]: xcb_connection_has_error() returned true Aug 30 09:44:50 minidsp-shd volumio[757]: info: Output device has changed, restarting MPD Aug 30 09:44:50 minidsp-shd volumio-remote-updater[542]: [2026-08-30 09:44:50] [connect] Successful connection Aug 30 09:44:50 minidsp-shd volumio[757]: info: Output device has changed, restarting Shairport Sync Aug 30 09:44:50 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:50 minidsp-shd sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 09:44:50 minidsp-shd sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:50 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:50 minidsp-shd sudo[1429]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:50 minidsp-shd sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 09:44:50 minidsp-shd sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:50 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 30 09:44:50 minidsp-shd systemd[1]: mpd.service: Succeeded. Aug 30 09:44:50 minidsp-shd systemd[1]: Stopped Music Player Daemon. Aug 30 09:44:50 minidsp-shd volumio[757]: xcb_connection_has_error() returned true Aug 30 09:44:50 minidsp-shd volumio[757]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 09:44:50 minidsp-shd volumio[757]: xcb_connection_has_error() returned true Aug 30 09:44:50 minidsp-shd volumio[757]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 30 09:44:50 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:50 minidsp-shd systemd[1]: Starting Music Player Daemon... Aug 30 09:44:50 minidsp-shd volumio[757]: info: QobuzConnect: setDeactiveState invoked Aug 30 09:44:50 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:50 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:50 minidsp-shd volumio[757]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Aug 30 09:44:50 minidsp-shd volumio[757]: info: Preparing to generate the ALSA configuration file Aug 30 09:44:50 minidsp-shd sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 30 09:44:50 minidsp-shd sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:50 minidsp-shd sudo[1437]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 09:44:50 minidsp-shd sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:50 minidsp-shd sudo[1437]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 09:44:50 minidsp-shd systemd[1]: Stopping Volumio Tidal Connect Service... Aug 30 09:44:50 minidsp-shd sudo[1437]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:50 minidsp-shd systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Aug 30 09:44:50 minidsp-shd systemd[1]: vtcs.service: Succeeded. Aug 30 09:44:50 minidsp-shd systemd[1]: Stopped Volumio Tidal Connect Service. Aug 30 09:44:50 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:50 minidsp-shd sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 30 09:44:50 minidsp-shd sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:50 minidsp-shd sudo[1441]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:51 minidsp-shd volumio[757]: info: go-librespot daemon successfully initialized Aug 30 09:44:51 minidsp-shd volumio[757]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 30 09:44:51 minidsp-shd volumio[757]: info: Reading ALSA contributions from plugins. Aug 30 09:44:51 minidsp-shd sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 30 09:44:51 minidsp-shd sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:51 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:51 minidsp-shd sudo[1445]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:51 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:51 minidsp-shd sudo[1453]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:51 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:51 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:51 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:51 minidsp-shd sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 30 09:44:51 minidsp-shd sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=debug msg="obtained new client token: AAGwpL2+fIKemLe41coMRqaw47kSifaQVqwiA8xz2+XQRpScpHm5n6JRJ9r+xkgfH6IX3f4Ev0gefmvTjKdoGsHzqOXtPmZyCFZpDIG1IKoB1Ieo8q6byS9dg3gmewALdb/pu04ooKGe+FBWr/0VYP53tc3j3flPZh8aWzk09/tN4i8CL/uz645LftvN2l5uTkmfcnQPjrwYv3b7R2ZB0sSpm5ezk3+eSvygxvHwFx5386PtulhffTO0Tg==" Aug 30 09:44:51 minidsp-shd qobuz-connect[1272]: 20260830 09:44:51.255 [1272.1272] INFO SampleApp: Stopping Local configuration server Aug 30 09:44:51 minidsp-shd qobuz-connect[1272]: 20260830 09:44:51.266 [1272.1272] INFO SampleApp: shat down connection on UNIX socket Aug 30 09:44:51 minidsp-shd systemd[1]: Stopping Volumio Qobuz Connect Service... Aug 30 09:44:51 minidsp-shd volumio[757]: info: MPD Permissions set Aug 30 09:44:51 minidsp-shd systemd[1]: qobuz-connect.service: Succeeded. Aug 30 09:44:51 minidsp-shd systemd[1]: Stopped Volumio Qobuz Connect Service. Aug 30 09:44:51 minidsp-shd systemd[1]: Started Volumio Qobuz Connect Service. Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Aug 30 09:44:51 minidsp-shd sudo[1456]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: Not Reporting Auto name since its the default one Aug 30 09:44:51 minidsp-shd volumio[757]: info: RAAT Overriding default device vendor model Aug 30 09:44:51 minidsp-shd volumio[757]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-35,"mute":false,"disableVolumeControl":false} Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreStateMachine::pushState Aug 30 09:44:51 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioPushState Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:51 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:51 minidsp-shd volumio[757]: info: MRS: Pushing multiroomSync output update for this device Aug 30 09:44:51 minidsp-shd volumio[757]: info: MRS: Pushing multiroomSync output Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=debug msg="connected to ap-gae2.spotify.com:443" Aug 30 09:44:51 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=debug msg="completed keyexchange" Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=debug msg="completed challenge" Aug 30 09:44:51 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=info msg="authenticated AP" username="12******51" Aug 30 09:44:51 minidsp-shd volumio[757]: info: Executing endpoint tc_getconfig Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 30 09:44:51 minidsp-shd volumio-remote-updater[542]: [2026-08-30 09:44:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1788054290 101 Aug 30 09:44:51 minidsp-shd volumio[757]: 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: 4 Aug 30 09:44:51 minidsp-shd volumio[757]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Aug 30 09:44:51 minidsp-shd volumio[757]: info: QobuzConnect: setDeactiveState invoked Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:51 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=info msg="authenticated Login5" username="12******51" Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=info msg="accepted zeroconf from iPhone" username="12******51" Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:51 minidsp-shd volumio[757]: info: Executing endpoint qc_getconfig Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.827 [1459.1459] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 30 09:44:51 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.854 [1459.1459] INFO VolumeManager: [0x2a1d150]: Setting new playback volume: 75 Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.854 [1459.1459] INFO VolumeManager: [0x2a1d150]: Setting new mute state: 0 Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.854 [1459.1459] INFO AudioStreamManager: [0x2a1cea8]: Setting new audio download buffer size: 1048576 Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.854 [1459.1459] INFO QobuzConnect: [0x2a1da18]: Client initialized! Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.854 [1459.1459] INFO SampleApp: Starting Avahi advertising, name: miniDSP SHD, service name: _qobuz-connect._tcp Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=debug msg="dealer connection opened" Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=trace msg="starting accesspoint recv loop" Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=trace msg="starting dealer recv loop" Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=debug msg="received connection id: YTY0MTUyNmMtMjc5...OTdBOENFMDg1Qw==" Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=trace msg="received accesspoint ping" Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 30 09:44:51 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:51+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.906 [1459.1459] INFO LocalConfigManager: [0x2a1cbd0]: Starting Local Configuration server Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.906 [1459.1459] INFO SampleApp: Starting Local configuration server Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.908 [1459.1459] INFO SampleApp: Connected to UNIX socket client 0x2a12ed8 Aug 30 09:44:51 minidsp-shd volumio[757]: xcb_connection_has_error() returned true Aug 30 09:44:51 minidsp-shd qobuz-connect[1459]: 20260830 09:44:51.977 [1459.1459] INFO SampleApp: Playback volume changed: 75 Aug 30 09:44:52 minidsp-shd volumio[757]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 30 09:44:52 minidsp-shd volumio[757]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="put connect state because NEW_DEVICE" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="update volume requested to 46529/65535" Aug 30 09:44:52 minidsp-shd sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=trace msg="received accesspoint pong ack" Aug 30 09:44:52 minidsp-shd sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:52 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:52 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:52 minidsp-shd volumio[757]: info: Starting Shairport Sync Aug 30 09:44:52 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Aug 30 09:44:52 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Aug 30 09:44:52 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Aug 30 09:44:52 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=trace msg="emitting websocket event: volume" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="handling transfer player command from de5051923f69ccf57eb912113b194895559fa555" Aug 30 09:44:52 minidsp-shd systemd[1]: Started RAAT DAEMON. Aug 30 09:44:52 minidsp-shd sudo[1476]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DX71VcjjnyaBQ" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=trace msg="fetched new page 0 with 75 items (list: 75)" uri="spotify:playlist:37i9dQZF1DX71VcjjnyaBQ" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="loading track (paused: true, position: 106988ms)" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:52 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:52 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:52 minidsp-shd volumio[757]: info: Asound.conf file unchanged, so no further update is needed Aug 30 09:44:52 minidsp-shd volumio[757]: info: Output device has changed, restarting MPD Aug 30 09:44:52 minidsp-shd sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 09:44:52 minidsp-shd sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=trace msg="emitting websocket event: will_play" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 439" Aug 30 09:44:52 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="selected format OGG_VORBIS_320 (c33d41766613336969b30edd1a7db10dad831e3b)" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="requested aes key for file c33d41766613336969b30edd1a7db10dad831e3b, gid: 7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:52 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=trace msg="found 2 cdn urls" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:52 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 09:44:52 minidsp-shd sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 09:44:52 minidsp-shd sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:52 minidsp-shd sudo[1491]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:52 minidsp-shd volumio[757]: info: Output device has changed, restarting Shairport Sync Aug 30 09:44:52 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:52 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:52 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 09:44:52 minidsp-shd sudo[1479]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:52 minidsp-shd sudo[1498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 09:44:52 minidsp-shd sudo[1498]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3618" Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 439" Aug 30 09:44:52 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 30 09:44:52 minidsp-shd systemd[1]: mpd.service: Succeeded. Aug 30 09:44:52 minidsp-shd systemd[1]: Stopped Music Player Daemon. Aug 30 09:44:52 minidsp-shd systemd[1]: Starting Music Player Daemon... Aug 30 09:44:52 minidsp-shd volumio[757]: xcb_connection_has_error() returned true Aug 30 09:44:52 minidsp-shd volumio[757]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 09:44:52 minidsp-shd volumio[757]: xcb_connection_has_error() returned true Aug 30 09:44:52 minidsp-shd volumio[757]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 30 09:44:52 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:52 minidsp-shd volumiologrotate[531]: ls: cannot access '/var/log/samba/log.wb-MINIDSP': No such file or directory Aug 30 09:44:52 minidsp-shd volumiologrotate[531]: ls: cannot access 'SHD': No such file or directory Aug 30 09:44:52 minidsp-shd volumio[757]: info: QobuzConnect: setDeactiveState invoked Aug 30 09:44:52 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:52 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:52 minidsp-shd volumio[757]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Aug 30 09:44:52 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:52+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2313" Aug 30 09:44:53 minidsp-shd volumio[757]: info: Preparing to generate the ALSA configuration file Aug 30 09:44:53 minidsp-shd sudo[1515]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 09:44:53 minidsp-shd sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:53 minidsp-shd sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 30 09:44:53 minidsp-shd sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:53 minidsp-shd sudo[1515]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 30 09:44:53 minidsp-shd sudo[1515]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:53 minidsp-shd sudo[1535]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:53 minidsp-shd sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 30 09:44:53 minidsp-shd sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:53 minidsp-shd sudo[1551]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:53 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:53 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:53 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:53 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:53 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:53 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:53 minidsp-shd sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 30 09:44:53 minidsp-shd sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:53 minidsp-shd volumio[757]: info: Raat Daemon started successfully Aug 30 09:44:53 minidsp-shd sudo[1557]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:53 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:53+08:00" level=debug msg="fetched first chunk of 21, total size is 10813612 bytes" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:53 minidsp-shd sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 30 09:44:53 minidsp-shd sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: Not Reporting Auto name since its the default one Aug 30 09:44:53 minidsp-shd volumio[757]: info: RAAT Overriding default device vendor model Aug 30 09:44:53 minidsp-shd volumio[757]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 30 09:44:53 minidsp-shd volumio[757]: info: Reading ALSA contributions from plugins. Aug 30 09:44:53 minidsp-shd qobuz-connect[1459]: 20260830 09:44:53.713 [1459.1459] INFO SampleApp: Stopping Local configuration server Aug 30 09:44:53 minidsp-shd systemd[1]: Stopping Volumio Qobuz Connect Service... Aug 30 09:44:53 minidsp-shd qobuz-connect[1459]: 20260830 09:44:53.725 [1459.1459] INFO SampleApp: shat down connection on UNIX socket Aug 30 09:44:53 minidsp-shd systemd[1]: qobuz-connect.service: Succeeded. Aug 30 09:44:53 minidsp-shd systemd[1]: Stopped Volumio Qobuz Connect Service. Aug 30 09:44:53 minidsp-shd volumio[757]: info: MPD Permissions set Aug 30 09:44:53 minidsp-shd volumio[757]: info: Shairport-Sync Started Aug 30 09:44:53 minidsp-shd volumio[757]: info: TidalConnect service started! Aug 30 09:44:53 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 30 09:44:53 minidsp-shd volumio[757]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 30 09:44:53 minidsp-shd sudo[1560]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:53 minidsp-shd systemd[1]: Started Volumio Qobuz Connect Service. Aug 30 09:44:53 minidsp-shd volumio[757]: info: Executing endpoint restartRAATSocket Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Aug 30 09:44:53 minidsp-shd volumio[757]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Aug 30 09:44:53 minidsp-shd volumio[757]: info: QobuzConnect: setDeactiveState invoked Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::volumioGetState Aug 30 09:44:53 minidsp-shd volumio[757]: info: CorePlayQueue::getTrack 0 Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 09:44:53 minidsp-shd volumio[757]: info: Not Reporting Auto name since its the default one Aug 30 09:44:53 minidsp-shd volumio[757]: info: RAAT Overriding default device vendor model Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="fetched chunk 1/20, size: 524288" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:54 minidsp-shd volumio[757]: info: TidalConnect service stoped! Aug 30 09:44:54 minidsp-shd volumio[757]: info: Initializing connection to go-librespot Websocket Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="fetched chunk 10/20, size: 524288" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:54 minidsp-shd volumio[757]: xcb_connection_has_error() returned true Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="fetched chunk 8/20, size: 524288" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="fetched chunk 2/20, size: 524288" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="fetched chunk 9/20, size: 524288" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="fetched chunk 3/20, size: 524288" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="fetched chunk 7/20, size: 524288" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=trace msg="seek to 106988ms (diff: 71ms, samples: 4718170, bytes: 3791617)" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="created new output device" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=info msg="loaded track \"Follow The Light\" (paused: true, position: 106988ms, duration: 303457ms, prefetched: false)" uri="spotify:track:7EY7ALYld4imSLpvLaLIjd" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=trace msg="emitting websocket event: metadata" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=trace msg="emitting websocket event: active" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="sending successful reply for dealer request" Aug 30 09:44:54 minidsp-shd sudo[1582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 30 09:44:54 minidsp-shd sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="new websocket client" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Aug 30 09:44:54 minidsp-shd volumio[757]: info: Executing endpoint qc_getconfig Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 30 09:44:54 minidsp-shd go-librespot[1313]: time="2026-08-30T09:44:54+08:00" level=trace msg="emitting websocket event: paused" Aug 30 09:44:54 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Aug 30 09:44:54 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Aug 30 09:44:54 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Aug 30 09:44:54 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.840 [1566.1566] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 30 09:44:54 minidsp-shd volumio[757]: info: TidalConnect service stoped! Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.859 [1566.1566] INFO VolumeManager: [0x146b150]: Setting new playback volume: 75 Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.862 [1566.1566] INFO VolumeManager: [0x146b150]: Setting new mute state: 0 Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.863 [1566.1566] INFO AudioStreamManager: [0x146aea8]: Setting new audio download buffer size: 1048576 Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.863 [1566.1566] INFO QobuzConnect: [0x146ba18]: Client initialized! Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.864 [1566.1566] INFO SampleApp: Starting Avahi advertising, name: miniDSP SHD, service name: _qobuz-connect._tcp Aug 30 09:44:54 minidsp-shd systemd[1]: Started RAAT DAEMON. Aug 30 09:44:54 minidsp-shd sudo[1582]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.930 [1566.1566] INFO LocalConfigManager: [0x146abd0]: Starting Local Configuration server Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.931 [1566.1566] INFO SampleApp: Starting Local configuration server Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.932 [1566.1566] INFO SampleApp: Connected to UNIX socket client 0x1460ed8 Aug 30 09:44:54 minidsp-shd volumio[757]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 30 09:44:54 minidsp-shd qobuz-connect[1566]: 20260830 09:44:54.989 [1566.1566] INFO SampleApp: Playback volume changed: 75 Aug 30 09:44:55 minidsp-shd volumio[757]: xcb_connection_has_error() returned true Aug 30 09:44:55 minidsp-shd sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 30 09:44:55 minidsp-shd sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:44:55 minidsp-shd volumio[757]: info: Connection to go-librespot Websocket established Aug 30 09:44:55 minidsp-shd volumio[757]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1DX71VcjjnyaBQ","uri":"spotify:track:7EY7ALYld4imSLpvLaLIjd","play_origin":"playlist/ondemand"}} Aug 30 09:44:55 minidsp-shd volumio[757]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 09:44:55 minidsp-shd volumio[757]: TypeError: Cannot read property 'service' of undefined Aug 30 09:44:55 minidsp-shd volumio[757]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Aug 30 09:44:55 minidsp-shd volumio[757]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Aug 30 09:44:55 minidsp-shd volumio[757]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Aug 30 09:44:55 minidsp-shd volumio[757]: at WebSocket.emit (events.js:400:28) Aug 30 09:44:55 minidsp-shd volumio[757]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Aug 30 09:44:55 minidsp-shd volumio[757]: at Receiver.emit (events.js:400:28) Aug 30 09:44:55 minidsp-shd volumio[757]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Aug 30 09:44:55 minidsp-shd volumio[757]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Aug 30 09:44:55 minidsp-shd volumio[757]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Aug 30 09:44:55 minidsp-shd volumio[757]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Aug 30 09:44:55 minidsp-shd volumio[757]: at writeOrBuffer (internal/streams/writable.js:358:12) Aug 30 09:44:55 minidsp-shd volumio[757]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Aug 30 09:44:55 minidsp-shd volumio[757]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Aug 30 09:44:55 minidsp-shd volumio[757]: at Socket.emit (events.js:400:28) Aug 30 09:44:55 minidsp-shd volumio[757]: at Socket.Readable.read (internal/streams/readable.js:504:10) Aug 30 09:44:55 minidsp-shd volumio[757]: at Socket.read (net.js:638:39) Aug 30 09:44:55 minidsp-shd volumio[757]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 09:44:55 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Aug 30 09:44:55 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Aug 30 09:44:55 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Aug 30 09:44:55 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Aug 30 09:44:55 minidsp-shd systemd[1]: Started RAAT DAEMON. Aug 30 09:44:55 minidsp-shd sudo[1604]: pam_unix(sudo:session): session closed for user root Aug 30 09:44:56 minidsp-shd sudo[1625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-30 09:43 Aug 30 09:44:56 minidsp-shd sudo[1625]: 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="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 11:08:05 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="2435172a9e07e22ce77410ca4817b0fa"