-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-10-19 11:20:27 CEST. -- Oct 19 11:19:05 volumio-werkstatt volumio[1372]: ........................................................................................................++++ Oct 19 11:19:13 volumio-werkstatt volumio[1372]: .............................................................++++ Oct 19 11:19:13 volumio-werkstatt volumio[1372]: e is 65537 (0x010001) Oct 19 11:19:13 volumio-werkstatt volumio[1372]: writing RSA key Oct 19 11:19:13 volumio-werkstatt volumio[982]: error: MyVolumio Plugin failed to start in a timely fashion Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: BOOT COMPLETED Oct 19 11:19:13 volumio-werkstatt volumio[982]: [Metrics] CommandRouter: 33s 864.31ms Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: CoreCommandRouter::volumiosetStartupVolume Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: VolumeController:: Setting startup Volume 40 Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: VolumeController::SetAlsaVolume40 Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: CoreCommandRouter::Close All Modals sent Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: CoreCommandRouter::Close All Modals sent Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: CoreStateMachine::pushState Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 11:19:13 volumio-werkstatt volumio[982]: info: CoreCommandRouter::volumioPushState Oct 19 11:19:14 volumio-werkstatt volumio[982]: <--- Last few GCs ---> Oct 19 11:19:14 volumio-werkstatt volumio[982]: [982:0x489d780] 37618 ms: Scavenge 188.2 (196.9) -> 188.0 (196.9) MB, 1.1 / 0.0 ms (average mu = 0.915, current mu = 0.934) allocation failure Oct 19 11:19:14 volumio-werkstatt volumio[982]: <--- JS stacktrace ---> Oct 19 11:19:14 volumio-werkstatt volumio[982]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Oct 19 11:19:14 volumio-werkstatt volumio-remote-updater[583]: [2024-10-19 11:19:14] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Oct 19 11:19:14 volumio-werkstatt volumio-remote-updater[583]: [2024-10-19 11:19:14] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Oct 19 11:19:14 volumio-werkstatt systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Oct 19 11:19:14 volumio-werkstatt volumio-remote-updater[583]: [2024-10-19 11:19:14] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Oct 19 11:19:14 volumio-werkstatt systemd[1]: volumio.service: Failed with result 'signal'. Oct 19 11:19:14 volumio-werkstatt systemd[1]: Started dynamicswap service. Oct 19 11:19:14 volumio-werkstatt systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 19 11:19:14 volumio-werkstatt systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 19 11:19:14 volumio-werkstatt systemd[1]: dynamicswap.service: Succeeded. Oct 19 11:19:14 volumio-werkstatt systemd[1]: Stopped Volumio Backend Module. Oct 19 11:19:14 volumio-werkstatt systemd[1]: Started Volumio Backend Module. Oct 19 11:19:17 volumio-werkstatt volumio[1571]: info: ------------------------------------------- Oct 19 11:19:17 volumio-werkstatt volumio[1571]: info: ----- Volumio3 ---- Oct 19 11:19:17 volumio-werkstatt volumio[1571]: info: ------------------------------------------- Oct 19 11:19:17 volumio-werkstatt volumio[1571]: info: ----- System startup ---- Oct 19 11:19:17 volumio-werkstatt volumio[1571]: info: ------------------------------------------- Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: MYVOLUMIO Environment detected Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Plugin folders cleanup Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning into folder /volumio/app/plugins/ Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning category audio_interface Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning category miscellanea Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning category music_service Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning category plugins.json Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning category system_controller Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning category user_interface Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning into folder /data/plugins/ Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning category audio_interface Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Scanning category user_interface Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Plugin folders cleanup completed Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: ------------------------------------------- Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: ----- Core plugins startup ---- Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: ------------------------------------------- Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Loading plugins from folder /volumio/app/plugins/ Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Adding plugin upnp to MyMusic Plugins Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Loading plugins from folder /data/plugins/ Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Loading plugin "system"... Oct 19 11:19:19 volumio-werkstatt volumio[1571]: info: Loading plugin "appearance"... Oct 19 11:19:19 volumio-werkstatt volumio-remote-updater[583]: [2024-10-19 11:19:19] [connect] Successful connection Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Loading plugin "network"... Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Refreshing Cached IP Addresses Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Loading plugin "services"... Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Loading plugin "alsa_controller"... Oct 19 11:19:20 volumio-werkstatt sudo[1816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 19 11:19:20 volumio-werkstatt sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 11:19:20 volumio-werkstatt sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:20 volumio-werkstatt sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:20 volumio-werkstatt sudo[1812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 11:19:20 volumio-werkstatt sudo[1812]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:20 volumio-werkstatt sudo[1814]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:20 volumio-werkstatt sudo[1812]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Loading plugin "wizard"... Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Loading plugin "networkfs"... Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Cannot mount NAS Audio at system boot, trial number 1 ,retrying in 5 seconds Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Cannot mount NAS Audio at system boot, trial number 1 ,retrying in 5 seconds Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Cannot mount NAS CD at system boot, trial number 1 ,retrying in 5 seconds Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Starting Udev Watcher for removable devices Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Ignoring mount for partition: boot Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Ignoring mount for partition: volumio Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Ignoring mount for partition: volumio_data Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Loading plugin "volumio_command_line_client"... Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Loading plugin "upnp"... Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: [1729329560642] Starting Upmpd Daemon Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Loading plugin "my_music"... Oct 19 11:19:20 volumio-werkstatt volumio[1571]: info: Loading plugin "mpd"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "upnp_browser"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "alarm-clock"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "airplay_emulation"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Starting Shairport Sync Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "last_100"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "webradio"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "i2s_dacs"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "volumiodiscovery"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 19 11:19:21 volumio-werkstatt volumio[1571]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 19 11:19:21 volumio-werkstatt volumio[1571]: *** WARNING *** For more information see Oct 19 11:19:21 volumio-werkstatt node[1571]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 19 11:19:21 volumio-werkstatt volumio[1571]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 19 11:19:21 volumio-werkstatt volumio[1571]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 19 11:19:21 volumio-werkstatt volumio[1571]: *** WARNING *** For more information see Oct 19 11:19:21 volumio-werkstatt node[1571]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 19 11:19:21 volumio-werkstatt node[1571]: *** WARNING *** For more information see Oct 19 11:19:21 volumio-werkstatt node[1571]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 19 11:19:21 volumio-werkstatt node[1571]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 19 11:19:21 volumio-werkstatt node[1571]: *** WARNING *** For more information see Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Discovery: Started advertising with name: Volumio-Werkstatt Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "outputs"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "albumart"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Plugin example_plugin is not enabled Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "inputs"... Oct 19 11:19:21 volumio-werkstatt volumio[1571]: info: Loading plugin "updater_comm"... Oct 19 11:19:22 volumio-werkstatt volumio[1571]: info: Plugin mpdemulation is not enabled Oct 19 11:19:22 volumio-werkstatt volumio[1571]: info: Loading plugin "rest_api"... Oct 19 11:19:22 volumio-werkstatt volumio[1571]: info: Loading plugin "websocket"... Oct 19 11:19:22 volumio-werkstatt volumio[1571]: info: Starting Socket.io Server version 2.3.0 Oct 19 11:19:22 volumio-werkstatt volumio[1571]: info: Loading plugin "fusiondsp"... Oct 19 11:19:22 volumio-werkstatt volumio[1571]: Forking 3 albumart workers Oct 19 11:19:22 volumio-werkstatt volumio[1571]: info: Applying required configuration parameters for plugin fusiondsp Oct 19 11:19:22 volumio-werkstatt volumio[1571]: info: Loading plugin "touch_display"... Oct 19 11:19:23 volumio-werkstatt volumio[1571]: Starting albumart workers Oct 19 11:19:23 volumio-werkstatt volumio[1571]: Starting albumart workers Oct 19 11:19:23 volumio-werkstatt volumio[1571]: Starting albumart workers Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: Applying required configuration parameters for plugin touch_display Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: Loading i18n strings for locale de Oct 19 11:19:23 volumio-werkstatt volumio[1571]: Updating browse sources language Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::initPlayerControls Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 11:19:24 volumio-werkstatt volumio[1571]: Express server listening on port 3000 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: [Metrics] WebUI: 7s 387.75ms Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreStateMachine::resetVolumioState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreStateMachine::getcurrentVolume Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioRetrievevolume Oct 19 11:19:24 volumio-werkstatt volumio[1571]: verbose: New Socket.io Connection to 192.168.115.63 from 192.168.115.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Edg/130.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Oct 19 11:19:24 volumio-werkstatt volumio-remote-updater[583]: [2024-10-19 11:19:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729329559 101 Oct 19 11:19:24 volumio-werkstatt sudo[1816]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:24 volumio-werkstatt volumio[1571]: 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: 2 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: verbose: New Socket.io Connection to 192.168.115.63:3000 from 192.168.115.60 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: verbose: New Socket.io Connection to 192.168.115.63:3000 from 192.168.115.60 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreStateMachine::pushState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioPushState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreStateMachine::updateTrackBlock Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrackBlock Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioRetrievevolume Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 11:19:24 volumio-werkstatt volumio[1571]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Reloading queue from file Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Received Get System Info Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Discovery: Getting this device information Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Listing playlists Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreStateMachine::pushState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioPushState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreStateMachine::setRepeat true single undefined Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreStateMachine::pushState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioPushState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreStateMachine::setRandom true Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreStateMachine::pushState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioPushState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Setting Device type: Raspberry PI Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Completed loading Core Plugins Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Preparing to generate the ALSA configuration file Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Reading ALSA contributions from plugins. Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Discovery: adding d1091a33-b90a-4e2f-b1e8-0483c8dea034 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Discovery: Found device Volumio-Buero Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Discovery: Connecting to remote: 192.168.115.61 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Discovery: adding f7b4a9e6-ba6e-4add-9704-6212f9312728 Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: Discovery: Found device Volumio-Werkstatt Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:24 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Discovery: Connected to remote: 192.168.115.61 Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Asound.conf file unchanged, so no further update is needed Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Output device has changed, restarting MPD Oct 19 11:19:25 volumio-werkstatt sudo[1898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 19 11:19:25 volumio-werkstatt sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Output device has changed, restarting Shairport Sync Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:25 volumio-werkstatt sudo[1898]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:25 volumio-werkstatt sudo[1901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 19 11:19:25 volumio-werkstatt sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:25 volumio-werkstatt systemd[1]: Stopping Music Player Daemon... Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: ___________ START PLUGINS ___________ Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: ControllerMpd::onStart: Initializing MPD Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Creating MPD Configuration file Oct 19 11:19:25 volumio-werkstatt sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 11:19:25 volumio-werkstatt sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: [1729329565359] CoreMusicLibrary::Adding element Medienserver Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 11:19:25 volumio-werkstatt sudo[1907]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:25 volumio-werkstatt sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 19 11:19:25 volumio-werkstatt sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: [1729329565496] CoreMusicLibrary::Adding element Last_100 Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: [1729329565500] CoreMusicLibrary::Adding element Webradio Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Initializing BBC Radios Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Loading i18n strings for locale de Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: FusionDsp - mixtype--------------------- Software Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Loading i18n strings for locale de Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Volumio Calling Home Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Preparing to generate the ALSA configuration file Oct 19 11:19:25 volumio-werkstatt sudo[1931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 19 11:19:25 volumio-werkstatt sudo[1931]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:25 volumio-werkstatt sudo[1933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 19 11:19:25 volumio-werkstatt sudo[1933]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:25 volumio-werkstatt sudo[1936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 19 11:19:25 volumio-werkstatt sudo[1936]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:25 volumio-werkstatt systemd[1]: Reloading. Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Cannot mount NAS Audio at system boot, trial number 2 ,retrying in 5 seconds Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Cannot mount NAS Audio at system boot, trial number 2 ,retrying in 5 seconds Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Cannot mount NAS CD at system boot, trial number 2 ,retrying in 5 seconds Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: Reading ALSA contributions from plugins. Oct 19 11:19:25 volumio-werkstatt volumio[1571]: info: touch_display: Backlight interface detected. Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: MPD Permissions set Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: Volumio called home Oct 19 11:19:26 volumio-werkstatt volumio[1571]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 19 11:19:26 volumio-werkstatt sudo[1962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Oct 19 11:19:26 volumio-werkstatt sudo[1962]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: touch_display: Raspberry Pi Foundation touch screen detected. Oct 19 11:19:26 volumio-werkstatt sudo[1962]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: Starting Shairport Sync Oct 19 11:19:26 volumio-werkstatt sudo[1965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 19 11:19:26 volumio-werkstatt sudo[1965]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:26 volumio-werkstatt sudo[1965]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: Starting Shairport Sync Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: Starting Shairport Sync Oct 19 11:19:26 volumio-werkstatt sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 19 11:19:26 volumio-werkstatt sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:26 volumio-werkstatt sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 19 11:19:26 volumio-werkstatt sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: touch_display: File permissions for backlight brightness control set. Oct 19 11:19:26 volumio-werkstatt sudo[1974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 19 11:19:26 volumio-werkstatt sudo[1974]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: Asound.conf file unchanged, so no further update is needed Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: Output device has changed, restarting MPD Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: Output device has changed, restarting Shairport Sync Oct 19 11:19:26 volumio-werkstatt sudo[1979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:26 volumio-werkstatt sudo[1979]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:26 volumio-werkstatt sudo[1979]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:26 volumio-werkstatt sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 19 11:19:26 volumio-werkstatt sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: MPD Permissions set Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: Starting Shairport Sync Oct 19 11:19:26 volumio-werkstatt sudo[1992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 19 11:19:26 volumio-werkstatt sudo[1992]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:26 volumio-werkstatt systemd[1]: mpd.service: Succeeded. Oct 19 11:19:26 volumio-werkstatt systemd[1]: Stopped Music Player Daemon. Oct 19 11:19:26 volumio-werkstatt sudo[1936]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: touch_display: systemctl daemon-reload succeeded. Oct 19 11:19:26 volumio-werkstatt sudo[1996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 19 11:19:26 volumio-werkstatt sudo[1996]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:26 volumio-werkstatt sudo[1931]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:26 volumio-werkstatt volumio[1571]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 19 11:19:26 volumio-werkstatt systemd[1]: Reloading. Oct 19 11:19:27 volumio-werkstatt ntpd[816]: Soliciting pool server 194.164.164.175 Oct 19 11:19:27 volumio-werkstatt ntpd[816]: Soliciting pool server 185.248.189.10 Oct 19 11:19:27 volumio-werkstatt systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 19 11:19:27 volumio-werkstatt volumio[1571]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 19 11:19:27 volumio-werkstatt volumio[1571]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Oct 19 11:19:27 volumio-werkstatt volumio[1571]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: camilladsp spawned new process with pid 2017, instance 1, run: true Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: camilladsp service started and running in background, instance 1 Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:19:27 volumio-werkstatt kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 19 11:19:27 volumio-werkstatt volumio[1571]: cannot open device 'volumioHw': Device or resource busy Oct 19 11:19:27 volumio-werkstatt volumio[1571]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 19 11:19:27 volumio-werkstatt volumio[1571]: cannot open device 'volumioHw': Device or resource busy Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: FusionDsp loaded Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 11:19:27 volumio-werkstatt sudo[2027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 19 11:19:27 volumio-werkstatt sudo[2027]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:27 volumio-werkstatt sudo[1933]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: Adding Signal Path Element [object Object] Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: Adding fusiondspeq DSP Signal Path Element Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: FusionDsp - ---- installed callbackRead Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 19 11:19:27 volumio-werkstatt sudo[1996]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:27 volumio-werkstatt systemd[1]: Starting Music Player Daemon... Oct 19 11:19:27 volumio-werkstatt systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 19 11:19:27 volumio-werkstatt volumio[1571]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 19 11:19:27 volumio-werkstatt systemd[1]: shairport-sync.service: Succeeded. Oct 19 11:19:27 volumio-werkstatt systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 19 11:19:27 volumio-werkstatt sudo[2027]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:27 volumio-werkstatt systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 19 11:19:27 volumio-werkstatt sudo[1992]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:27 volumio-werkstatt sudo[1970]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:27 volumio-werkstatt sudo[1974]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:27 volumio-werkstatt sudo[1968]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: touch_display: Volumio Kiosk started. Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:27 volumio-werkstatt volumio[1571]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:28 volumio-werkstatt sudo[2030]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 19 11:19:28 volumio-werkstatt sudo[2030]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: Shairport-Sync Started Oct 19 11:19:28 volumio-werkstatt volumio[1571]: Error adding Membership: Error: addMembership EINVAL Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: Shairport-Sync Started Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: Shairport-Sync Started Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: Shairport-Sync Started Oct 19 11:19:28 volumio-werkstatt sudo[2030]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: touch_display: X display number found: 0 Oct 19 11:19:28 volumio-werkstatt ntpd[816]: Soliciting pool server 144.76.76.107 Oct 19 11:19:28 volumio-werkstatt ntpd[816]: Soliciting pool server 129.70.132.37 Oct 19 11:19:28 volumio-werkstatt ntpd[816]: Soliciting pool server 194.50.19.117 Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: touch_display: X display number found: 0 Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:19:28 volumio-werkstatt volumio[1571]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 19 11:19:29 volumio-werkstatt ntpd[816]: Soliciting pool server 185.233.107.180 Oct 19 11:19:29 volumio-werkstatt ntpd[816]: Soliciting pool server 213.239.234.28 Oct 19 11:19:29 volumio-werkstatt ntpd[816]: Soliciting pool server 157.90.24.29 Oct 19 11:19:29 volumio-werkstatt ntpd[816]: Soliciting pool server 207.180.217.145 Oct 19 11:19:29 volumio-werkstatt mpd[2037]: Oct 19 11:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 19 11:19:30 volumio-werkstatt ntpd[816]: Soliciting pool server 167.235.70.245 Oct 19 11:19:30 volumio-werkstatt ntpd[816]: Soliciting pool server 62.169.23.198 Oct 19 11:19:30 volumio-werkstatt ntpd[816]: Soliciting pool server 62.75.236.38 Oct 19 11:19:30 volumio-werkstatt ntpd[816]: Soliciting pool server 78.46.162.102 Oct 19 11:19:30 volumio-werkstatt systemd[1]: Started Music Player Daemon. Oct 19 11:19:30 volumio-werkstatt sudo[1981]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:30 volumio-werkstatt sudo[1909]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:30 volumio-werkstatt sudo[1901]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: Completed starting Core Plugins Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: ------------------------------------------- Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: ----- MyVolumio plugins startup ---- Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: ------------------------------------------- Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 19 11:19:30 volumio-werkstatt sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 11:19:30 volumio-werkstatt sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:30 volumio-werkstatt sudo[2049]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: MPD running with PID2037 Oct 19 11:19:30 volumio-werkstatt volumio[1571]: ,establishing connection Oct 19 11:19:30 volumio-werkstatt sudo[2051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 11:19:30 volumio-werkstatt sudo[2051]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:30 volumio-werkstatt sudo[2051]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:30 volumio-werkstatt volumio[1571]: error: MPD error: The expression evaluated to a falsy value: Oct 19 11:19:30 volumio-werkstatt volumio[1571]: assert.ok(self.idling) Oct 19 11:19:30 volumio-werkstatt volumio[1571]: error: The expression evaluated to a falsy value: Oct 19 11:19:30 volumio-werkstatt volumio[1571]: assert.ok(self.idling) Oct 19 11:19:30 volumio-werkstatt volumio[1571]: error: MPD error: The expression evaluated to a falsy value: Oct 19 11:19:30 volumio-werkstatt volumio[1571]: assert.ok(self.idling) Oct 19 11:19:30 volumio-werkstatt volumio[1571]: error: The expression evaluated to a falsy value: Oct 19 11:19:30 volumio-werkstatt volumio[1571]: assert.ok(self.idling) Oct 19 11:19:30 volumio-werkstatt volumio[1571]: error: MPD error: The expression evaluated to a falsy value: Oct 19 11:19:30 volumio-werkstatt volumio[1571]: assert.ok(self.idling) Oct 19 11:19:30 volumio-werkstatt volumio[1571]: error: The expression evaluated to a falsy value: Oct 19 11:19:30 volumio-werkstatt volumio[1571]: assert.ok(self.idling) Oct 19 11:19:30 volumio-werkstatt volumio[1571]: error: updateQueue error: null Oct 19 11:19:30 volumio-werkstatt sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 19 11:19:30 volumio-werkstatt sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:19:30 volumio-werkstatt sudo[2055]: pam_unix(sudo:session): session closed for user root Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: Upmpdcli Daemon Started Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: Cannot mount NAS Audio at system boot, trial number 3 ,retrying in 5 seconds Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: Cannot mount NAS Audio at system boot, trial number 3 ,retrying in 5 seconds Oct 19 11:19:30 volumio-werkstatt volumio[1571]: info: Cannot mount NAS CD at system boot, trial number 3 ,retrying in 5 seconds Oct 19 11:19:31 volumio-werkstatt ntpd[816]: Soliciting pool server 128.140.109.119 Oct 19 11:19:31 volumio-werkstatt ntpd[816]: Soliciting pool server 2a01:239:25e:bd00::1 Oct 19 11:19:31 volumio-werkstatt ntpd[816]: Soliciting pool server 94.130.184.193 Oct 19 11:19:31 volumio-werkstatt ntpd[816]: Soliciting pool server 176.9.157.12 Oct 19 11:19:32 volumio-werkstatt ntpd[816]: Soliciting pool server 65.21.184.1 Oct 19 11:19:32 volumio-werkstatt ntpd[816]: Soliciting pool server 185.163.116.98 Oct 19 11:19:57 volumio-werkstatt ntpd[816]: receive: Unexpected origin timestamp 0xeabdf826.099a7cf8 does not match aorg 0000000000.00000000 from server@94.130.184.193 xmt 0xeabdf83d.f78877ff Oct 19 11:19:57 volumio-werkstatt ntpd[816]: receive: Unexpected origin timestamp 0xeabdf826.099b9772 does not match aorg 0000000000.00000000 from server@144.76.76.107 xmt 0xeabdf83d.f7fbdae4 Oct 19 11:19:57 volumio-werkstatt ntpd[816]: receive: Unexpected origin timestamp 0xeabdf826.0997d48e does not match aorg 0000000000.00000000 from server@185.163.116.98 xmt 0xeabdf83d.f6dd3a50 Oct 19 11:19:57 volumio-werkstatt ntpd[816]: receive: Unexpected origin timestamp 0xeabdf826.09993dff does not match aorg 0000000000.00000000 from server@176.9.157.12 xmt 0xeabdf83d.f845ce2c Oct 19 11:19:57 volumio-werkstatt ntpd[816]: receive: Unexpected origin timestamp 0xeabdf826.0991a3b0 does not match aorg 0000000000.00000000 from server@65.21.184.1 xmt 0xeabdf83d.f9a50fda Oct 19 11:19:58 volumio-werkstatt ntpd[816]: receive: Unexpected origin timestamp 0xeabdf826.099ca42e does not match aorg 0000000000.00000000 from server@194.164.164.175 xmt 0xeabdf83d.fa4b9e74 Oct 19 11:19:59 volumio-werkstatt volumio[1571]: info: Cannot mount NAS Audio at system boot, trial number 4 ,retrying in 5 seconds Oct 19 11:19:59 volumio-werkstatt volumio[1571]: info: Cannot mount NAS Audio at system boot, trial number 4 ,retrying in 5 seconds Oct 19 11:19:59 volumio-werkstatt volumio[1571]: info: Cannot mount NAS CD at system boot, trial number 4 ,retrying in 5 seconds Oct 19 11:20:00 volumio-werkstatt volumio[1571]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: Discovery: A device disappeared from network Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: Discovery: Device volumio-buero disappeared from network Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: Discovery: Disconnected from remote: 192.168.115.61 Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: Adding plugin bluetooth to MyMusic Plugins Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: Adding plugin multiroom to MyMusic Plugins Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: Adding plugin metavolumio to MyMusic Plugins Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: Adding plugin cd_controller to MyMusic Plugins Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 19 11:20:04 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 19 11:20:05 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 19 11:20:05 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 19 11:20:05 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:20:05 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:20:05 volumio-werkstatt volumio[1571]: info: Starting MyVolumio Remote Streaming Endpoints Oct 19 11:20:05 volumio-werkstatt volumio[1571]: info: MyVolumio login type: Token Oct 19 11:20:05 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 19 11:20:05 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 19 11:20:06 volumio-werkstatt volumio[1571]: info: Starting Streaming Service Transparent Proxy Oct 19 11:20:07 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 19 11:20:07 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 19 11:20:07 volumio-werkstatt volumio[1571]: info: Streaming services startup Oct 19 11:20:07 volumio-werkstatt volumio[1571]: info: Starting Streaming Daemon Oct 19 11:20:07 volumio-werkstatt sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 19 11:20:07 volumio-werkstatt sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:20:07 volumio-werkstatt sudo[2064]: pam_unix(sudo:session): session closed for user root Oct 19 11:20:07 volumio-werkstatt volumio[1571]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 19 11:20:07 volumio-werkstatt volumio[1571]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 19 11:20:07 volumio-werkstatt volumio[1571]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 19 11:20:07 volumio-werkstatt volumio[1571]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 19 11:20:07 volumio-werkstatt volumio[1571]: error: Cannot start Volumio Streaming Daemon Oct 19 11:20:07 volumio-werkstatt volumio[1571]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 19 11:20:07 volumio-werkstatt volumio[1571]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 19 11:20:08 volumio-werkstatt volumio[1571]: STREAMING PROXY: Starting server on port 3245 Oct 19 11:20:08 volumio-werkstatt volumio[1571]: Node JS runtime: 14 Oct 19 11:20:10 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 11:20:10 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 11:20:10 volumio-werkstatt volumio[1571]: info: Retrieving Cloud Streaming UI Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: Getting Tidal Cloud Configuration Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: Getting Qobuz Cloud Configuration Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: Asking plugin for UI Config Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: Getting Spotify Cloud Configuration Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: Asking plugin for UI Config Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: Saving Spotify Acccount Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: Got Tidal Cloud Configuration Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: Got it Oct 19 11:20:11 volumio-werkstatt volumio[1571]: info: Got it Oct 19 11:20:11 volumio-werkstatt volumio[1571]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 19 11:20:15 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 11:20:15 volumio-werkstatt volumio[1571]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 19 11:20:15 volumio-werkstatt volumio[1571]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 11:20:17 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 11:20:18 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 19 11:20:18 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 19 11:20:19 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 11:20:19 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:20:19 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 11:20:20 volumio-werkstatt volumio[1571]: info: MyVolumio token set successfully Oct 19 11:20:20 volumio-werkstatt volumio[1571]: info: MYVOLUMIO: Adding device Oct 19 11:20:20 volumio-werkstatt volumio[1571]: info: MYVOLUMIO: Evaluating Server Oct 19 11:20:20 volumio-werkstatt volumio-remote-updater[583]: No test mode Oct 19 11:20:20 volumio-werkstatt volumio-remote-updater[583]: No alpha test mode Oct 19 11:20:20 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 19 11:20:21 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: MyVolumio status changed Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: Streaming services startup Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: Starting Streaming Daemon Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: Removing browser output: myVolumio user plan is not superstar Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: Removing audio output: Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: Stoppping Tunnel 1 Oct 19 11:20:23 volumio-werkstatt sudo[2101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 19 11:20:23 volumio-werkstatt sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: Setting Geolocation for MyVolumio to eu4 Oct 19 11:20:23 volumio-werkstatt sudo[2103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:20:23 volumio-werkstatt sudo[2103]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 19 11:20:23 volumio-werkstatt sudo[2101]: pam_unix(sudo:session): session closed for user root Oct 19 11:20:23 volumio-werkstatt sudo[2103]: pam_unix(sudo:session): session closed for user root Oct 19 11:20:23 volumio-werkstatt volumio[1571]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: Remote SSH Stopped Oct 19 11:20:23 volumio-werkstatt volumio[1571]: error: Cannot start Volumio Streaming Daemon Oct 19 11:20:23 volumio-werkstatt volumio[1571]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 19 11:20:23 volumio-werkstatt volumio[1571]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetState Oct 19 11:20:23 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:20:23 volumio-werkstatt volumio[1571]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 19 11:20:24 volumio-werkstatt volumio[1571]: info: Updating MyVolumio device info Oct 19 11:20:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:20:24 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:20:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:20:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:20:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 19 11:20:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 19 11:20:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 19 11:20:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 19 11:20:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 11:20:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 11:20:25 volumio-werkstatt volumio[1571]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 19 11:20:25 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 19 11:20:27 volumio-werkstatt volumio[1571]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: Completed starting MyVolumio Plugin Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: BOOT COMPLETED Oct 19 11:20:27 volumio-werkstatt volumio[1571]: [Metrics] CommandRouter: 45s 757.16ms Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumiosetStartupVolume Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: VolumeController:: Setting startup Volume 40 Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: VolumeController::SetAlsaVolume40 Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::Close All Modals sent Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::Close All Modals sent Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CoreStateMachine::pushState Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CorePlayQueue::getTrack 0 Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::volumioPushState Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: camilladsp stopping service pid 2017... Oct 19 11:20:27 volumio-werkstatt sudo[2111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Audio Oct 19 11:20:27 volumio-werkstatt sudo[2111]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: camilladsp service terminated, instance 1 Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 19 11:20:27 volumio-werkstatt sudo[2111]: pam_unix(sudo:session): session closed for user root Oct 19 11:20:27 volumio-werkstatt volumio[1571]: info: camilladsp service started and running in background, instance 1 Oct 19 11:20:27 volumio-werkstatt volumio[1571]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 11:20:27 volumio-werkstatt volumio[1571]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Audio' Oct 19 11:20:27 volumio-werkstatt volumio[1571]: at Object.rmdirSync (fs.js:890:10) Oct 19 11:20:27 volumio-werkstatt volumio[1571]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Oct 19 11:20:27 volumio-werkstatt volumio[1571]: at ChildProcess.exithandler (child_process.js:299:7) Oct 19 11:20:27 volumio-werkstatt volumio[1571]: at ChildProcess.emit (events.js:315:20) Oct 19 11:20:27 volumio-werkstatt volumio[1571]: at maybeClose (internal/child_process.js:1048:16) Oct 19 11:20:27 volumio-werkstatt volumio[1571]: at Socket. (internal/child_process.js:439:11) Oct 19 11:20:27 volumio-werkstatt volumio[1571]: at Socket.emit (events.js:315:20) Oct 19 11:20:27 volumio-werkstatt volumio[1571]: at Pipe. (net.js:673:12) { Oct 19 11:20:27 volumio-werkstatt volumio[1571]: errno: -16, Oct 19 11:20:27 volumio-werkstatt volumio[1571]: syscall: 'rmdir', Oct 19 11:20:27 volumio-werkstatt volumio[1571]: code: 'EBUSY', Oct 19 11:20:27 volumio-werkstatt volumio[1571]: path: '/mnt/NAS/Audio' Oct 19 11:20:27 volumio-werkstatt volumio[1571]: } Oct 19 11:20:27 volumio-werkstatt volumio[1571]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 11:20:27 volumio-werkstatt sudo[2125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 11:19 Oct 19 11:20:27 volumio-werkstatt sudo[2125]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"