-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-09-02 18:32:37 CEST. -- Sep 02 18:31:48 minidsp-shd ntpd[734]: receive: Unexpected origin timestamp 0xe9a81099.6c7b67b3 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xec619a74.9f14c186 Sep 02 18:31:48 minidsp-shd ntpd[734]: receive: Unexpected origin timestamp 0xe9a81099.6c8d2b19 does not match aorg 0000000000.00000000 from server@176.126.86.247 xmt 0xec619a74.9f1c81c4 Sep 02 18:31:48 minidsp-shd ntpd[734]: receive: Unexpected origin timestamp 0xe9a81099.6c91b497 does not match aorg 0000000000.00000000 from server@194.58.206.148 xmt 0xec619a74.9f3bfb47 Sep 02 18:31:48 minidsp-shd systemd[1]: Starting Daily apt download activities... Sep 02 18:31:48 minidsp-shd volumio[630]: info: [1756830708730] Starting BluetoothController Sep 02 18:31:49 minidsp-shd volumio[630]: info: Loading plugin "alarm-clock"... Sep 02 18:31:49 minidsp-shd volumio[630]: info: Loading plugin "manifestui"... Sep 02 18:31:49 minidsp-shd volumio[630]: info: Loading plugin "metavolumio"... Sep 02 18:31:50 minidsp-shd systemd[1]: apt-daily.service: Succeeded. Sep 02 18:31:50 minidsp-shd systemd[1]: Started Daily apt download activities. Sep 02 18:31:50 minidsp-shd systemd[1]: Starting Daily apt upgrade and clean activities... Sep 02 18:31:51 minidsp-shd systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 02 18:31:51 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. Sep 02 18:31:52 minidsp-shd volumio[630]: info: Loading plugin "airplay_emulation"... Sep 02 18:31:52 minidsp-shd volumio[630]: info: Starting Shairport Sync Sep 02 18:31:52 minidsp-shd volumio[630]: info: Loading plugin "cd_controller"... Sep 02 18:31:53 minidsp-shd volumio[630]: info: Loading plugin "last_100"... Sep 02 18:31:53 minidsp-shd volumio[630]: info: Loading plugin "raat"... Sep 02 18:31:53 minidsp-shd volumio[630]: info: RAAT Plugin loaded Sep 02 18:31:53 minidsp-shd volumio[630]: info: Adding restartRAATSocket REST API Endpoint Sep 02 18:31:53 minidsp-shd volumio[630]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Sep 02 18:31:53 minidsp-shd volumio[630]: info: Loading plugin "streaming_services"... Sep 02 18:31:55 minidsp-shd volumio[630]: info: Starting Streaming Service Transparent Proxy Sep 02 18:31:55 minidsp-shd volumio[630]: info: Loading plugin "tidalconnect"... Sep 02 18:31:56 minidsp-shd volumio[630]: info: Loading plugin "webradio"... Sep 02 18:31:56 minidsp-shd volumio[630]: info: Loading plugin "i2s_dacs"... Sep 02 18:31:56 minidsp-shd volumio[630]: info: I2S DAC not set, start Auto-detection Sep 02 18:31:56 minidsp-shd volumio[630]: info: Loading plugin "volumiodiscovery"... Sep 02 18:31:56 minidsp-shd volumio[630]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 18:31:56 minidsp-shd node[630]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 18:31:56 minidsp-shd node[630]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 18:31:56 minidsp-shd volumio[630]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 18:31:56 minidsp-shd volumio[630]: *** WARNING *** For more information see Sep 02 18:31:56 minidsp-shd volumio[630]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 18:31:56 minidsp-shd volumio[630]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 18:31:56 minidsp-shd volumio[630]: *** WARNING *** For more information see Sep 02 18:31:56 minidsp-shd node[630]: *** WARNING *** For more information see Sep 02 18:31:56 minidsp-shd node[630]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 18:31:56 minidsp-shd node[630]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 18:31:56 minidsp-shd node[630]: *** WARNING *** For more information see Sep 02 18:31:56 minidsp-shd volumio[630]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 18:31:56 minidsp-shd volumio[630]: info: Discovery: Started advertising with name: miniDSP SHD Sep 02 18:31:56 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 18:31:56 minidsp-shd volumio[630]: info: Loading plugin "spop"... Sep 02 18:31:58 minidsp-shd volumio[630]: STREAMING PROXY: Starting server on port 3245 Sep 02 18:31:58 minidsp-shd volumio[630]: Node JS runtime: 14 Sep 02 18:31:59 minidsp-shd volumio[630]: info: Loading plugin "youtube2"... Sep 02 18:31:59 minidsp-shd volumio-remote-updater[461]: [2025-09-02 18:31:59] [connect] Successful connection Sep 02 18:32:00 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. Sep 02 18:32:02 minidsp-shd volumio[630]: info: Loading plugin "ytcr"... Sep 02 18:32:07 minidsp-shd volumio[630]: info: Loading plugin "ytmusic"... Sep 02 18:32:09 minidsp-shd volumio[630]: info: Loading plugin "multiroom"... Sep 02 18:32:12 minidsp-shd volumio[630]: info: Applying required configuration parameters for plugin multiroom Sep 02 18:32:12 minidsp-shd sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 02 18:32:12 minidsp-shd sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:12 minidsp-shd sudo[935]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:12 minidsp-shd volumio[630]: info: MRS: MultiRoom plugin initialized Sep 02 18:32:12 minidsp-shd volumio[630]: info: MRS: STOPPING SNAPCLIENT Sep 02 18:32:12 minidsp-shd volumio[630]: info: MRS: Snap server stop Sep 02 18:32:12 minidsp-shd sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 02 18:32:12 minidsp-shd sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:12 minidsp-shd volumio[630]: info: MRS: STOPPING volumioStreaming Sep 02 18:32:12 minidsp-shd sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 02 18:32:12 minidsp-shd sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:12 minidsp-shd sudo[951]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:12 minidsp-shd sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 02 18:32:12 minidsp-shd volumio[630]: info: Loading plugin "outputs"... Sep 02 18:32:12 minidsp-shd sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:12 minidsp-shd sudo[954]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:12 minidsp-shd volumio[630]: info: Loading plugin "albumart"... Sep 02 18:32:12 minidsp-shd sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 02 18:32:12 minidsp-shd sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:12 minidsp-shd sudo[959]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:12 minidsp-shd sudo[956]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:12 minidsp-shd volumio[630]: info: Loading plugin "ms_surface_dial"... Sep 02 18:32:13 minidsp-shd volumio[630]: Forking 3 albumart workers Sep 02 18:32:13 minidsp-shd volumio[630]: info: Applying required configuration parameters for plugin ms_surface_dial Sep 02 18:32:13 minidsp-shd volumio[630]: info: Plugin example_plugin is not enabled Sep 02 18:32:13 minidsp-shd volumio[630]: info: Loading plugin "hi_res_audio"... Sep 02 18:32:14 minidsp-shd volumio-remote-updater[461]: [2025-09-02 18:32:14] [connect] Successful connection Sep 02 18:32:15 minidsp-shd volumio[630]: Starting albumart workers Sep 02 18:32:15 minidsp-shd volumio[630]: Starting albumart workers Sep 02 18:32:15 minidsp-shd volumio[630]: Starting albumart workers Sep 02 18:32:17 minidsp-shd volumio[630]: info: Applying required configuration parameters for plugin hi_res_audio Sep 02 18:32:17 minidsp-shd volumio[630]: info: Loading plugin "inputs"... Sep 02 18:32:18 minidsp-shd kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Sep 02 18:32:18 minidsp-shd dhcpcd[507]: eth0: carrier lost Sep 02 18:32:18 minidsp-shd dhcpcd[735]: eth0: carrier lost Sep 02 18:32:18 minidsp-shd avahi-daemon[448]: Withdrawing address record for 192.168.1.35 on eth0. Sep 02 18:32:18 minidsp-shd avahi-daemon[448]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.35. Sep 02 18:32:18 minidsp-shd avahi-daemon[448]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 02 18:32:18 minidsp-shd dhcpcd[507]: eth0: deleting route to 192.168.1.0/24 Sep 02 18:32:18 minidsp-shd dhcpcd[507]: eth0: deleting default route via 192.168.1.1 Sep 02 18:32:18 minidsp-shd dhcpcd[735]: eth0: deleting route to 192.168.1.0/24 Sep 02 18:32:18 minidsp-shd dhcpcd[735]: eth0: deleting default route via 192.168.1.1 Sep 02 18:32:18 minidsp-shd volumio[630]: info: Loading plugin "qobuz"... Sep 02 18:32:18 minidsp-shd ifplugd(eth0)[598]: Link beat lost. Sep 02 18:32:19 minidsp-shd ntpd[734]: Deleting interface #3 eth0, 192.168.1.35#123, interface stats: received=100, sent=100, dropped=0, active_time=39 secs Sep 02 18:32:19 minidsp-shd ntpd[734]: 85.24.237.71 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 85.24.237.72 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 162.159.200.1 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 193.182.111.13 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 92.246.137.39 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 45.83.221.52 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 192.36.143.134 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 194.58.202.148 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 194.58.204.20 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 176.126.86.247 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 147.78.228.41 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 194.58.203.148 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 194.58.206.148 local addr 192.168.1.35 -> Sep 02 18:32:19 minidsp-shd ntpd[734]: 194.58.200.20 local addr 192.168.1.35 -> Sep 02 18:32:21 minidsp-shd volumio[630]: info: Loading plugin "tidal"... Sep 02 18:32:22 minidsp-shd kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 02 18:32:22 minidsp-shd dhcpcd[507]: eth0: carrier acquired Sep 02 18:32:22 minidsp-shd dhcpcd[735]: eth0: carrier acquired Sep 02 18:32:22 minidsp-shd dhcpcd[507]: eth0: IAID 85:00:02:94 Sep 02 18:32:22 minidsp-shd dhcpcd[735]: eth0: IAID 85:00:02:94 Sep 02 18:32:22 minidsp-shd dhcpcd[507]: eth0: soliciting a DHCP lease Sep 02 18:32:22 minidsp-shd dhcpcd[735]: eth0: soliciting an IPv6 router Sep 02 18:32:22 minidsp-shd dhcpcd[735]: eth0: soliciting a DHCP lease Sep 02 18:32:22 minidsp-shd dhcpcd[507]: eth0: offered 192.168.1.35 from 192.168.1.1 Sep 02 18:32:22 minidsp-shd dhcpcd[507]: eth0: soliciting an IPv6 router Sep 02 18:32:23 minidsp-shd ifplugd(eth0)[598]: Link beat detected. Sep 02 18:32:23 minidsp-shd dhcpcd[735]: eth0: offered 192.168.1.35 from 192.168.1.1 Sep 02 18:32:23 minidsp-shd dhcpcd[507]: eth0: probing address 192.168.1.35/24 Sep 02 18:32:23 minidsp-shd dhcpcd[735]: eth0: probing address 192.168.1.35/24 Sep 02 18:32:23 minidsp-shd volumio[630]: info: Loading plugin "oem_helper"... Sep 02 18:32:25 minidsp-shd volumio[630]: info: Applying required configuration parameters for plugin oem_helper Sep 02 18:32:25 minidsp-shd volumio[630]: info: Loading plugin "updater_comm"... Sep 02 18:32:25 minidsp-shd volumio[630]: info: Plugin mpdemulation is not enabled Sep 02 18:32:25 minidsp-shd volumio[630]: info: Loading plugin "rest_api"... Sep 02 18:32:25 minidsp-shd volumio[630]: info: Loading plugin "websocket"... Sep 02 18:32:25 minidsp-shd volumio[630]: info: Loading i18n strings for locale en Sep 02 18:32:25 minidsp-shd volumio[630]: Updating browse sources language Sep 02 18:32:25 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::initPlayerControls Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 18:32:26 minidsp-shd volumio[630]: Express server listening on port 3000 Sep 02 18:32:26 minidsp-shd volumio[630]: [Metrics] WebUI: 47s 141.01ms Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreStateMachine::resetVolumioState Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreStateMachine::getcurrentVolume Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreStateMachine::pushState Sep 02 18:32:26 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioPushState Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:32:26 minidsp-shd sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 18:32:26 minidsp-shd sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Sep 02 18:32:26 minidsp-shd sudo[1076]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:26 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Sep 02 18:32:26 minidsp-shd sudo[1079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 18:32:26 minidsp-shd sudo[1079]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:26 minidsp-shd volumio[630]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 02 18:32:26 minidsp-shd volumio[630]: info: Completed loading Core Plugins Sep 02 18:32:26 minidsp-shd volumio[630]: info: Preparing to generate the ALSA configuration file Sep 02 18:32:26 minidsp-shd sudo[1079]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:26 minidsp-shd volumio[630]: info: MRS: Removed streaming files Sep 02 18:32:26 minidsp-shd volumio[630]: info: MRS: volumioStreaming STOPPED Sep 02 18:32:26 minidsp-shd volumio[630]: info: MRS: SNAPSERVER STOPPED Sep 02 18:32:26 minidsp-shd volumio[630]: info: MRS: SNAPCLIENT STOPPED Sep 02 18:32:26 minidsp-shd volumio[630]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 02 18:32:26 minidsp-shd volumio[630]: wlan0 Interface doesn't support scanning. Sep 02 18:32:26 minidsp-shd volumio[630]: info: Cannot use regular scanning, forcing with ap-force Sep 02 18:32:26 minidsp-shd sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 02 18:32:26 minidsp-shd sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:26 minidsp-shd sudo[1084]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:26 minidsp-shd volumio[630]: command failed: No such device (-19) Sep 02 18:32:26 minidsp-shd volumio[630]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 02 18:32:26 minidsp-shd volumio[630]: command failed: No such device (-19) Sep 02 18:32:26 minidsp-shd volumio[630]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 02 18:32:26 minidsp-shd volumio[630]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 02 18:32:26 minidsp-shd volumio[630]: info: Reading ALSA contributions from plugins. Sep 02 18:32:27 minidsp-shd volumio[630]: info: Reloading queue from file Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreStateMachine::setRepeat true single undefined Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreStateMachine::pushState Sep 02 18:32:27 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioPushState Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreStateMachine::setRandom true Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreStateMachine::pushState Sep 02 18:32:27 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioPushState Sep 02 18:32:27 minidsp-shd volumio[630]: info: Setting Device type: NanoPi NEO 3 Sep 02 18:32:27 minidsp-shd volumio[630]: info: Discovery: A device disappeared from network Sep 02 18:32:27 minidsp-shd volumio[630]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 02 18:32:27 minidsp-shd volumio[630]: info: Asound.conf file unchanged, so no further update is needed Sep 02 18:32:27 minidsp-shd volumio[630]: info: Output device has changed, restarting MPD Sep 02 18:32:27 minidsp-shd sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 18:32:27 minidsp-shd sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:27 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Sep 02 18:32:27 minidsp-shd sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 18:32:27 minidsp-shd sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:27 minidsp-shd volumio[630]: info: Output device has changed, restarting Shairport Sync Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:32:27 minidsp-shd sudo[1097]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:27 minidsp-shd sudo[1094]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:27 minidsp-shd sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 18:32:27 minidsp-shd sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:27 minidsp-shd systemd[1]: Listening on mpd.socket. Sep 02 18:32:27 minidsp-shd systemd[1]: Starting Music Player Daemon... Sep 02 18:32:27 minidsp-shd volumio[630]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 18:32:27 minidsp-shd volumio[630]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:27 minidsp-shd volumio[630]: info: ___________ START PLUGINS ___________ Sep 02 18:32:27 minidsp-shd volumio[630]: info: ControllerMpd::onStart: Initializing MPD Sep 02 18:32:27 minidsp-shd volumio[630]: info: Creating MPD Configuration file Sep 02 18:32:27 minidsp-shd sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 18:32:27 minidsp-shd sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:32:27 minidsp-shd volumio[630]: info: [1756830747805] CoreMusicLibrary::Adding element Media Servers Sep 02 18:32:27 minidsp-shd sudo[1110]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:27 minidsp-shd sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 18:32:27 minidsp-shd sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 18:32:27 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 18:32:27 minidsp-shd systemd[1]: mpd.service: Succeeded. Sep 02 18:32:27 minidsp-shd systemd[1]: Stopped Music Player Daemon. Sep 02 18:32:27 minidsp-shd systemd[1]: Starting Music Player Daemon... Sep 02 18:32:27 minidsp-shd volumio[630]: info: Adding Manifest REST API Endpoints Sep 02 18:32:27 minidsp-shd volumio[630]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Sep 02 18:32:27 minidsp-shd volumio[630]: info: Adding METAVOLUMIO REST API Endpoints Sep 02 18:32:27 minidsp-shd volumio[630]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 02 18:32:27 minidsp-shd volumio[630]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 02 18:32:27 minidsp-shd volumio[630]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 02 18:32:27 minidsp-shd volumio[630]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:27 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:32:27 minidsp-shd sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Sep 02 18:32:27 minidsp-shd sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:27 minidsp-shd sudo[1117]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:28 minidsp-shd volumio[630]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:32:28 minidsp-shd volumio[630]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 18:32:28 minidsp-shd volumio[630]: info: Preparing CD Folders Sep 02 18:32:28 minidsp-shd volumio[630]: info: Adding CD REST API Endpoints Sep 02 18:32:28 minidsp-shd volumio[630]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 02 18:32:28 minidsp-shd volumio[630]: info: Starting UDEV Watcher for CD Sep 02 18:32:28 minidsp-shd volumio[630]: info: Detecting CD presence with UDEV Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:32:28 minidsp-shd volumio[630]: info: [1756830748226] CoreMusicLibrary::Adding element Last_100 Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:28 minidsp-shd volumio[630]: info: Starting RAAT Plugin Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Sep 02 18:32:28 minidsp-shd volumio[630]: info: Additional UI Settings Added for plugin music_service/raat Sep 02 18:32:28 minidsp-shd volumio[630]: info: Streaming services startup Sep 02 18:32:28 minidsp-shd volumio[630]: info: Starting Streaming Daemon Sep 02 18:32:28 minidsp-shd dhcpcd[735]: eth0: leased 192.168.1.35 for 86400 seconds Sep 02 18:32:28 minidsp-shd avahi-daemon[448]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.35. Sep 02 18:32:28 minidsp-shd avahi-daemon[448]: New relevant interface eth0.IPv4 for mDNS. Sep 02 18:32:28 minidsp-shd avahi-daemon[448]: Registering new address record for 192.168.1.35 on eth0.IPv4. Sep 02 18:32:28 minidsp-shd dhcpcd[735]: eth0: adding route to 192.168.1.0/24 Sep 02 18:32:28 minidsp-shd dhcpcd[735]: eth0: adding default route via 192.168.1.1 Sep 02 18:32:28 minidsp-shd sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 02 18:32:28 minidsp-shd sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:28 minidsp-shd sudo[1129]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:32:28 minidsp-shd volumio[630]: info: [1756830748546] CoreMusicLibrary::Adding element Webradio Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 18:32:28 minidsp-shd dhcpcd[507]: eth0: leased 192.168.1.35 for 86400 seconds Sep 02 18:32:28 minidsp-shd dhcpcd[507]: eth0: adding route to 192.168.1.0/24 Sep 02 18:32:28 minidsp-shd dhcpcd[507]: eth0: adding default route via 192.168.1.1 Sep 02 18:32:28 minidsp-shd sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 18:32:28 minidsp-shd sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:28 minidsp-shd volumio[630]: info: Creating Spotify config file Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:28 minidsp-shd sudo[1156]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:28 minidsp-shd ntpd[734]: ntpd exiting on signal 15 (Terminated) Sep 02 18:32:28 minidsp-shd systemd[1]: Stopping Network Time Service... Sep 02 18:32:28 minidsp-shd systemd[1]: ntp.service: Succeeded. Sep 02 18:32:28 minidsp-shd systemd[1]: Stopped Network Time Service. Sep 02 18:32:28 minidsp-shd systemd[1]: Starting Network Time Service... Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:32:28 minidsp-shd volumio[630]: info: [1756830748970] CoreMusicLibrary::Adding element YouTube2 Sep 02 18:32:28 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:28 minidsp-shd volumio[630]: Cannot find translation for source YouTube2 Sep 02 18:32:29 minidsp-shd ntpd[1196]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 02 18:32:29 minidsp-shd ntpd[1196]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 02 18:32:29 minidsp-shd systemd[1]: Started Network Time Service. Sep 02 18:32:29 minidsp-shd ntpd[1204]: proto: precision = 1.166 usec (-20) Sep 02 18:32:29 minidsp-shd ntpd[1204]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 02 18:32:29 minidsp-shd ntpd[1204]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 02 18:32:29 minidsp-shd ntpd[1204]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 980 days ago Sep 02 18:32:29 minidsp-shd ntpd[1204]: Listen and drop on 0 v6wildcard [::]:123 Sep 02 18:32:29 minidsp-shd ntpd[1204]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 02 18:32:29 minidsp-shd ntpd[1204]: Listen normally on 2 lo 127.0.0.1:123 Sep 02 18:32:29 minidsp-shd ntpd[1204]: Listen normally on 3 eth0 192.168.1.35:123 Sep 02 18:32:29 minidsp-shd ntpd[1204]: Listening on routing socket on fd #20 for interface updates Sep 02 18:32:29 minidsp-shd ntpd[1204]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 02 18:32:29 minidsp-shd ntpd[1204]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 02 18:32:29 minidsp-shd ntpd[1204]: ntpd exiting on signal 15 (Terminated) Sep 02 18:32:29 minidsp-shd systemd[1]: Stopping Network Time Service... Sep 02 18:32:29 minidsp-shd systemd[1]: ntp.service: Succeeded. Sep 02 18:32:29 minidsp-shd systemd[1]: Stopped Network Time Service. Sep 02 18:32:29 minidsp-shd systemd[1]: Starting Network Time Service... Sep 02 18:32:29 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:32:29 minidsp-shd volumio[630]: info: [1756830749180] CoreMusicLibrary::Adding element YouTube Music Sep 02 18:32:29 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:29 minidsp-shd volumio[630]: Cannot find translation for source YouTube2 Sep 02 18:32:29 minidsp-shd volumio[630]: Cannot find translation for source YouTube Music Sep 02 18:32:29 minidsp-shd ntpd[1209]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 02 18:32:29 minidsp-shd ntpd[1209]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 02 18:32:29 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Sep 02 18:32:29 minidsp-shd systemd[1]: Started Network Time Service. Sep 02 18:32:29 minidsp-shd ntpd[1215]: proto: precision = 1.166 usec (-20) Sep 02 18:32:29 minidsp-shd ntpd[1215]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 02 18:32:29 minidsp-shd ntpd[1215]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 02 18:32:29 minidsp-shd ntpd[1215]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 980 days ago Sep 02 18:32:29 minidsp-shd ntpd[1215]: Listen and drop on 0 v6wildcard [::]:123 Sep 02 18:32:29 minidsp-shd ntpd[1215]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 02 18:32:29 minidsp-shd ntpd[1215]: Listen normally on 2 lo 127.0.0.1:123 Sep 02 18:32:29 minidsp-shd ntpd[1215]: Listen normally on 3 eth0 192.168.1.35:123 Sep 02 18:32:29 minidsp-shd ntpd[1215]: Listening on routing socket on fd #20 for interface updates Sep 02 18:32:29 minidsp-shd ntpd[1215]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 02 18:32:29 minidsp-shd ntpd[1215]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 02 18:32:29 minidsp-shd volumio[630]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 02 18:32:29 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:29 minidsp-shd dbus-daemon[438]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=1000 pid=630 comm="/usr/bin/node /volumio/index.js ") Sep 02 18:32:29 minidsp-shd volumio[630]: error: Hi Res Audio Failed Login: Missing Login Data Sep 02 18:32:29 minidsp-shd volumio[630]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 02 18:32:29 minidsp-shd systemd[1]: Starting Bluetooth service... Sep 02 18:32:29 minidsp-shd volumio[630]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 02 18:32:29 minidsp-shd bluetoothd[1220]: Bluetooth daemon 5.23 Sep 02 18:32:29 minidsp-shd dbus-daemon[438]: [system] Successfully activated service 'org.bluez' Sep 02 18:32:29 minidsp-shd systemd[1]: Started Bluetooth service. Sep 02 18:32:29 minidsp-shd bluetoothd[1220]: Starting SDP server Sep 02 18:32:29 minidsp-shd bluetoothd[1220]: kernel lacks bnep-protocol support Sep 02 18:32:29 minidsp-shd bluetoothd[1220]: System does not support network plugin Sep 02 18:32:29 minidsp-shd dbus-daemon[438]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.11' (uid=0 pid=1220 comm="/usr/local/libexec/bluetooth/bluetoothd ") Sep 02 18:32:29 minidsp-shd bluetoothd[1220]: Bluetooth management interface 1.14 initialized Sep 02 18:32:29 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 18:32:29 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:32:29 minidsp-shd systemd[1]: Starting Hostname Service... Sep 02 18:32:29 minidsp-shd volumio[630]: info: Volumio Calling Home Sep 02 18:32:29 minidsp-shd volumio[630]: info: Stopping AccessToken refresher cron for QOBUZ Sep 02 18:32:29 minidsp-shd dbus-daemon[438]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 02 18:32:29 minidsp-shd systemd[1]: Started Hostname Service. Sep 02 18:32:29 minidsp-shd volumio-remote-updater[461]: [2025-09-02 18:32:29] [connect] Successful connection Sep 02 18:32:30 minidsp-shd volumio[630]: info: AccessToken refresher cron started for QOBUZ Sep 02 18:32:30 minidsp-shd volumio[630]: info: Stopping AccessToken refresher cron Sep 02 18:32:30 minidsp-shd volumio[630]: info: AccessToken refresher cron started Sep 02 18:32:30 minidsp-shd volumio[630]: info: Adding TIDAL REST API Endpoints Sep 02 18:32:30 minidsp-shd volumio[630]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 02 18:32:30 minidsp-shd volumio[630]: info: Adding QOBUZ REST API Endpoints Sep 02 18:32:30 minidsp-shd volumio[630]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 02 18:32:30 minidsp-shd ntpd[1215]: Soliciting pool server 194.58.200.20 Sep 02 18:32:30 minidsp-shd volumio[630]: Unhandled rejection Error: No sockets available, cannot start. Sep 02 18:32:30 minidsp-shd volumio[630]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 02 18:32:30 minidsp-shd volumio[630]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 02 18:32:30 minidsp-shd volumio[630]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 02 18:32:30 minidsp-shd volumio[630]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 02 18:32:30 minidsp-shd volumio[630]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 02 18:32:30 minidsp-shd volumio[630]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 02 18:32:30 minidsp-shd volumio[630]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 02 18:32:30 minidsp-shd volumio[630]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 02 18:32:30 minidsp-shd volumio[630]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 02 18:32:30 minidsp-shd volumio[630]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 02 18:32:30 minidsp-shd volumio[630]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 02 18:32:30 minidsp-shd volumio[630]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 02 18:32:30 minidsp-shd volumio[630]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 02 18:32:30 minidsp-shd volumio[630]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 02 18:32:30 minidsp-shd volumio[630]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 02 18:32:30 minidsp-shd volumio[630]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 02 18:32:30 minidsp-shd volumio[630]: info: Serial port opened successfully Sep 02 18:32:30 minidsp-shd volumio[630]: error: Cannot start Volumio Streaming Daemon Sep 02 18:32:30 minidsp-shd volumio[630]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 02 18:32:30 minidsp-shd volumio[630]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 02 18:32:30 minidsp-shd volumio[630]: info: RAAT Albumart path created successfully Sep 02 18:32:30 minidsp-shd volumio[630]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Sep 02 18:32:30 minidsp-shd volumio[630]: Can't get device info: No such device Sep 02 18:32:30 minidsp-shd volumio[630]: info: MPD Permissions set Sep 02 18:32:30 minidsp-shd volumio[630]: info: MPD Permissions set Sep 02 18:32:30 minidsp-shd volumio[630]: info: Upmpdcli Daemon Started Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioGetState Sep 02 18:32:30 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:30 minidsp-shd volumio[630]: info: msSurfaceDial volumioupdatevolume callback: {"vol":68,"dbVolume":-40.5,"disableVolumeControl":false} Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreStateMachine::pushState Sep 02 18:32:30 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioPushState Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Sep 02 18:32:30 minidsp-shd volumio[630]: info: Ignoring ROON Volume update because of undefined data Sep 02 18:32:30 minidsp-shd volumio[630]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Sep 02 18:32:30 minidsp-shd volumio[630]: info: [msSurfaceDial init()] check /org/bluez Sep 02 18:32:30 minidsp-shd volumio[630]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Sep 02 18:32:30 minidsp-shd volumio[630]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::Reload Ui Sep 02 18:32:30 minidsp-shd volumio-remote-updater[461]: [2025-09-02 18:32:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756830749 101 Sep 02 18:32:30 minidsp-shd volumio[630]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Sep 02 18:32:30 minidsp-shd volumio[630]: info: Volumio called home Sep 02 18:32:30 minidsp-shd volumio[630]: info: Spotify config file written Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Sep 02 18:32:30 minidsp-shd volumio[630]: info: updateDSP function in raat called! Sep 02 18:32:30 minidsp-shd volumio[630]: info: Updating RAAT Signal Path Sep 02 18:32:30 minidsp-shd volumio[630]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Sep 02 18:32:30 minidsp-shd volumio[630]: info: Reconfiguring and Restarting RAAT Plugin Sep 02 18:32:30 minidsp-shd sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 18:32:30 minidsp-shd sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:30 minidsp-shd systemd[1]: Started go-librespot Daemon. Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 18:32:30 minidsp-shd sudo[1238]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:30 minidsp-shd go-librespot[1244]: Librespot-go daemon starting... Sep 02 18:32:30 minidsp-shd volumio[630]: info: Not Reporting Auto name since its the default one Sep 02 18:32:30 minidsp-shd volumio[630]: info: RAAT Overriding default device vendor model Sep 02 18:32:30 minidsp-shd volumio[630]: info: Adding MINIDSP Inputs Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:32:30 minidsp-shd volumio[630]: info: [1756830750801] CoreMusicLibrary::Adding element Inputs Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:30 minidsp-shd volumio[630]: Cannot find translation for source YouTube2 Sep 02 18:32:30 minidsp-shd volumio[630]: Cannot find translation for source YouTube Music Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:32:30 minidsp-shd volumio[630]: info: [1756830750814] CoreMusicLibrary::Adding element Presets Sep 02 18:32:30 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:30 minidsp-shd volumio[630]: Cannot find translation for source YouTube2 Sep 02 18:32:30 minidsp-shd volumio[630]: Cannot find translation for source YouTube Music Sep 02 18:32:30 minidsp-shd volumio[630]: Cannot find translation for source Presets Sep 02 18:32:30 minidsp-shd volumio[630]: (node:630) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Sep 02 18:32:30 minidsp-shd volumio[630]: (Use `node --trace-deprecation ...` to show where the warning was created) Sep 02 18:32:30 minidsp-shd volumio[630]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 02 18:32:31 minidsp-shd sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:31 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:31+02:00" level=info msg="generated new device id: 404f0bc8ac60bc847685ada94f23dde81e11cac4" Sep 02 18:32:31 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:31+02:00" level=debug msg="stored credentials found for niklasala" Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 02 18:32:31 minidsp-shd ntpd[1215]: Soliciting pool server 194.58.206.148 Sep 02 18:32:31 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Sep 02 18:32:31 minidsp-shd ntpd[1215]: Soliciting pool server 194.58.206.20 Sep 02 18:32:31 minidsp-shd sudo[1252]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 02 18:32:31 minidsp-shd sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:31 minidsp-shd volumiobt[1258]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 02 18:32:31 minidsp-shd sudo[1261]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:31 minidsp-shd volumio[630]: info: No need to fix Spotify hosts Sep 02 18:32:31 minidsp-shd volumio[630]: info: Discovery: adding 293f6276-9c56-44da-a626-28158b0dad31 Sep 02 18:32:31 minidsp-shd volumio[630]: info: Discovery: Found device miniDSP SHD Sep 02 18:32:31 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioGetState Sep 02 18:32:31 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:31 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output for this device Sep 02 18:32:31 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output Sep 02 18:32:31 minidsp-shd volumio[630]: info: Adding audio output: Sep 02 18:32:31 minidsp-shd volumio[630]: info: Adding audio output: Sep 02 18:32:31 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 02 18:32:31 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:31+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 02 18:32:31 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:31+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 02 18:32:31 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:31+02:00" level=debug msg="zeroconf server listening on port 33619" Sep 02 18:32:32 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:32+02:00" level=debug msg="obtained new client token: AAAsFsdxGE8C4TXIfaNsFIZDWn+u93ZeqDaDBALYdvk21CqJPZ7kab1ZcHkw9kDoaORPJlXMYnmuSjBfldXxjNi5PH2JUfB1g2Q6FvAFRvhYikkzNHuzvtfDVsoQZTbCmF4Ws8qFGcPJYByTiXR1KpUdlNA6huWuRDlOF7yr8CwPkfvMliNADHx8U5Cty574+A7F7S5ADDi+ZNmSzWuZA0P/ud5H0aBqgq6bhAkSDPkI0x8uQCthR2NCxUc=" Sep 02 18:32:32 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:32+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Sep 02 18:32:32 minidsp-shd volumio[630]: info: TidalConnect service stoped! Sep 02 18:32:32 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:32+02:00" level=debug msg="completed keyexchange" Sep 02 18:32:32 minidsp-shd volumio[630]: info: Volumio BT Module successfully started Sep 02 18:32:32 minidsp-shd ntpd[1215]: Soliciting pool server 194.58.207.148 Sep 02 18:32:32 minidsp-shd ntpd[1215]: Soliciting pool server 176.126.86.247 Sep 02 18:32:32 minidsp-shd ntpd[1215]: Soliciting pool server 194.58.204.20 Sep 02 18:32:32 minidsp-shd volumio[630]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 18:32:32 minidsp-shd volumio[630]: SPOTIFY: BQBIj-hDLNZep5gwkVc1IA2QLgyRfaqicWtxcL1-gBMyzXEKCSV_jNXqswpeV2ItKd4Js-n49Qmvq6Sspq4Q0s2Aho9zPBXu83UGbUo5tOuGSB_yWPvqsWJbjz15-JwmSJtKiJnbF8nUSCZoOfbqnxvyKQ92A7juEjuERe_cJMwl8iEpzSyjA0xhNjWX1Np51c2YNViMAoTsc9w8JpQD-lhVpZy-NYq0MDu5obi5G9QDT1cgro4E-a5ppsbNDlKUHFNXJU8lZmcZ9YCPFvRT7bl7TUM5wic1ZI2c Sep 02 18:32:32 minidsp-shd volumio[630]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 18:32:32 minidsp-shd volumio[630]: info: New Spotify access token = BQBIj-hDLNZep5gwkVc1IA2QLgyRfaqicWtxcL1-gBMyzXEKCSV_jNXqswpeV2ItKd4Js-n49Qmvq6Sspq4Q0s2Aho9zPBXu83UGbUo5tOuGSB_yWPvqsWJbjz15-JwmSJtKiJnbF8nUSCZoOfbqnxvyKQ92A7juEjuERe_cJMwl8iEpzSyjA0xhNjWX1Np51c2YNViMAoTsc9w8JpQD-lhVpZy-NYq0MDu5obi5G9QDT1cgro4E-a5ppsbNDlKUHFNXJU8lZmcZ9YCPFvRT7bl7TUM5wic1ZI2c Sep 02 18:32:32 minidsp-shd volumio[630]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 18:32:32 minidsp-shd volumio[630]: info: Discovery: this is already registered, 293f6276-9c56-44da-a626-28158b0dad31 Sep 02 18:32:32 minidsp-shd volumio[630]: info: Discovery: Found device miniDSP SHD Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioGetState Sep 02 18:32:32 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:32 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:32 minidsp-shd volumio[630]: Cannot find translation for source YouTube2 Sep 02 18:32:32 minidsp-shd volumio[630]: Cannot find translation for source YouTube Music Sep 02 18:32:32 minidsp-shd volumio[630]: Cannot find translation for source Presets Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioStop Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreStateMachine::stop Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 18:32:32 minidsp-shd volumio[630]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"SPDIF","disableUiControls":true,"albumart":"/albumart"} Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 18:32:32 minidsp-shd sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 02 18:32:32 minidsp-shd sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 02 18:32:32 minidsp-shd volumio[630]: info: Enabling external Volume Control Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:32:32 minidsp-shd volumio[630]: info: Starting Shairport Sync Sep 02 18:32:32 minidsp-shd volumiobt[1258]: pulseaudio: no process found Sep 02 18:32:32 minidsp-shd sudo[1312]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:32 minidsp-shd volumio[630]: info: Starting Shairport Sync Sep 02 18:32:32 minidsp-shd sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 18:32:32 minidsp-shd sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:32 minidsp-shd volumio[630]: info: Starting Shairport Sync Sep 02 18:32:32 minidsp-shd dbus-daemon[438]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.18' (uid=1000 pid=1326 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 02 18:32:32 minidsp-shd sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 18:32:32 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:32+02:00" level=debug msg="completed challenge" Sep 02 18:32:32 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 02 18:32:32 minidsp-shd sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:32 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:32+02:00" level=debug msg="authenticated as niklasala" Sep 02 18:32:32 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 02 18:32:32 minidsp-shd sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 18:32:32 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 18:32:32 minidsp-shd sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:32 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Sep 02 18:32:32 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 18:32:32 minidsp-shd dbus-daemon[438]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 02 18:32:32 minidsp-shd rtkit-daemon[1330]: Successfully called chroot. Sep 02 18:32:32 minidsp-shd rtkit-daemon[1330]: Successfully dropped privileges. Sep 02 18:32:32 minidsp-shd rtkit-daemon[1330]: Successfully limited resources. Sep 02 18:32:32 minidsp-shd rtkit-daemon[1330]: Running. Sep 02 18:32:32 minidsp-shd dbus-daemon[438]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.19' (uid=0 pid=1330 comm="/usr/lib/rtkit/rtkit-daemon ") Sep 02 18:32:32 minidsp-shd rtkit-daemon[1330]: Canary thread running. Sep 02 18:32:32 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 02 18:32:32 minidsp-shd rtkit-daemon[1330]: Watchdog thread running. Sep 02 18:32:32 minidsp-shd volumio[630]: xcb_connection_has_error() returned true Sep 02 18:32:32 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:32+02:00" level=debug msg="authenticated as niklasala" Sep 02 18:32:32 minidsp-shd systemd[1]: Starting Authorization Manager... Sep 02 18:32:32 minidsp-shd volumio[630]: info: MRS: Found cast device: OLED65C26LD-3f7e1c945e2a78350cd370ba7f928617 Sep 02 18:32:32 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 18:32:32 minidsp-shd sudo[1320]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:32 minidsp-shd sudo[1323]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:32 minidsp-shd sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 02 18:32:32 minidsp-shd volumio[630]: info: Adding audio output: Sep 02 18:32:32 minidsp-shd sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="dealer connection opened" Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="initializing zeroconf session, username: niklasala" Sep 02 18:32:33 minidsp-shd sudo[1328]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="received connection id: ZmMzMWYzMjYtZTIwZS00NjBkLWI1NmMtOGVhYjY4ZWRlOWMxK2RlYWxlcit0Y3A6Ly8wYWIxNTI3Mi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArOTAwRkJBRUUzRTExNEIxRDYzNkJGQURBQjc5NjlCNjNBN0NBNjI4ODI3MzI5MkY1MzE3RjdERDE3QTI2MTEwMA==" Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 18:32:33 minidsp-shd systemd[1]: Started RAAT DAEMON. Sep 02 18:32:33 minidsp-shd volumio[630]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 18:32:33 minidsp-shd sudo[1337]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:33 minidsp-shd volumio[630]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 18:32:33 minidsp-shd volumio[630]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 18:32:33 minidsp-shd ntpd[1215]: Soliciting pool server 194.58.202.148 Sep 02 18:32:33 minidsp-shd ntpd[1215]: Soliciting pool server 162.159.200.1 Sep 02 18:32:33 minidsp-shd ntpd[1215]: Soliciting pool server 45.83.221.52 Sep 02 18:32:33 minidsp-shd ntpd[1215]: Soliciting pool server 192.121.108.100 Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 18:32:33 minidsp-shd go-librespot[1244]: time="2025-09-02T18:32:33+02:00" level=debug msg="autoplay enabled: false" Sep 02 18:32:33 minidsp-shd volumio[630]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 18:32:33 minidsp-shd volumio[630]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 18:32:33 minidsp-shd volumio[630]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 18:32:33 minidsp-shd polkitd[1336]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 02 18:32:33 minidsp-shd dbus-daemon[438]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 02 18:32:33 minidsp-shd systemd[1]: Started Authorization Manager. Sep 02 18:32:33 minidsp-shd volumio[630]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Sep 02 18:32:33 minidsp-shd volumio[630]: info: CoreCommandRouter::servicePushState Sep 02 18:32:33 minidsp-shd volumio[630]: info: CoreStateMachine::pushState Sep 02 18:32:33 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 18:32:33 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioPushState Sep 02 18:32:33 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output update for this device Sep 02 18:32:33 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output Sep 02 18:32:33 minidsp-shd volumio[630]: info: Raat Daemon started successfully Sep 02 18:32:33 minidsp-shd volumio[630]: info: Shairport-Sync Started Sep 02 18:32:33 minidsp-shd volumio[630]: Error adding Membership: Error: addMembership EINVAL Sep 02 18:32:33 minidsp-shd volumio[630]: info: Shairport-Sync Started Sep 02 18:32:33 minidsp-shd volumio[630]: info: Shairport-Sync Started Sep 02 18:32:33 minidsp-shd volumio[630]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 02 18:32:33 minidsp-shd volumio[630]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 02 18:32:33 minidsp-shd volumio[630]: info: MRS: Found cast device: Chromecast-Ultra-b31c9f7d245b3dd321696a0d314147ca Sep 02 18:32:33 minidsp-shd volumio[630]: info: Adding audio output: Sep 02 18:32:33 minidsp-shd sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 02 18:32:33 minidsp-shd sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:32:34 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Sep 02 18:32:34 minidsp-shd sudo[1357]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:34 minidsp-shd ntpd[1215]: Soliciting pool server 162.159.200.123 Sep 02 18:32:34 minidsp-shd ntpd[1215]: Soliciting pool server 85.24.237.71 Sep 02 18:32:34 minidsp-shd ntpd[1215]: Soliciting pool server 85.24.237.71 Sep 02 18:32:34 minidsp-shd volumio[630]: info: Executing endpoint restartRAATSocket Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 02 18:32:34 minidsp-shd volumio[630]: info: MRS: Getting audio outputs on start Sep 02 18:32:34 minidsp-shd volumio[630]: info: MRS: Requesting all other devices output Sep 02 18:32:34 minidsp-shd pulseaudio[1326]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 02 18:32:34 minidsp-shd pulseaudio[1326]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 02 18:32:34 minidsp-shd volumiobt[1258]: Applying permissions Sep 02 18:32:34 minidsp-shd volumiobt[1258]: Setting BT discoverable and pairable Sep 02 18:32:34 minidsp-shd pulseaudio[1326]: 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 Sep 02 18:32:34 minidsp-shd volumiobt[1258]: [38B blob data] Sep 02 18:32:34 minidsp-shd volumiobt[1258]: Invalid command Sep 02 18:32:34 minidsp-shd volumiobt[1258]: [bluetooth]# agent on Sep 02 18:32:34 minidsp-shd volumiobt[1258]: [bluetooth]# discoverable on Sep 02 18:32:34 minidsp-shd volumiobt[1258]: No default controller available Sep 02 18:32:34 minidsp-shd volumiobt[1258]: [bluetooth]# pairable on Sep 02 18:32:34 minidsp-shd volumiobt[1258]: No default controller available Sep 02 18:32:34 minidsp-shd volumiobt[1258]: [bluetooth]# agent NoInputNoOutput Sep 02 18:32:34 minidsp-shd volumiobt[1258]: Failed to register agent object Sep 02 18:32:34 minidsp-shd volumiobt[1258]: [bluetooth]# default-agent Sep 02 18:32:34 minidsp-shd volumiobt[1258]: No agent is registered Sep 02 18:32:34 minidsp-shd volumiobt[1258]: [bluetooth]# quit Sep 02 18:32:34 minidsp-shd volumiobt[1258]: [61B blob data] Sep 02 18:32:34 minidsp-shd volumiobt[1258]: [62B blob data] Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioGetState Sep 02 18:32:34 minidsp-shd volumio[630]: info: msSurfaceDial volumioupdatevolume callback: {"vol":68,"dbVolume":-40.5,"mute":false,"disableVolumeControl":false} Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreStateMachine::pushState Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioPushState Sep 02 18:32:34 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output update for this device Sep 02 18:32:34 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output Sep 02 18:32:34 minidsp-shd volumio[630]: error: Help! Some callbacks for volumioPushState are crashing! Sep 02 18:32:34 minidsp-shd volumio[630]: error: Cannot read property 'sendVolumeMute' of undefined Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioGetState Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioGetState Sep 02 18:32:34 minidsp-shd volumio[630]: info: msSurfaceDial volumioupdatevolume callback: {"vol":68,"dbVolume":-40.5,"mute":false,"disableVolumeControl":false} Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreStateMachine::pushState Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioPushState Sep 02 18:32:34 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output update for this device Sep 02 18:32:34 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output Sep 02 18:32:34 minidsp-shd volumio[630]: error: Help! Some callbacks for volumioPushState are crashing! Sep 02 18:32:34 minidsp-shd volumio[630]: error: Cannot read property 'sendVolumeMute' of undefined Sep 02 18:32:34 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Sep 02 18:32:34 minidsp-shd volumio[630]: info: go-librespot daemon successfully initialized Sep 02 18:32:34 minidsp-shd volumio[630]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Sep 02 18:32:35 minidsp-shd volumio[630]: info: Executing endpoint tc_getconfig Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 02 18:32:35 minidsp-shd vtcs[1359]: STARTING TidalConnect services, version: 1.3.0.19 Sep 02 18:32:35 minidsp-shd ntpd[1215]: Soliciting pool server 98.128.175.45 Sep 02 18:32:35 minidsp-shd ntpd[1215]: Soliciting pool server 2001:678:8::123 Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioGetState Sep 02 18:32:35 minidsp-shd vtcs[1359]: STARTED TidalConnect services. Sep 02 18:32:35 minidsp-shd volumio[630]: SPOTIFY: User informations: {"country":"SE","display_name":"niklasala","email":"niklas.ala@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/niklasala"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/niklasala","id":"niklasala","images":[],"product":"premium","type":"user","uri":"spotify:user:niklasala"} Sep 02 18:32:35 minidsp-shd volumio[630]: info: Spotify Successfully logged in Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:32:35 minidsp-shd volumio[630]: info: [1756830755508] CoreMusicLibrary::Adding element Spotify Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:32:35 minidsp-shd volumio[630]: Cannot find translation for source YouTube2 Sep 02 18:32:35 minidsp-shd volumio[630]: Cannot find translation for source YouTube Music Sep 02 18:32:35 minidsp-shd volumio[630]: Cannot find translation for source Presets Sep 02 18:32:35 minidsp-shd volumio[630]: Cannot find translation for source Spotify Sep 02 18:32:35 minidsp-shd volumio[630]: info: Executing endpoint tc_connect Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 02 18:32:35 minidsp-shd volumio[630]: info: Connecting to TidalConnect Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreCommandRouter::servicePushState Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreStateMachine::pushState Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioPushState Sep 02 18:32:35 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output update for this device Sep 02 18:32:35 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output Sep 02 18:32:35 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:35 minidsp-shd volumio[630]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreCommandRouter::servicePushState Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreStateMachine::pushState Sep 02 18:32:35 minidsp-shd volumio[630]: info: CoreCommandRouter::volumioPushState Sep 02 18:32:35 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output update for this device Sep 02 18:32:35 minidsp-shd volumio[630]: info: MRS: Pushing multiroomSync output Sep 02 18:32:35 minidsp-shd volumio[630]: info: CorePlayQueue::getTrack 0 Sep 02 18:32:35 minidsp-shd volumio[630]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Sep 02 18:32:36 minidsp-shd ntpd[1215]: Soliciting pool server 85.24.237.72 Sep 02 18:32:36 minidsp-shd volumio[630]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 02 18:32:36 minidsp-shd volumio[630]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 18:32:36 minidsp-shd volumio[630]: Error: bind EADDRINUSE 192.168.1.35:50133 Sep 02 18:32:36 minidsp-shd volumio[630]: at dgram.js:357:20 Sep 02 18:32:36 minidsp-shd volumio[630]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Sep 02 18:32:36 minidsp-shd volumio[630]: errno: -98, Sep 02 18:32:36 minidsp-shd volumio[630]: code: 'EADDRINUSE', Sep 02 18:32:36 minidsp-shd volumio[630]: syscall: 'bind', Sep 02 18:32:36 minidsp-shd volumio[630]: address: '192.168.1.35', Sep 02 18:32:36 minidsp-shd volumio[630]: port: 50133 Sep 02 18:32:36 minidsp-shd volumio[630]: } Sep 02 18:32:36 minidsp-shd volumio[630]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 18:32:37 minidsp-shd mpd[1116]: Sep 02 18:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 18:32:37 minidsp-shd systemd[1]: Started Music Player Daemon. Sep 02 18:32:37 minidsp-shd sudo[1101]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:37 minidsp-shd sudo[1112]: pam_unix(sudo:session): session closed for user root Sep 02 18:32:37 minidsp-shd sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 18:31 Sep 02 18:32:37 minidsp-shd sudo[1393]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"