-- Logs begin at Tue 2024-05-07 15:42:27 CEST, end at Tue 2024-05-07 15:52:43 CEST. -- May 07 15:51:54 minidsp-shd ntpd[778]: receive: Unexpected origin timestamp 0xe9e4ae5c.7f81a200 does not match aorg 0000000000.00000000 from server@217.61.62.224 xmt 0xe9e4b07a.7f05c0e2 May 07 15:51:54 minidsp-shd ntpd[778]: receive: Unexpected origin timestamp 0xe9e4ae5c.7f57f055 does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xe9e4b07a.7f22f299 May 07 15:51:55 minidsp-shd volumio-remote-updater[459]: [2024-05-07 15:51:55] [connect] Successful connection May 07 15:51:56 minidsp-shd volumio[790]: info: Loading plugin "network"... May 07 15:51:56 minidsp-shd volumio[790]: info: Refreshing Cached IP Addresses May 07 15:51:56 minidsp-shd sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 15:51:56 minidsp-shd sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:51:56 minidsp-shd volumio[790]: info: Loading plugin "services"... May 07 15:51:56 minidsp-shd sudo[835]: pam_unix(sudo:session): session closed for user root May 07 15:51:56 minidsp-shd sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 15:51:56 minidsp-shd sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:51:56 minidsp-shd volumio[790]: info: Loading plugin "alsa_controller"... May 07 15:51:56 minidsp-shd sudo[837]: pam_unix(sudo:session): session closed for user root May 07 15:51:56 minidsp-shd sudo[840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 07 15:51:56 minidsp-shd sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:51:56 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 15:51:56 minidsp-shd volumio[790]: info: Loading plugin "wizard"... May 07 15:51:56 minidsp-shd volumio[790]: info: Loading plugin "networkfs"... May 07 15:51:56 minidsp-shd volumio[790]: info: Starting Udev Watcher for removable devices May 07 15:51:56 minidsp-shd sudo[846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.10/Music /mnt/NAS/Musica May 07 15:51:56 minidsp-shd sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:51:56 minidsp-shd kernel: FS-Cache: Loaded May 07 15:51:56 minidsp-shd volumio[790]: info: Ignoring mount for partition: BOOT May 07 15:51:56 minidsp-shd volumio[790]: info: Ignoring mount for partition: volumio May 07 15:51:56 minidsp-shd volumio[790]: info: Ignoring mount for partition: volumio_data May 07 15:51:56 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 07 15:51:56 minidsp-shd volumio[790]: info: Loading plugin "volumio_command_line_client"... May 07 15:51:56 minidsp-shd volumio[790]: info: Loading plugin "upnp"... May 07 15:51:56 minidsp-shd volumio[790]: info: [1715089916919] Starting Upmpd Daemon May 07 15:51:56 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 07 15:51:56 minidsp-shd volumio[790]: info: Loading plugin "my_music"... May 07 15:51:56 minidsp-shd volumio[790]: info: Loading plugin "mpd"... May 07 15:51:56 minidsp-shd kernel: FS-Cache: Netfs 'cifs' registered for caching May 07 15:51:56 minidsp-shd kernel: Key type cifs.spnego registered May 07 15:51:56 minidsp-shd kernel: Key type cifs.idmap registered May 07 15:51:56 minidsp-shd kernel: CIFS: Attempting to mount //192.168.0.10/Music May 07 15:51:56 minidsp-shd kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 07 15:51:57 minidsp-shd volumio[790]: info: Loading plugin "upnp_browser"... May 07 15:51:58 minidsp-shd volumio[790]: info: Loading plugin "bluetooth"... May 07 15:51:58 minidsp-shd volumio[790]: info: [1715089918897] Starting BluetoothController May 07 15:51:59 minidsp-shd volumio[790]: info: Loading plugin "alarm-clock"... May 07 15:51:59 minidsp-shd volumio[790]: info: Loading plugin "manifestui"... May 07 15:51:59 minidsp-shd volumio[790]: info: Plugin metavolumio is not enabled May 07 15:51:59 minidsp-shd volumio[790]: info: Loading plugin "airplay_emulation"... May 07 15:51:59 minidsp-shd volumio[790]: info: Starting Shairport Sync May 07 15:51:59 minidsp-shd volumio[790]: info: Plugin cd_controller is not enabled May 07 15:51:59 minidsp-shd volumio[790]: info: Loading plugin "last_100"... May 07 15:52:00 minidsp-shd volumio[790]: info: Plugin raat is not enabled May 07 15:52:00 minidsp-shd volumio[790]: info: Loading plugin "streaming_services"... May 07 15:52:00 minidsp-shd sudo[840]: pam_unix(sudo:session): session closed for user root May 07 15:52:01 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. May 07 15:52:01 minidsp-shd sh[379]: timed out May 07 15:52:01 minidsp-shd sh[379]: dhcpcd exited May 07 15:52:01 minidsp-shd dhcpcd[416]: timed out May 07 15:52:01 minidsp-shd sh[379]: ifup: failed to bring up eth0 May 07 15:52:01 minidsp-shd dhcpcd[416]: dhcpcd exited May 07 15:52:01 minidsp-shd systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 07 15:52:01 minidsp-shd systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 07 15:52:02 minidsp-shd volumio[790]: info: Starting Streaming Service Transparent Proxy May 07 15:52:02 minidsp-shd volumio[790]: info: Loading plugin "tidalconnect"... May 07 15:52:02 minidsp-shd volumio[790]: info: Loading plugin "webradio"... May 07 15:52:03 minidsp-shd volumio[790]: info: Loading plugin "i2s_dacs"... May 07 15:52:03 minidsp-shd volumio[790]: info: I2S DAC not set, start Auto-detection May 07 15:52:03 minidsp-shd volumio[790]: info: Loading plugin "volumiodiscovery"... May 07 15:52:03 minidsp-shd volumio[790]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 07 15:52:03 minidsp-shd node[790]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 07 15:52:03 minidsp-shd node[790]: *** WARNING *** Please fix your application to use the native API of Avahi! May 07 15:52:03 minidsp-shd volumio[790]: *** WARNING *** Please fix your application to use the native API of Avahi! May 07 15:52:03 minidsp-shd volumio[790]: *** WARNING *** For more information see May 07 15:52:03 minidsp-shd volumio[790]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 07 15:52:03 minidsp-shd volumio[790]: *** WARNING *** Please fix your application to use the native API of Avahi! May 07 15:52:03 minidsp-shd volumio[790]: *** WARNING *** For more information see May 07 15:52:03 minidsp-shd node[790]: *** WARNING *** For more information see May 07 15:52:03 minidsp-shd node[790]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 07 15:52:03 minidsp-shd node[790]: *** WARNING *** Please fix your application to use the native API of Avahi! May 07 15:52:03 minidsp-shd node[790]: *** WARNING *** For more information see May 07 15:52:03 minidsp-shd volumio[790]: info: Applying required configuration parameters for plugin volumiodiscovery May 07 15:52:03 minidsp-shd volumio[790]: info: Discovery: Started advertising with name: miniDSP SHD May 07 15:52:03 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 07 15:52:03 minidsp-shd volumio[790]: info: Plugin multiroom is not enabled May 07 15:52:03 minidsp-shd volumio[790]: info: Loading plugin "outputs"... May 07 15:52:03 minidsp-shd volumio[790]: info: Loading plugin "albumart"... May 07 15:52:03 minidsp-shd volumio[790]: info: Loading plugin "ms_surface_dial"... May 07 15:52:04 minidsp-shd volumio[790]: Forking 3 albumart workers May 07 15:52:04 minidsp-shd volumio[790]: info: Applying required configuration parameters for plugin ms_surface_dial May 07 15:52:04 minidsp-shd volumio[790]: STREAMING PROXY: Starting server on port 3245 May 07 15:52:04 minidsp-shd volumio[790]: Node JS runtime: 14 May 07 15:52:04 minidsp-shd volumio[790]: info: Plugin example_plugin is not enabled May 07 15:52:04 minidsp-shd volumio[790]: info: Loading plugin "hi_res_audio"... May 07 15:52:06 minidsp-shd volumio[790]: Starting albumart workers May 07 15:52:06 minidsp-shd volumio[790]: Starting albumart workers May 07 15:52:06 minidsp-shd volumio[790]: Starting albumart workers May 07 15:52:07 minidsp-shd kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 07 15:52:07 minidsp-shd kernel: CIFS VFS: cifs_mount failed w/return code = -115 May 07 15:52:07 minidsp-shd sudo[846]: pam_unix(sudo:session): session closed for user root May 07 15:52:08 minidsp-shd volumio[790]: info: Applying required configuration parameters for plugin hi_res_audio May 07 15:52:08 minidsp-shd volumio[790]: info: Loading plugin "inputs"... May 07 15:52:09 minidsp-shd volumio[790]: info: Loading plugin "qobuz"... May 07 15:52:10 minidsp-shd volumio-remote-updater[459]: [2024-05-07 15:52:10] [connect] Successful connection May 07 15:52:12 minidsp-shd volumio[790]: info: Loading plugin "tidal"... May 07 15:52:14 minidsp-shd volumio[790]: info: Loading plugin "oem_helper"... May 07 15:52:16 minidsp-shd volumio[790]: info: Applying required configuration parameters for plugin oem_helper May 07 15:52:16 minidsp-shd volumio[790]: info: Loading plugin "updater_comm"... May 07 15:52:17 minidsp-shd volumio[790]: info: Plugin mpdemulation is not enabled May 07 15:52:17 minidsp-shd volumio[790]: info: Loading plugin "rest_api"... May 07 15:52:17 minidsp-shd volumio[790]: info: Loading plugin "websocket"... May 07 15:52:17 minidsp-shd volumio[790]: info: Loading i18n strings for locale en May 07 15:52:17 minidsp-shd volumio[790]: Updating browse sources language May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::initPlayerControls May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 15:52:17 minidsp-shd volumio[790]: Express server listening on port 3000 May 07 15:52:17 minidsp-shd volumio[790]: [Metrics] WebUI: 27s 632.89ms May 07 15:52:17 minidsp-shd volumio[790]: info: CoreStateMachine::resetVolumioState May 07 15:52:17 minidsp-shd volumio[790]: info: CoreStateMachine::getcurrentVolume May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioRetrievevolume May 07 15:52:17 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:17 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:17 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:17 minidsp-shd sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 15:52:17 minidsp-shd sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:17 minidsp-shd sudo[933]: pam_unix(sudo:session): session closed for user root May 07 15:52:17 minidsp-shd sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 15:52:17 minidsp-shd volumio[790]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 07 15:52:17 minidsp-shd sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:17 minidsp-shd volumio[790]: info: Completed loading Core Plugins May 07 15:52:17 minidsp-shd volumio[790]: info: Preparing to generate the ALSA configuration file May 07 15:52:17 minidsp-shd sudo[935]: pam_unix(sudo:session): session closed for user root May 07 15:52:17 minidsp-shd volumio[790]: info: Cannot mount NAS Musica at system boot, trial number 1 ,retrying in 5 seconds May 07 15:52:18 minidsp-shd volumio[790]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 1 May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 2 May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 3 May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 4 May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 5 May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 6 May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 7 May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 8 May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 9 May 07 15:52:18 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 10 May 07 15:52:18 minidsp-shd volumio[790]: info: Reloading queue from file May 07 15:52:18 minidsp-shd volumio[790]: info: Asound.conf file unchanged, so no further update is needed May 07 15:52:18 minidsp-shd volumio[790]: info: Output device has changed, restarting MPD May 07 15:52:18 minidsp-shd volumio[790]: info: Output device has changed, restarting Shairport Sync May 07 15:52:18 minidsp-shd sudo[944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 15:52:18 minidsp-shd sudo[944]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:18 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:18 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:18 minidsp-shd sudo[944]: pam_unix(sudo:session): session closed for user root May 07 15:52:18 minidsp-shd sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 15:52:18 minidsp-shd sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:18 minidsp-shd systemd[1]: Listening on mpd.socket. May 07 15:52:18 minidsp-shd volumio[790]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 15:52:18 minidsp-shd systemd[1]: Starting Music Player Daemon... May 07 15:52:18 minidsp-shd volumio[790]: info: ___________ START PLUGINS ___________ May 07 15:52:18 minidsp-shd volumio[790]: info: ControllerMpd::onStart: Initializing MPD May 07 15:52:18 minidsp-shd volumio[790]: info: Creating MPD Configuration file May 07 15:52:18 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 15:52:18 minidsp-shd sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 15:52:18 minidsp-shd sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:18 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 15:52:18 minidsp-shd volumio[790]: info: [1715089938782] CoreMusicLibrary::Adding element Media Servers May 07 15:52:18 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:18 minidsp-shd sudo[954]: pam_unix(sudo:session): session closed for user root May 07 15:52:18 minidsp-shd sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 15:52:18 minidsp-shd sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:18 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 07 15:52:18 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 07 15:52:18 minidsp-shd systemd[1]: mpd.service: Succeeded. May 07 15:52:18 minidsp-shd systemd[1]: Stopped Music Player Daemon. May 07 15:52:18 minidsp-shd systemd[1]: Starting Music Player Daemon... May 07 15:52:18 minidsp-shd volumio[790]: info: Adding Manifest REST API Endpoints May 07 15:52:18 minidsp-shd volumio[790]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui May 07 15:52:18 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:18 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:18 minidsp-shd sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD May 07 15:52:18 minidsp-shd sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:18 minidsp-shd sudo[961]: pam_unix(sudo:session): session closed for user root May 07 15:52:18 minidsp-shd volumio[790]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 15:52:19 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:19 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:19 minidsp-shd volumio[790]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 15:52:19 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 15:52:19 minidsp-shd volumio[790]: info: [1715089939096] CoreMusicLibrary::Adding element Last_100 May 07 15:52:19 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:19 minidsp-shd volumio[790]: info: Streaming services startup May 07 15:52:19 minidsp-shd volumio[790]: info: Starting Streaming Daemon May 07 15:52:19 minidsp-shd sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 07 15:52:19 minidsp-shd sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:19 minidsp-shd sudo[970]: pam_unix(sudo:session): session closed for user root May 07 15:52:19 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 15:52:19 minidsp-shd volumio[790]: info: [1715089939281] CoreMusicLibrary::Adding element Webradio May 07 15:52:19 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:19 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 15:52:19 minidsp-shd sudo[977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 07 15:52:19 minidsp-shd sudo[977]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:19 minidsp-shd dbus-daemon[472]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=1000 pid=790 comm="/usr/bin/node /volumio/index.js ") May 07 15:52:19 minidsp-shd volumio[790]: error: Hi Res Audio Failed Login: Missing Login Data May 07 15:52:19 minidsp-shd volumio[790]: info: Adding HIGHRESAUDIO REST API Endpoints May 07 15:52:19 minidsp-shd volumio[790]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 07 15:52:19 minidsp-shd systemd[1]: Starting Bluetooth service... May 07 15:52:19 minidsp-shd volumio[790]: info: Refreshing TIDAL token May 07 15:52:19 minidsp-shd sudo[977]: pam_unix(sudo:session): session closed for user root May 07 15:52:19 minidsp-shd bluetoothd[979]: Bluetooth daemon 5.23 May 07 15:52:19 minidsp-shd dbus-daemon[472]: [system] Successfully activated service 'org.bluez' May 07 15:52:19 minidsp-shd systemd[1]: Started Bluetooth service. May 07 15:52:19 minidsp-shd bluetoothd[979]: Starting SDP server May 07 15:52:19 minidsp-shd bluetoothd[979]: kernel lacks bnep-protocol support May 07 15:52:19 minidsp-shd bluetoothd[979]: System does not support network plugin May 07 15:52:19 minidsp-shd dbus-daemon[472]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=979 comm="/usr/local/libexec/bluetooth/bluetoothd ") May 07 15:52:19 minidsp-shd bluetoothd[979]: Bluetooth management interface 1.14 initialized May 07 15:52:19 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 15:52:19 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:19 minidsp-shd volumio[790]: info: Volumio Calling Home May 07 15:52:19 minidsp-shd systemd[1]: Starting Hostname Service... May 07 15:52:19 minidsp-shd volumio[790]: info: Stopping AccessToken refresher cron for QOBUZ May 07 15:52:19 minidsp-shd dbus-daemon[472]: [system] Successfully activated service 'org.freedesktop.hostname1' May 07 15:52:19 minidsp-shd systemd[1]: Started Hostname Service. May 07 15:52:20 minidsp-shd volumio[790]: info: AccessToken refresher cron started for QOBUZ May 07 15:52:20 minidsp-shd volumio[790]: info: Adding QOBUZ REST API Endpoints May 07 15:52:20 minidsp-shd volumio[790]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 07 15:52:20 minidsp-shd volumio[790]: info: Setting Device type: NanoPi NEO 3 May 07 15:52:20 minidsp-shd volumio[790]: info: CoreStateMachine::setRepeat true single undefined May 07 15:52:20 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:20 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:20 minidsp-shd volumio[790]: info: CoreStateMachine::setRandom null May 07 15:52:20 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:20 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:20 minidsp-shd volumio[790]: info: Serial port opened successfully May 07 15:52:20 minidsp-shd volumio[790]: error: Cannot start Volumio Streaming Daemon May 07 15:52:20 minidsp-shd volumio[790]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 07 15:52:20 minidsp-shd volumio[790]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 07 15:52:20 minidsp-shd volumio[790]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' May 07 15:52:20 minidsp-shd volumio[790]: Can't get device info: No such device May 07 15:52:20 minidsp-shd volumio[790]: info: MPD Permissions set May 07 15:52:20 minidsp-shd volumio[790]: info: MPD Permissions set May 07 15:52:20 minidsp-shd volumio[790]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} May 07 15:52:20 minidsp-shd volumio[790]: info: [msSurfaceDial init()] check /org/bluez May 07 15:52:20 minidsp-shd sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 07 15:52:20 minidsp-shd volumio[790]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null May 07 15:52:20 minidsp-shd sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:20 minidsp-shd volumio[790]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::Reload Ui May 07 15:52:20 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. May 07 15:52:20 minidsp-shd sudo[997]: pam_unix(sudo:session): session closed for user root May 07 15:52:20 minidsp-shd volumio[790]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:20 minidsp-shd volumio[790]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 May 07 15:52:20 minidsp-shd volumio[790]: info: Volumio called home May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:20 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat May 07 15:52:21 minidsp-shd volumio[790]: info: Adding MINIDSP Inputs May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 15:52:21 minidsp-shd volumio[790]: info: [1715089941143] CoreMusicLibrary::Adding element Inputs May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 15:52:21 minidsp-shd volumio[790]: info: [1715089941151] CoreMusicLibrary::Adding element Presets May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:21 minidsp-shd volumio[790]: Cannot find translation for source Presets May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioGetState May 07 15:52:21 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioGetVisibleSources May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:21 minidsp-shd volumio[790]: info: Listing playlists May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioGetQueue May 07 15:52:21 minidsp-shd volumio[790]: info: CoreStateMachine::getQueue May 07 15:52:21 minidsp-shd volumio[790]: info: CorePlayQueue::getQueue May 07 15:52:21 minidsp-shd volumio[790]: info: Upmpdcli Daemon Started May 07 15:52:21 minidsp-shd volumio[790]: info: Starting Shairport Sync May 07 15:52:21 minidsp-shd volumio[790]: info: Starting Shairport Sync May 07 15:52:21 minidsp-shd volumio[790]: info: Starting Shairport Sync May 07 15:52:21 minidsp-shd sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 15:52:21 minidsp-shd sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:21 minidsp-shd sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 15:52:21 minidsp-shd sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::volumiosetSourceActiveno-source May 07 15:52:21 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:21 minidsp-shd volumio[790]: Cannot find translation for source Presets May 07 15:52:21 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 15:52:21 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. May 07 15:52:21 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 15:52:21 minidsp-shd sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 15:52:21 minidsp-shd sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:22 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 15:52:22 minidsp-shd sudo[1019]: pam_unix(sudo:session): session closed for user root May 07 15:52:22 minidsp-shd sudo[1013]: pam_unix(sudo:session): session closed for user root May 07 15:52:22 minidsp-shd sudo[1015]: pam_unix(sudo:session): session closed for user root May 07 15:52:22 minidsp-shd sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 07 15:52:22 minidsp-shd sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:22 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. May 07 15:52:22 minidsp-shd sudo[1023]: pam_unix(sudo:session): session closed for user root May 07 15:52:22 minidsp-shd sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 07 15:52:22 minidsp-shd sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:22 minidsp-shd volumiobt[1027]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 07 15:52:22 minidsp-shd sudo[1028]: pam_unix(sudo:session): session closed for user root May 07 15:52:22 minidsp-shd volumio[790]: info: Access Token successfully retrieved May 07 15:52:22 minidsp-shd volumio[790]: info: Shairport-Sync Started May 07 15:52:22 minidsp-shd volumio[790]: Error adding Membership: Error: addMembership EINVAL May 07 15:52:22 minidsp-shd volumio[790]: info: Shairport-Sync Started May 07 15:52:22 minidsp-shd volumio[790]: info: Shairport-Sync Started May 07 15:52:22 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 07 15:52:22 minidsp-shd volumio[790]: info: Enabling external Volume Control May 07 15:52:22 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:22 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 15:52:22 minidsp-shd volumio[790]: info: TidalConnect service stoped! May 07 15:52:22 minidsp-shd volumio[790]: info: Volumio BT Module successfully started May 07 15:52:22 minidsp-shd volumio[790]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 May 07 15:52:22 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioGetState May 07 15:52:22 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:23 minidsp-shd sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.10/Music /mnt/NAS/Musica May 07 15:52:23 minidsp-shd sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:23 minidsp-shd kernel: CIFS: Attempting to mount //192.168.0.10/Music May 07 15:52:23 minidsp-shd kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 07 15:52:23 minidsp-shd volumio[790]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 07 15:52:23 minidsp-shd volumio[790]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 07 15:52:23 minidsp-shd sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 07 15:52:23 minidsp-shd sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:23 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. May 07 15:52:23 minidsp-shd sudo[1045]: pam_unix(sudo:session): session closed for user root May 07 15:52:23 minidsp-shd volumio[790]: info: Successfully retrieved User Session From TIDAL May 07 15:52:23 minidsp-shd sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 07 15:52:23 minidsp-shd sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:23 minidsp-shd volumiobt[1027]: pulseaudio: no process found May 07 15:52:23 minidsp-shd sudo[1050]: pam_unix(sudo:session): session closed for user root May 07 15:52:23 minidsp-shd dbus-daemon[472]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.15' (uid=1000 pid=1054 comm="/usr/bin/pulseaudio --high-priority=true ") May 07 15:52:23 minidsp-shd volumio[790]: info: msSurfaceDial volumioupdatevolume callback: {"mute":false,"disableVolumeControl":false} May 07 15:52:23 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:23 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:23 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 15:52:23 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:23 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 07 15:52:23 minidsp-shd volumio[790]: error: Help! Some callbacks for volumioPushState are crashing! May 07 15:52:23 minidsp-shd volumio[790]: error: Cannot read property 'sendVolumeMute' of undefined May 07 15:52:23 minidsp-shd dbus-daemon[472]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 07 15:52:23 minidsp-shd volumio[790]: ------------------------------------ BT MESSAGE: BT STATUS: running May 07 15:52:23 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. May 07 15:52:23 minidsp-shd rtkit-daemon[1055]: Successfully called chroot. May 07 15:52:23 minidsp-shd rtkit-daemon[1055]: Successfully dropped privileges. May 07 15:52:23 minidsp-shd rtkit-daemon[1055]: Successfully limited resources. May 07 15:52:23 minidsp-shd rtkit-daemon[1055]: Running. May 07 15:52:23 minidsp-shd rtkit-daemon[1055]: Canary thread running. May 07 15:52:23 minidsp-shd dbus-daemon[472]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.16' (uid=0 pid=1055 comm="/usr/lib/rtkit/rtkit-daemon ") May 07 15:52:23 minidsp-shd rtkit-daemon[1055]: Watchdog thread running. May 07 15:52:23 minidsp-shd volumio[790]: info: Successfully retrieved User Subscription From TIDAL May 07 15:52:23 minidsp-shd volumio[790]: info: Adding TIDAL to Browse Sources May 07 15:52:23 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 15:52:23 minidsp-shd volumio[790]: info: [1715089943823] CoreMusicLibrary::Adding element TIDAL May 07 15:52:23 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:23 minidsp-shd volumio[790]: Cannot find translation for source Presets May 07 15:52:23 minidsp-shd volumio[790]: Cannot find translation for source TIDAL May 07 15:52:23 minidsp-shd systemd[1]: Starting Authorization Manager... May 07 15:52:23 minidsp-shd volumio[790]: info: Stopping AccessToken refresher cron May 07 15:52:24 minidsp-shd volumio[790]: info: AccessToken refresher cron started May 07 15:52:24 minidsp-shd volumio[790]: info: Adding TIDAL REST API Endpoints May 07 15:52:24 minidsp-shd volumio[790]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 07 15:52:24 minidsp-shd polkitd[1058]: started daemon version 0.105 using authority implementation `local' version `0.105' May 07 15:52:24 minidsp-shd dbus-daemon[472]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 07 15:52:24 minidsp-shd systemd[1]: Started Authorization Manager. May 07 15:52:24 minidsp-shd volumio[790]: ------------------------------------ BT MESSAGE: BT STATUS: running May 07 15:52:24 minidsp-shd volumio[790]: info: Executing endpoint tc_getconfig May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 07 15:52:24 minidsp-shd vtcs[1047]: STARTING TidalConnect services, version: 1.3.0.19 May 07 15:52:24 minidsp-shd volumio[790]: info: msSurfaceDial volumioupdatevolume callback: {"vol":95,"dbVolume":-6.5,"mute":false,"disableVolumeControl":false} May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:24 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:24 minidsp-shd volumio[790]: error: Help! Some callbacks for volumioPushState are crashing! May 07 15:52:24 minidsp-shd volumio[790]: error: Cannot read property 'sendVolumeMute' of undefined May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume May 07 15:52:24 minidsp-shd vtcs[1047]: STARTED TidalConnect services. May 07 15:52:24 minidsp-shd volumio[790]: info: Executing endpoint tc_connect May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 07 15:52:24 minidsp-shd volumio[790]: info: Connecting to TidalConnect May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::servicePushState May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:24 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:24 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:24 minidsp-shd volumio[790]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 07 15:52:24 minidsp-shd volumio[790]: verbose: CURRENT POSITION 0 May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::syncState stateService stop May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::syncState currentStatus stop May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:24 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:24 minidsp-shd volumio[790]: info: No code May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:24 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::servicePushState May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:24 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:24 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:24 minidsp-shd volumio[790]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 07 15:52:24 minidsp-shd volumio[790]: verbose: CURRENT POSITION 0 May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::syncState stateService stop May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::syncState currentStatus stop May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:24 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:24 minidsp-shd volumio[790]: info: No code May 07 15:52:24 minidsp-shd volumio[790]: info: CoreStateMachine::pushState May 07 15:52:24 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:24 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioPushState May 07 15:52:24 minidsp-shd pulseaudio[1054]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 07 15:52:24 minidsp-shd pulseaudio[1054]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 07 15:52:24 minidsp-shd volumiobt[1027]: Applying permissions May 07 15:52:24 minidsp-shd volumiobt[1027]: Setting BT discoverable and pairable May 07 15:52:24 minidsp-shd pulseaudio[1054]: 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 May 07 15:52:25 minidsp-shd volumiobt[1027]: [38B blob data] May 07 15:52:25 minidsp-shd volumiobt[1027]: Invalid command May 07 15:52:25 minidsp-shd volumiobt[1027]: [bluetooth]# agent on May 07 15:52:25 minidsp-shd volumiobt[1027]: [bluetooth]# discoverable on May 07 15:52:25 minidsp-shd volumiobt[1027]: No default controller available May 07 15:52:25 minidsp-shd volumiobt[1027]: [bluetooth]# pairable on May 07 15:52:25 minidsp-shd volumiobt[1027]: No default controller available May 07 15:52:25 minidsp-shd volumiobt[1027]: [bluetooth]# agent NoInputNoOutput May 07 15:52:25 minidsp-shd volumiobt[1027]: Failed to register agent object May 07 15:52:25 minidsp-shd volumiobt[1027]: [bluetooth]# default-agent May 07 15:52:25 minidsp-shd volumiobt[1027]: No agent is registered May 07 15:52:25 minidsp-shd volumiobt[1027]: [bluetooth]# quit May 07 15:52:25 minidsp-shd volumiobt[1027]: [61B blob data] May 07 15:52:25 minidsp-shd volumiobt[1027]: [62B blob data] May 07 15:52:25 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110:3000 from 192.168.1.105 UA: node-XMLHttpRequest Total Clients: 13 May 07 15:52:25 minidsp-shd volumio-remote-updater[459]: [2024-05-07 15:52:25] [connect] Successful connection May 07 15:52:25 minidsp-shd volumio-remote-updater[459]: [2024-05-07 15:52:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715089945 101 May 07 15:52:25 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 14 May 07 15:52:25 minidsp-shd sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 15:52:25 minidsp-shd sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:25 minidsp-shd sudo[1082]: pam_unix(sudo:session): session closed for user root May 07 15:52:25 minidsp-shd sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 15:52:25 minidsp-shd sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:25 minidsp-shd sudo[1084]: pam_unix(sudo:session): session closed for user root May 07 15:52:25 minidsp-shd volumio[790]: verbose: New Socket.io Connection to 192.168.1.110 from 192.168.1.105 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 15 May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 15:52:26 minidsp-shd volumio[790]: info: Retrieving Cloud Streaming UI May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioGetState May 07 15:52:26 minidsp-shd volumio[790]: info: CorePlayQueue::getTrack 0 May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioGetVisibleSources May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 15:52:26 minidsp-shd volumio[790]: info: Getting Tidal Cloud Configuration May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 15:52:26 minidsp-shd volumio[790]: info: Getting Qobuz Cloud Configuration May 07 15:52:26 minidsp-shd volumio[790]: info: Asking plugin for UI Config May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 15:52:26 minidsp-shd volumio[790]: info: Getting Spotify Cloud Configuration May 07 15:52:26 minidsp-shd volumio[790]: info: Asking plugin for UI Config May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 15:52:26 minidsp-shd volumio[790]: info: Saving Spotify Acccount May 07 15:52:26 minidsp-shd volumio[790]: info: Got it May 07 15:52:26 minidsp-shd volumio[790]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 15:52:26 minidsp-shd volumio[790]: info: TidalConnect service started! May 07 15:52:26 minidsp-shd volumio[790]: info: Got Tidal Cloud Configuration May 07 15:52:26 minidsp-shd volumio[790]: info: Got it May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioGetBrowseSources May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioGetBrowseSources May 07 15:52:26 minidsp-shd volumio[790]: info: CoreCommandRouter::volumioGetBrowseSources May 07 15:52:27 minidsp-shd mpd[960]: May 07 15:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 07 15:52:27 minidsp-shd systemd[1]: Started Music Player Daemon. May 07 15:52:27 minidsp-shd volumio[790]: Upnp client error: Error: This socket has been ended by the other party May 07 15:52:27 minidsp-shd sudo[946]: pam_unix(sudo:session): session closed for user root May 07 15:52:27 minidsp-shd sudo[956]: pam_unix(sudo:session): session closed for user root May 07 15:52:27 minidsp-shd volumio[790]: Upnp client error: Error: This socket has been ended by the other party May 07 15:52:27 minidsp-shd volumio[790]: info: Completed starting Core Plugins May 07 15:52:27 minidsp-shd volumio[790]: info: ------------------------------------------- May 07 15:52:27 minidsp-shd volumio[790]: info: ----- MyVolumio plugins startup ---- May 07 15:52:27 minidsp-shd volumio[790]: info: ------------------------------------------- May 07 15:52:27 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Fetching plans data.... May 07 15:52:27 minidsp-shd volumio[790]: info: MPD running with PID960 May 07 15:52:27 minidsp-shd volumio[790]: ,establishing connection May 07 15:52:27 minidsp-shd volumio[790]: error: MPD error: The expression evaluated to a falsy value: May 07 15:52:27 minidsp-shd volumio[790]: assert.ok(self.idling) May 07 15:52:27 minidsp-shd volumio[790]: error: The expression evaluated to a falsy value: May 07 15:52:27 minidsp-shd volumio[790]: assert.ok(self.idling) May 07 15:52:27 minidsp-shd volumio[790]: error: MPD error: The expression evaluated to a falsy value: May 07 15:52:27 minidsp-shd volumio[790]: assert.ok(self.idling) May 07 15:52:27 minidsp-shd volumio[790]: error: The expression evaluated to a falsy value: May 07 15:52:27 minidsp-shd volumio[790]: assert.ok(self.idling) May 07 15:52:27 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:27 minidsp-shd volumio[790]: error: MPD error: The expression evaluated to a falsy value: May 07 15:52:27 minidsp-shd volumio[790]: assert.ok(self.idling) May 07 15:52:27 minidsp-shd volumio[790]: error: The expression evaluated to a falsy value: May 07 15:52:27 minidsp-shd volumio[790]: assert.ok(self.idling) May 07 15:52:27 minidsp-shd volumio[790]: error: updateQueue error: null May 07 15:52:27 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 07 15:52:29 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 15:52:29 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 15:52:29 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP May 07 15:52:29 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 07 15:52:30 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 07 15:52:31 minidsp-shd volumio[999]: Generating RSA private key, 4096 bit long modulus (2 primes) May 07 15:52:31 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:32 minidsp-shd volumio[790]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 07 15:52:33 minidsp-shd kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 07 15:52:33 minidsp-shd kernel: CIFS VFS: cifs_mount failed w/return code = -115 May 07 15:52:33 minidsp-shd sudo[1040]: pam_unix(sudo:session): session closed for user root May 07 15:52:33 minidsp-shd volumio[790]: info: Cannot mount NAS Musica at system boot, trial number 2 ,retrying in 5 seconds May 07 15:52:35 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 07 15:52:35 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 07 15:52:37 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 07 15:52:37 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 07 15:52:37 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:37 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:37 minidsp-shd volumio[790]: info: Starting MyVolumio Remote Streaming Endpoints May 07 15:52:37 minidsp-shd volumio[790]: info: MyVolumio login type: Token May 07 15:52:37 minidsp-shd volumio[790]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 07 15:52:37 minidsp-shd volumio[790]: 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' May 07 15:52:38 minidsp-shd volumio[999]: .....................................................................................................++++ May 07 15:52:38 minidsp-shd sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.10/Music /mnt/NAS/Musica May 07 15:52:38 minidsp-shd sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:38 minidsp-shd kernel: CIFS: Attempting to mount //192.168.0.10/Music May 07 15:52:38 minidsp-shd kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 07 15:52:39 minidsp-shd volumio[790]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 07 15:52:39 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 15:52:39 minidsp-shd volumio[790]: info: MyVolumio token set successfully May 07 15:52:39 minidsp-shd volumio[790]: info: MYVOLUMIO: Adding device May 07 15:52:39 minidsp-shd volumio[790]: info: MYVOLUMIO: Evaluating Server May 07 15:52:40 minidsp-shd volumio[790]: info: MyVolumio status changed May 07 15:52:40 minidsp-shd volumio[790]: info: Streaming services startup May 07 15:52:40 minidsp-shd volumio[790]: info: Starting Streaming Daemon May 07 15:52:40 minidsp-shd volumio[790]: info: Removing browser output: myVolumio user plan is not superstar May 07 15:52:40 minidsp-shd volumio[790]: info: Removing audio output: May 07 15:52:40 minidsp-shd volumio[790]: info: Stoppping Tunnel 1 May 07 15:52:40 minidsp-shd sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 07 15:52:40 minidsp-shd sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:40 minidsp-shd sudo[1171]: pam_unix(sudo:session): session closed for user root May 07 15:52:40 minidsp-shd sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 07 15:52:40 minidsp-shd sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 15:52:40 minidsp-shd volumio[790]: error: Cannot start Volumio Streaming Daemon May 07 15:52:40 minidsp-shd volumio[790]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 07 15:52:40 minidsp-shd volumio[790]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 07 15:52:40 minidsp-shd sudo[1174]: pam_unix(sudo:session): session closed for user root May 07 15:52:40 minidsp-shd volumio[790]: info: Remote SSH Stopped May 07 15:52:40 minidsp-shd volumio[790]: info: Setting Geolocation for MyVolumio to eu10 May 07 15:52:40 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:40 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:40 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:41 minidsp-shd volumio[790]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 07 15:52:41 minidsp-shd volumio[790]: info: Updating MyVolumio device info May 07 15:52:41 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:41 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:41 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 15:52:42 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 07 15:52:42 minidsp-shd volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 07 15:52:42 minidsp-shd volumio[790]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 07 15:52:42 minidsp-shd volumio[790]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 15:52:42 minidsp-shd volumio[790]: Error: getaddrinfo -3007 May 07 15:52:42 minidsp-shd volumio[790]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) May 07 15:52:42 minidsp-shd volumio[790]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) May 07 15:52:42 minidsp-shd volumio[790]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { May 07 15:52:42 minidsp-shd volumio[790]: code: -3007, May 07 15:52:42 minidsp-shd volumio[790]: errno: -3007, May 07 15:52:42 minidsp-shd volumio[790]: syscall: 'getaddrinfo' May 07 15:52:42 minidsp-shd volumio[790]: } May 07 15:52:42 minidsp-shd volumio[790]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 15:52:43 minidsp-shd volumio[999]: ...............................................................++++ May 07 15:52:43 minidsp-shd volumio[999]: e is 65537 (0x010001) May 07 15:52:43 minidsp-shd volumio[999]: writing RSA key May 07 15:52:43 minidsp-shd sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-07 15:51 May 07 15:52:43 minidsp-shd sudo[1202]: 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"