-- Logs begin at Thu 2024-10-03 06:11:16 -05, end at Thu 2024-10-10 07:49:32 -05. -- Oct 10 07:48:00 minidsp-shd volumio[627]: info: RAAT Plugin loaded Oct 10 07:48:00 minidsp-shd volumio[627]: info: Adding restartRAATSocket REST API Endpoint Oct 10 07:48:00 minidsp-shd volumio[627]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 10 07:48:00 minidsp-shd volumio[627]: info: Loading plugin "streaming_services"... Oct 10 07:48:01 minidsp-shd systemd[1]: apt-daily.service: Succeeded. Oct 10 07:48:01 minidsp-shd systemd[1]: Started Daily apt download activities. Oct 10 07:48:01 minidsp-shd systemd[1]: Starting Daily apt upgrade and clean activities... Oct 10 07:48:02 minidsp-shd systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 10 07:48:02 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. Oct 10 07:48:02 minidsp-shd volumio[627]: info: Starting Streaming Service Transparent Proxy Oct 10 07:48:02 minidsp-shd volumio[627]: info: Loading plugin "tidalconnect"... Oct 10 07:48:03 minidsp-shd volumio[627]: info: Loading plugin "webradio"... Oct 10 07:48:03 minidsp-shd volumio[627]: info: Loading plugin "i2s_dacs"... Oct 10 07:48:03 minidsp-shd volumio[627]: info: I2S DAC not set, start Auto-detection Oct 10 07:48:03 minidsp-shd volumio[627]: info: Loading plugin "volumiodiscovery"... Oct 10 07:48:03 minidsp-shd volumio[627]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 07:48:03 minidsp-shd volumio[627]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 07:48:03 minidsp-shd node[627]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 07:48:03 minidsp-shd volumio[627]: *** WARNING *** For more information see Oct 10 07:48:03 minidsp-shd volumio[627]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 07:48:03 minidsp-shd volumio[627]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 07:48:03 minidsp-shd volumio[627]: *** WARNING *** For more information see Oct 10 07:48:03 minidsp-shd node[627]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 07:48:03 minidsp-shd node[627]: *** WARNING *** For more information see Oct 10 07:48:03 minidsp-shd node[627]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 07:48:03 minidsp-shd node[627]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 07:48:03 minidsp-shd node[627]: *** WARNING *** For more information see Oct 10 07:48:03 minidsp-shd volumio[627]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 10 07:48:03 minidsp-shd volumio[627]: info: Discovery: Started advertising with name: miniDSP SHD Oct 10 07:48:03 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 07:48:03 minidsp-shd volumio[627]: info: Loading plugin "soundcloud"... Oct 10 07:48:04 minidsp-shd volumio[627]: info: Loading plugin "spop"... Oct 10 07:48:05 minidsp-shd volumio[627]: STREAMING PROXY: Starting server on port 3245 Oct 10 07:48:05 minidsp-shd volumio[627]: Node JS runtime: 14 Oct 10 07:48:07 minidsp-shd volumio[627]: info: Loading plugin "multiroom"... Oct 10 07:48:08 minidsp-shd volumio-remote-updater[458]: [2024-10-10 07:48:08] [connect] Successful connection Oct 10 07:48:08 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. Oct 10 07:48:09 minidsp-shd volumio[627]: info: Applying required configuration parameters for plugin multiroom Oct 10 07:48:10 minidsp-shd sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 10 07:48:10 minidsp-shd sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:10 minidsp-shd sudo[923]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:10 minidsp-shd volumio[627]: info: MRS: MultiRoom plugin initialized Oct 10 07:48:10 minidsp-shd volumio[627]: info: MRS: STOPPING SNAPCLIENT Oct 10 07:48:10 minidsp-shd volumio[627]: info: MRS: Snap server stop Oct 10 07:48:10 minidsp-shd sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 10 07:48:10 minidsp-shd sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:10 minidsp-shd volumio[627]: info: MRS: STOPPING volumioStreaming Oct 10 07:48:10 minidsp-shd sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 10 07:48:10 minidsp-shd sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:10 minidsp-shd volumio[627]: info: Loading plugin "outputs"... Oct 10 07:48:10 minidsp-shd sudo[944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 10 07:48:10 minidsp-shd sudo[944]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:10 minidsp-shd volumio[627]: info: Loading plugin "albumart"... Oct 10 07:48:10 minidsp-shd sudo[939]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:10 minidsp-shd sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 10 07:48:10 minidsp-shd sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:10 minidsp-shd sudo[947]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:10 minidsp-shd volumio[627]: info: Loading plugin "ms_surface_dial"... Oct 10 07:48:10 minidsp-shd sudo[941]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:10 minidsp-shd sudo[944]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:11 minidsp-shd volumio[627]: Forking 3 albumart workers Oct 10 07:48:11 minidsp-shd volumio[627]: info: Applying required configuration parameters for plugin ms_surface_dial Oct 10 07:48:11 minidsp-shd volumio[627]: info: Plugin example_plugin is not enabled Oct 10 07:48:11 minidsp-shd volumio[627]: info: Loading plugin "hi_res_audio"... Oct 10 07:48:13 minidsp-shd volumio[627]: Starting albumart workers Oct 10 07:48:13 minidsp-shd volumio[627]: Starting albumart workers Oct 10 07:48:13 minidsp-shd volumio[627]: Starting albumart workers Oct 10 07:48:14 minidsp-shd volumio[627]: info: Applying required configuration parameters for plugin hi_res_audio Oct 10 07:48:14 minidsp-shd volumio[627]: info: Loading plugin "inputs"... Oct 10 07:48:16 minidsp-shd volumio[627]: info: Loading plugin "qobuz"... Oct 10 07:48:18 minidsp-shd volumio[627]: info: Loading plugin "tidal"... Oct 10 07:48:21 minidsp-shd volumio[627]: info: Loading plugin "oem_helper"... Oct 10 07:48:23 minidsp-shd volumio-remote-updater[458]: [2024-10-10 07:48:23] [connect] Successful connection Oct 10 07:48:23 minidsp-shd volumio[627]: info: Applying required configuration parameters for plugin oem_helper Oct 10 07:48:23 minidsp-shd volumio[627]: info: Loading plugin "updater_comm"... Oct 10 07:48:23 minidsp-shd volumio[627]: info: Plugin mpdemulation is not enabled Oct 10 07:48:23 minidsp-shd volumio[627]: info: Loading plugin "rest_api"... Oct 10 07:48:23 minidsp-shd volumio[627]: info: Loading plugin "websocket"... Oct 10 07:48:23 minidsp-shd volumio[627]: info: Loading i18n strings for locale en Oct 10 07:48:23 minidsp-shd volumio[627]: Updating browse sources language Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::initPlayerControls Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:23 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 07:48:24 minidsp-shd volumio[627]: Express server listening on port 3000 Oct 10 07:48:24 minidsp-shd volumio[627]: [Metrics] WebUI: 37s 847.43ms Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreStateMachine::resetVolumioState Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreStateMachine::getcurrentVolume Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreStateMachine::pushState Oct 10 07:48:24 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioPushState Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:24 minidsp-shd sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 07:48:24 minidsp-shd sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Oct 10 07:48:24 minidsp-shd sudo[1012]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:24 minidsp-shd sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 07:48:24 minidsp-shd sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:24 minidsp-shd volumio[627]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 10 07:48:24 minidsp-shd volumio[627]: info: Completed loading Core Plugins Oct 10 07:48:24 minidsp-shd volumio[627]: info: Preparing to generate the ALSA configuration file Oct 10 07:48:24 minidsp-shd sudo[1014]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:24 minidsp-shd volumio[627]: info: MRS: Removed streaming files Oct 10 07:48:24 minidsp-shd volumio[627]: info: MRS: volumioStreaming STOPPED Oct 10 07:48:24 minidsp-shd volumio[627]: info: MRS: SNAPSERVER STOPPED Oct 10 07:48:24 minidsp-shd volumio[627]: info: MRS: SNAPCLIENT STOPPED Oct 10 07:48:24 minidsp-shd volumio[627]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 10 07:48:24 minidsp-shd volumio[627]: wlan0 Interface doesn't support scanning. Oct 10 07:48:24 minidsp-shd volumio[627]: info: Cannot use regular scanning, forcing with ap-force Oct 10 07:48:24 minidsp-shd sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 10 07:48:24 minidsp-shd sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:24 minidsp-shd sudo[1020]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:24 minidsp-shd volumio[627]: command failed: No such device (-19) Oct 10 07:48:24 minidsp-shd volumio[627]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 10 07:48:24 minidsp-shd volumio[627]: command failed: No such device (-19) Oct 10 07:48:24 minidsp-shd volumio[627]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 10 07:48:24 minidsp-shd volumio[627]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 10 07:48:24 minidsp-shd volumio[627]: info: Reading ALSA contributions from plugins. Oct 10 07:48:24 minidsp-shd volumio-remote-updater[458]: [2024-10-10 07:48:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728564503 101 Oct 10 07:48:24 minidsp-shd volumio[627]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 07:48:24 minidsp-shd volumio[627]: info: Discovery: Getting this device information Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:24 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:24 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 07:48:25 minidsp-shd volumio[627]: info: Reloading queue from file Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreStateMachine::setRepeat true single undefined Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreStateMachine::pushState Oct 10 07:48:25 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioPushState Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreStateMachine::setRandom false Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreStateMachine::pushState Oct 10 07:48:25 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioPushState Oct 10 07:48:25 minidsp-shd volumio[627]: info: Setting Device type: NanoPi NEO 3 Oct 10 07:48:25 minidsp-shd volumio[627]: info: Asound.conf file unchanged, so no further update is needed Oct 10 07:48:25 minidsp-shd volumio[627]: info: Output device has changed, restarting MPD Oct 10 07:48:25 minidsp-shd sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 10 07:48:25 minidsp-shd sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:25 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Oct 10 07:48:25 minidsp-shd sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 07:48:25 minidsp-shd sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:25 minidsp-shd volumio[627]: info: Output device has changed, restarting Shairport Sync Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:25 minidsp-shd sudo[1033]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:25 minidsp-shd sudo[1030]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:25 minidsp-shd sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 07:48:25 minidsp-shd sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:25 minidsp-shd systemd[1]: Listening on mpd.socket. Oct 10 07:48:25 minidsp-shd systemd[1]: Starting Music Player Daemon... Oct 10 07:48:25 minidsp-shd volumio[627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 07:48:25 minidsp-shd volumio[627]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:25 minidsp-shd volumio[627]: info: ___________ START PLUGINS ___________ Oct 10 07:48:25 minidsp-shd volumio[627]: info: ControllerMpd::onStart: Initializing MPD Oct 10 07:48:25 minidsp-shd volumio[627]: info: Creating MPD Configuration file Oct 10 07:48:25 minidsp-shd sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 07:48:25 minidsp-shd sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:25 minidsp-shd sudo[1046]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 07:48:25 minidsp-shd volumio[627]: info: [1728564505682] CoreMusicLibrary::Adding element Media Servers Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:25 minidsp-shd sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 07:48:25 minidsp-shd sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 07:48:25 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 10 07:48:25 minidsp-shd systemd[1]: mpd.service: Succeeded. Oct 10 07:48:25 minidsp-shd systemd[1]: Stopped Music Player Daemon. Oct 10 07:48:25 minidsp-shd systemd[1]: Starting Music Player Daemon... Oct 10 07:48:25 minidsp-shd volumio[627]: info: Adding Manifest REST API Endpoints Oct 10 07:48:25 minidsp-shd volumio[627]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Oct 10 07:48:25 minidsp-shd volumio[627]: info: Adding METAVOLUMIO REST API Endpoints Oct 10 07:48:25 minidsp-shd volumio[627]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 10 07:48:25 minidsp-shd volumio[627]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 10 07:48:25 minidsp-shd volumio[627]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 10 07:48:25 minidsp-shd volumio[627]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:25 minidsp-shd sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Oct 10 07:48:25 minidsp-shd sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:25 minidsp-shd sudo[1053]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:25 minidsp-shd volumio[627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:25 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:26 minidsp-shd volumio[627]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 07:48:26 minidsp-shd volumio[627]: info: Preparing CD Folders Oct 10 07:48:26 minidsp-shd volumio[627]: info: Adding CD REST API Endpoints Oct 10 07:48:26 minidsp-shd volumio[627]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 10 07:48:26 minidsp-shd volumio[627]: info: Starting UDEV Watcher for CD Oct 10 07:48:26 minidsp-shd volumio[627]: info: Detecting CD presence with UDEV Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 07:48:26 minidsp-shd volumio[627]: info: [1728564506096] CoreMusicLibrary::Adding element Last_100 Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:26 minidsp-shd volumio[627]: info: Starting RAAT Plugin Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 10 07:48:26 minidsp-shd volumio[627]: info: Additional UI Settings Added for plugin music_service/raat Oct 10 07:48:26 minidsp-shd volumio[627]: info: Streaming services startup Oct 10 07:48:26 minidsp-shd volumio[627]: info: Starting Streaming Daemon Oct 10 07:48:26 minidsp-shd sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 10 07:48:26 minidsp-shd sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:26 minidsp-shd sudo[1064]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 07:48:26 minidsp-shd volumio[627]: info: [1728564506359] CoreMusicLibrary::Adding element Webradio Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 07:48:26 minidsp-shd sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 10 07:48:26 minidsp-shd sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 07:48:26 minidsp-shd volumio[627]: info: [1728564506433] CoreMusicLibrary::Adding element SoundCloud Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:26 minidsp-shd volumio[627]: Cannot find translation for source SoundCloud Oct 10 07:48:26 minidsp-shd sudo[1071]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:26 minidsp-shd volumio[627]: info: Creating Spotify config file Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 10 07:48:26 minidsp-shd volumio[627]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 10 07:48:26 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:26 minidsp-shd dbus-daemon[450]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=1000 pid=627 comm="/usr/bin/node /volumio/index.js ") Oct 10 07:48:26 minidsp-shd volumio[627]: error: Hi Res Audio Failed Login: Missing Login Data Oct 10 07:48:26 minidsp-shd volumio[627]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 10 07:48:26 minidsp-shd volumio[627]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 10 07:48:26 minidsp-shd systemd[1]: Starting Bluetooth service... Oct 10 07:48:26 minidsp-shd bluetoothd[1077]: Bluetooth daemon 5.23 Oct 10 07:48:26 minidsp-shd dbus-daemon[450]: [system] Successfully activated service 'org.bluez' Oct 10 07:48:26 minidsp-shd systemd[1]: Started Bluetooth service. Oct 10 07:48:26 minidsp-shd bluetoothd[1077]: Starting SDP server Oct 10 07:48:26 minidsp-shd bluetoothd[1077]: kernel lacks bnep-protocol support Oct 10 07:48:26 minidsp-shd bluetoothd[1077]: System does not support network plugin Oct 10 07:48:26 minidsp-shd dbus-daemon[450]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.11' (uid=0 pid=1077 comm="/usr/local/libexec/bluetooth/bluetoothd ") Oct 10 07:48:26 minidsp-shd bluetoothd[1077]: Bluetooth management interface 1.14 initialized Oct 10 07:48:27 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 07:48:27 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:27 minidsp-shd volumio[627]: info: Volumio Calling Home Oct 10 07:48:27 minidsp-shd systemd[1]: Starting Hostname Service... Oct 10 07:48:27 minidsp-shd volumio[627]: info: Stopping AccessToken refresher cron for QOBUZ Oct 10 07:48:27 minidsp-shd volumio[627]: info: AccessToken refresher cron started for QOBUZ Oct 10 07:48:27 minidsp-shd volumio[627]: info: Stopping AccessToken refresher cron Oct 10 07:48:27 minidsp-shd dbus-daemon[450]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 10 07:48:27 minidsp-shd systemd[1]: Started Hostname Service. Oct 10 07:48:27 minidsp-shd volumio[627]: info: AccessToken refresher cron started Oct 10 07:48:27 minidsp-shd volumio[627]: info: Adding TIDAL REST API Endpoints Oct 10 07:48:27 minidsp-shd volumio[627]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 10 07:48:27 minidsp-shd volumio[627]: info: Adding QOBUZ REST API Endpoints Oct 10 07:48:27 minidsp-shd volumio[627]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 10 07:48:27 minidsp-shd volumio[627]: info: Discovery: adding caa1550d-2c36-4af5-95fa-fa9de621d957 Oct 10 07:48:27 minidsp-shd volumio[627]: info: Discovery: Found device miniDSP SHD Oct 10 07:48:27 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:27 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:27 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output for this device Oct 10 07:48:27 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output Oct 10 07:48:27 minidsp-shd volumio[627]: info: Adding audio output: Oct 10 07:48:27 minidsp-shd volumio[627]: info: Adding audio output: Oct 10 07:48:27 minidsp-shd volumio[627]: info: Serial port opened successfully Oct 10 07:48:27 minidsp-shd volumio[627]: error: Cannot start Volumio Streaming Daemon Oct 10 07:48:27 minidsp-shd volumio[627]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 10 07:48:27 minidsp-shd volumio[627]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 10 07:48:27 minidsp-shd volumio[627]: info: RAAT Albumart path created successfully Oct 10 07:48:27 minidsp-shd volumio[627]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Oct 10 07:48:27 minidsp-shd volumio[627]: Can't get device info: No such device Oct 10 07:48:27 minidsp-shd volumio[627]: info: MPD Permissions set Oct 10 07:48:27 minidsp-shd volumio[627]: info: MPD Permissions set Oct 10 07:48:27 minidsp-shd volumio[627]: info: Upmpdcli Daemon Started Oct 10 07:48:27 minidsp-shd volumio[627]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-34,"disableVolumeControl":false} Oct 10 07:48:27 minidsp-shd volumio[627]: info: CoreStateMachine::pushState Oct 10 07:48:27 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:27 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 07:48:27 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioPushState Oct 10 07:48:27 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output update for this device Oct 10 07:48:27 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output Oct 10 07:48:27 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 10 07:48:27 minidsp-shd volumio[627]: info: Ignoring ROON Volume update because of undefined data Oct 10 07:48:27 minidsp-shd volumio[627]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Oct 10 07:48:27 minidsp-shd volumio[627]: info: [msSurfaceDial init()] check /org/bluez Oct 10 07:48:27 minidsp-shd volumio[627]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Oct 10 07:48:27 minidsp-shd volumio[627]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Oct 10 07:48:27 minidsp-shd volumio[627]: info: CoreCommandRouter::Reload Ui Oct 10 07:48:28 minidsp-shd volumio[627]: info: Volumio called home Oct 10 07:48:28 minidsp-shd volumio[627]: info: Spotify config file written Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 10 07:48:28 minidsp-shd volumio[627]: info: updateDSP function in raat called! Oct 10 07:48:28 minidsp-shd volumio[627]: info: Updating RAAT Signal Path Oct 10 07:48:28 minidsp-shd volumio[627]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Oct 10 07:48:28 minidsp-shd volumio[627]: info: Reconfiguring and Restarting RAAT Plugin Oct 10 07:48:28 minidsp-shd sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 10 07:48:28 minidsp-shd sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: Not Reporting Auto name since its the default one Oct 10 07:48:28 minidsp-shd volumio[627]: info: RAAT Overriding default device vendor model Oct 10 07:48:28 minidsp-shd go-librespot[1100]: Librespot-go daemon starting... Oct 10 07:48:28 minidsp-shd volumio[627]: info: Adding MINIDSP Inputs Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 07:48:28 minidsp-shd volumio[627]: info: [1728564508202] CoreMusicLibrary::Adding element Inputs Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:28 minidsp-shd sudo[1094]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:28 minidsp-shd volumio[627]: Cannot find translation for source SoundCloud Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 07:48:28 minidsp-shd volumio[627]: info: [1728564508217] CoreMusicLibrary::Adding element Presets Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:28 minidsp-shd volumio[627]: Cannot find translation for source SoundCloud Oct 10 07:48:28 minidsp-shd volumio[627]: Cannot find translation for source Presets Oct 10 07:48:28 minidsp-shd volumio[627]: (node:627) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 10 07:48:28 minidsp-shd volumio[627]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 10 07:48:28 minidsp-shd volumio[627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:28-05:00" level=info msg="generated new device id: 85281b23750bedc5061e49f1122ae2e9284a12c0" Oct 10 07:48:28 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:28-05:00" level=debug msg="stored credentials found for 12127342891" Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 10 07:48:28 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:28 minidsp-shd volumio[627]: info: No need to fix Spotify hosts Oct 10 07:48:28 minidsp-shd sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 10 07:48:28 minidsp-shd sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:28 minidsp-shd volumio[627]: info: Starting Shairport Sync Oct 10 07:48:29 minidsp-shd volumio[627]: info: Starting Shairport Sync Oct 10 07:48:29 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Oct 10 07:48:29 minidsp-shd sudo[1120]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:29 minidsp-shd volumio[627]: info: Starting Shairport Sync Oct 10 07:48:29 minidsp-shd sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 07:48:29 minidsp-shd sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:29 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 10 07:48:29 minidsp-shd sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 07:48:29 minidsp-shd sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:29 minidsp-shd sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 10 07:48:29 minidsp-shd sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:29 minidsp-shd volumiobt[1124]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 10 07:48:29 minidsp-shd sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 07:48:29 minidsp-shd sudo[1127]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:29 minidsp-shd sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:29 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 07:48:29 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Oct 10 07:48:29 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 07:48:29 minidsp-shd volumio[627]: xcb_connection_has_error() returned true Oct 10 07:48:29 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 07:48:29 minidsp-shd sudo[1125]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:29 minidsp-shd sudo[1128]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:29 minidsp-shd sudo[1131]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:29 minidsp-shd volumio[627]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 10 07:48:29 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:29 minidsp-shd volumio[627]: Cannot find translation for source SoundCloud Oct 10 07:48:29 minidsp-shd volumio[627]: Cannot find translation for source Presets Oct 10 07:48:29 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:29-05:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 10 07:48:29 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:29-05:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 10 07:48:29 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:29-05:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 10 07:48:29 minidsp-shd sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 10 07:48:29 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:29-05:00" level=debug msg="zeroconf server listening on port 44757" Oct 10 07:48:29 minidsp-shd sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:29 minidsp-shd systemd[1]: Started RAAT DAEMON. Oct 10 07:48:29 minidsp-shd sudo[1140]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:29 minidsp-shd volumio[627]: info: Raat Daemon started successfully Oct 10 07:48:29 minidsp-shd volumio[627]: info: Shairport-Sync Started Oct 10 07:48:29 minidsp-shd volumio[627]: Error adding Membership: Error: addMembership EINVAL Oct 10 07:48:29 minidsp-shd volumio[627]: info: Shairport-Sync Started Oct 10 07:48:29 minidsp-shd volumio[627]: info: Shairport-Sync Started Oct 10 07:48:29 minidsp-shd volumio[627]: info: TidalConnect service stoped! Oct 10 07:48:29 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:29-05:00" level=debug msg="obtained new client token: AADapZZZgsLfHxlYdf6aDJbiI0g+x/LIrD2sNYLf8bCDKb/RDnvkNk5BZLnblaG8U7EqSc0xh5mIbBFTBv5rLc1E95GKSNCnQ+mmTl+0woAUE6U+f9L+Wu/kMC7LJGTt17GXeQGBP8OkSGEwKccDjTFLxAPQT/X4TnoXLIn+HPFTTITbirkXosGLG2kopyYcfVRNjNAUT9WQDBiZ2Zd6Q8EQ98B8tAFWfuifvTY3WxOAOAdicAukR4KC7g9XVJX5" Oct 10 07:48:29 minidsp-shd volumio[627]: info: Volumio BT Module successfully started Oct 10 07:48:29 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 10 07:48:29 minidsp-shd volumio[627]: info: Enabling external Volume Control Oct 10 07:48:29 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:29 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:30 minidsp-shd volumio[627]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 10 07:48:30 minidsp-shd volumio[627]: SPOTIFY: BQBWw3H6bp9ApHaCJFCfMhfZoijIqq_XDif3NKTsIlHWv0vNkCaI7uLBFsMHC2A7I9Bl_c90BZXtmGH5odJ_hUPfBjh9Qs_Y8GHTFBidNqaSKfUWZY6muPl6g-aojyJ-zKKK7FeUadYa0BWTLUK25I8bylfDSFVQqlZvNEyJ7WVELUvQY90QJK6su5ypu4YJG7aIvVRQt8-vf-xJKVLwPRQK_Wyx1CC7Zrh88dwFSgFISFCnzORtPw16LKi8ZRIFrexTmWs Oct 10 07:48:30 minidsp-shd volumio[627]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 10 07:48:30 minidsp-shd volumio[627]: info: New Spotify access token = BQBWw3H6bp9ApHaCJFCfMhfZoijIqq_XDif3NKTsIlHWv0vNkCaI7uLBFsMHC2A7I9Bl_c90BZXtmGH5odJ_hUPfBjh9Qs_Y8GHTFBidNqaSKfUWZY6muPl6g-aojyJ-zKKK7FeUadYa0BWTLUK25I8bylfDSFVQqlZvNEyJ7WVELUvQY90QJK6su5ypu4YJG7aIvVRQt8-vf-xJKVLwPRQK_Wyx1CC7Zrh88dwFSgFISFCnzORtPw16LKi8ZRIFrexTmWs Oct 10 07:48:30 minidsp-shd volumio[627]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 10 07:48:30 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:30-05:00" level=info msg="connected to ap-guc3.spotify.com:4070" Oct 10 07:48:30 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:30-05:00" level=debug msg="completed keyexchange" Oct 10 07:48:30 minidsp-shd volumio[627]: info: Executing endpoint restartRAATSocket Oct 10 07:48:30 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 10 07:48:30 minidsp-shd sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 10 07:48:30 minidsp-shd volumio[627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Oct 10 07:48:30 minidsp-shd sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:30 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:30 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:30 minidsp-shd volumiobt[1124]: pulseaudio: no process found Oct 10 07:48:30 minidsp-shd sudo[1162]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:30 minidsp-shd volumio[627]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 10 07:48:30 minidsp-shd volumio[627]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 10 07:48:30 minidsp-shd dbus-daemon[450]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.16' (uid=1000 pid=1169 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 10 07:48:30 minidsp-shd sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 10 07:48:30 minidsp-shd sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:30 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 10 07:48:30 minidsp-shd dbus-daemon[450]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 10 07:48:30 minidsp-shd rtkit-daemon[1171]: Successfully called chroot. Oct 10 07:48:30 minidsp-shd rtkit-daemon[1171]: Successfully dropped privileges. Oct 10 07:48:30 minidsp-shd rtkit-daemon[1171]: Successfully limited resources. Oct 10 07:48:30 minidsp-shd rtkit-daemon[1171]: Running. Oct 10 07:48:30 minidsp-shd dbus-daemon[450]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.17' (uid=0 pid=1171 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 10 07:48:30 minidsp-shd rtkit-daemon[1171]: Canary thread running. Oct 10 07:48:30 minidsp-shd rtkit-daemon[1171]: Watchdog thread running. Oct 10 07:48:30 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Oct 10 07:48:30 minidsp-shd sudo[1167]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:30 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 10 07:48:30 minidsp-shd systemd[1]: Starting Authorization Manager... Oct 10 07:48:30 minidsp-shd volumio[627]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 10 07:48:30 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:30-05:00" level=debug msg="completed challenge" Oct 10 07:48:30 minidsp-shd volumio[627]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 10 07:48:30 minidsp-shd volumio[627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Oct 10 07:48:30 minidsp-shd polkitd[1177]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 10 07:48:30 minidsp-shd dbus-daemon[450]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 10 07:48:30 minidsp-shd systemd[1]: Started Authorization Manager. Oct 10 07:48:30 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:30-05:00" level=debug msg="authenticated as 12127342891" Oct 10 07:48:31 minidsp-shd volumio[627]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-34,"mute":false,"disableVolumeControl":false} Oct 10 07:48:31 minidsp-shd volumio[627]: info: CoreStateMachine::pushState Oct 10 07:48:31 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:31 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 07:48:31 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioPushState Oct 10 07:48:31 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output update for this device Oct 10 07:48:31 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output Oct 10 07:48:31 minidsp-shd volumio[627]: error: Help! Some callbacks for volumioPushState are crashing! Oct 10 07:48:31 minidsp-shd volumio[627]: error: Cannot read property 'sendVolumeMute' of undefined Oct 10 07:48:31 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:31-05:00" level=debug msg="authenticated as 12127342891" Oct 10 07:48:31 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:31 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:31 minidsp-shd volumio[627]: info: MRS: Getting audio outputs on start Oct 10 07:48:31 minidsp-shd volumio[627]: info: MRS: Requesting all other devices output Oct 10 07:48:31 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:31-05:00" level=debug msg="dealer connection opened" Oct 10 07:48:31 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:31-05:00" level=debug msg="initializing zeroconf session, username: 12127342891" Oct 10 07:48:31 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:31-05:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 10 07:48:31 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:31-05:00" level=debug msg="received connection id: ODY1OTg1NGYtOTIxZi00ZmJkLWI5OGYtNjIyZmM3MGNjZTg1K2RlYWxlcit0Y3A6Ly8wYWNiZDhlOC5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArNzM2QjhDMTAxMjlCMTZDQTJDMTE4RTc0NTI0RTFEMEU3Q0Y2NEM4RUI5MzJFNTQwNUExMTc3OTBCRkREQkNGRQ==" Oct 10 07:48:31 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:31-05:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 10 07:48:31 minidsp-shd volumio[627]: SPOTIFY: User informations: {"display_name":"Eduardo Arrarte Stahr","external_urls":{"spotify":"https://open.spotify.com/user/12127342891"},"href":"https://api.spotify.com/v1/users/12127342891","id":"12127342891","images":[{"url":"https://scontent-iad3-1.xx.fbcdn.net/v/t31.18172-1/19702644_10155332059580915_160063678147591371_o.jpg?stp=cp0_dst-jpg_s50x50&_nc_cat=107&ccb=1-7&_nc_sid=312bcd&_nc_ohc=ymyrt6fpey0Q7kNvgFKNtC_&_nc_ht=scontent-iad3-1.xx&edm=AP4hL3IEAAAA&_nc_gid=AeIfcbfN6ja8--1G9t7w3tG&oh=00_AYAYvGgbPMumPi7iYiERt4UNnunW_kZqxvDoNUCtNdmvzw&oe=672E92F8","height":64,"width":64},{"url":"https://scontent-iad3-1.xx.fbcdn.net/v/t31.18172-1/19702644_10155332059580915_160063678147591371_o.jpg?stp=dst-jpg_s320x320&_nc_cat=107&ccb=1-7&_nc_sid=05c18e&_nc_ohc=ymyrt6fpey0Q7kNvgFKNtC_&_nc_ht=scontent-iad3-1.xx&edm=AP4hL3IEAAAA&_nc_gid=AeIfcbfN6ja8--1G9t7w3tG&oh=00_AYCPBchyH_shRtF2za9EeL3HOp0cHheP6goaAHBLxepsTQ&oe=672E92F8","height":300,"width":300}],"type":"user","uri":"spotify:user:12127342891","followers":{"href":null,"total":33},"country":"PE","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"eduardoarrarte@gmail.com"} Oct 10 07:48:31 minidsp-shd volumio[627]: info: Spotify Successfully logged in Oct 10 07:48:31 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 07:48:31 minidsp-shd volumio[627]: info: [1728564511745] CoreMusicLibrary::Adding element Spotify Oct 10 07:48:31 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:31 minidsp-shd volumio[627]: Cannot find translation for source SoundCloud Oct 10 07:48:31 minidsp-shd volumio[627]: Cannot find translation for source Presets Oct 10 07:48:31 minidsp-shd volumio[627]: Cannot find translation for source Spotify Oct 10 07:48:31 minidsp-shd volumio[627]: info: go-librespot daemon successfully initialized Oct 10 07:48:31 minidsp-shd volumio[627]: info: Executing endpoint tc_getconfig Oct 10 07:48:31 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 10 07:48:31 minidsp-shd vtcs[1172]: STARTING TidalConnect services, version: 1.3.0.19 Oct 10 07:48:32 minidsp-shd volumio[627]: info: msSurfaceDial volumioupdatevolume callback: {"vol":73,"dbVolume":-34,"mute":false,"disableVolumeControl":false} Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreStateMachine::pushState Oct 10 07:48:32 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:32 minidsp-shd pulseaudio[1169]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 10 07:48:32 minidsp-shd pulseaudio[1169]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 10 07:48:32 minidsp-shd volumiobt[1124]: Applying permissions Oct 10 07:48:32 minidsp-shd volumiobt[1124]: Setting BT discoverable and pairable Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioPushState Oct 10 07:48:32 minidsp-shd pulseaudio[1169]: 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 Oct 10 07:48:32 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output update for this device Oct 10 07:48:32 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output Oct 10 07:48:32 minidsp-shd volumio[627]: error: Help! Some callbacks for volumioPushState are crashing! Oct 10 07:48:32 minidsp-shd volumio[627]: error: Cannot read property 'sendVolumeMute' of undefined Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 10 07:48:32 minidsp-shd vtcs[1172]: STARTED TidalConnect services. Oct 10 07:48:32 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:32-05:00" level=debug msg="put connect state because NEW_DEVICE" Oct 10 07:48:32 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:32-05:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 10 07:48:32 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:32-05:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 10 07:48:32 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:32-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 505" Oct 10 07:48:32 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:32-05:00" level=debug msg="autoplay enabled: false" Oct 10 07:48:32 minidsp-shd volumiobt[1124]: [38B blob data] Oct 10 07:48:32 minidsp-shd volumiobt[1124]: Invalid command Oct 10 07:48:32 minidsp-shd volumiobt[1124]: [bluetooth]# agent on Oct 10 07:48:32 minidsp-shd volumiobt[1124]: [bluetooth]# discoverable on Oct 10 07:48:32 minidsp-shd volumiobt[1124]: No default controller available Oct 10 07:48:32 minidsp-shd volumiobt[1124]: [bluetooth]# pairable on Oct 10 07:48:32 minidsp-shd volumiobt[1124]: No default controller available Oct 10 07:48:32 minidsp-shd volumiobt[1124]: [bluetooth]# agent NoInputNoOutput Oct 10 07:48:32 minidsp-shd volumiobt[1124]: Failed to register agent object Oct 10 07:48:32 minidsp-shd volumiobt[1124]: [bluetooth]# default-agent Oct 10 07:48:32 minidsp-shd volumiobt[1124]: No agent is registered Oct 10 07:48:32 minidsp-shd volumiobt[1124]: [bluetooth]# quit Oct 10 07:48:32 minidsp-shd volumiobt[1124]: [61B blob data] Oct 10 07:48:32 minidsp-shd volumiobt[1124]: [62B blob data] Oct 10 07:48:32 minidsp-shd volumio[627]: info: Executing endpoint tc_connect Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 10 07:48:32 minidsp-shd volumio[627]: info: Connecting to TidalConnect Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreCommandRouter::servicePushState Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreStateMachine::pushState Oct 10 07:48:32 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioPushState Oct 10 07:48:32 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output update for this device Oct 10 07:48:32 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output Oct 10 07:48:32 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:32 minidsp-shd volumio[627]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreCommandRouter::servicePushState Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreStateMachine::pushState Oct 10 07:48:32 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:32 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioPushState Oct 10 07:48:32 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output update for this device Oct 10 07:48:32 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output Oct 10 07:48:32 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:32 minidsp-shd volumio[627]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 10 07:48:33 minidsp-shd volumio[627]: info: TidalConnect service started! Oct 10 07:48:33 minidsp-shd mpd[1052]: Oct 10 07:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 07:48:34 minidsp-shd systemd[1]: Started Music Player Daemon. Oct 10 07:48:34 minidsp-shd volumio[627]: Upnp client error: Error: This socket has been ended by the other party Oct 10 07:48:34 minidsp-shd volumio[627]: Upnp client error: Error: This socket has been ended by the other party Oct 10 07:48:34 minidsp-shd sudo[1037]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:34 minidsp-shd sudo[1048]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:34 minidsp-shd volumio[627]: info: Completed starting Core Plugins Oct 10 07:48:34 minidsp-shd volumio[627]: info: ------------------------------------------- Oct 10 07:48:34 minidsp-shd volumio[627]: info: ----- MyVolumio plugins startup ---- Oct 10 07:48:34 minidsp-shd volumio[627]: info: ------------------------------------------- Oct 10 07:48:34 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 10 07:48:34 minidsp-shd volumio[627]: error: MPD error: The expression evaluated to a falsy value: Oct 10 07:48:34 minidsp-shd volumio[627]: assert.ok(self.idling) Oct 10 07:48:34 minidsp-shd volumio[627]: error: The expression evaluated to a falsy value: Oct 10 07:48:34 minidsp-shd volumio[627]: assert.ok(self.idling) Oct 10 07:48:34 minidsp-shd volumio[627]: info: MPD running with PID1052 Oct 10 07:48:34 minidsp-shd volumio[627]: ,establishing connection Oct 10 07:48:34 minidsp-shd volumio[627]: error: updateQueue error: null Oct 10 07:48:34 minidsp-shd volumio[627]: error: updateQueue error: null Oct 10 07:48:34 minidsp-shd volumio[627]: info: Initializing connection to go-librespot Websocket Oct 10 07:48:34 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:34-05:00" level=debug msg="new websocket client" Oct 10 07:48:34 minidsp-shd volumio[627]: info: Connection to go-librespot Websocket established Oct 10 07:48:35 minidsp-shd volumio[1035]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 10 07:48:36 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:36 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:36 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:36 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 07:48:36 minidsp-shd volumio[627]: info: Not Reporting Auto name since its the default one Oct 10 07:48:36 minidsp-shd volumio[627]: info: RAAT Overriding default device vendor model Oct 10 07:48:36 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 10 07:48:36 minidsp-shd volumio[627]: xcb_connection_has_error() returned true Oct 10 07:48:36 minidsp-shd sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 10 07:48:36 minidsp-shd sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:36 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Oct 10 07:48:36 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 10 07:48:36 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Oct 10 07:48:36 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Oct 10 07:48:36 minidsp-shd volumio[627]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 10 07:48:36 minidsp-shd volumio[627]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 10 07:48:36 minidsp-shd systemd[1]: Started RAAT DAEMON. Oct 10 07:48:36 minidsp-shd sudo[1226]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:36 minidsp-shd volumio[627]: info: Raat Daemon started successfully Oct 10 07:48:36 minidsp-shd volumio[627]: info: Executing endpoint restartRAATSocket Oct 10 07:48:36 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 10 07:48:36 minidsp-shd volumio[627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Oct 10 07:48:36 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:36 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:36 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 10 07:48:36 minidsp-shd volumio[627]: info: updateDSP function in raat called! Oct 10 07:48:36 minidsp-shd volumio[627]: info: Updating RAAT Signal Path Oct 10 07:48:37 minidsp-shd volumio[627]: info: Getting Spotify volume Oct 10 07:48:37 minidsp-shd volumio[627]: info: Spotify volume: 100 Oct 10 07:48:37 minidsp-shd volumio[627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Oct 10 07:48:38 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:38 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:38 minidsp-shd volumio[627]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 10 07:48:38 minidsp-shd volumio[627]: SPOTIFY: SPOTIFY VOLUME 100 Oct 10 07:48:38 minidsp-shd volumio[627]: SPOTIFY: VOLUMIO VOLUME 73 Oct 10 07:48:38 minidsp-shd volumio[627]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 10 07:48:38 minidsp-shd volumio[627]: info: Setting Spotify Volume from Volumio: 73 Oct 10 07:48:39 minidsp-shd volumio[627]: SPOTIFY: SETTING SPOTIFY VOLUME 73 Oct 10 07:48:39 minidsp-shd volumio[627]: info: Sending Spotify command with payload to local API: /player/volume Oct 10 07:48:39 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:39-05:00" level=debug msg="update volume to 47840/65535" Oct 10 07:48:39 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 07:48:39 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 07:48:39 minidsp-shd volumio[627]: info: Discovery: Getting this device information Oct 10 07:48:39 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:39 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:39 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 07:48:39 minidsp-shd volumio[627]: verbose: New Socket.io Connection to 192.168.86.234:3000 from 192.168.86.168 UA: Dart/3.4 (dart:io) Total Clients: 6 Oct 10 07:48:39 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:39-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 10 07:48:39 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:39-05:00" level=trace msg="emitting websocket event: volume" Oct 10 07:48:39 minidsp-shd volumio[627]: SPOTIFY: received: {"type":"volume","data":{"value":73,"max":100}} Oct 10 07:48:39 minidsp-shd volumio[627]: SPOTIFY: RECEIVED SPOTIFY VOLUME 73 Oct 10 07:48:39 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:39 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:39 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 10 07:48:39 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 10 07:48:40 minidsp-shd volumio[1035]: .......................................................................++++ Oct 10 07:48:42 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 07:48:42 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 07:48:42 minidsp-shd volumio[627]: info: Discovery: Getting this device information Oct 10 07:48:42 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:42 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:42 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 07:48:42 minidsp-shd volumio[1035]: ..........................++++ Oct 10 07:48:42 minidsp-shd volumio[1035]: e is 65537 (0x010001) Oct 10 07:48:42 minidsp-shd volumio[1035]: writing RSA key Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 10 07:48:42 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 10 07:48:43 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 10 07:48:45 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 10 07:48:45 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 10 07:48:45 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:45 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:45 minidsp-shd volumio[627]: info: Starting MyVolumio Remote Streaming Endpoints Oct 10 07:48:45 minidsp-shd volumio[627]: info: MyVolumio login type: Token Oct 10 07:48:45 minidsp-shd volumio[627]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 10 07:48:45 minidsp-shd volumio[627]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Oct 10 07:48:45 minidsp-shd volumio[627]: error: MyVolumio Custom Token format not valid, refreshing it Oct 10 07:48:46 minidsp-shd sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 07:48:46 minidsp-shd sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:46 minidsp-shd sudo[1294]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:46 minidsp-shd sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 07:48:46 minidsp-shd sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:46 minidsp-shd sudo[1296]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:46 minidsp-shd volumio[627]: verbose: New Socket.io Connection to 192.168.86.234 from 192.168.86.168 UA: Mozilla/5.0 (Linux; Android 14; Pixel 8 Pro Build/AP2A.240905.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Total Clients: 7 Oct 10 07:48:46 minidsp-shd volumio[627]: info: MyVolumio login type: Token Oct 10 07:48:47 minidsp-shd sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 07:48:47 minidsp-shd sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:47 minidsp-shd sudo[1302]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:47 minidsp-shd sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 07:48:47 minidsp-shd sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:47 minidsp-shd sudo[1304]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:47 minidsp-shd volumio[627]: verbose: New Socket.io Connection to 192.168.86.234 from 192.168.86.168 UA: Mozilla/5.0 (Linux; Android 14; Pixel 8 Pro Build/AP2A.240905.003; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Total Clients: 8 Oct 10 07:48:47 minidsp-shd volumio[627]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 10 07:48:47 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:48:47 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:47 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 07:48:47 minidsp-shd volumio[627]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 10 07:48:47 minidsp-shd volumio[627]: info: Listing playlists Oct 10 07:48:47 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 10 07:48:47 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 10 07:48:47 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 10 07:48:47 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 07:48:48 minidsp-shd volumio[627]: info: MyVolumio token set successfully Oct 10 07:48:48 minidsp-shd volumio[627]: info: MYVOLUMIO: Adding device Oct 10 07:48:48 minidsp-shd volumio[627]: info: MYVOLUMIO: Evaluating Server Oct 10 07:48:48 minidsp-shd volumio[627]: info: MyVolumio status changed Oct 10 07:48:48 minidsp-shd volumio[627]: info: Streaming services startup Oct 10 07:48:48 minidsp-shd volumio[627]: info: Starting Streaming Daemon Oct 10 07:48:48 minidsp-shd volumio[627]: info: Removing browser output: myVolumio user plan is not superstar Oct 10 07:48:48 minidsp-shd volumio[627]: info: Removing audio output: Oct 10 07:48:48 minidsp-shd volumio[627]: info: Stoppping Tunnel 1 Oct 10 07:48:48 minidsp-shd sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 10 07:48:48 minidsp-shd sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:49 minidsp-shd sudo[1327]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:49 minidsp-shd sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 10 07:48:49 minidsp-shd sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:48:49 minidsp-shd volumio[627]: error: Cannot start Volumio Streaming Daemon Oct 10 07:48:49 minidsp-shd volumio[627]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 10 07:48:49 minidsp-shd volumio[627]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 10 07:48:49 minidsp-shd sudo[1330]: pam_unix(sudo:session): session closed for user root Oct 10 07:48:49 minidsp-shd volumio[627]: info: Remote SSH Stopped Oct 10 07:48:49 minidsp-shd volumio[627]: info: Setting Geolocation for MyVolumio to us1 Oct 10 07:48:49 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:49 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:49 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:49 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 07:48:49 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:48:49 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetQueue Oct 10 07:48:49 minidsp-shd volumio[627]: info: CoreStateMachine::getQueue Oct 10 07:48:49 minidsp-shd volumio[627]: info: CorePlayQueue::getQueue Oct 10 07:48:49 minidsp-shd volumio[627]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 10 07:48:50 minidsp-shd volumio[627]: info: Updating MyVolumio device info Oct 10 07:48:50 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:50 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:50 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:50 minidsp-shd volumio[627]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 10 07:48:52 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 07:48:52 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 10 07:48:52 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 07:48:53 minidsp-shd volumio[627]: info: MYVOLUMIO: Adding device Oct 10 07:48:53 minidsp-shd volumio[627]: info: MYVOLUMIO: Evaluating Server Oct 10 07:48:54 minidsp-shd volumio[627]: error: MyVolumio Plugin failed to start in a timely fashion Oct 10 07:48:54 minidsp-shd volumio[627]: info: BOOT COMPLETED Oct 10 07:48:54 minidsp-shd volumio[627]: [Metrics] CommandRouter: 66s 499.20ms Oct 10 07:48:54 minidsp-shd volumio[627]: info: CoreCommandRouter::volumiosetStartupVolume Oct 10 07:48:54 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:48:54 minidsp-shd volumio[627]: info: VolumeController:: Setting startup Volume 60 Oct 10 07:48:54 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 10 07:48:54 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:54 minidsp-shd volumio[627]: info: CoreCommandRouter::Close All Modals sent Oct 10 07:48:54 minidsp-shd volumio[627]: info: CoreCommandRouter::Close All Modals sent Oct 10 07:48:54 minidsp-shd volumio[627]: info: msSurfaceDial volumioupdatevolume callback: {"vol":60,"dbVolume":-51,"mute":false,"disableVolumeControl":false} Oct 10 07:48:54 minidsp-shd volumio[627]: info: CoreStateMachine::pushState Oct 10 07:48:54 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:48:54 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 07:48:54 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioPushState Oct 10 07:48:54 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output update for this device Oct 10 07:48:54 minidsp-shd volumio[627]: info: MRS: Pushing multiroomSync output Oct 10 07:48:54 minidsp-shd volumio[627]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Oct 10 07:48:54 minidsp-shd volumio[627]: SPOTIFY: SPOTIFY VOLUME 73 Oct 10 07:48:54 minidsp-shd volumio[627]: SPOTIFY: VOLUMIO VOLUME 60 Oct 10 07:48:54 minidsp-shd volumio[627]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 10 07:48:54 minidsp-shd volumio[627]: info: Setting Spotify Volume from Volumio: 60 Oct 10 07:48:55 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 10 07:48:55 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 07:48:55 minidsp-shd volumio[627]: xcb_connection_has_error() returned true Oct 10 07:48:55 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 10 07:48:55 minidsp-shd volumio[627]: info: Setting Geolocation for MyVolumio to us1 Oct 10 07:48:55 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:55 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:55 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:55 minidsp-shd volumio[627]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 10 07:48:56 minidsp-shd volumio[627]: SPOTIFY: SETTING SPOTIFY VOLUME 60 Oct 10 07:48:56 minidsp-shd volumio[627]: info: Sending Spotify command with payload to local API: /player/volume Oct 10 07:48:56 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:56-05:00" level=debug msg="update volume to 39321/65535" Oct 10 07:48:56 minidsp-shd volumio[627]: info: Updating MyVolumio device info Oct 10 07:48:56 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:56 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:56 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:56 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:56-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 10 07:48:56 minidsp-shd go-librespot[1100]: time="2024-10-10T07:48:56-05:00" level=trace msg="emitting websocket event: volume" Oct 10 07:48:56 minidsp-shd volumio[627]: SPOTIFY: received: {"type":"volume","data":{"value":60,"max":100}} Oct 10 07:48:56 minidsp-shd volumio[627]: SPOTIFY: RECEIVED SPOTIFY VOLUME 60 Oct 10 07:48:57 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 07:48:57 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 07:48:57 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 07:48:57 minidsp-shd volumio[627]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 10 07:48:57 minidsp-shd volumio-remote-updater[458]: No test mode Oct 10 07:48:57 minidsp-shd volumio-remote-updater[458]: No alpha test mode Oct 10 07:48:57 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 10 07:48:57 minidsp-shd systemd[1]: systemd-hostnamed.service: Succeeded. Oct 10 07:48:57 minidsp-shd volumio[627]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 10 07:48:57 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 10 07:49:00 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 07:49:00 minidsp-shd volumio[627]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Oct 10 07:49:00 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:49:00 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:49:02 minidsp-shd volumio[627]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/minidspshd/buster/armhf Oct 10 07:49:05 minidsp-shd volumio[627]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/minidspshd/buster/armhf Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSoundCloud Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:49:05 minidsp-shd volumio[627]: Cannot find translation for source Presets Oct 10 07:49:05 minidsp-shd volumio[627]: Cannot find translation for source Spotify Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 07:49:05 minidsp-shd volumio[627]: info: Check plugin dependencies Oct 10 07:49:05 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 07:49:05 minidsp-shd volumio[627]: info: Rename folder Oct 10 07:49:05 minidsp-shd volumio[627]: info: Folder /tmp/downloaded_plugin.zip removed Oct 10 07:49:05 minidsp-shd volumio[627]: info: Move to category Oct 10 07:49:06 minidsp-shd volumio[627]: info: Checking if install.sh is present Oct 10 07:49:06 minidsp-shd volumio[627]: info: Executing install.sh Oct 10 07:49:06 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 07:49:06 minidsp-shd volumio[627]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 10 07:49:06 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 10 07:49:06 minidsp-shd volumio[627]: info: Received Get System Version Oct 10 07:49:06 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 07:49:06 minidsp-shd volumio[627]: info: Received Get System Info Oct 10 07:49:06 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 07:49:06 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 07:49:06 minidsp-shd volumio[627]: info: Discovery: Getting this device information Oct 10 07:49:06 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:49:06 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:49:06 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 07:49:06 minidsp-shd sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/soundcloud/install.sh Oct 10 07:49:06 minidsp-shd sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 07:49:06 minidsp-shd sudo[1370]: pam_unix(sudo:session): session closed for user root Oct 10 07:49:06 minidsp-shd volumio[627]: info: Install script completed Oct 10 07:49:06 minidsp-shd volumio[627]: info: Done installing plugin. Oct 10 07:49:06 minidsp-shd volumio[627]: info: Enabling plugin soundcloud Oct 10 07:49:06 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 07:49:06 minidsp-shd volumio[627]: info: [{"prettyName":"SoundCloud","name":"soundcloud","category":"music_service","version":"0.1.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true}] Oct 10 07:49:06 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 10 07:49:06 minidsp-shd volumio[627]: info: SoundCloud plugin installed Oct 10 07:49:06 minidsp-shd volumio[627]: Plugin install end detected on script Oct 10 07:49:06 minidsp-shd volumio[627]: info: Folder /tmp/plugins removed Oct 10 07:49:06 minidsp-shd volumio[627]: info: Folder /tmp/downloaded_plugin.zip removed Oct 10 07:49:06 minidsp-shd volumio[627]: info: Folder /data/temp removed Oct 10 07:49:12 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 07:49:12 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 07:49:12 minidsp-shd volumio[627]: info: Discovery: Getting this device information Oct 10 07:49:12 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioGetState Oct 10 07:49:12 minidsp-shd volumio[627]: info: CorePlayQueue::getTrack 0 Oct 10 07:49:12 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 07:49:28 minidsp-shd volumio[627]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/minidspshd/buster/armhf Oct 10 07:49:31 minidsp-shd volumio[627]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/minidspshd/buster/armhf Oct 10 07:49:31 minidsp-shd volumio[627]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSoundCloud Oct 10 07:49:31 minidsp-shd volumio[627]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 07:49:31 minidsp-shd volumio[627]: Cannot find translation for source Presets Oct 10 07:49:31 minidsp-shd volumio[627]: Cannot find translation for source Spotify Oct 10 07:49:31 minidsp-shd volumio[627]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 07:49:31 minidsp-shd volumio[627]: TypeError: Cannot read property 'clear' of undefined Oct 10 07:49:31 minidsp-shd volumio[627]: at SoundCloudContext.reset (/data/plugins/music_service/soundcloud/lib/soundcloud.js:83:21) Oct 10 07:49:31 minidsp-shd volumio[627]: at ControllerSoundCloud.onStop (/data/plugins/music_service/soundcloud/index.js:177:8) Oct 10 07:49:31 minidsp-shd volumio[627]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 10 07:49:31 minidsp-shd volumio[627]: at /volumio/app/pluginmanager.js:956:12 Oct 10 07:49:31 minidsp-shd volumio[627]: at ChildProcess.exithandler (child_process.js:374:7) Oct 10 07:49:31 minidsp-shd volumio[627]: at ChildProcess.emit (events.js:400:28) Oct 10 07:49:31 minidsp-shd volumio[627]: at maybeClose (internal/child_process.js:1088:16) Oct 10 07:49:31 minidsp-shd volumio[627]: at Socket. (internal/child_process.js:446:11) Oct 10 07:49:31 minidsp-shd volumio[627]: at Socket.emit (events.js:400:28) Oct 10 07:49:31 minidsp-shd volumio[627]: at Pipe. (net.js:686:12) Oct 10 07:49:31 minidsp-shd volumio[627]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 07:49:32 minidsp-shd sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 07:48 Oct 10 07:49:32 minidsp-shd sudo[1383]: 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"