-- Logs begin at Sat 2024-11-23 15:05:24 CET, end at Sat 2024-11-23 15:07:38 CET. -- Nov 23 15:06:09 minidsp-shd volumio[697]: Starting albumart workers Nov 23 15:06:09 minidsp-shd volumio[697]: Starting albumart workers Nov 23 15:06:09 minidsp-shd volumio[697]: Starting albumart workers Nov 23 15:06:10 minidsp-shd volumio[697]: info: Applying required configuration parameters for plugin hi_res_audio Nov 23 15:06:10 minidsp-shd volumio[697]: info: Loading plugin "inputs"... Nov 23 15:06:11 minidsp-shd volumio[697]: info: Loading plugin "qobuz"... Nov 23 15:06:13 minidsp-shd volumio[697]: info: Loading plugin "tidal"... Nov 23 15:06:15 minidsp-shd volumio[697]: info: Loading plugin "oem_helper"... Nov 23 15:06:15 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. Nov 23 15:06:16 minidsp-shd volumio[697]: info: Applying required configuration parameters for plugin oem_helper Nov 23 15:06:16 minidsp-shd volumio[697]: info: Loading plugin "updater_comm"... Nov 23 15:06:17 minidsp-shd volumio[697]: info: Plugin mpdemulation is not enabled Nov 23 15:06:17 minidsp-shd volumio[697]: info: Loading plugin "rest_api"... Nov 23 15:06:17 minidsp-shd volumio[697]: info: Loading plugin "websocket"... Nov 23 15:06:17 minidsp-shd volumio[697]: info: Loading plugin "motherearthradio"... Nov 23 15:06:17 minidsp-shd volumio[697]: info: Applying required configuration parameters for plugin motherearthradio Nov 23 15:06:17 minidsp-shd volumio[697]: info: [1732370777670] [MotherEarth] API delay: 5 Nov 23 15:06:17 minidsp-shd volumio[697]: info: Loading i18n strings for locale nl Nov 23 15:06:17 minidsp-shd volumio[697]: Updating browse sources language Nov 23 15:06:17 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::initPlayerControls Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: Express server listening on port 3000 Nov 23 15:06:18 minidsp-shd volumio[697]: [Metrics] WebUI: 26s 10.14ms Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreStateMachine::resetVolumioState Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreStateMachine::getcurrentVolume Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Nov 23 15:06:18 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:18 minidsp-shd sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 15:06:18 minidsp-shd sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:18 minidsp-shd sudo[954]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:18 minidsp-shd sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 15:06:18 minidsp-shd sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:18 minidsp-shd volumio[697]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 23 15:06:18 minidsp-shd volumio[697]: info: Completed loading Core Plugins Nov 23 15:06:18 minidsp-shd volumio[697]: info: Preparing to generate the ALSA configuration file Nov 23 15:06:18 minidsp-shd sudo[957]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:18 minidsp-shd volumio[697]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Nov 23 15:06:18 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 192.168.0.35 from 192.168.0.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Total Clients: 1 Nov 23 15:06:18 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 192.168.0.35 from 192.168.0.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Total Clients: 2 Nov 23 15:06:18 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 192.168.0.35 from 192.168.0.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Total Clients: 3 Nov 23 15:06:18 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 192.168.0.35 from 192.168.0.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Total Clients: 4 Nov 23 15:06:18 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 192.168.0.35 from 192.168.0.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Total Clients: 5 Nov 23 15:06:18 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 192.168.0.35 from 192.168.0.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Total Clients: 6 Nov 23 15:06:18 minidsp-shd volumio[697]: info: Reloading queue from file Nov 23 15:06:18 minidsp-shd volumio[697]: info: Asound.conf file unchanged, so no further update is needed Nov 23 15:06:18 minidsp-shd volumio[697]: info: Output device has changed, restarting MPD Nov 23 15:06:18 minidsp-shd sudo[967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 15:06:18 minidsp-shd sudo[967]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:18 minidsp-shd sudo[967]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:18 minidsp-shd volumio[697]: info: ___________ START PLUGINS ___________ Nov 23 15:06:18 minidsp-shd volumio[697]: info: ControllerMpd::onStart: Initializing MPD Nov 23 15:06:18 minidsp-shd sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 15:06:18 minidsp-shd sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:18 minidsp-shd volumio[697]: info: Creating MPD Configuration file Nov 23 15:06:18 minidsp-shd systemd[1]: Listening on mpd.socket. Nov 23 15:06:18 minidsp-shd systemd[1]: Starting Music Player Daemon... Nov 23 15:06:18 minidsp-shd sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 15:06:18 minidsp-shd sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:06:18 minidsp-shd sudo[973]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:18 minidsp-shd volumio[697]: info: [1732370778782] CoreMusicLibrary::Adding element Media Servers Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:18 minidsp-shd sudo[977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 15:06:18 minidsp-shd sudo[977]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 23 15:06:18 minidsp-shd volumio[697]: info: Adding Manifest REST API Endpoints Nov 23 15:06:18 minidsp-shd volumio[697]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Nov 23 15:06:18 minidsp-shd volumio[697]: info: Adding METAVOLUMIO REST API Endpoints Nov 23 15:06:18 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 23 15:06:18 minidsp-shd systemd[1]: mpd.service: Succeeded. Nov 23 15:06:18 minidsp-shd volumio[697]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 23 15:06:18 minidsp-shd volumio[697]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 23 15:06:18 minidsp-shd volumio[697]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 23 15:06:18 minidsp-shd systemd[1]: Stopped Music Player Daemon. Nov 23 15:06:18 minidsp-shd volumio[697]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 23 15:06:18 minidsp-shd volumio[697]: info: Preparing CD Folders Nov 23 15:06:18 minidsp-shd sudo[980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP-SHD Nov 23 15:06:18 minidsp-shd sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:18 minidsp-shd volumio[697]: info: Adding CD REST API Endpoints Nov 23 15:06:18 minidsp-shd volumio[697]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 23 15:06:18 minidsp-shd systemd[1]: Starting Music Player Daemon... Nov 23 15:06:18 minidsp-shd volumio[697]: info: Starting UDEV Watcher for CD Nov 23 15:06:18 minidsp-shd volumio[697]: info: Detecting CD presence with UDEV Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:06:18 minidsp-shd volumio[697]: info: [1732370778896] CoreMusicLibrary::Adding element Last_100 Nov 23 15:06:18 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:18 minidsp-shd volumio[697]: info: Streaming services startup Nov 23 15:06:18 minidsp-shd volumio[697]: info: Starting Streaming Daemon Nov 23 15:06:18 minidsp-shd kernel: Bluetooth: Core ver 2.22 Nov 23 15:06:18 minidsp-shd kernel: NET: Registered protocol family 31 Nov 23 15:06:18 minidsp-shd kernel: Bluetooth: HCI device and connection manager initialized Nov 23 15:06:18 minidsp-shd kernel: Bluetooth: HCI socket layer initialized Nov 23 15:06:18 minidsp-shd kernel: Bluetooth: L2CAP socket layer initialized Nov 23 15:06:18 minidsp-shd kernel: Bluetooth: SCO socket layer initialized Nov 23 15:06:18 minidsp-shd sudo[980]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:18 minidsp-shd sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 23 15:06:18 minidsp-shd sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:18 minidsp-shd sudo[985]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:06:19 minidsp-shd volumio[697]: info: [1732370779046] CoreMusicLibrary::Adding element Webradio Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:06:19 minidsp-shd volumio[697]: info: [1732370779074] CoreMusicLibrary::Adding element Mixcloud Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:19 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:06:19 minidsp-shd volumio[697]: info: [1732370779094] CoreMusicLibrary::Adding element SoundCloud Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:19 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:06:19 minidsp-shd volumio[697]: Cannot find translation for source SoundCloud Nov 23 15:06:19 minidsp-shd volumio[697]: info: Creating Spotify config file Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:19 minidsp-shd volumio[697]: error: Hi Res Audio Failed Login: Missing Login Data Nov 23 15:06:19 minidsp-shd volumio[697]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 23 15:06:19 minidsp-shd volumio[697]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 23 15:06:19 minidsp-shd volumio[697]: info: Refreshing QOBUZ token Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:06:19 minidsp-shd volumio[697]: info: [1732370779319] CoreMusicLibrary::Adding element Mother Earth Radio Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:19 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:06:19 minidsp-shd volumio[697]: Cannot find translation for source SoundCloud Nov 23 15:06:19 minidsp-shd volumio[697]: Cannot find translation for source Mother Earth Radio Nov 23 15:06:19 minidsp-shd volumio[697]: info: Volumio Calling Home Nov 23 15:06:19 minidsp-shd volumio[697]: info: Stopping AccessToken refresher cron Nov 23 15:06:19 minidsp-shd volumio[697]: info: AccessToken refresher cron started Nov 23 15:06:19 minidsp-shd volumio[697]: info: Adding TIDAL REST API Endpoints Nov 23 15:06:19 minidsp-shd volumio[697]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 23 15:06:19 minidsp-shd volumio-remote-updater[419]: [2024-11-23 15:06:19] [connect] Successful connection Nov 23 15:06:19 minidsp-shd volumio[697]: info: Setting Device type: NanoPi NEO 2 Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreStateMachine::setRepeat false single undefined Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Nov 23 15:06:19 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreStateMachine::setRandom false Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Nov 23 15:06:19 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:06:19 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Nov 23 15:06:19 minidsp-shd volumio[697]: info: Serial port opened successfully Nov 23 15:06:19 minidsp-shd volumio[697]: error: Cannot start Volumio Streaming Daemon Nov 23 15:06:19 minidsp-shd volumio[697]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 23 15:06:19 minidsp-shd volumio[697]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 23 15:06:19 minidsp-shd volumio[697]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP-SHD' Nov 23 15:06:19 minidsp-shd volumio[697]: Can't get device info: No such device Nov 23 15:06:19 minidsp-shd volumio[697]: info: MPD Permissions set Nov 23 15:06:19 minidsp-shd volumio[697]: info: MPD Permissions set Nov 23 15:06:19 minidsp-shd volumio-remote-updater[419]: [2024-11-23 15:06:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732370779 101 Nov 23 15:06:19 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 7 Nov 23 15:06:19 minidsp-shd sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 23 15:06:19 minidsp-shd sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:19 minidsp-shd volumio[697]: info: Spotify config file written Nov 23 15:06:19 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Nov 23 15:06:19 minidsp-shd sudo[1009]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:19 minidsp-shd sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 23 15:06:19 minidsp-shd sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:19 minidsp-shd systemd[1]: Started go-librespot Daemon. Nov 23 15:06:19 minidsp-shd sudo[1013]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:19 minidsp-shd volumio[697]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd go-librespot[1015]: Librespot-go daemon starting... Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Nov 23 15:06:20 minidsp-shd volumio[697]: info: Adding MINIDSP Inputs Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:06:20 minidsp-shd volumio[697]: info: [1732370780233] CoreMusicLibrary::Adding element Inputs Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source SoundCloud Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Mother Earth Radio Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:06:20 minidsp-shd volumio[697]: info: [1732370780240] CoreMusicLibrary::Adding element Presets Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source SoundCloud Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Mother Earth Radio Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Presets Nov 23 15:06:20 minidsp-shd volumio[697]: info: Volumio called home Nov 23 15:06:20 minidsp-shd volumio[697]: info: Upmpdcli Daemon Started Nov 23 15:06:20 minidsp-shd volumio[697]: info: Discovery: adding 56cc5ad8-b28c-44fd-aa83-5383f8324c77 Nov 23 15:06:20 minidsp-shd volumio[697]: info: Discovery: Found device miniDSP-SHD Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Nov 23 15:06:20 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Nov 23 15:06:20 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:20 minidsp-shd volumio[697]: info: Access Token successfully retrieved Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 23 15:06:20 minidsp-shd volumio[697]: info: [1732370780643] CoreMusicLibrary::Adding element QOBUZ Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source SoundCloud Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Mother Earth Radio Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Presets Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Nov 23 15:06:20 minidsp-shd volumio[697]: info: Stopping AccessToken refresher cron for QOBUZ Nov 23 15:06:20 minidsp-shd volumio[697]: info: AccessToken refresher cron started for QOBUZ Nov 23 15:06:20 minidsp-shd go-librespot[1015]: time="2024-11-23T15:06:20+01:00" level=info msg="generated new device id: d29489ac798774c528d0273ad6d8289d81319238" Nov 23 15:06:20 minidsp-shd volumio[697]: info: Adding QOBUZ REST API Endpoints Nov 23 15:06:20 minidsp-shd volumio[697]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::volumiosetSourceActiveno-source Nov 23 15:06:20 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source SoundCloud Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Mother Earth Radio Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source Presets Nov 23 15:06:20 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Nov 23 15:06:21 minidsp-shd go-librespot[1015]: time="2024-11-23T15:06:21+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 15:06:21 minidsp-shd go-librespot[1015]: time="2024-11-23T15:06:21+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 15:06:21 minidsp-shd go-librespot[1015]: time="2024-11-23T15:06:21+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 15:06:21 minidsp-shd go-librespot[1015]: time="2024-11-23T15:06:21+01:00" level=debug msg="zeroconf server listening on port 35599" Nov 23 15:06:21 minidsp-shd volumio[697]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Nov 23 15:06:21 minidsp-shd sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 23 15:06:21 minidsp-shd sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:21 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Nov 23 15:06:21 minidsp-shd sudo[1036]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:21 minidsp-shd sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 23 15:06:21 minidsp-shd sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:21 minidsp-shd volumiobt[1038]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 23 15:06:21 minidsp-shd sudo[1039]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:22 minidsp-shd volumio[697]: info: Volumio BT Module successfully started Nov 23 15:06:22 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 23 15:06:22 minidsp-shd volumio[697]: info: Enabling external Volume Control Nov 23 15:06:22 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:22 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:23 minidsp-shd sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 23 15:06:23 minidsp-shd sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:23 minidsp-shd volumiobt[1038]: pulseaudio: no process found Nov 23 15:06:23 minidsp-shd sudo[1042]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:23 minidsp-shd dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.11' (uid=1000 pid=1046 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 23 15:06:23 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 23 15:06:23 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 23 15:06:23 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Nov 23 15:06:23 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:06:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Nov 23 15:06:23 minidsp-shd dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 23 15:06:23 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 23 15:06:23 minidsp-shd rtkit-daemon[1047]: Successfully called chroot. Nov 23 15:06:23 minidsp-shd rtkit-daemon[1047]: Successfully dropped privileges. Nov 23 15:06:23 minidsp-shd rtkit-daemon[1047]: Successfully limited resources. Nov 23 15:06:23 minidsp-shd rtkit-daemon[1047]: Running. Nov 23 15:06:23 minidsp-shd rtkit-daemon[1047]: Canary thread running. Nov 23 15:06:23 minidsp-shd dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.12' (uid=0 pid=1047 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 23 15:06:23 minidsp-shd rtkit-daemon[1047]: Watchdog thread running. Nov 23 15:06:23 minidsp-shd systemd[1]: Starting Authorization Manager... Nov 23 15:06:23 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 23 15:06:23 minidsp-shd volumio[697]: info: go-librespot daemon successfully initialized Nov 23 15:06:23 minidsp-shd mpd[981]: Nov 23 15:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 15:06:23 minidsp-shd polkitd[1050]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 23 15:06:23 minidsp-shd dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 23 15:06:23 minidsp-shd systemd[1]: Started Authorization Manager. Nov 23 15:06:23 minidsp-shd systemd[1]: Started Music Player Daemon. Nov 23 15:06:23 minidsp-shd sudo[977]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:23 minidsp-shd sudo[970]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:23 minidsp-shd sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=koen,password=Marantz@!5019,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.16/Music /mnt/NAS/Music Nov 23 15:06:23 minidsp-shd sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06: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. Nov 23 15:06:23 minidsp-shd volumio[697]: Upnp client error: Error: This socket has been ended by the other party Nov 23 15:06:23 minidsp-shd volumio[697]: verbose: New Socket.io Connection to 192.168.0.35 from 192.168.0.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Total Clients: 10 Nov 23 15:06:23 minidsp-shd volumio[697]: info: Completed starting Core Plugins Nov 23 15:06:23 minidsp-shd volumio[697]: info: ------------------------------------------- Nov 23 15:06:23 minidsp-shd volumio[697]: info: ----- MyVolumio plugins startup ---- Nov 23 15:06:23 minidsp-shd volumio[697]: info: ------------------------------------------- Nov 23 15:06:23 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 23 15:06:23 minidsp-shd kernel: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 23 15:06:23 minidsp-shd sudo[1058]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:23 minidsp-shd volumio[697]: info: MPD running with PID981 Nov 23 15:06:23 minidsp-shd volumio[697]: ,establishing connection Nov 23 15:06:23 minidsp-shd volumio[697]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Nov 23 15:06:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Nov 23 15:06:23 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:23 minidsp-shd volumio[697]: error: MPD error: The expression evaluated to a falsy value: Nov 23 15:06:23 minidsp-shd volumio[697]: assert.ok(self.idling) Nov 23 15:06:23 minidsp-shd volumio[697]: error: The expression evaluated to a falsy value: Nov 23 15:06:23 minidsp-shd volumio[697]: assert.ok(self.idling) Nov 23 15:06:23 minidsp-shd volumio[697]: error: MPD error: The expression evaluated to a falsy value: Nov 23 15:06:23 minidsp-shd volumio[697]: assert.ok(self.idling) Nov 23 15:06:23 minidsp-shd volumio[697]: error: The expression evaluated to a falsy value: Nov 23 15:06:23 minidsp-shd volumio[697]: assert.ok(self.idling) Nov 23 15:06:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 15:06:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 15:06:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:06:23 minidsp-shd dbus-daemon[428]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.32' (uid=1000 pid=1046 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 23 15:06:23 minidsp-shd volumio[697]: info: Listing playlists Nov 23 15:06:23 minidsp-shd systemd[1]: Starting Bluetooth service... Nov 23 15:06:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetQueue Nov 23 15:06:23 minidsp-shd volumio[697]: info: CoreStateMachine::getQueue Nov 23 15:06:23 minidsp-shd volumio[697]: info: CorePlayQueue::getQueue Nov 23 15:06:23 minidsp-shd bluetoothd[1079]: Bluetooth daemon 5.23 Nov 23 15:06:23 minidsp-shd dbus-daemon[428]: [system] Successfully activated service 'org.bluez' Nov 23 15:06:23 minidsp-shd systemd[1]: Started Bluetooth service. Nov 23 15:06:23 minidsp-shd bluetoothd[1079]: Starting SDP server Nov 23 15:06:23 minidsp-shd kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 23 15:06:23 minidsp-shd kernel: Bluetooth: BNEP filters: protocol multicast Nov 23 15:06:23 minidsp-shd kernel: Bluetooth: BNEP socket layer initialized Nov 23 15:06:23 minidsp-shd bluetoothd[1079]: Bluetooth management interface 1.14 initialized Nov 23 15:06:23 minidsp-shd pulseaudio[1046]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 23 15:06:23 minidsp-shd pulseaudio[1046]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 23 15:06:23 minidsp-shd volumiobt[1038]: Applying permissions Nov 23 15:06:23 minidsp-shd volumiobt[1038]: Setting BT discoverable and pairable Nov 23 15:06:23 minidsp-shd dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.33' (uid=0 pid=1079 comm="/usr/local/libexec/bluetooth/bluetoothd ") Nov 23 15:06:23 minidsp-shd pulseaudio[1046]: 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 Nov 23 15:06:23 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 23 15:06:23 minidsp-shd systemd[1]: Starting Hostname Service... Nov 23 15:06:23 minidsp-shd volumiobt[1038]: [38B blob data] Nov 23 15:06:23 minidsp-shd volumiobt[1038]: Invalid command Nov 23 15:06:23 minidsp-shd volumiobt[1038]: [bluetooth]# agent on Nov 23 15:06:23 minidsp-shd volumiobt[1038]: [bluetooth]# discoverable on Nov 23 15:06:23 minidsp-shd volumiobt[1038]: No default controller available Nov 23 15:06:23 minidsp-shd volumiobt[1038]: [bluetooth]# pairable on Nov 23 15:06:23 minidsp-shd volumiobt[1038]: No default controller available Nov 23 15:06:23 minidsp-shd volumiobt[1038]: [bluetooth]# agent NoInputNoOutput Nov 23 15:06:23 minidsp-shd volumio[697]: error: updateQueue error: null Nov 23 15:06:23 minidsp-shd volumiobt[1038]: Failed to register agent object Nov 23 15:06:23 minidsp-shd volumiobt[1038]: [bluetooth]# default-agent Nov 23 15:06:23 minidsp-shd volumiobt[1038]: No agent is registered Nov 23 15:06:23 minidsp-shd volumiobt[1038]: [bluetooth]# quit Nov 23 15:06:23 minidsp-shd volumiobt[1038]: [61B blob data] Nov 23 15:06:23 minidsp-shd volumiobt[1038]: [62B blob data] Nov 23 15:06:23 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 23 15:06:24 minidsp-shd volumio[697]: info: CoreStateMachine::pushState Nov 23 15:06:24 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:24 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 15:06:24 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioPushState Nov 23 15:06:24 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Nov 23 15:06:24 minidsp-shd dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 23 15:06:24 minidsp-shd systemd[1]: Started Hostname Service. Nov 23 15:06:24 minidsp-shd volumio[1012]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 23 15:06:26 minidsp-shd volumio[697]: info: Initializing connection to go-librespot Websocket Nov 23 15:06:26 minidsp-shd go-librespot[1015]: time="2024-11-23T15:06:26+01:00" level=debug msg="new websocket client" Nov 23 15:06:26 minidsp-shd volumio[697]: info: Connection to go-librespot Websocket established Nov 23 15:06:28 minidsp-shd volumio[697]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 23 15:06:28 minidsp-shd sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=koen,password=Marantz@!5019,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.16/Music /mnt/NAS/Music Nov 23 15:06:28 minidsp-shd sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:28 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. Nov 23 15:06:28 minidsp-shd sudo[1102]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:28 minidsp-shd kernel: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 23 15:06:28 minidsp-shd volumio[697]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds Nov 23 15:06:29 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Nov 23 15:06:29 minidsp-shd volumio[697]: info: Getting Spotify volume Nov 23 15:06:29 minidsp-shd volumio[697]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Nov 23 15:06:29 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Nov 23 15:06:29 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:29 minidsp-shd volumio[697]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 23 15:06:29 minidsp-shd volumio[697]: SPOTIFY: SPOTIFY VOLUME undefined Nov 23 15:06:29 minidsp-shd volumio[697]: SPOTIFY: VOLUMIO VOLUME 100 Nov 23 15:06:29 minidsp-shd volumio[697]: info: Aligning Spotify Volume to Volumio Volume Nov 23 15:06:29 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Nov 23 15:06:29 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:29 minidsp-shd volumio[697]: info: Setting Spotify Volume from Volumio: 100 Nov 23 15:06:31 minidsp-shd volumio[697]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Nov 23 15:06:31 minidsp-shd volumio[697]: info: Sending Spotify command with payload to local API: /player/volume Nov 23 15:06:31 minidsp-shd volumio[1012]: ................................................................................++++ Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 23 15:06:31 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 23 15:06:32 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 23 15:06:33 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 23 15:06:33 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 23 15:06:33 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:33 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:33 minidsp-shd volumio[697]: info: Starting MyVolumio Remote Streaming Endpoints Nov 23 15:06:33 minidsp-shd volumio[697]: info: MyVolumio login type: Token Nov 23 15:06:33 minidsp-shd volumio[697]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 23 15:06:33 minidsp-shd volumio[697]: 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' Nov 23 15:06:33 minidsp-shd sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=koen,password=Marantz@!5019,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.16/Music /mnt/NAS/Music Nov 23 15:06:33 minidsp-shd sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:33 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Nov 23 15:06:33 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. Nov 23 15:06:33 minidsp-shd kernel: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 23 15:06:33 minidsp-shd sudo[1108]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:33 minidsp-shd volumio[697]: info: Cannot mount NAS Music at system boot, trial number 4 ,retrying in 5 seconds Nov 23 15:06:33 minidsp-shd volumio[697]: error: MyVolumio Custom Token format not valid, refreshing it Nov 23 15:06:33 minidsp-shd volumio[697]: info: handleBrowseUri took 229 milliseconds Nov 23 15:06:34 minidsp-shd volumio[697]: info: Preload queue cleared Nov 23 15:06:34 minidsp-shd volumio[697]: info: Preload queue cleared Nov 23 15:06:34 minidsp-shd volumio[1012]: ............................++++ Nov 23 15:06:34 minidsp-shd volumio[1012]: e is 65537 (0x010001) Nov 23 15:06:34 minidsp-shd volumio[1012]: writing RSA key Nov 23 15:06:35 minidsp-shd volumio[697]: info: MyVolumio login type: Token Nov 23 15:06:35 minidsp-shd volumio[697]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 23 15:06:35 minidsp-shd volumio[697]: info: MyVolumio token set successfully Nov 23 15:06:35 minidsp-shd volumio[697]: info: MYVOLUMIO: Adding device Nov 23 15:06:35 minidsp-shd volumio[697]: info: MYVOLUMIO: Evaluating Server Nov 23 15:06:36 minidsp-shd volumio[697]: info: MyVolumio status changed Nov 23 15:06:36 minidsp-shd volumio[697]: info: Streaming services startup Nov 23 15:06:36 minidsp-shd volumio[697]: info: Starting Streaming Daemon Nov 23 15:06:36 minidsp-shd volumio[697]: info: Removing browser output: myVolumio user plan is not superstar Nov 23 15:06:36 minidsp-shd volumio[697]: info: Removing audio output: Nov 23 15:06:36 minidsp-shd volumio[697]: info: Stoppping Tunnel 1 Nov 23 15:06:36 minidsp-shd sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 23 15:06:36 minidsp-shd sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:36 minidsp-shd sudo[1151]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:36 minidsp-shd sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 23 15:06:36 minidsp-shd sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06:36 minidsp-shd volumio[697]: error: Cannot start Volumio Streaming Daemon Nov 23 15:06:36 minidsp-shd volumio[697]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 23 15:06:36 minidsp-shd volumio[697]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 23 15:06:36 minidsp-shd sudo[1154]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:36 minidsp-shd volumio[697]: info: Remote SSH Stopped Nov 23 15:06:36 minidsp-shd volumio[697]: info: Setting Geolocation for MyVolumio to eu2 Nov 23 15:06:36 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:36 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:36 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:37 minidsp-shd volumio[697]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 23 15:06:37 minidsp-shd volumio[697]: info: Updating MyVolumio device info Nov 23 15:06:37 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:37 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:37 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:38 minidsp-shd volumio[697]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 23 15:06:38 minidsp-shd sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=koen,password=Marantz@!5019,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.16/Music /mnt/NAS/Music Nov 23 15:06:38 minidsp-shd sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:06: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. Nov 23 15:06:38 minidsp-shd kernel: Status code returned 0xc000006d STATUS_LOGON_FAILURE Nov 23 15:06:38 minidsp-shd sudo[1157]: pam_unix(sudo:session): session closed for user root Nov 23 15:06:38 minidsp-shd volumio[697]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 23 15:06:39 minidsp-shd volumio[697]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 15:06:39 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 23 15:06:39 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 23 15:06:39 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 23 15:06:39 minidsp-shd volumio[697]: info: Received Get System Version Nov 23 15:06:39 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:06:39 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Nov 23 15:06:41 minidsp-shd volumio[697]: info: MYVOLUMIO: Adding device Nov 23 15:06:41 minidsp-shd volumio[697]: info: MYVOLUMIO: Evaluating Server Nov 23 15:06:42 minidsp-shd volumio[697]: info: Setting Geolocation for MyVolumio to eu8 Nov 23 15:06:42 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:42 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:42 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:42 minidsp-shd volumio[697]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 23 15:06:43 minidsp-shd volumio-remote-updater[419]: No test mode Nov 23 15:06:43 minidsp-shd volumio-remote-updater[419]: No alpha test mode Nov 23 15:06:43 minidsp-shd volumio[697]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 23 15:06:43 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 23 15:06:43 minidsp-shd volumio[697]: info: Updating MyVolumio device info Nov 23 15:06:43 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:43 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:43 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:43 minidsp-shd volumio[697]: error: MyVolumio Plugin failed to start in a timely fashion Nov 23 15:06:43 minidsp-shd volumio[697]: info: BOOT COMPLETED Nov 23 15:06:43 minidsp-shd volumio[697]: [Metrics] CommandRouter: 50s 267.24ms Nov 23 15:06:43 minidsp-shd volumio[697]: info: CoreCommandRouter::volumiosetStartupVolume Nov 23 15:06:43 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:43 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:43 minidsp-shd volumio[697]: info: CoreCommandRouter::Close All Modals sent Nov 23 15:06:43 minidsp-shd volumio[697]: info: CoreCommandRouter::Close All Modals sent Nov 23 15:06:43 minidsp-shd volumio[697]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 23 15:06:44 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 23 15:06:44 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 15:06:44 minidsp-shd volumio[697]: xcb_connection_has_error() returned true Nov 23 15:06:44 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 23 15:06:48 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 15:06:48 minidsp-shd volumio[697]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Nov 23 15:06:48 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetState Nov 23 15:06:48 minidsp-shd volumio[697]: info: CorePlayQueue::getTrack 0 Nov 23 15:06:49 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:06:49 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 15:06:49 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 15:06:49 minidsp-shd volumio-remote-updater[419]: No test mode Nov 23 15:06:49 minidsp-shd volumio-remote-updater[419]: No alpha test mode Nov 23 15:06:49 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 23 15:06:49 minidsp-shd volumio[697]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 23 15:06:49 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 23 15:06:52 minidsp-shd volumio-remote-updater[419]: No test mode Nov 23 15:06:52 minidsp-shd volumio-remote-updater[419]: No alpha test mode Nov 23 15:06:52 minidsp-shd volumio[697]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 23 15:06:52 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 23 15:06:53 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:53 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 15:06:53 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 23 15:06:53 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 23 15:06:53 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 23 15:06:53 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioGetBrowseSources Nov 23 15:06:53 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:06:54 minidsp-shd systemd[1]: systemd-hostnamed.service: Succeeded. Nov 23 15:06:54 minidsp-shd volumio[697]: info: CoreCommandRouter::Close All Modals sent Nov 23 15:07:01 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 15:07:01 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 23 15:07:13 minidsp-shd volumio[697]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/minidspshd/buster/armhf Nov 23 15:07:14 minidsp-shd volumio[697]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/minidspshd/buster/armhf Nov 23 15:07:14 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSoundCloud Nov 23 15:07:14 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:07:14 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:07:14 minidsp-shd volumio[697]: Cannot find translation for source Mother Earth Radio Nov 23 15:07:14 minidsp-shd volumio[697]: Cannot find translation for source Presets Nov 23 15:07:14 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Nov 23 15:07:14 minidsp-shd volumio[697]: info: Check plugin dependencies Nov 23 15:07:15 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:07:15 minidsp-shd volumio[697]: info: Rename folder Nov 23 15:07:15 minidsp-shd volumio[697]: info: Folder /tmp/downloaded_plugin.zip removed Nov 23 15:07:15 minidsp-shd volumio[697]: info: Move to category Nov 23 15:07:15 minidsp-shd volumio[697]: info: Checking if install.sh is present Nov 23 15:07:15 minidsp-shd volumio[697]: info: Executing install.sh Nov 23 15:07:15 minidsp-shd sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/soundcloud/install.sh Nov 23 15:07:15 minidsp-shd sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:07:15 minidsp-shd sudo[1242]: pam_unix(sudo:session): session closed for user root Nov 23 15:07:15 minidsp-shd volumio[697]: info: Install script completed Nov 23 15:07:15 minidsp-shd volumio[697]: info: Done installing plugin. Nov 23 15:07:15 minidsp-shd volumio[697]: info: Enabling plugin soundcloud Nov 23 15:07:15 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 15:07:15 minidsp-shd volumio[697]: info: [{"prettyName":"Mixcloud","name":"mixcloud","category":"music_service","version":"0.1.1","icon":"fa-mixcloud","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.0.0","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SoundCloud","name":"soundcloud","category":"music_service","version":"0.1.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true}] Nov 23 15:07:15 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 23 15:07:15 minidsp-shd volumio[697]: info: SoundCloud plugin installed Nov 23 15:07:15 minidsp-shd volumio[697]: Plugin install end detected on script Nov 23 15:07:15 minidsp-shd volumio[697]: info: Folder /tmp/plugins removed Nov 23 15:07:15 minidsp-shd volumio[697]: info: Folder /tmp/downloaded_plugin.zip removed Nov 23 15:07:15 minidsp-shd volumio[697]: info: Folder /data/temp removed Nov 23 15:07:21 minidsp-shd volumio[697]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/minidspshd/buster/armhf Nov 23 15:07:23 minidsp-shd volumio[697]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/minidspshd/buster/armhf Nov 23 15:07:23 minidsp-shd go-librespot[1015]: time="2024-11-23T15:07:23+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Nov 23 15:07:23 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Nov 23 15:07:23 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:07:23 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:07:23 minidsp-shd volumio[697]: Cannot find translation for source Mother Earth Radio Nov 23 15:07:23 minidsp-shd volumio[697]: Cannot find translation for source Presets Nov 23 15:07:23 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Nov 23 15:07:23 minidsp-shd sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Nov 23 15:07:23 minidsp-shd sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:07:23 minidsp-shd systemd[1]: Stopping go-librespot Daemon... Nov 23 15:07:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 23 15:07:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Succeeded. Nov 23 15:07:23 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Nov 23 15:07:23 minidsp-shd sudo[1247]: pam_unix(sudo:session): session closed for user root Nov 23 15:07:23 minidsp-shd volumio[697]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 23 15:07:23 minidsp-shd volumio[697]: info: Connection to go-librespot Websocket closed Nov 23 15:07:23 minidsp-shd volumio[697]: info: Check plugin dependencies Nov 23 15:07:24 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 15:07:24 minidsp-shd volumio[697]: info: Rename folder Nov 23 15:07:24 minidsp-shd volumio[697]: info: Folder /tmp/downloaded_plugin.zip removed Nov 23 15:07:24 minidsp-shd volumio[697]: info: Move to category Nov 23 15:07:26 minidsp-shd volumio[697]: info: Checking if install.sh is present Nov 23 15:07:26 minidsp-shd volumio[697]: info: Executing install.sh Nov 23 15:07:26 minidsp-shd sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Nov 23 15:07:26 minidsp-shd sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 15:07:26 minidsp-shd volumio[697]: info: Installing Go-librespot Nov 23 15:07:26 minidsp-shd volumio[697]: info: Checking old vollibrespot installs Nov 23 15:07:26 minidsp-shd systemd[1]: Reloading. Nov 23 15:07:27 minidsp-shd systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 23 15:07:27 minidsp-shd systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 23 15:07:27 minidsp-shd systemd[1]: serial-getty@ttyS0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 23 15:07:27 minidsp-shd volumio[697]: info: Dowloading daemon Nov 23 15:07:30 minidsp-shd volumio[697]: info: Creating Start Script Nov 23 15:07:30 minidsp-shd systemd[1]: Reloading. Nov 23 15:07:30 minidsp-shd systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 23 15:07:30 minidsp-shd sudo[1261]: pam_unix(sudo:session): session closed for user root Nov 23 15:07:30 minidsp-shd volumio[697]: Plugin install end detected on script Nov 23 15:07:30 minidsp-shd volumio[697]: info: Install script completed Nov 23 15:07:30 minidsp-shd volumio[697]: info: Done installing plugin. Nov 23 15:07:30 minidsp-shd volumio[697]: info: Enabling plugin spop Nov 23 15:07:30 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 15:07:30 minidsp-shd volumio[697]: info: [{"prettyName":"Mixcloud","name":"mixcloud","category":"music_service","version":"0.1.1","icon":"fa-mixcloud","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.0.0","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SoundCloud","name":"soundcloud","category":"music_service","version":"0.1.5","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false}] Nov 23 15:07:30 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 23 15:07:31 minidsp-shd volumio[697]: info: Folder /tmp/plugins removed Nov 23 15:07:31 minidsp-shd volumio[697]: info: Folder /tmp/downloaded_plugin.zip removed Nov 23 15:07:31 minidsp-shd volumio[697]: info: Folder /data/temp removed Nov 23 15:07:36 minidsp-shd volumio[697]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/minidspshd/buster/armhf Nov 23 15:07:37 minidsp-shd volumio[697]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/minidspshd/buster/armhf Nov 23 15:07:37 minidsp-shd volumio[697]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSoundCloud Nov 23 15:07:37 minidsp-shd volumio[697]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 15:07:37 minidsp-shd volumio[697]: Cannot find translation for source Mixcloud Nov 23 15:07:37 minidsp-shd volumio[697]: Cannot find translation for source Mother Earth Radio Nov 23 15:07:37 minidsp-shd volumio[697]: Cannot find translation for source Presets Nov 23 15:07:37 minidsp-shd volumio[697]: Cannot find translation for source QOBUZ Nov 23 15:07:37 minidsp-shd volumio[697]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 15:07:37 minidsp-shd volumio[697]: TypeError: Cannot read property 'clear' of undefined Nov 23 15:07:37 minidsp-shd volumio[697]: at SoundCloudContext.reset (/data/plugins/music_service/soundcloud/lib/soundcloud.js:83:21) Nov 23 15:07:37 minidsp-shd volumio[697]: at ControllerSoundCloud.onStop (/data/plugins/music_service/soundcloud/index.js:177:8) Nov 23 15:07:37 minidsp-shd volumio[697]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 23 15:07:37 minidsp-shd volumio[697]: at /volumio/app/pluginmanager.js:956:12 Nov 23 15:07:37 minidsp-shd volumio[697]: at ChildProcess.exithandler (child_process.js:374:7) Nov 23 15:07:37 minidsp-shd volumio[697]: at ChildProcess.emit (events.js:400:28) Nov 23 15:07:37 minidsp-shd volumio[697]: at maybeClose (internal/child_process.js:1088:16) Nov 23 15:07:37 minidsp-shd volumio[697]: at Socket. (internal/child_process.js:446:11) Nov 23 15:07:37 minidsp-shd volumio[697]: at Socket.emit (events.js:400:28) Nov 23 15:07:37 minidsp-shd volumio[697]: at Pipe. (net.js:686:12) Nov 23 15:07:37 minidsp-shd volumio[697]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 15:07:38 minidsp-shd sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 15:06 Nov 23 15:07:38 minidsp-shd sudo[1316]: 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:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"