-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Fri 2025-05-02 18:41:57 CDT. -- May 02 18:41:48 volumio-pi4b1gbgarage systemd-timedated[999]: Changed local time to Fri May 2 18:41:48 2025 May 02 18:41:48 volumio-pi4b1gbgarage sudo[997]: pam_unix(sudo:session): session closed for user root May 02 18:41:48 volumio-pi4b1gbgarage volumio-time-update[599]: volumio-time-update-util: System time updated successfully. May 02 18:41:48 volumio-pi4b1gbgarage systemd[1]: Starting Daily apt download activities... May 02 18:41:48 volumio-pi4b1gbgarage systemd[1]: Started Volumio Time Update Utility. May 02 18:41:49 volumio-pi4b1gbgarage volumio[979]: info: ------------------------------------------- May 02 18:41:49 volumio-pi4b1gbgarage volumio[979]: info: ----- Volumio3 ---- May 02 18:41:49 volumio-pi4b1gbgarage volumio[979]: info: ------------------------------------------- May 02 18:41:49 volumio-pi4b1gbgarage volumio[979]: info: ----- System startup ---- May 02 18:41:49 volumio-pi4b1gbgarage volumio[979]: info: ------------------------------------------- May 02 18:41:50 volumio-pi4b1gbgarage systemd[1]: systemd-fsckd.service: Succeeded. May 02 18:41:50 volumio-pi4b1gbgarage nmbd[718]: [2025/05/02 18:41:50.087098, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 02 18:41:50 volumio-pi4b1gbgarage systemd[1]: Started Samba NMB Daemon. May 02 18:41:50 volumio-pi4b1gbgarage nmbd[718]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 02 18:41:50 volumio-pi4b1gbgarage systemd[1]: Starting Samba Winbind Daemon... May 02 18:41:50 volumio-pi4b1gbgarage winbindd[1028]: [2025/05/02 18:41:50.360606, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 02 18:41:50 volumio-pi4b1gbgarage winbindd[1028]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 02 18:41:50 volumio-pi4b1gbgarage systemd[1]: Started Samba Winbind Daemon. May 02 18:41:50 volumio-pi4b1gbgarage winbindd[1028]: [2025/05/02 18:41:50.458299, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 02 18:41:50 volumio-pi4b1gbgarage winbindd[1028]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 02 18:41:50 volumio-pi4b1gbgarage systemd[1]: Starting Samba SMB Daemon... May 02 18:41:50 volumio-pi4b1gbgarage systemd[1]: apt-daily.service: Succeeded. May 02 18:41:50 volumio-pi4b1gbgarage systemd[1]: Started Daily apt download activities. May 02 18:41:50 volumio-pi4b1gbgarage systemd[1]: Starting Daily apt upgrade and clean activities... May 02 18:41:50 volumio-pi4b1gbgarage volumio[979]: info: MYVOLUMIO Environment detected May 02 18:41:51 volumio-pi4b1gbgarage smbd[1063]: [2025/05/02 18:41:51.082568, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: Started Samba SMB Daemon. May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Plugin folders cleanup May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Scanning into folder /volumio/app/plugins/ May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Scanning category audio_interface May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Scanning category miscellanea May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Scanning category music_service May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Scanning category plugins.json May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Scanning category system_controller May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Scanning category user_interface May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Scanning into folder /data/plugins/ May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Scanning category audio_interface May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Plugin folders cleanup completed May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: ------------------------------------------- May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: ----- Core plugins startup ---- May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: ------------------------------------------- May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Loading plugins from folder /volumio/app/plugins/ May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Adding plugin upnp to MyMusic Plugins May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Adding plugin airplay_emulation to MyMusic Plugins May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Adding plugin upnp_browser to MyMusic Plugins May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Loading plugins from folder /data/plugins/ May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "system"... May 02 18:41:51 volumio-pi4b1gbgarage smbd[1063]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: Reached target Multi-User System. May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: Reached target Graphical Interface. May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: Starting Update UTMP about System Runlevel Changes... May 02 18:41:51 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "appearance"... May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: Started Update UTMP about System Runlevel Changes. May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: apt-daily-upgrade.service: Succeeded. May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: Started Daily apt upgrade and clean activities. May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: Startup finished in 33.529s (kernel) + 33.760s (userspace) = 1min 7.290s. May 02 18:41:51 volumio-pi4b1gbgarage sh[488]: timed out May 02 18:41:51 volumio-pi4b1gbgarage dhcpcd[532]: timed out May 02 18:41:51 volumio-pi4b1gbgarage sh[488]: dhcpcd exited May 02 18:41:51 volumio-pi4b1gbgarage dhcpcd[532]: dhcpcd exited May 02 18:41:51 volumio-pi4b1gbgarage sh[488]: ifup: failed to bring up eth0 May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 02 18:41:51 volumio-pi4b1gbgarage systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: [2025/05/02 18:41:52.030822, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: my_name_register_failed: Failed to register my name VOLUMIO PI4B_1G<20> on subnet 192.168.50.180. May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: [2025/05/02 18:41:52.030942, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: standard_fail_register: Failed to register/refresh name VOLUMIO PI4B_1G<20> on subnet 192.168.50.180 May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: [2025/05/02 18:41:52.031000, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: my_name_register_failed: Failed to register my name VOLUMIO PI4B_1G<03> on subnet 192.168.50.180. May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: [2025/05/02 18:41:52.031054, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: standard_fail_register: Failed to register/refresh name VOLUMIO PI4B_1G<03> on subnet 192.168.50.180 May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: [2025/05/02 18:41:52.031105, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: my_name_register_failed: Failed to register my name VOLUMIO PI4B_1G<00> on subnet 192.168.50.180. May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: [2025/05/02 18:41:52.031155, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) May 02 18:41:52 volumio-pi4b1gbgarage nmbd[718]: standard_fail_register: Failed to register/refresh name VOLUMIO PI4B_1G<00> on subnet 192.168.50.180 May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "network"... May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Refreshing Cached IP Addresses May 02 18:41:52 volumio-pi4b1gbgarage sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 18:41:52 volumio-pi4b1gbgarage sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:52 volumio-pi4b1gbgarage sudo[1126]: pam_unix(sudo:session): session closed for user root May 02 18:41:52 volumio-pi4b1gbgarage sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 18:41:52 volumio-pi4b1gbgarage sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "services"... May 02 18:41:52 volumio-pi4b1gbgarage sudo[1128]: pam_unix(sudo:session): session closed for user root May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "alsa_controller"... May 02 18:41:52 volumio-pi4b1gbgarage sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 02 18:41:52 volumio-pi4b1gbgarage sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "wizard"... May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "networkfs"... May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Starting Udev Watcher for removable devices May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Ignoring mount for partition: boot May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Ignoring mount for partition: volumio May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Ignoring mount for partition: volumio_data May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "volumio_command_line_client"... May 02 18:41:52 volumio-pi4b1gbgarage sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=macneil,password=657@Mulvey,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.200/music /mnt/NAS/music May 02 18:41:52 volumio-pi4b1gbgarage sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "upnp"... May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: [1746229312360] Starting Upmpd Daemon May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "my_music"... May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "mpd"... May 02 18:41:52 volumio-pi4b1gbgarage kernel: Key type cifs.spnego registered May 02 18:41:52 volumio-pi4b1gbgarage kernel: Key type cifs.idmap registered May 02 18:41:52 volumio-pi4b1gbgarage kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 02 18:41:52 volumio-pi4b1gbgarage kernel: CIFS: Attempting to mount //192.168.50.200/music May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "upnp_browser"... May 02 18:41:52 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "alarm-clock"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "airplay_emulation"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Starting Shairport Sync May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "last_100"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "webradio"... May 02 18:41:53 volumio-pi4b1gbgarage sudo[1152]: pam_unix(sudo:session): session closed for user root May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "i2s_dacs"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: I2S DAC not set, start Auto-detection May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "volumiodiscovery"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 02 18:41:53 volumio-pi4b1gbgarage node[979]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi! May 02 18:41:53 volumio-pi4b1gbgarage node[979]: *** WARNING *** Please fix your application to use the native API of Avahi! May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: *** WARNING *** For more information see May 02 18:41:53 volumio-pi4b1gbgarage node[979]: *** WARNING *** For more information see May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 02 18:41:53 volumio-pi4b1gbgarage node[979]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: *** WARNING *** Please fix your application to use the native API of Avahi! May 02 18:41:53 volumio-pi4b1gbgarage node[979]: *** WARNING *** Please fix your application to use the native API of Avahi! May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: *** WARNING *** For more information see May 02 18:41:53 volumio-pi4b1gbgarage node[979]: *** WARNING *** For more information see May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Applying required configuration parameters for plugin volumiodiscovery May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Discovery: Started advertising with name: Volumio Pi4B_1GB_Garage May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "outputs"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "albumart"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Plugin example_plugin is not enabled May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "inputs"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "updater_comm"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Plugin mpdemulation is not enabled May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "rest_api"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "websocket"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Starting Socket.io Server version 2.3.0 May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Loading plugin "fusiondsp"... May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: Forking 3 albumart workers May 02 18:41:53 volumio-pi4b1gbgarage volumio[979]: info: Applying required configuration parameters for plugin fusiondsp May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: Starting albumart workers May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: Starting albumart workers May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Loading i18n strings for locale en May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: Updating browse sources language May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: Starting albumart workers May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::initPlayerControls May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: Express server listening on port 3000 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: [Metrics] WebUI: 5s 742.62ms May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreStateMachine::resetVolumioState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreStateMachine::getcurrentVolume May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioRetrievevolume May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Volumio Network Manager: Network status updated: 2 May 02 18:41:54 volumio-pi4b1gbgarage volumio-remote-updater[585]: [2025-05-02 18:41:54] [connect] Successful connection May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: verbose: New Socket.io Connection to 192.168.50.180:3000 from 192.168.50.38 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: verbose: New Socket.io Connection to 192.168.50.180:3000 from 192.168.50.248 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: verbose: New Socket.io Connection to 192.168.50.180:3000 from 192.168.50.38 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: verbose: New Socket.io Connection to 192.168.50.180:3000 from 192.168.50.248 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreStateMachine::pushState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioPushState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreStateMachine::updateTrackBlock May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrackBlock May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioRetrievevolume May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Discovery: Getting this device information May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioGetState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Reloading queue from file May 02 18:41:54 volumio-pi4b1gbgarage volumio-remote-updater[585]: [2025-05-02 18:41:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746229314 101 May 02 18:41:54 volumio-pi4b1gbgarage systemd[1]: systemd-hostnamed.service: Succeeded. May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: 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: 5 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreStateMachine::pushState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioPushState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreStateMachine::setRepeat true single undefined May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreStateMachine::pushState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioPushState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreStateMachine::setRandom false May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreStateMachine::pushState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioPushState May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Setting Device type: Raspberry PI May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Completed loading Core Plugins May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Preparing to generate the ALSA configuration file May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Reading ALSA contributions from plugins. May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Asound.conf file unchanged, so no further update is needed May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Output device has changed, restarting MPD May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Output device has changed, restarting Shairport Sync May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: ___________ START PLUGINS ___________ May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: ControllerMpd::onStart: Initializing MPD May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Creating MPD Configuration file May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: [1746229314907] CoreMusicLibrary::Adding element Media Servers May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 18:41:54 volumio-pi4b1gbgarage sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:54 volumio-pi4b1gbgarage sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 18:41:54 volumio-pi4b1gbgarage sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:54 volumio-pi4b1gbgarage sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 18:41:54 volumio-pi4b1gbgarage sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 18:41:54 volumio-pi4b1gbgarage sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:54 volumio-pi4b1gbgarage sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:54 volumio-pi4b1gbgarage sudo[1257]: pam_unix(sudo:session): session closed for user root May 02 18:41:54 volumio-pi4b1gbgarage sudo[1264]: pam_unix(sudo:session): session closed for user root May 02 18:41:54 volumio-pi4b1gbgarage systemd[1]: Stopping Music Player Daemon... May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: [1746229314994] CoreMusicLibrary::Adding element Last_100 May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: [1746229314997] CoreMusicLibrary::Adding element Webradio May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 18:41:54 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Initializing BBC Radios May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Loading i18n strings for locale en May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: FusionDsp - mixtype--------------------- Software May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Volumio Calling Home May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: mpd.service: Succeeded. May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Stopped Music Player Daemon. May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Preparing to generate the ALSA configuration file May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Starting Music Player Daemon... May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: adding d99606ea-48f3-4946-bcba-4f651f11cd5e May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: Found device Volumio Pi4B_1GB_Garage May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioGetState May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: adding 93f47ec2-aa41-472d-b7d4-9c1fdd5bbe8f May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: Found device Volumio Pi3B+_1GB_B May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: Connecting to remote: 192.168.50.38 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: adding 1ce93ab2-ef3b-4e18-a2b6-f4493941ba21 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: Found device Volumio Pi4b_1Gb_Roam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: Connecting to remote: 192.168.50.248 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Reading ALSA contributions from plugins. May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: MPD Permissions set May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: MPD Permissions set May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioGetState May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioGetState May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: verbose: New Socket.io Connection to 192.168.50.180:3000 from 192.168.50.50 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage sudo[1289]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 18:41:55 volumio-pi4b1gbgarage sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:55 volumio-pi4b1gbgarage sudo[1289]: pam_unix(sudo:session): session closed for user root May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Starting Shairport Sync May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Starting Shairport Sync May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Starting Shairport Sync May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioGetState May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Asound.conf file unchanged, so no further update is needed May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Output device has changed, restarting MPD May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Output device has changed, restarting Shairport Sync May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 18:41:55 volumio-pi4b1gbgarage sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:55 volumio-pi4b1gbgarage sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 18:41:55 volumio-pi4b1gbgarage sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 18:41:55 volumio-pi4b1gbgarage sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 18:41:55 volumio-pi4b1gbgarage sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 18:41:55 volumio-pi4b1gbgarage sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:55 volumio-pi4b1gbgarage sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::volumioGetState May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CorePlayQueue::getTrack 0 May 02 18:41:55 volumio-pi4b1gbgarage sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 18:41:55 volumio-pi4b1gbgarage sudo[1308]: pam_unix(sudo:session): session closed for user root May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: shairport-sync.service: Succeeded. May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 18:41:55 volumio-pi4b1gbgarage sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: Connected to remote: 192.168.50.38 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Discovery: Connected to remote: 192.168.50.248 May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: MPD Permissions set May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 18:41:55 volumio-pi4b1gbgarage sudo[1304]: pam_unix(sudo:session): session closed for user root May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: mpd.service: Succeeded. May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Stopped Music Player Daemon. May 02 18:41:55 volumio-pi4b1gbgarage sudo[1306]: pam_unix(sudo:session): session closed for user root May 02 18:41:55 volumio-pi4b1gbgarage sudo[1302]: pam_unix(sudo:session): session closed for user root May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Shairport-Sync Started May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: Error adding Membership: Error: addMembership EINVAL May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Starting Music Player Daemon... May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Volumio called home May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Shairport-Sync Started May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Shairport-Sync Started May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Starting Shairport Sync May 02 18:41:55 volumio-pi4b1gbgarage sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 18:41:55 volumio-pi4b1gbgarage sudo[1325]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 18:41:55 volumio-pi4b1gbgarage sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:55 volumio-pi4b1gbgarage sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:55 volumio-pi4b1gbgarage sudo[1325]: pam_unix(sudo:session): session closed for user root May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: shairport-sync.service: Succeeded. May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 18:41:55 volumio-pi4b1gbgarage systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 18:41:55 volumio-pi4b1gbgarage sudo[1328]: pam_unix(sudo:session): session closed for user root May 02 18:41:55 volumio-pi4b1gbgarage sudo[1136]: pam_unix(sudo:session): session closed for user root May 02 18:41:55 volumio-pi4b1gbgarage volumio[979]: info: Shairport-Sync Started May 02 18:41:56 volumio-pi4b1gbgarage mpd[1331]: May 02 18:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 18:41:56 volumio-pi4b1gbgarage systemd[1]: Started Music Player Daemon. May 02 18:41:56 volumio-pi4b1gbgarage sudo[1266]: pam_unix(sudo:session): session closed for user root May 02 18:41:56 volumio-pi4b1gbgarage sudo[1259]: pam_unix(sudo:session): session closed for user root May 02 18:41:56 volumio-pi4b1gbgarage sudo[1310]: pam_unix(sudo:session): session closed for user root May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: info: Completed starting Core Plugins May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: info: ------------------------------------------- May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: info: ----- MyVolumio plugins startup ---- May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: info: ------------------------------------------- May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: info: [MyVolumio PluginManager] Fetching plans data.... May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: info: MPD running with PID1331 May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: ,establishing connection May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: error: MPD error: The expression evaluated to a falsy value: May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: assert.ok(self.idling) May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: error: The expression evaluated to a falsy value: May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: assert.ok(self.idling) May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: error: MPD error: The expression evaluated to a falsy value: May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: assert.ok(self.idling) May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: error: The expression evaluated to a falsy value: May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: assert.ok(self.idling) May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: error: MPD error: The expression evaluated to a falsy value: May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: assert.ok(self.idling) May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: error: The expression evaluated to a falsy value: May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: assert.ok(self.idling) May 02 18:41:56 volumio-pi4b1gbgarage volumio[979]: error: updateQueue error: null May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: info: camilladsp service started and running in background, instance 1 May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: info: FusionDsp loaded May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 18:41:57 volumio-pi4b1gbgarage sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 02 18:41:57 volumio-pi4b1gbgarage sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: info: FusionDsp - Reporting Fusion DSP Enabled May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: info: Adding Signal Path Element [object Object] May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: info: Adding fusiondspeq DSP Signal Path Element May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: info: FusionDsp - ---- installed callbackRead May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: at onErrorNT (internal/child_process.js:465:16) May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: errno: -2, May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: code: 'ENOENT', May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: spawnargs: [ May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: '-p', May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: 9876, May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: '-o', May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: '/tmp/camilladsp.log', May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: '-l', May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: 'warn', May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: ] May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: } May 02 18:41:57 volumio-pi4b1gbgarage volumio[979]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 18:41:57 volumio-pi4b1gbgarage systemd[1]: Started FusionDsp Daemon. May 02 18:41:57 volumio-pi4b1gbgarage sudo[1350]: pam_unix(sudo:session): session closed for user root May 02 18:41:57 volumio-pi4b1gbgarage sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 18:40 May 02 18:41:57 volumio-pi4b1gbgarage sudo[1362]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"