-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-02-15 16:23:35 CET. -- Feb 15 16:22:15 motivo systemd-timedated[1133]: Changed local time to Sat Feb 15 16:22:15 2025 Feb 15 16:22:15 motivo sudo[1131]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:15 motivo volumio-time-update[723]: volumio-time-update-util: System time updated successfully. Feb 15 16:22:15 motivo systemd[1]: Starting Daily apt download activities... Feb 15 16:22:15 motivo systemd[1]: Started Volumio Time Update Utility. Feb 15 16:22:15 motivo volumio[878]: info: Starting Streaming Service Transparent Proxy Feb 15 16:22:15 motivo volumio[878]: info: Plugin tidalconnect is not enabled Feb 15 16:22:15 motivo volumio[878]: info: Loading plugin "webradio"... Feb 15 16:22:15 motivo ntpd[1093]: Soliciting pool server 136.243.127.23 Feb 15 16:22:15 motivo ntpd[1093]: Soliciting pool server 185.232.69.65 Feb 15 16:22:15 motivo ntpd[1093]: Soliciting pool server 213.206.164.3 Feb 15 16:22:16 motivo volumio[878]: info: Loading plugin "i2s_dacs"... Feb 15 16:22:16 motivo volumio[878]: info: I2S DAC not set, start Auto-detection Feb 15 16:22:16 motivo volumio[878]: info: Loading plugin "volumiodiscovery"... Feb 15 16:22:16 motivo volumio[878]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 16:22:16 motivo node[878]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 16:22:16 motivo volumio[878]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 16:22:16 motivo node[878]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 16:22:16 motivo volumio[878]: *** WARNING *** For more information see Feb 15 16:22:16 motivo node[878]: *** WARNING *** For more information see Feb 15 16:22:16 motivo volumio[878]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 16:22:16 motivo volumio[878]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 16:22:16 motivo volumio[878]: *** WARNING *** For more information see Feb 15 16:22:16 motivo node[878]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 16:22:16 motivo node[878]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 16:22:16 motivo node[878]: *** WARNING *** For more information see Feb 15 16:22:16 motivo volumio[878]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 15 16:22:16 motivo volumio[878]: info: Discovery: Started advertising with name: Motivo Feb 15 16:22:16 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 16:22:16 motivo volumio[878]: info: Loading plugin "youtube2"... Feb 15 16:22:16 motivo volumio[878]: STREAMING PROXY: Starting server on port 3245 Feb 15 16:22:16 motivo volumio[878]: Node JS runtime: 14 Feb 15 16:22:16 motivo nmbd[799]: [2025/02/15 16:22:16.554340, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 15 16:22:16 motivo systemd[1]: Started Samba NMB Daemon. Feb 15 16:22:16 motivo nmbd[799]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 15 16:22:16 motivo nmbd[799]: [2025/02/15 16:22:16.568589, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 15 16:22:16 motivo nmbd[799]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.82 for name WORKGROUP<1d>. Feb 15 16:22:16 motivo nmbd[799]: This response was from IP 192.168.178.74, reporting an IP address of 192.168.178.74. Feb 15 16:22:16 motivo systemd[1]: Starting Samba Winbind Daemon... Feb 15 16:22:16 motivo winbindd[1182]: [2025/02/15 16:22:16.835599, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 15 16:22:16 motivo winbindd[1182]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 15 16:22:16 motivo winbindd[1182]: [2025/02/15 16:22:16.847053, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 15 16:22:16 motivo systemd[1]: Started Samba Winbind Daemon. Feb 15 16:22:16 motivo winbindd[1182]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 15 16:22:16 motivo ntpd[1093]: Soliciting pool server 141.144.241.16 Feb 15 16:22:16 motivo ntpd[1093]: Soliciting pool server 88.198.200.96 Feb 15 16:22:16 motivo ntpd[1093]: Soliciting pool server 90.187.112.137 Feb 15 16:22:16 motivo systemd[1]: Starting Samba SMB Daemon... Feb 15 16:22:16 motivo ntpd[1093]: Soliciting pool server 148.251.5.46 Feb 15 16:22:17 motivo systemd[1]: apt-daily.service: Succeeded. Feb 15 16:22:17 motivo systemd[1]: Started Daily apt download activities. Feb 15 16:22:17 motivo systemd[1]: Starting Daily apt upgrade and clean activities... Feb 15 16:22:17 motivo smbd[1196]: [2025/02/15 16:22:17.260440, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 15 16:22:17 motivo smbd[1196]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 15 16:22:17 motivo systemd[1]: Started Samba SMB Daemon. Feb 15 16:22:17 motivo systemd[1]: Reached target Multi-User System. Feb 15 16:22:17 motivo systemd[1]: Reached target Graphical Interface. Feb 15 16:22:17 motivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 15 16:22:17 motivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 15 16:22:17 motivo systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 15 16:22:17 motivo ntpd[1093]: Soliciting pool server 141.98.136.83 Feb 15 16:22:17 motivo ntpd[1093]: Soliciting pool server 185.13.148.71 Feb 15 16:22:17 motivo ntpd[1093]: Soliciting pool server 79.133.44.137 Feb 15 16:22:18 motivo systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 15 16:22:18 motivo systemd[1]: Started Daily apt upgrade and clean activities. Feb 15 16:22:18 motivo systemd[1]: Startup finished in 13.438s (kernel) + 20.998s (userspace) = 34.436s. Feb 15 16:22:18 motivo ntpd[1093]: Soliciting pool server 144.91.126.59 Feb 15 16:22:18 motivo ntpd[1093]: Soliciting pool server 89.58.6.143 Feb 15 16:22:18 motivo ntpd[1093]: Soliciting pool server 176.9.157.155 Feb 15 16:22:19 motivo volumio[878]: info: Loading plugin "now_playing"... Feb 15 16:22:21 motivo volumio[878]: info: Plugin multiroom is not enabled Feb 15 16:22:21 motivo volumio[878]: info: Loading plugin "outputs"... Feb 15 16:22:21 motivo volumio[878]: info: Loading plugin "albumart"... Feb 15 16:22:21 motivo volumio[878]: info: Plugin example_plugin is not enabled Feb 15 16:22:21 motivo volumio[878]: info: Loading plugin "hi_res_audio"... Feb 15 16:22:22 motivo ntpd[1093]: receive: Unexpected origin timestamp 0xeb5b2fad.dfa1234c does not match aorg 0000000000.00000000 from server@90.187.112.137 xmt 0xeb5b2fae.412afd90 Feb 15 16:22:22 motivo ntpd[1093]: receive: Unexpected origin timestamp 0xeb5b2fad.df9a079a does not match aorg 0000000000.00000000 from server@185.13.148.71 xmt 0xeb5b2fae.4a85e65f Feb 15 16:22:22 motivo volumio-remote-updater[730]: [2025-02-15 16:22:22] [connect] Successful connection Feb 15 16:22:22 motivo volumio[878]: Forking 3 albumart workers Feb 15 16:22:23 motivo volumio[878]: Starting albumart workers Feb 15 16:22:23 motivo volumio[878]: Starting albumart workers Feb 15 16:22:23 motivo volumio[878]: info: Applying required configuration parameters for plugin hi_res_audio Feb 15 16:22:23 motivo volumio[878]: info: Loading plugin "inputs"... Feb 15 16:22:23 motivo volumio[878]: Starting albumart workers Feb 15 16:22:24 motivo volumio[878]: info: Loading plugin "qobuz"... Feb 15 16:22:25 motivo volumio[878]: info: Loading plugin "smart_inputs"... Feb 15 16:22:25 motivo volumio[878]: info: Loading plugin "tidal"... Feb 15 16:22:26 motivo volumio[878]: info: Loading plugin "motivocontrol"... Feb 15 16:22:26 motivo volumio[878]: info: Initializing System Ready GPIO for kernel version: 6.6.30-v8+ Feb 15 16:22:26 motivo volumio[878]: info: Adding this device properties Feb 15 16:22:26 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Feb 15 16:22:26 motivo volumio[878]: info: Setting Additional Device Volumio Properties: [object Object] Feb 15 16:22:26 motivo volumio[878]: info: Checking For Motivo Hardware Feb 15 16:22:26 motivo volumio[878]: info: Loading plugin "updater_comm"... Feb 15 16:22:27 motivo volumio[878]: info: Plugin mpdemulation is not enabled Feb 15 16:22:27 motivo volumio[878]: info: Loading plugin "rest_api"... Feb 15 16:22:27 motivo volumio[878]: info: Loading plugin "websocket"... Feb 15 16:22:27 motivo volumio[878]: info: Starting Socket.io Server version 2.3.0 Feb 15 16:22:27 motivo volumio[878]: info: Loading plugin "fusiondsp"... Feb 15 16:22:27 motivo volumio[878]: info: Applying required configuration parameters for plugin fusiondsp Feb 15 16:22:27 motivo volumio[878]: info: Loading plugin "Systeminfo"... Feb 15 16:22:27 motivo volumio[878]: info: Loading i18n strings for locale de Feb 15 16:22:27 motivo volumio[878]: Updating browse sources language Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::initPlayerControls Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 16:22:27 motivo volumio[878]: Express server listening on port 3000 Feb 15 16:22:27 motivo volumio[878]: [Metrics] WebUI: 18s 391.97ms Feb 15 16:22:27 motivo volumio[878]: info: CoreStateMachine::resetVolumioState Feb 15 16:22:27 motivo volumio[878]: info: CoreStateMachine::getcurrentVolume Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 16:22:27 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:27 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:27 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:27 motivo volumio[878]: info: Volumio Network Manager: Network status updated: 0 Feb 15 16:22:27 motivo volumio[878]: info: Motivo Hardware found Feb 15 16:22:27 motivo volumio[878]: info: Cannot mount NAS Qnap2 at system boot, trial number 1 ,retrying in 5 seconds Feb 15 16:22:27 motivo volumio[878]: info: Cannot mount NAS Qnap3 at system boot, trial number 1 ,retrying in 5 seconds Feb 15 16:22:28 motivo volumio[878]: info: Reloading queue from file Feb 15 16:22:28 motivo volumio[878]: info: CoreStateMachine::setRepeat false single undefined Feb 15 16:22:28 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:28 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:28 motivo volumio[878]: info: CoreStateMachine::setRandom null Feb 15 16:22:28 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:28 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:28 motivo volumio[878]: info: Setting Device type: Raspberry PI Feb 15 16:22:28 motivo volumio[878]: info: Completed loading Core Plugins Feb 15 16:22:28 motivo volumio[878]: info: Preparing to generate the ALSA configuration file Feb 15 16:22:28 motivo volumio[878]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 16:22:28 motivo volumio[878]: info: Reading ALSA contributions from plugins. Feb 15 16:22:28 motivo volumio[878]: info: Asound.conf file unchanged, so no further update is needed Feb 15 16:22:28 motivo volumio[878]: info: Output device has changed, restarting MPD Feb 15 16:22:28 motivo volumio[878]: info: Output device has changed, restarting Shairport Sync Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:28 motivo sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 16:22:28 motivo sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:28 motivo sudo[1499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 16:22:28 motivo sudo[1497]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:28 motivo sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:28 motivo systemd[1]: Stopping Music Player Daemon... Feb 15 16:22:28 motivo volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 15 16:22:28 motivo volumio[878]: info: ___________ START PLUGINS ___________ Feb 15 16:22:28 motivo volumio[878]: info: ControllerMpd::onStart: Initializing MPD Feb 15 16:22:28 motivo volumio[878]: info: Creating MPD Configuration file Feb 15 16:22:28 motivo systemd[1]: mpd.service: Succeeded. Feb 15 16:22:28 motivo systemd[1]: Stopped Music Player Daemon. Feb 15 16:22:28 motivo sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 16:22:28 motivo sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:28 motivo volumio[878]: info: Adding METAVOLUMIO REST API Endpoints Feb 15 16:22:28 motivo volumio[878]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 15 16:22:28 motivo volumio[878]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 15 16:22:28 motivo volumio[878]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 15 16:22:28 motivo volumio[878]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:28 motivo systemd[1]: Starting Music Player Daemon... Feb 15 16:22:28 motivo sudo[1508]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:28 motivo sudo[1511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 16:22:28 motivo sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:28 motivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 15 16:22:28 motivo systemd[1]: mpd.service: Succeeded. Feb 15 16:22:28 motivo systemd[1]: Stopped Music Player Daemon. Feb 15 16:22:28 motivo systemd[1]: Starting Music Player Daemon... Feb 15 16:22:28 motivo volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:28 motivo volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 16:22:28 motivo volumio[878]: info: [1739632948574] CoreMusicLibrary::Adding element Last_100 Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 16:22:28 motivo sudo[1519]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 16:22:28 motivo sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:28 motivo volumio[878]: info: Streaming services startup Feb 15 16:22:28 motivo volumio[878]: info: Starting Streaming Daemon Feb 15 16:22:28 motivo sudo[1519]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:28 motivo sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 15 16:22:28 motivo sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:28 motivo sudo[1526]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 16:22:28 motivo volumio[878]: info: [1739632948657] CoreMusicLibrary::Adding element Webradio Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 16:22:28 motivo volumio[878]: info: Initializing BBC Radios Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 16:22:28 motivo volumio[878]: info: [1739632948755] CoreMusicLibrary::Adding element YouTube2 Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 16:22:28 motivo volumio[878]: Cannot find translation for source YouTube2 Feb 15 16:22:28 motivo volumio[878]: info: [now-playing] ConfigUpdater: config is up to date. Feb 15 16:22:28 motivo volumio[878]: error: Hi Res Audio Failed Login: Missing Login Data Feb 15 16:22:28 motivo volumio[878]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 15 16:22:28 motivo volumio[878]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 15 16:22:28 motivo volumio[878]: info: Initializing Serial Communication on port /dev/ttyAMA4 Feb 15 16:22:28 motivo volumio[878]: info: Touch Event Listener Process Starting Feb 15 16:22:28 motivo kernel: uart-pl011 fe201800.serial: no DMA platform data Feb 15 16:22:28 motivo volumio[878]: info: Refreshing QOBUZ token Feb 15 16:22:28 motivo sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Feb 15 16:22:28 motivo sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:28 motivo volumio[878]: info: Adding inputs REST Endpoints Feb 15 16:22:28 motivo volumio[878]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 15 16:22:28 motivo volumio[878]: info: Scanning Audio Inputs Feb 15 16:22:28 motivo volumio[878]: info: Checking against Known Cards name Feb 15 16:22:28 motivo volumio[878]: info: Checking against Known Cards name Feb 15 16:22:28 motivo volumio[878]: info: Adding Server instance for streaming Feb 15 16:22:28 motivo volumio[878]: info: Loading i18n strings for locale de Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 15 16:22:28 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:28 motivo volumio[878]: info: FusionDsp - mixtype--------------------- None Feb 15 16:22:28 motivo volumio[878]: info: Volumio Calling Home Feb 15 16:22:29 motivo volumio[878]: info: Preparing to generate the ALSA configuration file Feb 15 16:22:29 motivo volumio[878]: info: Stopping AccessToken refresher cron Feb 15 16:22:29 motivo volumio[878]: info: AccessToken refresher cron started Feb 15 16:22:29 motivo volumio[878]: info: Adding TIDAL REST API Endpoints Feb 15 16:22:29 motivo volumio[878]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Feb 15 16:22:29 motivo volumio[878]: info: [now-playing] App is listening on port 4004. Feb 15 16:22:29 motivo volumio[878]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 15 16:22:29 motivo volumio[878]: info: Discovery: adding ce23ee39-fb47-408c-ab69-f94c0d73a0e5 Feb 15 16:22:29 motivo volumio[878]: info: Discovery: Found device Motivo Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:29 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:29 motivo volumio[878]: info: Serial port opened successfully Feb 15 16:22:29 motivo volumio[878]: info: Sending serial start messages Feb 15 16:22:29 motivo volumio[878]: info: Display with brightness control is connected, setting display brightness Feb 15 16:22:29 motivo volumio[878]: info: Setting Display Brightness to 9 Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: Reporting MCU Network Status: 0 Feb 15 16:22:29 motivo volumio[878]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 16:22:29 motivo volumio[878]: info: Reading ALSA contributions from plugins. Feb 15 16:22:29 motivo volumio[878]: error: Cannot start Volumio Streaming Daemon Feb 15 16:22:29 motivo volumio[878]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 15 16:22:29 motivo volumio[878]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 15 16:22:29 motivo volumio[878]: info: MPD Permissions set Feb 15 16:22:29 motivo volumio[878]: info: MPD Permissions set Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Feb 15 16:22:29 motivo volumio[878]: info: Setting Device Volume Override Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 15 16:22:29 motivo volumio[878]: info: Updating Volume Controller Parameters: Device: 0 Name: S/PDIF + Optical Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 15 16:22:29 motivo volumio[878]: info: Disabling external Volume Control Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:29 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:29 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:29 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:29 motivo volumio[878]: info: Volumio called home Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Feb 15 16:22:29 motivo volumio[878]: info: Setting Additional System Software info: Hardware Revision: 2.3 Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Feb 15 16:22:29 motivo volumio[878]: info: Setting HW Firmware info: undefined Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Feb 15 16:22:29 motivo volumio[878]: info: Setting HW Version info: 2.3 Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Feb 15 16:22:29 motivo volumio[878]: info: Setting Additional System Software info: Hardware Revision: 2.3, Firmware Version: 0.1.9 Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Feb 15 16:22:29 motivo volumio[878]: info: Setting HW Firmware info: 0.1.9 Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Feb 15 16:22:29 motivo volumio[878]: info: Setting HW Version info: 2.3 Feb 15 16:22:29 motivo volumio[878]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 15 16:22:29 motivo volumio[878]: info: MCU Signalled Headphone Mode Disabled Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 15 16:22:29 motivo volumio[878]: info: MCU Signalled Sleep Mode Disabled Feb 15 16:22:29 motivo volumio[878]: info: Enabling Advanced system settings configuration Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Feb 15 16:22:29 motivo volumio[878]: info: Additional UI Settings Added for plugin music_service/inputs Feb 15 16:22:29 motivo volumio[878]: info: MCU Signalled Auto Boot Mode On Power Disabled Feb 15 16:22:29 motivo sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Feb 15 16:22:29 motivo sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:29 motivo volumio[878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo sudo[1555]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:29 motivo mpd[1524]: Feb 15 16:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo systemd[1]: Started Music Player Daemon. Feb 15 16:22:29 motivo sudo[1511]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:29 motivo sudo[1499]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:29 motivo volumio[878]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Feb 15 16:22:29 motivo volumio[878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 15 16:22:29 motivo volumio[878]: info: Access Token successfully retrieved Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 16:22:29 motivo volumio[878]: info: [1739632949713] CoreMusicLibrary::Adding element QOBUZ Feb 15 16:22:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 16:22:29 motivo volumio[878]: Cannot find translation for source YouTube2 Feb 15 16:22:29 motivo volumio[878]: Cannot find translation for source QOBUZ Feb 15 16:22:29 motivo volumio[878]: info: Stopping AccessToken refresher cron for QOBUZ Feb 15 16:22:29 motivo volumio[878]: info: AccessToken refresher cron started for QOBUZ Feb 15 16:22:29 motivo volumio[878]: info: Adding QOBUZ REST API Endpoints Feb 15 16:22:29 motivo volumio[878]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Feb 15 16:22:29 motivo volumio[878]: info: Completed starting Core Plugins Feb 15 16:22:29 motivo volumio[878]: info: ------------------------------------------- Feb 15 16:22:29 motivo volumio[878]: info: ----- MyVolumio plugins startup ---- Feb 15 16:22:29 motivo volumio[878]: info: ------------------------------------------- Feb 15 16:22:29 motivo volumio[878]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 15 16:22:29 motivo sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Feb 15 16:22:29 motivo sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:29 motivo sudo[1580]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:29 motivo volumio[878]: info: MPD running with PID1524 Feb 15 16:22:29 motivo volumio[878]: ,establishing connection Feb 15 16:22:29 motivo volumio[878]: info: Starting Shairport Sync Feb 15 16:22:29 motivo volumio[878]: info: Starting Shairport Sync Feb 15 16:22:29 motivo sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 16:22:29 motivo sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:29 motivo volumio[878]: info: Starting Shairport Sync Feb 15 16:22:29 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 16:22:29 motivo sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 16:22:29 motivo systemd[1]: shairport-sync.service: Succeeded. Feb 15 16:22:29 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 16:22:29 motivo sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:29 motivo volumio[878]: info: Adding Inputs via Serial API Feb 15 16:22:29 motivo volumio[878]: info: Adding Advanced Audio Settings via Serial API Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 15 16:22:30 motivo volumio[878]: info: Additional UI Settings Added for plugin music_service/inputs Feb 15 16:22:30 motivo sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 16:22:30 motivo sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:30 motivo volumio[878]: info: Adding Advanced Audio Settings via Serial API Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 15 16:22:30 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 16:22:30 motivo sudo[1583]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:30 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 16:22:30 motivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 15 16:22:30 motivo systemd[1]: shairport-sync.service: Succeeded. Feb 15 16:22:30 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 16:22:30 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 16:22:30 motivo sudo[1588]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:30 motivo sudo[1586]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:30 motivo volumio[878]: error: MPD error: The expression evaluated to a falsy value: Feb 15 16:22:30 motivo volumio[878]: assert.ok(self.idling) Feb 15 16:22:30 motivo volumio[878]: error: The expression evaluated to a falsy value: Feb 15 16:22:30 motivo volumio[878]: assert.ok(self.idling) Feb 15 16:22:30 motivo volumio[878]: error: MPD error: The expression evaluated to a falsy value: Feb 15 16:22:30 motivo volumio[878]: assert.ok(self.idling) Feb 15 16:22:30 motivo volumio[878]: error: The expression evaluated to a falsy value: Feb 15 16:22:30 motivo volumio[878]: assert.ok(self.idling) Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:30 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:30 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:30 motivo volumio[878]: info: Shairport-Sync Started Feb 15 16:22:30 motivo volumio[878]: Error adding Membership: Error: addMembership EINVAL Feb 15 16:22:30 motivo volumio[878]: info: Shairport-Sync Started Feb 15 16:22:30 motivo volumio[878]: info: Shairport-Sync Started Feb 15 16:22:30 motivo volumio[878]: info: Asound.conf file unchanged, so no further update is needed Feb 15 16:22:30 motivo volumio[878]: info: Output device has changed, restarting MPD Feb 15 16:22:30 motivo volumio[878]: info: Output device has changed, restarting Shairport Sync Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:30 motivo sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 16:22:30 motivo sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:30 motivo sudo[1597]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:30 motivo sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 16:22:30 motivo sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:30 motivo volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:30 motivo systemd[1]: Stopping Music Player Daemon... Feb 15 16:22:30 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:30 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:30 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:30 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::servicePushState Feb 15 16:22:30 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:30 motivo volumio[878]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received inputs Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::volumiosetSourceActiveno-source Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 16:22:30 motivo volumio[878]: Cannot find translation for source YouTube2 Feb 15 16:22:30 motivo volumio[878]: Cannot find translation for source QOBUZ Feb 15 16:22:30 motivo systemd[1]: mpd.service: Succeeded. Feb 15 16:22:30 motivo systemd[1]: Stopped Music Player Daemon. Feb 15 16:22:30 motivo volumio[878]: error: updateQueue error: null Feb 15 16:22:30 motivo systemd[1]: Starting Music Player Daemon... Feb 15 16:22:30 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:30 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:30 motivo volumio[878]: info: MPD Permissions set Feb 15 16:22:30 motivo sudo[1605]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 16:22:30 motivo sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:30 motivo sudo[1605]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:30 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:30 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:30 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:30 motivo volumio[878]: info: Starting Shairport Sync Feb 15 16:22:30 motivo sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 16:22:30 motivo sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:30 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 16:22:30 motivo systemd[1]: shairport-sync.service: Succeeded. Feb 15 16:22:30 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 16:22:30 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 16:22:30 motivo sudo[1613]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:30 motivo volumio[878]: info: Shairport-Sync Started Feb 15 16:22:30 motivo volumio[878]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 2 Feb 15 16:22:31 motivo volumio[878]: info: camilladsp spawned new process with pid 1625, instance 1, run: true Feb 15 16:22:31 motivo volumio[878]: info: camilladsp service started and running in background, instance 1 Feb 15 16:22:31 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:31 motivo volumio[878]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Feb 15 16:22:31 motivo volumio[878]: info: FusionDsp loaded Feb 15 16:22:31 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 15 16:22:31 motivo volumio[878]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 15 16:22:31 motivo volumio[878]: info: Adding Signal Path Element [object Object] Feb 15 16:22:31 motivo volumio[878]: info: Adding fusiondspeq DSP Signal Path Element Feb 15 16:22:31 motivo volumio[878]: info: FusionDsp - ---- installed callbackRead Feb 15 16:22:31 motivo volumio[878]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 15 16:22:31 motivo sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 15 16:22:31 motivo sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:31 motivo volumio[878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 15 16:22:31 motivo volumio[878]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 15 16:22:31 motivo systemd[1]: Started FusionDsp Daemon. Feb 15 16:22:31 motivo sudo[1635]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:31 motivo volumio[878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 15 16:22:31 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:31 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:31 motivo volumio[878]: info: MCU Signalled Playback Inactive Feb 15 16:22:31 motivo mpd[1608]: Feb 15 16:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 15 16:22:31 motivo systemd[1]: Started Music Player Daemon. Feb 15 16:22:31 motivo sudo[1599]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:31 motivo volumio[878]: error: updateQueue error: null Feb 15 16:22:31 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 15 16:22:31 motivo volumio[878]: info: Initializing I2S Bus Feb 15 16:22:31 motivo volumio[878]: info: Successfully initialized Motivo I2S Bus Feb 15 16:22:32 motivo volumio[878]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 5 Feb 15 16:22:32 motivo volumio[878]: info: Reporting MCU Network Status: 1 Feb 15 16:22:32 motivo volumio[878]: info: Volumio Network Manager: Network status updated: 1 Feb 15 16:22:33 motivo sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=Dpmr2007Dpmr2007,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.178.129/Multimedia2/Musik /mnt/NAS/Qnap2 Feb 15 16:22:33 motivo sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:33 motivo sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=2.0 192.168.178.125:Download/Musik /mnt/NAS/Qnap3 Feb 15 16:22:33 motivo sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:33 motivo kernel: CIFS: Attempting to mount //192.168.178.129/Multimedia2/Musik Feb 15 16:22:33 motivo systemd[1]: systemd-fsckd.service: Succeeded. Feb 15 16:22:33 motivo sudo[1661]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:33 motivo sudo[1659]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:34 motivo volumio[878]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 6 Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:34 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetQueue Feb 15 16:22:34 motivo volumio[878]: info: CoreStateMachine::getQueue Feb 15 16:22:34 motivo volumio[878]: info: CorePlayQueue::getQueue Feb 15 16:22:34 motivo volumio[878]: info: Listing playlists Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 16:22:34 motivo volumio[878]: info: Received Get System Info Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 15 16:22:34 motivo volumio[878]: info: Discovery: Getting this device information Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:34 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:34 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 15 16:22:34 motivo volumio[878]: info: Received Get System Info Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 15 16:22:34 motivo volumio[878]: info: Discovery: Getting this device information Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:34 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:34 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetQueue Feb 15 16:22:34 motivo volumio[878]: info: CoreStateMachine::getQueue Feb 15 16:22:34 motivo volumio[878]: info: CorePlayQueue::getQueue Feb 15 16:22:34 motivo volumio[878]: info: Listing playlists Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 16:22:34 motivo volumio[878]: info: Received Get System Info Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 15 16:22:34 motivo volumio[878]: info: Discovery: Getting this device information Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:34 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:34 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:34 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:22:35 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:22:35 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:22:35 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:22:35 motivo volumio[878]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 15 16:22:35 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 15 16:22:36 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 15 16:22:36 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 15 16:22:36 motivo volumio[878]: info: Received Get System Info Feb 15 16:22:36 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 15 16:22:36 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 15 16:22:36 motivo volumio[878]: info: Discovery: Getting this device information Feb 15 16:22:36 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:36 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:36 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 15 16:22:36 motivo systemd[1]: systemd-hostnamed.service: Succeeded. Feb 15 16:22:36 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 15 16:22:37 motivo volumio-remote-updater[730]: [2025-02-15 16:22:37] [connect] Successful connection Feb 15 16:22:37 motivo volumio-remote-updater[730]: [2025-02-15 16:22:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739632957 101 Feb 15 16:22:37 motivo volumio[878]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 15 16:22:38 motivo volumio[878]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 15 16:22:39 motivo volumio[878]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 15 16:22:39 motivo volumio[878]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 15 16:22:39 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:39 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:39 motivo volumio[878]: info: Starting MyVolumio Remote Streaming Endpoints Feb 15 16:22:39 motivo volumio[878]: info: MyVolumio login type: Token Feb 15 16:22:39 motivo volumio[878]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 15 16:22:39 motivo volumio[878]: 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' Feb 15 16:22:39 motivo volumio[878]: info: Checking for updated MCU Firmware Feb 15 16:22:39 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 16:22:39 motivo volumio[878]: info: Firware on device is on latest version, no need to update Feb 15 16:22:39 motivo volumio[878]: error: MyVolumio Custom Token format not valid, refreshing it Feb 15 16:22:40 motivo volumio[878]: info: MyVolumio login type: Token Feb 15 16:22:40 motivo volumio[878]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 15 16:22:40 motivo volumio[878]: info: MyVolumio token set successfully Feb 15 16:22:40 motivo volumio[878]: info: MYVOLUMIO: Adding device Feb 15 16:22:40 motivo volumio[878]: info: MYVOLUMIO: Evaluating Server Feb 15 16:22:41 motivo volumio[878]: info: MyVolumio status changed Feb 15 16:22:41 motivo volumio[878]: info: Streaming services startup Feb 15 16:22:41 motivo volumio[878]: info: Starting Streaming Daemon Feb 15 16:22:41 motivo volumio[878]: info: Removing browser output: myVolumio user plan is not superstar Feb 15 16:22:41 motivo volumio[878]: info: Removing audio output: Feb 15 16:22:41 motivo volumio[878]: info: Stoppping Tunnel 1 Feb 15 16:22:41 motivo sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 15 16:22:41 motivo sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 15 16:22:41 motivo sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:41 motivo sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 16:22:41 motivo sudo[1709]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:41 motivo volumio[878]: error: Cannot start Volumio Streaming Daemon Feb 15 16:22:41 motivo volumio[878]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 15 16:22:41 motivo volumio[878]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 15 16:22:41 motivo sudo[1711]: pam_unix(sudo:session): session closed for user root Feb 15 16:22:41 motivo volumio[878]: info: Remote SSH Stopped Feb 15 16:22:42 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 15 16:22:42 motivo volumio[878]: info: Setting Geolocation for MyVolumio to eu4 Feb 15 16:22:42 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:42 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:42 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:42 motivo volumio[878]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 15 16:22:43 motivo volumio[878]: info: Updating MyVolumio device info Feb 15 16:22:43 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:43 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:43 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:43 motivo volumio[878]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 15 16:22:45 motivo systemd[1]: systemd-timedated.service: Succeeded. Feb 15 16:22:46 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 15 16:22:46 motivo volumio[878]: info: CURURI: music-library Feb 15 16:22:46 motivo volumio[878]: info: Preload queue cleared Feb 15 16:22:46 motivo volumio[878]: info: MYVOLUMIO: Adding device Feb 15 16:22:46 motivo volumio[878]: info: MYVOLUMIO: Evaluating Server Feb 15 16:22:47 motivo volumio[878]: info: Setting Geolocation for MyVolumio to eu4 Feb 15 16:22:47 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:47 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:47 motivo volumio[878]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 15 16:22:48 motivo volumio[878]: info: Updating MyVolumio device info Feb 15 16:22:48 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:49 motivo volumio[878]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 15 16:22:49 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 15 16:22:49 motivo volumio[878]: info: CURURI: music-library/NAS Feb 15 16:22:49 motivo volumio[878]: info: Preload queue cleared Feb 15 16:22:49 motivo volumio[878]: error: MyVolumio Plugin failed to start in a timely fashion Feb 15 16:22:49 motivo volumio[878]: [Metrics] CommandRouter: 39s 760.74ms Feb 15 16:22:49 motivo volumio[878]: info: CoreCommandRouter::volumiosetStartupVolume Feb 15 16:22:49 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:49 motivo volumio[878]: info: VolumeController:: Setting startup Volume 60 Feb 15 16:22:49 motivo volumio[878]: info: VolumeController::SetAlsaVolume60 Feb 15 16:22:49 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:49 motivo volumio[878]: info: CoreCommandRouter::Close All Modals sent Feb 15 16:22:49 motivo volumio[878]: info: CoreCommandRouter::Close All Modals sent Feb 15 16:22:49 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:49 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:49 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 16:22:49 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:50 motivo volumio[878]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 15 16:22:50 motivo volumio[878]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 15 16:22:50 motivo volumio[878]: info: camilladsp stopping service pid 1625... Feb 15 16:22:50 motivo volumio[878]: info: camilladsp service terminated, instance 1 Feb 15 16:22:50 motivo volumio[878]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 15 16:22:50 motivo volumio[878]: info: camilladsp service started and running in background, instance 1 Feb 15 16:22:50 motivo volumio[878]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 15 16:22:50 motivo volumio[878]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 15 16:22:50 motivo volumio[878]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 15 16:22:50 motivo volumio[878]: info: camilladsp spawned new process with pid 1753, instance 1, run: true Feb 15 16:22:50 motivo volumio[878]: error: FusionDsp - WebSocket error: [object Object] Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 15 16:22:50 motivo volumio[878]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 15 16:22:50 motivo volumio[878]: info: Received Get System Version Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 16:22:50 motivo volumio[878]: info: Received Get System Info Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 15 16:22:50 motivo volumio[878]: info: Discovery: Getting this device information Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:50 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 15 16:22:50 motivo volumio[878]: info: CURURI: music-library/NAS/Qnap2 Feb 15 16:22:50 motivo volumio[878]: info: Preload queue cleared Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 15 16:22:50 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 15 16:22:51 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 15 16:22:51 motivo volumio[878]: info: CURURI: music-library/NAS/Qnap2/SACD Feb 15 16:22:52 motivo volumio[878]: info: Preload queue cleared Feb 15 16:22:54 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 15 16:22:54 motivo volumio[878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 15 16:22:54 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:22:54 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:55 motivo volumio[878]: info: camilladsp spawned new process with pid 1776, instance 1, run: true Feb 15 16:22:56 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 15 16:22:56 motivo volumio[878]: info: CURURI: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005) Feb 15 16:22:56 motivo volumio[878]: info: Preload queue cleared Feb 15 16:22:56 motivo volumio[878]: info: BOOT COMPLETED Feb 15 16:22:57 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 15 16:22:57 motivo volumio[878]: info: CURURI: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1 Feb 15 16:22:57 motivo volumio[878]: info: Preload queue cleared Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/02. Kraftwerk - Planet Of Visions.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/03. Kraftwerk - Tour De France Etape 1.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/04. Kraftwerk - Chrono.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/05. Kraftwerk - Tour De France Etape 2.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/06. Kraftwerk - Vitamin.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/07. Kraftwerk - Tour De France.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/08. Kraftwerk - Autobahn.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/09. Kraftwerk - Das Model.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neon Lights.dsf Feb 15 16:22:57 motivo volumio[878]: info: Preloading song: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neonlicht.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F01.%20Kraftwerk%20-%20Die%20Mensch%20Maschine.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/02. Kraftwerk - Planet Of Visions.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F02.%20Kraftwerk%20-%20Planet%20Of%20Visions.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/02. Kraftwerk - Planet Of Visions.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/03. Kraftwerk - Tour De France Etape 1.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F03.%20Kraftwerk%20-%20Tour%20De%20France%20Etape%201.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/03. Kraftwerk - Tour De France Etape 1.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/04. Kraftwerk - Chrono.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F04.%20Kraftwerk%20-%20Chrono.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/04. Kraftwerk - Chrono.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/05. Kraftwerk - Tour De France Etape 2.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F05.%20Kraftwerk%20-%20Tour%20De%20France%20Etape%202.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/05. Kraftwerk - Tour De France Etape 2.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/06. Kraftwerk - Vitamin.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F06.%20Kraftwerk%20-%20Vitamin.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/06. Kraftwerk - Vitamin.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/07. Kraftwerk - Tour De France.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F07.%20Kraftwerk%20-%20Tour%20De%20France.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/07. Kraftwerk - Tour De France.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/08. Kraftwerk - Autobahn.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F08.%20Kraftwerk%20-%20Autobahn.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/08. Kraftwerk - Autobahn.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/09. Kraftwerk - Das Model.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F09.%20Kraftwerk%20-%20Das%20Model.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/09. Kraftwerk - Das Model.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neon Lights.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F10.%20Kraftwerk%20-%20Neon%20Lights.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neon Lights.dsf Feb 15 16:22:57 motivo volumio[878]: info: Exploding uri music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neonlicht.dsf in service mpd Feb 15 16:22:57 motivo volumio[878]: info: ALBUMART /albumart?cacheid=462&web=Kraftwerk/Minimum%20-%20Maximum/small&path=%2Fmnt%2FNAS%2FQnap2%2FSACD%2FKraftwerk%20-%20Minimum%20-%20Maximum_SACD_Deutsche%20Version%20(2005)%2FDisk-1%2F10.%20Kraftwerk%20-%20Neonlicht.dsf&metadata=false Feb 15 16:22:57 motivo volumio[878]: info: URI /mnt/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neonlicht.dsf Feb 15 16:22:58 motivo volumio[878]: info: Preload queue cleared Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::ClearQueue Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::stop Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::clearPlayQueue Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::saveQueue Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::addQueueItems Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::addQueueItems Feb 15 16:22:58 motivo volumio[878]: info: Preload queue cleared Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::saveQueue Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::updateTrackBlock Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::getTrackBlock Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::volumioPlay Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::play index 0 Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::addQueueItems Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::addQueueItems Feb 15 16:22:58 motivo volumio[878]: info: Preload queue cleared Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/02. Kraftwerk - Planet Of Visions.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/02. Kraftwerk - Planet Of Visions.dsf Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/03. Kraftwerk - Tour De France Etape 1.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/03. Kraftwerk - Tour De France Etape 1.dsf Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/04. Kraftwerk - Chrono.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/04. Kraftwerk - Chrono.dsf Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/05. Kraftwerk - Tour De France Etape 2.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/05. Kraftwerk - Tour De France Etape 2.dsf Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/06. Kraftwerk - Vitamin.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/06. Kraftwerk - Vitamin.dsf Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/07. Kraftwerk - Tour De France.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/07. Kraftwerk - Tour De France.dsf Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/08. Kraftwerk - Autobahn.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/08. Kraftwerk - Autobahn.dsf Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/09. Kraftwerk - Das Model.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/09. Kraftwerk - Das Model.dsf Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neon Lights.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neon Lights.dsf Feb 15 16:22:58 motivo volumio[878]: info: Adding Item to queue: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neonlicht.dsf Feb 15 16:22:58 motivo volumio[878]: info: Using cached record of: music-library/NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/10. Kraftwerk - Neonlicht.dsf Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::stop Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::volumioPushQueue Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::saveQueue Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::play index undefined Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::updateTrackBlock Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::getTrackBlock Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::startPlaybackTimer Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::clearAddPlayTracks NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 16:22:58 motivo volumio[878]: info: sendMpdCommand stop took 10 milliseconds Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 16:22:58 motivo volumio[878]: info: Feb 15 16:22:58 motivo volumio[878]: ---------------------------- MPD announces system playlist update Feb 15 16:22:58 motivo volumio[878]: info: Ignoring MPD Status Update Feb 15 16:22:58 motivo volumio[878]: info: sendMpdCommand clear took 2 milliseconds Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::sendMpdCommand add "NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf" Feb 15 16:22:58 motivo volumio[878]: error: updateQueue error: null Feb 15 16:22:58 motivo volumio[878]: info: Feb 15 16:22:58 motivo volumio[878]: ---------------------------- MPD announces system playlist update Feb 15 16:22:58 motivo volumio[878]: info: Ignoring MPD Status Update Feb 15 16:22:58 motivo volumio[878]: info: ------------------------------ 4ms Feb 15 16:22:58 motivo volumio[878]: info: sendMpdCommand add "NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf" took 3 milliseconds Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::sendMpdCommand play Feb 15 16:22:58 motivo volumio[878]: info: ------------------------------ 3ms Feb 15 16:22:58 motivo volumio[878]: info: sendMpdCommand play took 3 milliseconds Feb 15 16:22:58 motivo volumio[878]: info: Feb 15 16:22:58 motivo volumio[878]: ---------------------------- MPD announces state update: player Feb 15 16:22:58 motivo volumio[878]: info: ControllerMpd::getState Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::sendMpdCommand status Feb 15 16:22:58 motivo volumio[878]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 Feb 15 16:22:58 motivo volumio[878]: info: FusionDsp - ---- read samplerate from file: 352800 Feb 15 16:22:58 motivo volumio[878]: info: camilladsp stopping service pid 1776... Feb 15 16:22:58 motivo volumio[878]: info: camilladsp service terminated, instance 1 Feb 15 16:22:58 motivo volumio[878]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 15 16:22:58 motivo volumio[878]: info: camilladsp service started and running in background, instance 1 Feb 15 16:22:58 motivo volumio[878]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 Feb 15 16:22:58 motivo volumio[878]: info: FusionDsp - ---- read samplerate from file: 352800 Feb 15 16:22:58 motivo volumio[878]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 15 16:22:58 motivo volumio[878]: info: Feb 15 16:22:58 motivo volumio[878]: ---------------------------- MPD announces state update: player Feb 15 16:22:58 motivo volumio[878]: info: sendMpdCommand status took 58 milliseconds Feb 15 16:22:58 motivo volumio[878]: info: ControllerMpd::getState Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::sendMpdCommand status Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::parseState Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 16:22:58 motivo volumio[878]: info: sendMpdCommand status took 5 milliseconds Feb 15 16:22:58 motivo volumio[878]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::parseState Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::parseTrackInfo Feb 15 16:22:58 motivo volumio[878]: info: ControllerMpd::pushState Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::servicePushState Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:58 motivo volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":475,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Die Mensch Maschine","artist":"Kraftwerk","album":"Minimum - Maximum","uri":"NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf","trackType":"dsf"} Feb 15 16:22:58 motivo volumio[878]: verbose: CURRENT POSITION 0 Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::syncState stateService play Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::syncState currentStatus stop Feb 15 16:22:58 motivo volumio[878]: info: ------------------------------ 76ms Feb 15 16:22:58 motivo volumio[878]: info: camilladsp spawned new process with pid 1789, instance 1, run: true Feb 15 16:22:58 motivo volumio[878]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 15 16:22:58 motivo volumio[878]: verbose: ControllerMpd::parseTrackInfo Feb 15 16:22:58 motivo volumio[878]: info: ControllerMpd::pushState Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::servicePushState Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:58 motivo volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":360,"duration":475,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Die Mensch Maschine","artist":"Kraftwerk","album":"Minimum - Maximum","uri":"NAS/Qnap2/SACD/Kraftwerk - Minimum - Maximum_SACD_Deutsche Version (2005)/Disk-1/01. Kraftwerk - Die Mensch Maschine.dsf","trackType":"dsf"} Feb 15 16:22:58 motivo volumio[878]: verbose: CURRENT POSITION 0 Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::syncState stateService play Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::syncState currentStatus play Feb 15 16:22:58 motivo volumio[878]: info: Received an update from plugin. extracting info from payload Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:58 motivo volumio[878]: info: CoreStateMachine::pushState Feb 15 16:22:58 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::volumioPushState Feb 15 16:22:58 motivo volumio[878]: info: ------------------------------ 113ms Feb 15 16:22:58 motivo volumio[878]: error: FusionDsp - WebSocket error: [object Object] Feb 15 16:22:58 motivo volumio[878]: info: Signalling Playback active due to playback status change Feb 15 16:22:58 motivo volumio[878]: info: Signalling Playback active due to playback status change Feb 15 16:22:58 motivo volumio[878]: info: MCU Signalled Playback Active Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 16:22:58 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 15 16:22:59 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 16:22:59 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 15 16:22:59 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 15 16:22:59 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 15 16:22:59 motivo volumio[878]: info: CoreCommandRouter::volumioGetBrowseSources Feb 15 16:22:59 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 16:22:59 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 15 16:22:59 motivo volumio-remote-updater[730]: No test mode Feb 15 16:22:59 motivo volumio-remote-updater[730]: No alpha test mode Feb 15 16:22:59 motivo volumio[878]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 15 16:22:59 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 15 16:23:01 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:23:01 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:23:03 motivo volumio[878]: info: Executing endpoint metavolumio Feb 15 16:23:03 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 15 16:23:03 motivo volumio[878]: info: Executing endpoint metavolumio Feb 15 16:23:03 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 15 16:23:03 motivo volumio[878]: info: Executing endpoint metavolumio Feb 15 16:23:03 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 15 16:23:10 motivo volumio[878]: info: CoreCommandRouter::volumioGetState Feb 15 16:23:10 motivo volumio[878]: info: CorePlayQueue::getTrack 0 Feb 15 16:23:12 motivo volumio[878]: info: Executing endpoint metavolumio Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 15 16:23:12 motivo volumio[878]: info: Executing endpoint metavolumio Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 15 16:23:12 motivo volumio[878]: info: Executing endpoint metavolumio Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:23:12 motivo volumio[878]: info: Retrieving Cloud Streaming UI Feb 15 16:23:12 motivo volumio[878]: info: Getting Tidal Cloud Configuration Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:23:12 motivo volumio[878]: info: Getting Qobuz Cloud Configuration Feb 15 16:23:12 motivo volumio[878]: info: Asking plugin for UI Config Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:23:12 motivo volumio[878]: info: Getting Spotify Cloud Configuration Feb 15 16:23:12 motivo volumio[878]: info: Asking plugin for UI Config Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:23:12 motivo volumio[878]: info: Saving Spotify Acccount Feb 15 16:23:12 motivo volumio[878]: info: Got it Feb 15 16:23:12 motivo volumio[878]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 15 16:23:12 motivo volumio[878]: info: Got Tidal Cloud Configuration Feb 15 16:23:12 motivo volumio[878]: info: Got it Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 16:23:12 motivo volumio[878]: info: CoreCommandRouter::volumioGetBrowseSources Feb 15 16:23:13 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 16:23:13 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 15 16:23:17 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 16:23:21 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 16:23:25 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 16:23:29 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 16:23:33 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 16:23:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 15 16:23:34 motivo volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 15 16:23:34 motivo volumio[878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 16:23:34 motivo volumio[878]: Error: dns service error: unknown Feb 15 16:23:34 motivo volumio[878]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Feb 15 16:23:34 motivo volumio[878]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 15 16:23:34 motivo volumio[878]: errorCode: -65537 Feb 15 16:23:34 motivo volumio[878]: } Feb 15 16:23:34 motivo volumio[878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 16:23:35 motivo sudo[1963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 16:22 Feb 15 16:23:35 motivo sudo[1963]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:02:37 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="f3429790fcb2ce685253f61650bd5f66"