-- Logs begin at Sat 2023-11-25 14:14:08 CET, end at Fri 2024-05-10 10:49:37 CEST. -- May 10 10:48:56 minidsp-shd ntpd[760]: receive: Unexpected origin timestamp 0xe90c71bb.22a305e0 does not match aorg 0000000000.00000000 from server@193.203.3.170 xmt 0xe9e85df8.69bdb3a5 May 10 10:48:56 minidsp-shd ntpd[760]: receive: Unexpected origin timestamp 0xe90c71bb.22801153 does not match aorg 0000000000.00000000 from server@49.12.199.148 xmt 0xe9e85df8.6ad56d7a May 10 10:48:56 minidsp-shd ntpd[760]: receive: Unexpected origin timestamp 0xe90c71bb.2297d9a1 does not match aorg 0000000000.00000000 from server@94.16.122.152 xmt 0xe9e85df8.6a9e83ec May 10 10:48:56 minidsp-shd ntpd[760]: receive: Unexpected origin timestamp 0xe90c71bb.22c2145b does not match aorg 0000000000.00000000 from server@213.239.234.28 xmt 0xe9e85df8.6ac642e1 May 10 10:48:56 minidsp-shd ntpd[760]: receive: Unexpected origin timestamp 0xe90c71bb.22ae58c5 does not match aorg 0000000000.00000000 from server@178.63.52.31 xmt 0xe9e85df8.6af0df2e May 10 10:48:56 minidsp-shd ntpd[760]: receive: Unexpected origin timestamp 0xe90c71bb.22b22236 does not match aorg 0000000000.00000000 from server@176.9.44.212 xmt 0xe9e85df8.6aef4946 May 10 10:48:56 minidsp-shd ntpd[760]: receive: Unexpected origin timestamp 0xe90c71bb.22a98add does not match aorg 0000000000.00000000 from server@46.235.112.25 xmt 0xe9e85df8.6baac3fc May 10 10:48:56 minidsp-shd systemd[1]: Starting Daily apt download activities... May 10 10:48:57 minidsp-shd volumio[772]: info: Loading plugin "network"... May 10 10:48:57 minidsp-shd volumio[772]: info: Refreshing Cached IP Addresses May 10 10:48:57 minidsp-shd sudo[846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 10:48:57 minidsp-shd sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:48:57 minidsp-shd sudo[846]: pam_unix(sudo:session): session closed for user root May 10 10:48:57 minidsp-shd volumio[772]: info: Loading plugin "services"... May 10 10:48:57 minidsp-shd sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 10:48:57 minidsp-shd sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:48:57 minidsp-shd volumio[772]: info: Loading plugin "alsa_controller"... May 10 10:48:57 minidsp-shd sudo[850]: pam_unix(sudo:session): session closed for user root May 10 10:48:57 minidsp-shd sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 10 10:48:57 minidsp-shd sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:48:57 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 10:48:57 minidsp-shd volumio[772]: info: Loading plugin "wizard"... May 10 10:48:57 minidsp-shd volumio[772]: info: Loading plugin "volumio_command_line_client"... May 10 10:48:57 minidsp-shd volumio[772]: info: Loading plugin "upnp"... May 10 10:48:57 minidsp-shd volumio[772]: info: [1715330937757] Starting Upmpd Daemon May 10 10:48:57 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 10:48:57 minidsp-shd volumio[772]: info: Loading plugin "my_music"... May 10 10:48:57 minidsp-shd volumio[772]: info: Loading plugin "mpd"... May 10 10:48:57 minidsp-shd systemd[1]: apt-daily.service: Succeeded. May 10 10:48:57 minidsp-shd systemd[1]: Started Daily apt download activities. May 10 10:48:57 minidsp-shd systemd[1]: Starting Daily apt upgrade and clean activities... May 10 10:48:58 minidsp-shd volumio[772]: info: Creating MPD Configuration file May 10 10:48:58 minidsp-shd volumio[772]: info: Loading plugin "upnp_browser"... May 10 10:48:58 minidsp-shd sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 10:48:58 minidsp-shd sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:48:58 minidsp-shd sudo[907]: pam_unix(sudo:session): session closed for user root May 10 10:48:58 minidsp-shd sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 10:48:58 minidsp-shd sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:48:58 minidsp-shd systemd[1]: Stopping Music Player Daemon... May 10 10:48:59 minidsp-shd systemd[1]: mpd.service: Succeeded. May 10 10:48:59 minidsp-shd systemd[1]: Stopped Music Player Daemon. May 10 10:48:59 minidsp-shd systemd[1]: Starting Music Player Daemon... May 10 10:48:59 minidsp-shd systemd[1]: apt-daily-upgrade.service: Succeeded. May 10 10:48:59 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. May 10 10:48:59 minidsp-shd volumio[772]: info: Loading plugin "networkfs"... May 10 10:49:00 minidsp-shd volumio[772]: info: Starting Udev Watcher for removable devices May 10 10:49:00 minidsp-shd sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=desktop,password=& May 10 10:49:08 minidsp-shd volumio[772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 10 10:49:08 minidsp-shd volumio[772]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 10:49:08 minidsp-shd volumio[772]: *** WARNING *** For more information see May 10 10:49:08 minidsp-shd node[772]: *** WARNING *** For more information see May 10 10:49:08 minidsp-shd node[772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 10 10:49:08 minidsp-shd node[772]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 10:49:08 minidsp-shd node[772]: *** WARNING *** For more information see May 10 10:49:08 minidsp-shd volumio[772]: info: Applying required configuration parameters for plugin volumiodiscovery May 10 10:49:08 minidsp-shd volumio[772]: info: Discovery: Started advertising with name: miniDSP SHD May 10 10:49:08 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 10:49:08 minidsp-shd volumio[772]: info: Loading plugin "spop"... May 10 10:49:08 minidsp-shd wpa_supplicant[695]: wlan0: CTRL-EVENT-BEACON-LOSS May 10 10:49:10 minidsp-shd volumio[772]: STREAMING PROXY: Starting server on port 3245 May 10 10:49:10 minidsp-shd volumio[772]: Node JS runtime: 14 May 10 10:49:10 minidsp-shd wpa_supplicant[695]: wlan0: CTRL-EVENT-BEACON-LOSS May 10 10:49:10 minidsp-shd volumio-remote-updater[478]: [2024-05-10 10:49:10] [connect] Successful connection May 10 10:49:11 minidsp-shd volumio[772]: info: Plugin multiroom is not enabled May 10 10:49:11 minidsp-shd volumio[772]: info: Loading plugin "outputs"... May 10 10:49:11 minidsp-shd volumio[772]: info: Loading plugin "albumart"... May 10 10:49:11 minidsp-shd volumio[772]: info: Loading plugin "ms_surface_dial"... May 10 10:49:12 minidsp-shd volumio[772]: Forking 3 albumart workers May 10 10:49:12 minidsp-shd volumio[772]: info: Applying required configuration parameters for plugin ms_surface_dial May 10 10:49:13 minidsp-shd volumio[772]: info: Plugin example_plugin is not enabled May 10 10:49:13 minidsp-shd volumio[772]: info: Loading plugin "hi_res_audio"... May 10 10:49:14 minidsp-shd volumio[772]: Starting albumart workers May 10 10:49:14 minidsp-shd volumio[772]: Starting albumart workers May 10 10:49:14 minidsp-shd volumio[772]: Starting albumart workers May 10 10:49:14 minidsp-shd wpa_supplicant[695]: wlan0: CTRL-EVENT-BEACON-LOSS May 10 10:49:16 minidsp-shd volumio[772]: info: Applying required configuration parameters for plugin hi_res_audio May 10 10:49:16 minidsp-shd volumio[772]: info: Loading plugin "inputs"... May 10 10:49:17 minidsp-shd volumio[772]: info: Loading plugin "qobuz"... May 10 10:49:20 minidsp-shd volumio[772]: info: Loading plugin "tidal"... May 10 10:49:21 minidsp-shd wpa_supplicant[695]: wlan0: CTRL-EVENT-BEACON-LOSS May 10 10:49:22 minidsp-shd volumio[772]: info: Loading plugin "oem_helper"... May 10 10:49:24 minidsp-shd volumio[772]: info: Applying required configuration parameters for plugin oem_helper May 10 10:49:24 minidsp-shd volumio[772]: info: Loading plugin "updater_comm"... May 10 10:49:24 minidsp-shd volumio[772]: info: Plugin mpdemulation is not enabled May 10 10:49:24 minidsp-shd volumio[772]: info: Loading plugin "rest_api"... May 10 10:49:24 minidsp-shd volumio[772]: info: Loading plugin "websocket"... May 10 10:49:24 minidsp-shd volumio[772]: info: Loading i18n strings for locale en May 10 10:49:24 minidsp-shd volumio[772]: Updating browse sources language May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::initPlayerControls May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 10:49:25 minidsp-shd volumio[772]: Express server listening on port 3000 May 10 10:49:25 minidsp-shd volumio[772]: [Metrics] WebUI: 34s 616.67ms May 10 10:49:25 minidsp-shd volumio[772]: info: CoreStateMachine::resetVolumioState May 10 10:49:25 minidsp-shd volumio[772]: info: CoreStateMachine::getcurrentVolume May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioRetrievevolume May 10 10:49:25 minidsp-shd volumio[772]: info: CoreStateMachine::pushState May 10 10:49:25 minidsp-shd volumio[772]: info: CorePlayQueue::getTrack 0 May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioPushState May 10 10:49:25 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 10:49:25 minidsp-shd sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 10:49:25 minidsp-shd sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:25 minidsp-shd sudo[1009]: pam_unix(sudo:session): session closed for user root May 10 10:49:25 minidsp-shd sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 10:49:25 minidsp-shd sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:25 minidsp-shd wpa_supplicant[695]: wlan0: CTRL-EVENT-BEACON-LOSS May 10 10:49:25 minidsp-shd sudo[1011]: pam_unix(sudo:session): session closed for user root May 10 10:49:25 minidsp-shd volumio[772]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 10 10:49:25 minidsp-shd volumio[772]: info: Completed loading Core Plugins May 10 10:49:25 minidsp-shd volumio[772]: info: Preparing to generate the ALSA configuration file May 10 10:49:25 minidsp-shd volumio[772]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Hardware May 10 10:49:25 minidsp-shd volumio[772]: info: Cannot mount NAS blob at system boot, trial number 1 ,retrying in 5 seconds May 10 10:49:25 minidsp-shd volumio[772]: info: MPD Permissions set May 10 10:49:25 minidsp-shd volumio-remote-updater[478]: [2024-05-10 10:49:25] [connect] Successful connection May 10 10:49:26 minidsp-shd volumio[772]: info: MPD running with PID929 May 10 10:49:26 minidsp-shd volumio[772]: ,establishing connection May 10 10:49:26 minidsp-shd volumio[772]: error: MPD error: The expression evaluated to a falsy value: May 10 10:49:26 minidsp-shd volumio[772]: assert.ok(self.idling) May 10 10:49:26 minidsp-shd volumio[772]: error: The expression evaluated to a falsy value: May 10 10:49:26 minidsp-shd volumio[772]: assert.ok(self.idling) May 10 10:49:26 minidsp-shd volumio-remote-updater[478]: [2024-05-10 10:49:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715330965 101 May 10 10:49:26 minidsp-shd volumio[772]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: Reloading queue from file May 10 10:49:26 minidsp-shd volumio[772]: info: Asound.conf file unchanged, so no further update is needed May 10 10:49:26 minidsp-shd volumio[772]: info: Output device has changed, restarting MPD May 10 10:49:26 minidsp-shd sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 10:49:26 minidsp-shd sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:26 minidsp-shd volumio[772]: info: Output device has changed, restarting Shairport Sync May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 10:49:26 minidsp-shd sudo[1028]: pam_unix(sudo:session): session closed for user root May 10 10:49:26 minidsp-shd sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 10:49:26 minidsp-shd sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:26 minidsp-shd systemd[1]: Stopping Music Player Daemon... May 10 10:49:26 minidsp-shd volumio[772]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 10:49:26 minidsp-shd volumio[772]: info: ___________ START PLUGINS ___________ May 10 10:49:26 minidsp-shd systemd[1]: mpd.service: Succeeded. May 10 10:49:26 minidsp-shd systemd[1]: Stopped Music Player Daemon. May 10 10:49:26 minidsp-shd systemd[1]: Starting Music Player Daemon... May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 10:49:26 minidsp-shd volumio[772]: info: [1715330966808] CoreMusicLibrary::Adding element Media Servers May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 10:49:26 minidsp-shd volumio[772]: info: Adding Manifest REST API Endpoints May 10 10:49:26 minidsp-shd volumio[772]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui May 10 10:49:26 minidsp-shd volumio[772]: info: Adding METAVOLUMIO REST API Endpoints May 10 10:49:26 minidsp-shd volumio[772]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 10 10:49:26 minidsp-shd volumio[772]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 10 10:49:26 minidsp-shd volumio[772]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 10 10:49:26 minidsp-shd volumio[772]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:26 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 10:49:26 minidsp-shd sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD May 10 10:49:26 minidsp-shd sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:26 minidsp-shd sudo[1038]: pam_unix(sudo:session): session closed for user root May 10 10:49:27 minidsp-shd volumio[772]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 10:49:27 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:27 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 10:49:27 minidsp-shd volumio[772]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 10:49:27 minidsp-shd volumio[772]: info: Preparing CD Folders May 10 10:49:27 minidsp-shd volumio[772]: info: Adding CD REST API Endpoints May 10 10:49:27 minidsp-shd volumio[772]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 10 10:49:27 minidsp-shd volumio[772]: info: Starting UDEV Watcher for CD May 10 10:49:27 minidsp-shd volumio[772]: info: Detecting CD presence with UDEV May 10 10:49:27 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 10 10:49:27 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 10:49:27 minidsp-shd volumio[772]: info: [1715330967658] CoreMusicLibrary::Adding element Last_100 May 10 10:49:27 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 10:49:27 minidsp-shd volumio[772]: info: Streaming services startup May 10 10:49:27 minidsp-shd volumio[772]: info: Starting Streaming Daemon May 10 10:49:27 minidsp-shd sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 10 10:49:27 minidsp-shd sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:27 minidsp-shd wpa_supplicant[695]: wlan0: CTRL-EVENT-BEACON-LOSS May 10 10:49:27 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 10:49:27 minidsp-shd sudo[1049]: pam_unix(sudo:session): session closed for user root May 10 10:49:27 minidsp-shd volumio[772]: info: [1715330967801] CoreMusicLibrary::Adding element Webradio May 10 10:49:27 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 10:49:27 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 10:49:27 minidsp-shd volumio[772]: info: Creating Spotify config file May 10 10:49:27 minidsp-shd dbus-daemon[487]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=1000 pid=772 comm="/usr/bin/node /volumio/index.js ") May 10 10:49:27 minidsp-shd volumio[772]: error: Hi Res Audio Failed Login: Missing Login Data May 10 10:49:27 minidsp-shd systemd[1]: Starting Bluetooth service... May 10 10:49:27 minidsp-shd volumio[772]: info: Adding HIGHRESAUDIO REST API Endpoints May 10 10:49:27 minidsp-shd volumio[772]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 10 10:49:27 minidsp-shd bluetoothd[1055]: Bluetooth daemon 5.23 May 10 10:49:27 minidsp-shd dbus-daemon[487]: [system] Successfully activated service 'org.bluez' May 10 10:49:27 minidsp-shd systemd[1]: Started Bluetooth service. May 10 10:49:27 minidsp-shd bluetoothd[1055]: Starting SDP server May 10 10:49:28 minidsp-shd bluetoothd[1055]: kernel lacks bnep-protocol support May 10 10:49:28 minidsp-shd bluetoothd[1055]: System does not support network plugin May 10 10:49:28 minidsp-shd dbus-daemon[487]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=1055 comm="/usr/local/libexec/bluetooth/bluetoothd ") May 10 10:49:28 minidsp-shd bluetoothd[1055]: Bluetooth management interface 1.14 initialized May 10 10:49:28 minidsp-shd systemd[1]: Starting Hostname Service... May 10 10:49:28 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 10:49:28 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 10:49:28 minidsp-shd volumio[772]: info: Volumio Calling Home May 10 10:49:28 minidsp-shd dbus-daemon[487]: [system] Successfully activated service 'org.freedesktop.hostname1' May 10 10:49:28 minidsp-shd systemd[1]: Started Hostname Service. May 10 10:49:28 minidsp-shd volumio[772]: info: Stopping AccessToken refresher cron for QOBUZ May 10 10:49:28 minidsp-shd volumio[772]: info: AccessToken refresher cron started for QOBUZ May 10 10:49:28 minidsp-shd volumio[772]: info: Stopping AccessToken refresher cron May 10 10:49:28 minidsp-shd volumio[772]: info: AccessToken refresher cron started May 10 10:49:28 minidsp-shd volumio[772]: info: Adding TIDAL REST API Endpoints May 10 10:49:28 minidsp-shd volumio[772]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 10 10:49:28 minidsp-shd volumio[772]: info: Adding QOBUZ REST API Endpoints May 10 10:49:28 minidsp-shd volumio[772]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 10 10:49:28 minidsp-shd volumio[772]: info: Completed starting Core Plugins May 10 10:49:28 minidsp-shd volumio[772]: info: ------------------------------------------- May 10 10:49:28 minidsp-shd volumio[772]: info: ----- MyVolumio plugins startup ---- May 10 10:49:28 minidsp-shd volumio[772]: info: ------------------------------------------- May 10 10:49:28 minidsp-shd volumio[772]: info: [MyVolumio PluginManager] Fetching plans data.... May 10 10:49:28 minidsp-shd volumio[772]: info: CoreStateMachine::setRepeat null single undefined May 10 10:49:28 minidsp-shd volumio[772]: info: CoreStateMachine::pushState May 10 10:49:28 minidsp-shd volumio[772]: info: CorePlayQueue::getTrack 0 May 10 10:49:28 minidsp-shd wpa_supplicant[695]: wlan0: CTRL-EVENT-BEACON-LOSS May 10 10:49:28 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 10:49:28 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioPushState May 10 10:49:28 minidsp-shd volumio[772]: info: CoreStateMachine::setRandom null May 10 10:49:28 minidsp-shd volumio[772]: info: CoreStateMachine::pushState May 10 10:49:28 minidsp-shd volumio[772]: info: CorePlayQueue::getTrack 0 May 10 10:49:28 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioPushState May 10 10:49:28 minidsp-shd volumio[772]: error: updateQueue error: null May 10 10:49:28 minidsp-shd volumio[772]: info: Serial port opened successfully May 10 10:49:29 minidsp-shd volumio[772]: error: Cannot start Volumio Streaming Daemon May 10 10:49:29 minidsp-shd volumio[772]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 10 10:49:29 minidsp-shd volumio[772]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 10 10:49:29 minidsp-shd volumio[772]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' May 10 10:49:29 minidsp-shd volumio[772]: Can't get device info: No such device May 10 10:49:29 minidsp-shd volumio[772]: info: MPD Permissions set May 10 10:49:29 minidsp-shd volumio[772]: [29B blob data] May 10 10:49:29 minidsp-shd volumio[772]: info: Serial Data: PUSH MODEL 1 May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat May 10 10:49:29 minidsp-shd volumio[772]: info: Adding MINIDSP Inputs May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 10:49:29 minidsp-shd volumio[772]: info: [1715330969074] CoreMusicLibrary::Adding element Inputs May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 10:49:29 minidsp-shd volumio[772]: info: [1715330969084] CoreMusicLibrary::Adding element Presets May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 10:49:29 minidsp-shd volumio[772]: Cannot find translation for source Presets May 10 10:49:29 minidsp-shd volumio[772]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} May 10 10:49:29 minidsp-shd volumio[772]: info: [msSurfaceDial init()] check /org/bluez May 10 10:49:29 minidsp-shd volumio[772]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null May 10 10:49:29 minidsp-shd sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 10 10:49:29 minidsp-shd sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:29 minidsp-shd volumio[772]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::Reload Ui May 10 10:49:29 minidsp-shd volumio[772]: info: Volumio called home May 10 10:49:29 minidsp-shd volumio[772]: info: Spotify config file written May 10 10:49:29 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. May 10 10:49:29 minidsp-shd sudo[1072]: pam_unix(sudo:session): session closed for user root May 10 10:49:29 minidsp-shd volumio[772]: info: Discovery: adding 32c40a8b-bd6e-48d3-a185-ca98466ad2ea May 10 10:49:29 minidsp-shd volumio[772]: info: Discovery: Found device miniDSP SHD May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioGetState May 10 10:49:29 minidsp-shd volumio[772]: info: CorePlayQueue::getTrack 0 May 10 10:49:29 minidsp-shd sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 10 10:49:29 minidsp-shd sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:29 minidsp-shd volumio[772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:29 minidsp-shd systemd[1]: Started go-librespot Daemon. May 10 10:49:29 minidsp-shd sudo[1076]: pam_unix(sudo:session): session closed for user root May 10 10:49:29 minidsp-shd go-librespot[1078]: Librespot-go daemon starting... May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 10 10:49:29 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:29 minidsp-shd go-librespot[1078]: time="2024-05-10T10:49:29+02:00" level=info msg="generated new device id: 7eb579e281559b5e8257d1f8d8c279f241e73ca6" May 10 10:49:29 minidsp-shd volumio[772]: info: Upmpdcli Daemon Started May 10 10:49:29 minidsp-shd volumio[772]: info: Starting Shairport Sync May 10 10:49:29 minidsp-shd volumio[772]: info: Starting Shairport Sync May 10 10:49:29 minidsp-shd volumio[772]: info: Starting Shairport Sync May 10 10:49:29 minidsp-shd sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 10:49:29 minidsp-shd sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:30 minidsp-shd sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 10:49:30 minidsp-shd sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:30 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 10 10:49:30 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. May 10 10:49:30 minidsp-shd sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 10:49:30 minidsp-shd sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:30 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 10 10:49:30 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 10 10:49:30 minidsp-shd sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 10 10:49:30 minidsp-shd sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:30 minidsp-shd sudo[1094]: pam_unix(sudo:session): session closed for user root May 10 10:49:30 minidsp-shd sudo[1092]: pam_unix(sudo:session): session closed for user root May 10 10:49:30 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. May 10 10:49:30 minidsp-shd sudo[1101]: pam_unix(sudo:session): session closed for user root May 10 10:49:30 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 10 10:49:30 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. May 10 10:49:30 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 10 10:49:30 minidsp-shd sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 10 10:49:30 minidsp-shd sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 10:49:30 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 10 10:49:30 minidsp-shd volumiobt[1105]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 10 10:49:30 minidsp-shd sudo[1106]: pam_unix(sudo:session): session closed for user root May 10 10:49:30 minidsp-shd sudo[1097]: pam_unix(sudo:session): session closed for user root May 10 10:49:30 minidsp-shd volumio[772]: [30B blob data] May 10 10:49:30 minidsp-shd volumio[772]: info: Serial Data: PUSH SOURCE 7 May 10 10:49:30 minidsp-shd volumio[772]: info: CoreCommandRouter::volumiosetSourceActiveno-source May 10 10:49:30 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 10:49:30 minidsp-shd volumio[772]: Cannot find translation for source Presets May 10 10:49:30 minidsp-shd volumio[772]: info: Shairport-Sync Started May 10 10:49:30 minidsp-shd volumio[772]: Error adding Membership: Error: addMembership EINVAL May 10 10:49:30 minidsp-shd volumio[772]: info: Volumio BT Module successfully started May 10 10:49:30 minidsp-shd volumio[772]: info: Shairport-Sync Started May 10 10:49:30 minidsp-shd volumio[772]: info: Shairport-Sync Started May 10 10:49:30 minidsp-shd volumio[772]: [30B blob data] May 10 10:49:30 minidsp-shd volumio[772]: info: Serial Data: PUSH PRESET 1 May 10 10:49:30 minidsp-shd go-librespot[1078]: time="2024-05-10T10:49:30+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 10 10:49:30 minidsp-shd go-librespot[1078]: time="2024-05-10T10:49:30+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 10 10:49:30 minidsp-shd go-librespot[1078]: time="2024-05-10T10:49:30+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 10 10:49:30 minidsp-shd go-librespot[1078]: time="2024-05-10T10:49:30+02:00" level=debug msg="zeroconf server listening on port 33125" May 10 10:49:30 minidsp-shd volumio[772]: info: CoreCommandRouter::volumioGetState May 10 10:49:30 minidsp-shd volumio[772]: info: CorePlayQueue::getTrack 0 May 10 10:49:30 minidsp-shd volumio[772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 May 10 10:49:31 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 10 10:49:31 minidsp-shd volumio[772]: info: Enabling external Volume Control May 10 10:49:31 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:31 minidsp-shd volumio[772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 10:49:31 minidsp-shd sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=desktop,password=&