-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2024-09-08 13:41:05 CEST. -- Sep 08 13:40:01 volumio volumio[1487]: .......................................................................................................................................................................................................................................................++++ Sep 08 13:40:01 volumio volumio[1487]: e is 65537 (0x010001) Sep 08 13:40:02 volumio volumio[1487]: writing RSA key Sep 08 13:40:02 volumio volumio[1258]: info: Starting Uninstall of plugin audio_interface - fusiondsp Sep 08 13:40:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 08 13:40:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 08 13:40:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 08 13:40:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 08 13:40:02 volumio volumio[1258]: info: CoreCommandRouter::volumioGetBrowseSources Sep 08 13:40:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 13:40:03 volumio volumio-remote-updater[883]: No test mode Sep 08 13:40:03 volumio volumio-remote-updater[883]: No alpha test mode Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 08 13:40:03 volumio volumio[1258]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 08 13:40:03 volumio volumio[1258]: info: Received Get System Version Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:03 volumio volumio[1258]: info: Received Get System Info Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:40:03 volumio volumio[1258]: info: Discovery: Getting this device information Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:03 volumio volumio[1258]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:03 volumio volumio[1258]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 08 13:40:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 08 13:40:08 volumio dhcpcd[1196]: wlan0: carrier lost Sep 08 13:40:08 volumio wpa_supplicant[1083]: wlan0: Associated with 44:48:b9:25:a6:e8 Sep 08 13:40:08 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-CONNECTED - Connection to 44:48:b9:25:a6:e8 completed [id=0 id_str=] Sep 08 13:40:08 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 08 13:40:08 volumio avahi-daemon[873]: Withdrawing address record for 192.168.1.157 on wlan0. Sep 08 13:40:08 volumio avahi-daemon[873]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.157. Sep 08 13:40:08 volumio avahi-daemon[873]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 08 13:40:08 volumio dhcpcd[1196]: wlan0: deleting route to 192.168.1.0/24 Sep 08 13:40:08 volumio dhcpcd[1196]: wlan0: deleting default route via 192.168.1.1 Sep 08 13:40:08 volumio volumio[1258]: info: Discovery: A device disappeared from network Sep 08 13:40:08 volumio volumio[1258]: info: Discovery: Device volumio disappeared from network Sep 08 13:40:08 volumio dhcpcd[1196]: wlan0: carrier acquired Sep 08 13:40:08 volumio dhcpcd[1196]: wlan0: IAID dd:a8:27:3d Sep 08 13:40:09 volumio dhcpcd[1196]: wlan0: rebinding lease of 192.168.1.157 Sep 08 13:40:09 volumio dhcpcd[1196]: wlan0: soliciting an IPv6 router Sep 08 13:40:09 volumio volumio[1258]: info: Starting Uninstall of plugin audio_interface - fusiondsp Sep 08 13:40:09 volumio ntpd[976]: Deleting interface #4 wlan0, 192.168.1.157#123, interface stats: received=0, sent=0, dropped=0, active_time=29 secs Sep 08 13:40:10 volumio ntpd[976]: Soliciting pool server 193.149.0.221 Sep 08 13:40:11 volumio ntpd[976]: Soliciting pool server 217.160.115.110 Sep 08 13:40:11 volumio ntpd[976]: Soliciting pool server 217.71.200.210 Sep 08 13:40:12 volumio ntpd[976]: Soliciting pool server 158.227.98.15 Sep 08 13:40:12 volumio ntpd[976]: Soliciting pool server 208.85.20.220 Sep 08 13:40:13 volumio ntpd[976]: Soliciting pool server 94.143.139.219 Sep 08 13:40:13 volumio ntpd[976]: Soliciting pool server 178.32.88.247 Sep 08 13:40:13 volumio ntpd[976]: Soliciting pool server 178.32.88.247 Sep 08 13:40:14 volumio dhcpcd[1196]: wlan0: probing for an IPv4LL address Sep 08 13:40:14 volumio dhcpcd[1196]: wlan0: DHCP lease expired Sep 08 13:40:14 volumio dhcpcd[1196]: wlan0: soliciting a DHCP lease Sep 08 13:40:14 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-DISCONNECTED bssid=44:48:b9:25:a6:e8 reason=15 Sep 08 13:40:14 volumio dhcpcd[1196]: wlan0: carrier lost Sep 08 13:40:14 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 08 13:40:14 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=ES Sep 08 13:40:14 volumio ntpd[976]: Soliciting pool server 65.20.99.74 Sep 08 13:40:14 volumio ntpd[976]: Soliciting pool server 193.149.0.217 Sep 08 13:40:14 volumio wpa_supplicant[1083]: wlan0: Trying to associate with SSID 'Alex-House' Sep 08 13:40:15 volumio ntpd[976]: Soliciting pool server 178.215.228.24 Sep 08 13:40:16 volumio volumio[1258]: info: Enabling plugin fusiondsp Sep 08 13:40:16 volumio volumio[1258]: info: Loading plugin "fusiondsp"... Sep 08 13:40:16 volumio volumio[1258]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:16 volumio volumio[1258]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 08 13:40:16 volumio volumio[1258]: info: Reading ALSA contributions from plugins. Sep 08 13:40:16 volumio volumio[1258]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:40:16 volumio volumio[1258]: info: Output device has changed, restarting MPD Sep 08 13:40:16 volumio sudo[1837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:16 volumio volumio[1258]: info: Output device has changed, restarting Shairport Sync Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:16 volumio sudo[1837]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio sudo[1837]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio sudo[1839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:16 volumio sudo[1839]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:40:16 volumio volumio[1258]: xcb_connection_has_error() returned true Sep 08 13:40:16 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:16 volumio volumio[1258]: xcb_connection_has_error() returned true Sep 08 13:40:16 volumio volumio[1258]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:16 volumio vtcs[1639]: [2024-09-08 13:40:16.617] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Sep 08 13:40:16 volumio vtcs[1639]: [2024-09-08 13:40:16.618] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Sep 08 13:40:16 volumio sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 13:40:16 volumio volumio[1258]: info: PLUGIN START: fusiondsp Sep 08 13:40:16 volumio volumio[1258]: info: Loading i18n strings for locale es Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: FusionDsp - mixtype--------------------- Hardware Sep 08 13:40:16 volumio volumio[1258]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:16 volumio volumio[1258]: info: Done. Sep 08 13:40:16 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:16 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Sep 08 13:40:16 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 08 13:40:16 volumio systemd[1]: vtcs.service: Succeeded. Sep 08 13:40:16 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Sep 08 13:40:16 volumio volumio[1258]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 08 13:40:16 volumio volumio[1258]: info: Reading ALSA contributions from plugins. Sep 08 13:40:16 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 13:40:16 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 13:40:16 volumio volumio[1258]: info: MPD Permissions set Sep 08 13:40:16 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio sudo[1845]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:16 volumio sudo[1845]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio sudo[1845]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: Starting Shairport Sync Sep 08 13:40:16 volumio volumio[1258]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:40:16 volumio volumio[1258]: info: Output device has changed, restarting MPD Sep 08 13:40:16 volumio sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:16 volumio sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio sudo[1866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:16 volumio sudo[1866]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:40:16 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:40:16 volumio sudo[1866]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:16 volumio volumio[1258]: info: Output device has changed, restarting Shairport Sync Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:16 volumio sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:16 volumio sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio volumio[1258]: xcb_connection_has_error() returned true Sep 08 13:40:16 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:16 volumio volumio[1258]: xcb_connection_has_error() returned true Sep 08 13:40:16 volumio volumio[1258]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:16 volumio sudo[1863]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 08 13:40:16 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:16 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:16 volumio sudo[1878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 13:40:16 volumio sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 13:40:16 volumio sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 13:40:16 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:16 volumio sudo[1878]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio volumio[1258]: info: MPD Permissions set Sep 08 13:40:16 volumio volumio[1258]: info: Shairport-Sync Started Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio sudo[1881]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:16 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:16 volumio volumio[1258]: info: Starting Shairport Sync Sep 08 13:40:16 volumio sudo[1895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:16 volumio sudo[1895]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:16 volumio ntpd[976]: Soliciting pool server 162.159.200.1 Sep 08 13:40:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:40:16 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:40:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 13:40:16 volumio volumio[1258]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 13:40:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:16 volumio sudo[1895]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:16 volumio volumio[1258]: info: Shairport-Sync Started Sep 08 13:40:17 volumio mpd[1889]: Sep 08 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:40:17 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:40:17 volumio sudo[1839]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:17 volumio sudo[1869]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:17 volumio volumio[1258]: error: MPD error: The expression evaluated to a falsy value: Sep 08 13:40:17 volumio volumio[1258]: assert.ok(self.idling) Sep 08 13:40:17 volumio volumio[1258]: error: The expression evaluated to a falsy value: Sep 08 13:40:17 volumio volumio[1258]: assert.ok(self.idling) Sep 08 13:40:17 volumio volumio[1258]: error: updateQueue error: null Sep 08 13:40:17 volumio dhcpcd[1196]: wlan0: carrier acquired Sep 08 13:40:17 volumio wpa_supplicant[1083]: wlan0: Associated with 46:48:b9:25:a6:ef Sep 08 13:40:17 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-CONNECTED - Connection to 46:48:b9:25:a6:ef completed [id=0 id_str=] Sep 08 13:40:17 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 08 13:40:17 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=ES Sep 08 13:40:17 volumio dhcpcd[1196]: wlan0: IAID dd:a8:27:3d Sep 08 13:40:17 volumio dhcpcd[1196]: wlan0: carrier lost Sep 08 13:40:17 volumio dhcpcd[1196]: wlan0: carrier acquired Sep 08 13:40:17 volumio dhcpcd[1196]: wlan0: IAID dd:a8:27:3d Sep 08 13:40:17 volumio ntpd[976]: Soliciting pool server 90.68.206.60 Sep 08 13:40:17 volumio dhcpcd[1196]: wlan0: soliciting a DHCP lease Sep 08 13:40:18 volumio dhcpcd[1196]: wlan0: soliciting an IPv6 router Sep 08 13:40:18 volumio volumio[1258]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Sep 08 13:40:18 volumio volumio[1258]: info: camilladsp service started and running in background, instance 1 Sep 08 13:40:18 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:18 volumio volumio[1258]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:40:18 volumio volumio[1258]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 08 13:40:18 volumio volumio[1258]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:40:18 volumio volumio[1258]: info: FusionDsp loaded Sep 08 13:40:18 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:18 volumio sudo[1931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 08 13:40:18 volumio sudo[1931]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:18 volumio volumio[1258]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 08 13:40:18 volumio volumio[1258]: info: Adding Signal Path Element [object Object] Sep 08 13:40:18 volumio volumio[1258]: info: Adding fusiondspeq DSP Signal Path Element Sep 08 13:40:18 volumio volumio[1258]: info: FusionDsp - ---- installed callbackRead Sep 08 13:40:18 volumio volumio[1258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:40:18 volumio volumio[1258]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 08 13:40:18 volumio volumio[1258]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 08 13:40:18 volumio volumio[1258]: at onErrorNT (internal/child_process.js:465:16) Sep 08 13:40:18 volumio volumio[1258]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 08 13:40:18 volumio volumio[1258]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 08 13:40:18 volumio volumio[1258]: at listOnTimeout (internal/timers.js:523:9) Sep 08 13:40:18 volumio volumio[1258]: at processTimers (internal/timers.js:497:7) { Sep 08 13:40:18 volumio volumio[1258]: errno: -2, Sep 08 13:40:18 volumio volumio[1258]: code: 'ENOENT', Sep 08 13:40:18 volumio volumio[1258]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:40:18 volumio volumio[1258]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:40:18 volumio volumio[1258]: spawnargs: [ Sep 08 13:40:18 volumio volumio[1258]: '-p', Sep 08 13:40:18 volumio volumio[1258]: 9876, Sep 08 13:40:18 volumio volumio[1258]: '-o', Sep 08 13:40:18 volumio volumio[1258]: '/tmp/camilladsp.log', Sep 08 13:40:18 volumio volumio[1258]: '-l', Sep 08 13:40:18 volumio volumio[1258]: 'warn', Sep 08 13:40:18 volumio volumio[1258]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 08 13:40:18 volumio volumio[1258]: ] Sep 08 13:40:18 volumio volumio[1258]: } Sep 08 13:40:18 volumio volumio[1258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:40:18 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:40:18 volumio sudo[1931]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:18 volumio volumio[1933]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:40:18 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:40:18 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:40:18 volumio sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 13:39 Sep 08 13:40:18 volumio sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:18 volumio sudo[1943]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:19 volumio volumio-remote-updater[883]: [2024-09-08 13:40:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 08 13:40:19 volumio volumio-remote-updater[883]: [2024-09-08 13:40:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 08 13:40:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 13:40:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 13:40:19 volumio systemd[1]: Started dynamicswap service. Sep 08 13:40:19 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 13:40:44 volumio ntpd[976]: receive: Unexpected origin timestamp 0xea880ba3.df740407 does not match aorg 0000000000.00000000 from server@193.149.0.217 xmt 0xea880bbc.dcf2d6c1 Sep 08 13:40:44 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 13:40:44 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 08 13:40:44 volumio systemd[1]: Stopped Volumio Backend Module. Sep 08 13:40:44 volumio ntpd[976]: receive: Unexpected origin timestamp 0xea880ba3.df760abe does not match aorg 0000000000.00000000 from server@94.143.139.219 xmt 0xea880bbc.dd5d3074 Sep 08 13:40:44 volumio ntpd[976]: receive: Unexpected origin timestamp 0xea880ba3.df789ab5 does not match aorg 0000000000.00000000 from server@217.160.115.110 xmt 0xea880bbc.ddb6478d Sep 08 13:40:44 volumio ntpd[976]: receive: Unexpected origin timestamp 0xea880ba3.df76e748 does not match aorg 0000000000.00000000 from server@158.227.98.15 xmt 0xea880bbc.ddc6406c Sep 08 13:40:44 volumio ntpd[976]: receive: Unexpected origin timestamp 0xea880ba3.df71d0f8 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xea880bbc.df95e63c Sep 08 13:40:44 volumio systemd[1]: Started Volumio Backend Module. Sep 08 13:40:44 volumio systemd[1]: Started dynamicswap service. Sep 08 13:40:44 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 13:40:45 volumio volumio[1956]: info: ------------------------------------------- Sep 08 13:40:45 volumio volumio[1956]: info: ----- Volumio3 ---- Sep 08 13:40:45 volumio volumio[1956]: info: ------------------------------------------- Sep 08 13:40:45 volumio volumio[1956]: info: ----- System startup ---- Sep 08 13:40:45 volumio volumio[1956]: info: ------------------------------------------- Sep 08 13:40:45 volumio volumio[1956]: info: MYVOLUMIO Environment detected Sep 08 13:40:45 volumio volumio[1956]: info: Plugin folders cleanup Sep 08 13:40:45 volumio volumio[1956]: info: Scanning into folder /volumio/app/plugins/ Sep 08 13:40:45 volumio volumio[1956]: info: Scanning category audio_interface Sep 08 13:40:45 volumio volumio[1956]: info: Scanning category miscellanea Sep 08 13:40:45 volumio volumio[1956]: info: Scanning category music_service Sep 08 13:40:45 volumio volumio[1956]: info: Scanning category plugins.json Sep 08 13:40:45 volumio volumio[1956]: info: Scanning category system_controller Sep 08 13:40:45 volumio volumio[1956]: info: Scanning category user_interface Sep 08 13:40:45 volumio volumio[1956]: info: Scanning into folder /data/plugins/ Sep 08 13:40:45 volumio volumio[1956]: info: Scanning category audio_interface Sep 08 13:40:45 volumio volumio[1956]: info: Plugin folders cleanup completed Sep 08 13:40:45 volumio volumio[1956]: info: ------------------------------------------- Sep 08 13:40:45 volumio volumio[1956]: info: ----- Core plugins startup ---- Sep 08 13:40:45 volumio volumio[1956]: info: ------------------------------------------- Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 13:40:45 volumio volumio[1956]: info: Adding plugin upnp to MyMusic Plugins Sep 08 13:40:45 volumio volumio[1956]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 13:40:45 volumio volumio[1956]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugins from folder /data/plugins/ Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "system"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "appearance"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "network"... Sep 08 13:40:45 volumio volumio[1956]: info: Refreshing Cached IP Addresses Sep 08 13:40:45 volumio sudo[1983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 13:40:45 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:45 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "services"... Sep 08 13:40:45 volumio sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "alsa_controller"... Sep 08 13:40:45 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:45 volumio sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 13:40:45 volumio sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:45 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:45 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "wizard"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "networkfs"... Sep 08 13:40:45 volumio volumio[1956]: info: Starting Udev Watcher for removable devices Sep 08 13:40:45 volumio volumio[1956]: info: Ignoring mount for partition: boot Sep 08 13:40:45 volumio volumio[1956]: info: Ignoring mount for partition: volumio Sep 08 13:40:45 volumio volumio[1956]: info: Ignoring mount for partition: volumio_data Sep 08 13:40:45 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "volumio_command_line_client"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "upnp"... Sep 08 13:40:45 volumio volumio[1956]: info: [1725795645756] Starting Upmpd Daemon Sep 08 13:40:45 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "my_music"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "mpd"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "upnp_browser"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "alarm-clock"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "airplay_emulation"... Sep 08 13:40:45 volumio volumio[1956]: info: Starting Shairport Sync Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "last_100"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "webradio"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "i2s_dacs"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "volumiodiscovery"... Sep 08 13:40:45 volumio volumio[1956]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:45 volumio node[1956]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:45 volumio node[1956]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:45 volumio volumio[1956]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:45 volumio volumio[1956]: *** WARNING *** For more information see Sep 08 13:40:45 volumio volumio[1956]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:45 volumio volumio[1956]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:45 volumio volumio[1956]: *** WARNING *** For more information see Sep 08 13:40:45 volumio node[1956]: *** WARNING *** For more information see Sep 08 13:40:45 volumio node[1956]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:45 volumio node[1956]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:45 volumio node[1956]: *** WARNING *** For more information see Sep 08 13:40:45 volumio volumio[1956]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 13:40:45 volumio volumio[1956]: info: Discovery: Started advertising with name: Volumio Sep 08 13:40:45 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "outputs"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "albumart"... Sep 08 13:40:45 volumio volumio[1956]: info: Plugin example_plugin is not enabled Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "inputs"... Sep 08 13:40:45 volumio volumio[1956]: info: Loading plugin "updater_comm"... Sep 08 13:40:45 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:40:45 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Sep 08 13:40:45 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:40:46 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:40:46 volumio volumio[1956]: info: Plugin mpdemulation is not enabled Sep 08 13:40:46 volumio volumio[1956]: info: Loading plugin "rest_api"... Sep 08 13:40:46 volumio volumio[1956]: info: Loading plugin "websocket"... Sep 08 13:40:46 volumio volumio[2003]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:40:46 volumio volumio[1956]: info: Starting Socket.io Server version 2.3.0 Sep 08 13:40:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:40:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:40:46 volumio volumio[1956]: info: Loading plugin "fusiondsp"... Sep 08 13:40:46 volumio volumio[1956]: Forking 3 albumart workers Sep 08 13:40:46 volumio volumio[1956]: info: Applying required configuration parameters for plugin fusiondsp Sep 08 13:40:46 volumio volumio[1956]: info: Loading i18n strings for locale es Sep 08 13:40:46 volumio volumio[1956]: Updating browse sources language Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::initPlayerControls Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: Express server listening on port 3000 Sep 08 13:40:46 volumio volumio[1956]: [Metrics] WebUI: 1s 21.54ms Sep 08 13:40:46 volumio volumio[1956]: info: CoreStateMachine::resetVolumioState Sep 08 13:40:46 volumio volumio[1956]: info: CoreStateMachine::getcurrentVolume Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:40:46 volumio volumio[1956]: verbose: New Socket.io Connection to 192.168.1.179 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Herring/90.1.1410.5 Engine version: 3 Transport: polling Total Clients: 1 Sep 08 13:40:46 volumio volumio[1956]: Starting albumart workers Sep 08 13:40:46 volumio volumio[1956]: Starting albumart workers Sep 08 13:40:46 volumio volumio[1956]: Starting albumart workers Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: Reloading queue from file Sep 08 13:40:46 volumio volumio[1956]: info: CoreStateMachine::setRepeat null single undefined Sep 08 13:40:46 volumio volumio[1956]: info: CoreStateMachine::pushState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:46 volumio volumio[1956]: info: CoreStateMachine::setRandom null Sep 08 13:40:46 volumio volumio[1956]: info: CoreStateMachine::pushState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:46 volumio volumio[1956]: info: Setting Device type: Raspberry PI Sep 08 13:40:46 volumio volumio[1956]: info: Completed loading Core Plugins Sep 08 13:40:46 volumio volumio[1956]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:46 volumio volumio[1956]: info: VolumeController:: Volume=46 Mute =false Sep 08 13:40:46 volumio volumio[1956]: info: CoreStateMachine::pushState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:46 volumio volumio[1956]: info: CoreStateMachine::updateTrackBlock Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrackBlock Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:40:46 volumio volumio[1956]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 08 13:40:46 volumio volumio[1956]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Sep 08 13:40:46 volumio volumio[1956]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 08 13:40:46 volumio volumio[1956]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:40:46 volumio volumio[1956]: info: Reading ALSA contributions from plugins. Sep 08 13:40:46 volumio volumio[1956]: info: Asound.conf file written Sep 08 13:40:46 volumio sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 08 13:40:46 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio volumio[1956]: info: Output device has changed, restarting MPD Sep 08 13:40:46 volumio volumio[1956]: info: Output device has changed, restarting Shairport Sync Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:46 volumio sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:46 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio sudo[2060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:46 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:40:46 volumio volumio[1956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:46 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:46 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:46 volumio volumio[1956]: info: ___________ START PLUGINS ___________ Sep 08 13:40:46 volumio volumio[1956]: info: ControllerMpd::onStart: Initializing MPD Sep 08 13:40:46 volumio volumio[1956]: info: Creating MPD Configuration file Sep 08 13:40:46 volumio sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:46 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:46 volumio volumio[1956]: info: [1725795646498] CoreMusicLibrary::Adding element Media Servers Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:46 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:46 volumio sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:46 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:46 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 08 13:40:46 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:46 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:46 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:46 volumio volumio[1956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:46 volumio sudo[2085]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:46 volumio sudo[2085]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio sudo[2085]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio volumio[1956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:46 volumio volumio[1956]: info: [1725795646544] CoreMusicLibrary::Adding element Last_100 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:46 volumio volumio[1956]: info: [1725795646545] CoreMusicLibrary::Adding element Webradio Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:46 volumio volumio[1956]: info: Initializing BBC Radios Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: Loading i18n strings for locale es Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: FusionDsp - mixtype--------------------- Hardware Sep 08 13:40:46 volumio volumio[1956]: info: Volumio Calling Home Sep 08 13:40:46 volumio volumio[1956]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:46 volumio volumio[1956]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:40:46 volumio volumio[1956]: info: Reading ALSA contributions from plugins. Sep 08 13:40:46 volumio volumio[1956]: verbose: New Socket.io Connection to 192.168.1.179 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Herring/90.1.1410.5 Engine version: 3 Transport: polling Total Clients: 2 Sep 08 13:40:46 volumio volumio[1956]: info: MPD Permissions set Sep 08 13:40:46 volumio volumio[1956]: info: MPD Permissions set Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: VolumeController:: Volume=46 Mute =false Sep 08 13:40:46 volumio volumio[1956]: info: CoreStateMachine::pushState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:46 volumio volumio[1956]: info: Received Get System Info Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:40:46 volumio volumio[1956]: info: Discovery: Getting this device information Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 13:40:46 volumio volumio[1956]: info: Received Get System Info Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:40:46 volumio volumio[1956]: info: Discovery: Getting this device information Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio volumio[1956]: info: Listing playlists Sep 08 13:40:46 volumio volumio[1956]: info: Starting Shairport Sync Sep 08 13:40:46 volumio volumio[1956]: info: Starting Shairport Sync Sep 08 13:40:46 volumio sudo[2116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:46 volumio sudo[2116]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio volumio[1956]: info: Starting Shairport Sync Sep 08 13:40:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:40:46 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:40:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:46 volumio sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:46 volumio sudo[2118]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:46 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:46 volumio sudo[2121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:46 volumio sudo[2121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:40:46 volumio sudo[2116]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 08 13:40:46 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:40:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:46 volumio volumio[1956]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:40:46 volumio volumio[1956]: info: Output device has changed, restarting MPD Sep 08 13:40:46 volumio sudo[2118]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio sudo[2121]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio mpd[2092]: Sep 08 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:40:46 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:40:46 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio sudo[2129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:46 volumio sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio sudo[2129]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio volumio[1956]: info: Output device has changed, restarting Shairport Sync Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:46 volumio sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:46 volumio sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:40:46 volumio volumio[1956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:46 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:46 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:46 volumio volumio[1956]: info: Shairport-Sync Started Sep 08 13:40:46 volumio volumio[1956]: Error adding Membership: Error: addMembership EINVAL Sep 08 13:40:46 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:46 volumio volumio[1956]: info: MPD Permissions set Sep 08 13:40:46 volumio volumio[1956]: info: Completed starting Core Plugins Sep 08 13:40:46 volumio volumio[1956]: info: ------------------------------------------- Sep 08 13:40:46 volumio volumio[1956]: info: ----- MyVolumio plugins startup ---- Sep 08 13:40:46 volumio volumio[1956]: info: ------------------------------------------- Sep 08 13:40:46 volumio volumio[1956]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 08 13:40:46 volumio volumio[1956]: info: Shairport-Sync Started Sep 08 13:40:46 volumio volumio[1956]: info: Shairport-Sync Started Sep 08 13:40:46 volumio volumio[1956]: error: Cannot initialize MPD Connection: MPD is not running Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:46 volumio sudo[2139]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:46 volumio sudo[2139]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:46 volumio sudo[2139]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:46 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:47 volumio volumio[1956]: info: Starting Shairport Sync Sep 08 13:40:47 volumio mpd[2144]: Sep 08 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:40:47 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:40:47 volumio sudo[2149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:47 volumio sudo[2134]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:47 volumio sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:40:47 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:40:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:47 volumio volumio[1956]: error: MPD error: The expression evaluated to a falsy value: Sep 08 13:40:47 volumio volumio[1956]: assert.ok(self.idling) Sep 08 13:40:47 volumio volumio[1956]: error: The expression evaluated to a falsy value: Sep 08 13:40:47 volumio volumio[1956]: assert.ok(self.idling) Sep 08 13:40:47 volumio volumio[1956]: error: updateQueue error: null Sep 08 13:40:47 volumio volumio[1956]: error: updateQueue error: null Sep 08 13:40:47 volumio volumio[1956]: info: Discovery: adding 1ea99d28-e957-42b8-80ae-afd56707d40e Sep 08 13:40:47 volumio volumio[1956]: info: Discovery: Found device Volumio Sep 08 13:40:47 volumio volumio[1956]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:47 volumio volumio[1956]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:47 volumio sudo[2149]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:47 volumio volumio[1956]: info: Shairport-Sync Started Sep 08 13:40:47 volumio dhcpcd[1196]: wlan0: offered 192.168.1.157 from 192.168.1.1 Sep 08 13:40:47 volumio volumio[1956]: info: Volumio called home Sep 08 13:40:47 volumio dhcpcd[1196]: wlan0: probing address 192.168.1.157/24 Sep 08 13:40:48 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:40:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. Sep 08 13:40:48 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:40:48 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:40:48 volumio volumio[2157]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:40:48 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:40:48 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:40:48 volumio volumio[1956]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 08 13:40:48 volumio volumio[1956]: 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 Sep 08 13:40:48 volumio volumio[1956]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 08 13:40:48 volumio volumio[1956]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Sep 08 13:40:48 volumio volumio[1956]: info: camilladsp service started and running in background, instance 1 Sep 08 13:40:48 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:48 volumio volumio[1956]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:40:48 volumio volumio[1956]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 08 13:40:48 volumio volumio[1956]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:40:48 volumio volumio[1956]: info: FusionDsp loaded Sep 08 13:40:48 volumio volumio[1956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:48 volumio sudo[2168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 08 13:40:48 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:48 volumio volumio[1956]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 08 13:40:48 volumio volumio[1956]: info: Adding Signal Path Element [object Object] Sep 08 13:40:48 volumio volumio[1956]: info: Adding fusiondspeq DSP Signal Path Element Sep 08 13:40:48 volumio volumio[1956]: info: FusionDsp - ---- installed callbackRead Sep 08 13:40:48 volumio volumio[1956]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:40:48 volumio volumio[1956]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 08 13:40:48 volumio volumio[1956]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 08 13:40:48 volumio volumio[1956]: at onErrorNT (internal/child_process.js:465:16) Sep 08 13:40:48 volumio volumio[1956]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 08 13:40:48 volumio volumio[1956]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 08 13:40:48 volumio volumio[1956]: at listOnTimeout (internal/timers.js:523:9) Sep 08 13:40:48 volumio volumio[1956]: at processTimers (internal/timers.js:497:7) { Sep 08 13:40:48 volumio volumio[1956]: errno: -2, Sep 08 13:40:48 volumio volumio[1956]: code: 'ENOENT', Sep 08 13:40:48 volumio volumio[1956]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:40:48 volumio volumio[1956]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:40:48 volumio volumio[1956]: spawnargs: [ Sep 08 13:40:48 volumio volumio[1956]: '-p', Sep 08 13:40:48 volumio volumio[1956]: 9876, Sep 08 13:40:48 volumio volumio[1956]: '-o', Sep 08 13:40:48 volumio volumio[1956]: '/tmp/camilladsp.log', Sep 08 13:40:48 volumio volumio[1956]: '-l', Sep 08 13:40:48 volumio volumio[1956]: 'warn', Sep 08 13:40:48 volumio volumio[1956]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 08 13:40:48 volumio volumio[1956]: ] Sep 08 13:40:48 volumio volumio[1956]: } Sep 08 13:40:48 volumio volumio[1956]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:40:48 volumio sudo[2179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 13:39 Sep 08 13:40:48 volumio sudo[2179]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:48 volumio sudo[2179]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:48 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 13:40:48 volumio sudo[1988]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:48 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:48 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 13:40:48 volumio systemd[1]: Started dynamicswap service. Sep 08 13:40:48 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 13:40:48 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 13:40:48 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Sep 08 13:40:48 volumio systemd[1]: Stopped Volumio Backend Module. Sep 08 13:40:49 volumio systemd[1]: Started Volumio Backend Module. Sep 08 13:40:49 volumio systemd[1]: Started dynamicswap service. Sep 08 13:40:49 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 13:40:49 volumio volumio[2190]: info: ------------------------------------------- Sep 08 13:40:49 volumio volumio[2190]: info: ----- Volumio3 ---- Sep 08 13:40:49 volumio volumio[2190]: info: ------------------------------------------- Sep 08 13:40:49 volumio volumio[2190]: info: ----- System startup ---- Sep 08 13:40:49 volumio volumio[2190]: info: ------------------------------------------- Sep 08 13:40:49 volumio volumio[2190]: info: MYVOLUMIO Environment detected Sep 08 13:40:49 volumio volumio[2190]: info: Plugin folders cleanup Sep 08 13:40:49 volumio volumio[2190]: info: Scanning into folder /volumio/app/plugins/ Sep 08 13:40:49 volumio volumio[2190]: info: Scanning category audio_interface Sep 08 13:40:49 volumio volumio[2190]: info: Scanning category miscellanea Sep 08 13:40:49 volumio volumio[2190]: info: Scanning category music_service Sep 08 13:40:49 volumio volumio[2190]: info: Scanning category plugins.json Sep 08 13:40:49 volumio volumio[2190]: info: Scanning category system_controller Sep 08 13:40:49 volumio volumio[2190]: info: Scanning category user_interface Sep 08 13:40:49 volumio volumio[2190]: info: Scanning into folder /data/plugins/ Sep 08 13:40:49 volumio volumio[2190]: info: Scanning category audio_interface Sep 08 13:40:49 volumio volumio[2190]: info: Plugin folders cleanup completed Sep 08 13:40:49 volumio volumio[2190]: info: ------------------------------------------- Sep 08 13:40:49 volumio volumio[2190]: info: ----- Core plugins startup ---- Sep 08 13:40:49 volumio volumio[2190]: info: ------------------------------------------- Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 13:40:49 volumio volumio[2190]: info: Adding plugin upnp to MyMusic Plugins Sep 08 13:40:49 volumio volumio[2190]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 13:40:49 volumio volumio[2190]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugins from folder /data/plugins/ Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "system"... Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "appearance"... Sep 08 13:40:49 volumio volumio-remote-updater[883]: [2024-09-08 13:40:49] [connect] Successful connection Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "network"... Sep 08 13:40:49 volumio volumio[2190]: info: Refreshing Cached IP Addresses Sep 08 13:40:49 volumio sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 13:40:49 volumio sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:49 volumio sudo[2217]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "services"... Sep 08 13:40:49 volumio sudo[2219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 13:40:49 volumio sudo[2219]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "alsa_controller"... Sep 08 13:40:49 volumio sudo[2222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 13:40:49 volumio sudo[2222]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:49 volumio sudo[2219]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:49 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "wizard"... Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "networkfs"... Sep 08 13:40:49 volumio volumio[2190]: info: Starting Udev Watcher for removable devices Sep 08 13:40:49 volumio volumio[2190]: info: Ignoring mount for partition: boot Sep 08 13:40:49 volumio volumio[2190]: info: Ignoring mount for partition: volumio Sep 08 13:40:49 volumio volumio[2190]: info: Ignoring mount for partition: volumio_data Sep 08 13:40:49 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "volumio_command_line_client"... Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "upnp"... Sep 08 13:40:49 volumio volumio[2190]: info: [1725795649863] Starting Upmpd Daemon Sep 08 13:40:49 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "my_music"... Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "mpd"... Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "upnp_browser"... Sep 08 13:40:49 volumio volumio[2190]: info: Loading plugin "alarm-clock"... Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "airplay_emulation"... Sep 08 13:40:50 volumio volumio[2190]: info: Starting Shairport Sync Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "last_100"... Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "webradio"... Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "i2s_dacs"... Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "volumiodiscovery"... Sep 08 13:40:50 volumio volumio[2190]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:50 volumio node[2190]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:50 volumio node[2190]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:50 volumio volumio[2190]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:50 volumio volumio[2190]: *** WARNING *** For more information see Sep 08 13:40:50 volumio volumio[2190]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:50 volumio volumio[2190]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:50 volumio volumio[2190]: *** WARNING *** For more information see Sep 08 13:40:50 volumio node[2190]: *** WARNING *** For more information see Sep 08 13:40:50 volumio node[2190]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:50 volumio node[2190]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:50 volumio node[2190]: *** WARNING *** For more information see Sep 08 13:40:50 volumio volumio[2190]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 13:40:50 volumio volumio[2190]: info: Discovery: Started advertising with name: Volumio Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "outputs"... Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "albumart"... Sep 08 13:40:50 volumio volumio[2190]: info: Plugin example_plugin is not enabled Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "inputs"... Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "updater_comm"... Sep 08 13:40:50 volumio volumio[2190]: info: Plugin mpdemulation is not enabled Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "rest_api"... Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "websocket"... Sep 08 13:40:50 volumio volumio[2190]: info: Starting Socket.io Server version 2.3.0 Sep 08 13:40:50 volumio volumio[2190]: info: Loading plugin "fusiondsp"... Sep 08 13:40:50 volumio volumio[2190]: Forking 3 albumart workers Sep 08 13:40:50 volumio volumio[2190]: info: Applying required configuration parameters for plugin fusiondsp Sep 08 13:40:50 volumio volumio[2190]: info: Loading i18n strings for locale es Sep 08 13:40:50 volumio volumio[2190]: Updating browse sources language Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::initPlayerControls Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: Express server listening on port 3000 Sep 08 13:40:50 volumio volumio[2190]: [Metrics] WebUI: 1s 14.29ms Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::resetVolumioState Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::getcurrentVolume Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:40:50 volumio volumio-remote-updater[883]: [2024-09-08 13:40:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725795649 101 Sep 08 13:40:50 volumio volumio[2190]: 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: 1 Sep 08 13:40:50 volumio volumio[2190]: verbose: New Socket.io Connection to 192.168.1.179 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Herring/90.1.1410.5 Engine version: 3 Transport: polling Total Clients: 2 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: Reloading queue from file Sep 08 13:40:50 volumio volumio[2190]: Starting albumart workers Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::setRepeat null single undefined Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::pushState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::setRandom null Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::pushState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:50 volumio volumio[2190]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 08 13:40:50 volumio volumio[2190]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Sep 08 13:40:50 volumio volumio[2190]: Starting albumart workers Sep 08 13:40:50 volumio volumio[2190]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 08 13:40:50 volumio volumio[2190]: info: Setting Device type: Raspberry PI Sep 08 13:40:50 volumio volumio[2190]: info: Received Get System Info Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:40:50 volumio volumio[2190]: info: Discovery: Getting this device information Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 13:40:50 volumio volumio[2190]: info: Received Get System Info Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:40:50 volumio volumio[2190]: info: Discovery: Getting this device information Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: Listing playlists Sep 08 13:40:50 volumio volumio[2190]: info: VolumeController:: Volume=46 Mute =false Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::pushState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::updateTrackBlock Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrackBlock Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:40:50 volumio volumio[2190]: Starting albumart workers Sep 08 13:40:50 volumio volumio[2190]: info: Completed loading Core Plugins Sep 08 13:40:50 volumio volumio[2190]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:50 volumio volumio[2190]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:40:50 volumio volumio[2190]: info: Reading ALSA contributions from plugins. Sep 08 13:40:50 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:40:50 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. Sep 08 13:40:50 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:40:50 volumio volumio[2190]: info: VolumeController:: Volume=46 Mute =false Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::pushState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:50 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:40:50 volumio volumio[2190]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:40:50 volumio volumio[2190]: info: Output device has changed, restarting MPD Sep 08 13:40:50 volumio volumio[2273]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:40:50 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:40:50 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:40:50 volumio sudo[2275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:50 volumio sudo[2275]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:50 volumio volumio[2190]: info: Output device has changed, restarting Shairport Sync Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:50 volumio sudo[2278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:50 volumio sudo[2278]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:50 volumio sudo[2275]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:50 volumio volumio[2190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:50 volumio volumio[2190]: info: ___________ START PLUGINS ___________ Sep 08 13:40:50 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:40:50 volumio volumio[2190]: info: ControllerMpd::onStart: Initializing MPD Sep 08 13:40:50 volumio volumio[2190]: info: Creating MPD Configuration file Sep 08 13:40:50 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:50 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:50 volumio sudo[2293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:50 volumio sudo[2293]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:50 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:50 volumio volumio[2190]: info: [1725795650615] CoreMusicLibrary::Adding element Media Servers Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:50 volumio sudo[2293]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:50 volumio sudo[2304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:50 volumio sudo[2304]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:50 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 08 13:40:50 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:50 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:50 volumio volumio[2190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:50 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:50 volumio volumio[2190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:50 volumio volumio[2190]: info: [1725795650661] CoreMusicLibrary::Adding element Last_100 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:50 volumio volumio[2190]: info: [1725795650662] CoreMusicLibrary::Adding element Webradio Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:50 volumio volumio[2190]: info: Initializing BBC Radios Sep 08 13:40:50 volumio sudo[2317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:50 volumio sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:50 volumio sudo[2317]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: Loading i18n strings for locale es Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: FusionDsp - mixtype--------------------- Hardware Sep 08 13:40:50 volumio volumio[2190]: info: Volumio Calling Home Sep 08 13:40:50 volumio volumio[2190]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:50 volumio volumio[2190]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:40:50 volumio volumio[2190]: info: Reading ALSA contributions from plugins. Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:50 volumio volumio[2190]: info: VolumeController::SetAlsaVolume0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreStateMachine::pushState Sep 08 13:40:50 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:50 volumio volumio[2190]: info: MPD Permissions set Sep 08 13:40:50 volumio volumio[2190]: info: MPD Permissions set Sep 08 13:40:50 volumio volumio[2190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:50 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:50 volumio mpd[2325]: Sep 08 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:40:50 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:40:50 volumio sudo[2278]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:50 volumio sudo[2304]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:51 volumio volumio[2190]: info: Completed starting Core Plugins Sep 08 13:40:51 volumio volumio[2190]: info: ------------------------------------------- Sep 08 13:40:51 volumio volumio[2190]: info: ----- MyVolumio plugins startup ---- Sep 08 13:40:51 volumio volumio[2190]: info: ------------------------------------------- Sep 08 13:40:51 volumio volumio[2190]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 08 13:40:51 volumio volumio[2190]: info: Volumio called home Sep 08 13:40:51 volumio volumio[2190]: info: Starting Shairport Sync Sep 08 13:40:51 volumio volumio[2190]: info: Starting Shairport Sync Sep 08 13:40:51 volumio sudo[2350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:51 volumio sudo[2350]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:51 volumio volumio[2190]: info: Starting Shairport Sync Sep 08 13:40:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:40:51 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:40:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:51 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 08 13:40:51 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 08 13:40:51 volumio sudo[2353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:51 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:51 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:51 volumio sudo[2355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:51 volumio sudo[2355]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:51 volumio sudo[2350]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:51 volumio volumio[2190]: info: MPD running with PID2325 Sep 08 13:40:51 volumio volumio[2190]: ,establishing connection Sep 08 13:40:51 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 08 13:40:51 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 08 13:40:51 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:51 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 08 13:40:51 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 08 13:40:51 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:51 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:51 volumio sudo[2355]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:51 volumio volumio[2190]: error: MPD error: The expression evaluated to a falsy value: Sep 08 13:40:51 volumio volumio[2190]: assert.ok(self.idling) Sep 08 13:40:51 volumio volumio[2190]: error: The expression evaluated to a falsy value: Sep 08 13:40:51 volumio volumio[2190]: assert.ok(self.idling) Sep 08 13:40:51 volumio volumio[2190]: error: MPD error: The expression evaluated to a falsy value: Sep 08 13:40:51 volumio volumio[2190]: assert.ok(self.idling) Sep 08 13:40:51 volumio volumio[2190]: error: The expression evaluated to a falsy value: Sep 08 13:40:51 volumio volumio[2190]: assert.ok(self.idling) Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:51 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:51 volumio volumio[2190]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 08 13:40:51 volumio volumio[2190]: Job for shairport-sync.service failed. Sep 08 13:40:51 volumio volumio[2190]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 08 13:40:51 volumio volumio[2190]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:40:51 volumio volumio[2190]: info: Output device has changed, restarting MPD Sep 08 13:40:51 volumio sudo[2359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:51 volumio sudo[2359]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:51 volumio volumio[2190]: info: Output device has changed, restarting Shairport Sync Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:51 volumio sudo[2359]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:51 volumio sudo[2362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:51 volumio sudo[2362]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:51 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:40:51 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:51 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:51 volumio volumio[2190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:51 volumio volumio[2190]: error: MPD error: Error: write EPIPE Sep 08 13:40:51 volumio volumio[2190]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Sep 08 13:40:51 volumio volumio[2190]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 08 13:40:51 volumio volumio[2190]: Job for shairport-sync.service failed. Sep 08 13:40:51 volumio volumio[2190]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 08 13:40:51 volumio volumio[2190]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 08 13:40:51 volumio volumio[2190]: Job for shairport-sync.service failed. Sep 08 13:40:51 volumio volumio[2190]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 08 13:40:51 volumio volumio[2190]: info: MPD Permissions set Sep 08 13:40:51 volumio volumio[2190]: info: Discovery: adding 1ea99d28-e957-42b8-80ae-afd56707d40e Sep 08 13:40:51 volumio volumio[2190]: info: Discovery: Found device Volumio Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:51 volumio volumio[2190]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:51 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio sudo[2367]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:51 volumio sudo[2367]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:51 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:51 volumio sudo[2367]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:51 volumio volumio[2190]: info: Starting Shairport Sync Sep 08 13:40:51 volumio sudo[2376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:51 volumio sudo[2376]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:51 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 08 13:40:51 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 08 13:40:51 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:51 volumio sudo[2376]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:51 volumio volumio[2190]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 08 13:40:51 volumio volumio[2190]: Job for shairport-sync.service failed. Sep 08 13:40:51 volumio volumio[2190]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 08 13:40:51 volumio mpd[2374]: Sep 08 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:40:51 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:40:51 volumio sudo[2362]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:51 volumio volumio[2190]: error: updateQueue error: null Sep 08 13:40:52 volumio volumio[2190]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 08 13:40:52 volumio volumio[2190]: 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 Sep 08 13:40:52 volumio volumio[2190]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 08 13:40:52 volumio volumio[2190]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Sep 08 13:40:52 volumio volumio[2190]: info: camilladsp service started and running in background, instance 1 Sep 08 13:40:52 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:52 volumio volumio[2190]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:40:52 volumio volumio[2190]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 08 13:40:52 volumio volumio[2190]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:40:52 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:40:52 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 4. Sep 08 13:40:52 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:40:52 volumio volumio[2190]: info: FusionDsp loaded Sep 08 13:40:52 volumio volumio[2190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:52 volumio sudo[2392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 08 13:40:52 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:52 volumio volumio[2190]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 08 13:40:52 volumio volumio[2190]: info: Adding Signal Path Element [object Object] Sep 08 13:40:52 volumio volumio[2190]: info: Adding fusiondspeq DSP Signal Path Element Sep 08 13:40:52 volumio volumio[2190]: info: FusionDsp - ---- installed callbackRead Sep 08 13:40:52 volumio volumio[2190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:40:52 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:40:52 volumio volumio[2190]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 08 13:40:52 volumio volumio[2190]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 08 13:40:52 volumio volumio[2190]: at onErrorNT (internal/child_process.js:465:16) Sep 08 13:40:52 volumio volumio[2190]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 08 13:40:52 volumio volumio[2190]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 08 13:40:52 volumio volumio[2190]: at listOnTimeout (internal/timers.js:523:9) Sep 08 13:40:52 volumio volumio[2190]: at processTimers (internal/timers.js:497:7) { Sep 08 13:40:52 volumio volumio[2190]: errno: -2, Sep 08 13:40:52 volumio volumio[2190]: code: 'ENOENT', Sep 08 13:40:52 volumio volumio[2190]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:40:52 volumio volumio[2190]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:40:52 volumio volumio[2190]: spawnargs: [ Sep 08 13:40:52 volumio volumio[2190]: '-p', Sep 08 13:40:52 volumio volumio[2190]: 9876, Sep 08 13:40:52 volumio volumio[2190]: '-o', Sep 08 13:40:52 volumio volumio[2190]: '/tmp/camilladsp.log', Sep 08 13:40:52 volumio volumio[2190]: '-l', Sep 08 13:40:52 volumio volumio[2190]: 'warn', Sep 08 13:40:52 volumio volumio[2190]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 08 13:40:52 volumio volumio[2190]: ] Sep 08 13:40:52 volumio volumio[2190]: } Sep 08 13:40:52 volumio volumio[2190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:40:52 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:52 volumio volumio[2390]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:40:52 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:40:52 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:40:52 volumio sudo[2403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 13:39 Sep 08 13:40:52 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:52 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:52 volumio volumio-remote-updater[883]: [2024-09-08 13:40:52] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 08 13:40:52 volumio volumio-remote-updater[883]: [2024-09-08 13:40:52] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 08 13:40:52 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 13:40:52 volumio sudo[2222]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:52 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 13:40:52 volumio systemd[1]: Started dynamicswap service. Sep 08 13:40:53 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 13:40:53 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 13:40:53 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Sep 08 13:40:53 volumio systemd[1]: Stopped Volumio Backend Module. Sep 08 13:40:53 volumio systemd[1]: Started Volumio Backend Module. Sep 08 13:40:53 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Sep 08 13:40:53 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Sep 08 13:40:53 volumio systemd[1]: Failed to start dynamicswap service. Sep 08 13:40:53 volumio dhcpcd[1196]: wlan0: leased 192.168.1.157 for 43200 seconds Sep 08 13:40:53 volumio avahi-daemon[873]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.157. Sep 08 13:40:53 volumio dhcpcd[1196]: wlan0: adding route to 192.168.1.0/24 Sep 08 13:40:53 volumio avahi-daemon[873]: New relevant interface wlan0.IPv4 for mDNS. Sep 08 13:40:53 volumio dhcpcd[1196]: wlan0: adding default route via 192.168.1.1 Sep 08 13:40:53 volumio avahi-daemon[873]: Registering new address record for 192.168.1.157 on wlan0.IPv4. Sep 08 13:40:53 volumio volumio[2414]: info: ------------------------------------------- Sep 08 13:40:53 volumio volumio[2414]: info: ----- Volumio3 ---- Sep 08 13:40:53 volumio volumio[2414]: info: ------------------------------------------- Sep 08 13:40:53 volumio volumio[2414]: info: ----- System startup ---- Sep 08 13:40:53 volumio volumio[2414]: info: ------------------------------------------- Sep 08 13:40:53 volumio volumio[2414]: info: MYVOLUMIO Environment detected Sep 08 13:40:53 volumio volumio[2414]: info: Plugin folders cleanup Sep 08 13:40:53 volumio volumio[2414]: info: Scanning into folder /volumio/app/plugins/ Sep 08 13:40:53 volumio volumio[2414]: info: Scanning category audio_interface Sep 08 13:40:53 volumio volumio[2414]: info: Scanning category miscellanea Sep 08 13:40:53 volumio volumio[2414]: info: Scanning category music_service Sep 08 13:40:53 volumio volumio[2414]: info: Scanning category plugins.json Sep 08 13:40:53 volumio volumio[2414]: info: Scanning category system_controller Sep 08 13:40:53 volumio volumio[2414]: info: Scanning category user_interface Sep 08 13:40:53 volumio volumio[2414]: info: Scanning into folder /data/plugins/ Sep 08 13:40:53 volumio volumio[2414]: info: Scanning category audio_interface Sep 08 13:40:53 volumio volumio[2414]: info: Plugin folders cleanup completed Sep 08 13:40:53 volumio volumio[2414]: info: ------------------------------------------- Sep 08 13:40:53 volumio volumio[2414]: info: ----- Core plugins startup ---- Sep 08 13:40:53 volumio volumio[2414]: info: ------------------------------------------- Sep 08 13:40:53 volumio volumio[2414]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 13:40:53 volumio volumio[2414]: info: Adding plugin upnp to MyMusic Plugins Sep 08 13:40:53 volumio volumio[2414]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 13:40:53 volumio volumio[2414]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 13:40:53 volumio volumio[2414]: info: Loading plugins from folder /data/plugins/ Sep 08 13:40:53 volumio volumio[2414]: info: Loading plugin "system"... Sep 08 13:40:53 volumio volumio[2414]: info: Loading plugin "appearance"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "network"... Sep 08 13:40:54 volumio volumio[2414]: info: Refreshing Cached IP Addresses Sep 08 13:40:54 volumio sudo[2456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 13:40:54 volumio sudo[2456]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:54 volumio sudo[2456]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:54 volumio sudo[2458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 13:40:54 volumio sudo[2458]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "services"... Sep 08 13:40:54 volumio sudo[2458]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "alsa_controller"... Sep 08 13:40:54 volumio sudo[2462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 13:40:54 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "wizard"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "networkfs"... Sep 08 13:40:54 volumio volumio[2414]: info: Starting Udev Watcher for removable devices Sep 08 13:40:54 volumio volumio[2414]: info: Ignoring mount for partition: boot Sep 08 13:40:54 volumio volumio[2414]: info: Ignoring mount for partition: volumio Sep 08 13:40:54 volumio volumio[2414]: info: Ignoring mount for partition: volumio_data Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "volumio_command_line_client"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "upnp"... Sep 08 13:40:54 volumio volumio[2414]: info: [1725795654084] Starting Upmpd Daemon Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "my_music"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "mpd"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "upnp_browser"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "alarm-clock"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "airplay_emulation"... Sep 08 13:40:54 volumio volumio[2414]: info: Starting Shairport Sync Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "last_100"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "webradio"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "i2s_dacs"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "volumiodiscovery"... Sep 08 13:40:54 volumio volumio[2414]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:54 volumio node[2414]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:54 volumio volumio[2414]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:54 volumio node[2414]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:54 volumio volumio[2414]: *** WARNING *** For more information see Sep 08 13:40:54 volumio node[2414]: *** WARNING *** For more information see Sep 08 13:40:54 volumio volumio[2414]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:54 volumio node[2414]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:54 volumio volumio[2414]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:54 volumio node[2414]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:54 volumio volumio[2414]: *** WARNING *** For more information see Sep 08 13:40:54 volumio node[2414]: *** WARNING *** For more information see Sep 08 13:40:54 volumio volumio[2414]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 13:40:54 volumio volumio[2414]: info: Discovery: Started advertising with name: Volumio Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "outputs"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "albumart"... Sep 08 13:40:54 volumio volumio[2414]: info: Plugin example_plugin is not enabled Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "inputs"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "updater_comm"... Sep 08 13:40:54 volumio volumio[2414]: info: Plugin mpdemulation is not enabled Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "rest_api"... Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "websocket"... Sep 08 13:40:54 volumio volumio[2414]: info: Starting Socket.io Server version 2.3.0 Sep 08 13:40:54 volumio volumio[2414]: info: Loading plugin "fusiondsp"... Sep 08 13:40:54 volumio volumio[2414]: Forking 3 albumart workers Sep 08 13:40:54 volumio volumio[2414]: info: Applying required configuration parameters for plugin fusiondsp Sep 08 13:40:54 volumio volumio[2414]: info: Loading i18n strings for locale es Sep 08 13:40:54 volumio volumio[2414]: Updating browse sources language Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::initPlayerControls Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: Express server listening on port 3000 Sep 08 13:40:54 volumio volumio[2414]: [Metrics] WebUI: 0s 991.08ms Sep 08 13:40:54 volumio volumio[2414]: info: CoreStateMachine::resetVolumioState Sep 08 13:40:54 volumio volumio[2414]: info: CoreStateMachine::getcurrentVolume Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:40:54 volumio volumio[2414]: verbose: New Socket.io Connection to 192.168.1.179 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Herring/90.1.1410.5 Engine version: 3 Transport: polling Total Clients: 1 Sep 08 13:40:54 volumio volumio[2414]: Starting albumart workers Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: Starting albumart workers Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: Reloading queue from file Sep 08 13:40:54 volumio volumio[2414]: info: CoreStateMachine::setRepeat null single undefined Sep 08 13:40:54 volumio volumio[2414]: info: CoreStateMachine::pushState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:54 volumio volumio[2414]: info: CoreStateMachine::setRandom null Sep 08 13:40:54 volumio volumio[2414]: info: CoreStateMachine::pushState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:54 volumio volumio[2414]: info: Setting Device type: Raspberry PI Sep 08 13:40:54 volumio volumio[2414]: info: Received Get System Info Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:40:54 volumio volumio[2414]: info: Discovery: Getting this device information Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 13:40:54 volumio volumio[2414]: info: Received Get System Info Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:40:54 volumio volumio[2414]: info: Discovery: Getting this device information Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: Listing playlists Sep 08 13:40:54 volumio volumio[2414]: Starting albumart workers Sep 08 13:40:54 volumio volumio[2414]: info: VolumeController:: Volume=0 Mute =false Sep 08 13:40:54 volumio volumio[2414]: info: CoreStateMachine::pushState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:54 volumio volumio[2414]: info: CoreStateMachine::updateTrackBlock Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrackBlock Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:40:54 volumio volumio[2414]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 08 13:40:54 volumio volumio[2414]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Sep 08 13:40:54 volumio volumio[2414]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 08 13:40:54 volumio volumio[2414]: info: Completed loading Core Plugins Sep 08 13:40:54 volumio volumio[2414]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:54 volumio volumio[2414]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:40:54 volumio volumio[2414]: info: Reading ALSA contributions from plugins. Sep 08 13:40:54 volumio volumio[2414]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:40:54 volumio volumio[2414]: info: Output device has changed, restarting MPD Sep 08 13:40:54 volumio volumio[2414]: info: Output device has changed, restarting Shairport Sync Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:54 volumio sudo[2514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:54 volumio sudo[2514]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:54 volumio sudo[2520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:54 volumio sudo[2520]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:54 volumio sudo[2514]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:54 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:40:54 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 5. Sep 08 13:40:54 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:40:54 volumio volumio[2414]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:54 volumio volumio[2414]: info: ___________ START PLUGINS ___________ Sep 08 13:40:54 volumio volumio[2414]: info: ControllerMpd::onStart: Initializing MPD Sep 08 13:40:54 volumio volumio[2414]: info: Creating MPD Configuration file Sep 08 13:40:54 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:40:54 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:40:54 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:54 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:54 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:54 volumio volumio[2534]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:40:54 volumio sudo[2541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:54 volumio sudo[2541]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:54 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:40:54 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:54 volumio volumio[2414]: info: [1725795654834] CoreMusicLibrary::Adding element Media Servers Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:54 volumio sudo[2544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:54 volumio sudo[2544]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:54 volumio sudo[2539]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:54 volumio sudo[2539]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:54 volumio sudo[2541]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:54 volumio sudo[2539]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:54 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 08 13:40:54 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:54 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:54 volumio ntpd[976]: Listen normally on 5 wlan0 192.168.1.157:123 Sep 08 13:40:54 volumio ntpd[976]: new interface(s) found: waking up resolver Sep 08 13:40:54 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:54 volumio volumio[2414]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:54 volumio sudo[2549]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:54 volumio sudo[2549]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:54 volumio sudo[2549]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:54 volumio volumio[2414]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:54 volumio volumio[2414]: info: [1725795654887] CoreMusicLibrary::Adding element Last_100 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:54 volumio volumio[2414]: info: [1725795654888] CoreMusicLibrary::Adding element Webradio Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:54 volumio volumio[2414]: info: Initializing BBC Radios Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: Loading i18n strings for locale es Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: FusionDsp - mixtype--------------------- Hardware Sep 08 13:40:54 volumio volumio[2414]: info: Volumio Calling Home Sep 08 13:40:54 volumio volumio[2414]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:54 volumio volumio[2414]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:40:54 volumio volumio[2414]: info: Reading ALSA contributions from plugins. Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:54 volumio volumio[2414]: info: MPD Permissions set Sep 08 13:40:54 volumio volumio[2414]: info: MPD Permissions set Sep 08 13:40:54 volumio volumio[2414]: info: VolumeController:: Volume=0 Mute =false Sep 08 13:40:54 volumio volumio[2414]: info: CoreStateMachine::pushState Sep 08 13:40:54 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:54 volumio volumio[2414]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:54 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio mpd[2564]: Sep 08 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:40:55 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:40:55 volumio sudo[2520]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:55 volumio sudo[2544]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:55 volumio volumio[2414]: info: Volumio called home Sep 08 13:40:55 volumio volumio[2414]: info: Completed starting Core Plugins Sep 08 13:40:55 volumio volumio[2414]: info: ------------------------------------------- Sep 08 13:40:55 volumio volumio[2414]: info: ----- MyVolumio plugins startup ---- Sep 08 13:40:55 volumio volumio[2414]: info: ------------------------------------------- Sep 08 13:40:55 volumio volumio[2414]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 08 13:40:55 volumio volumio[2414]: info: Starting Shairport Sync Sep 08 13:40:55 volumio volumio[2414]: info: Starting Shairport Sync Sep 08 13:40:55 volumio sudo[2591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:55 volumio sudo[2591]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:55 volumio volumio[2414]: info: Starting Shairport Sync Sep 08 13:40:55 volumio sudo[2594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:55 volumio sudo[2594]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:55 volumio sudo[2598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:55 volumio sudo[2598]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:55 volumio volumio[2414]: error: MPD error: The expression evaluated to a falsy value: Sep 08 13:40:55 volumio volumio[2414]: assert.ok(self.idling) Sep 08 13:40:55 volumio volumio[2414]: error: The expression evaluated to a falsy value: Sep 08 13:40:55 volumio volumio[2414]: assert.ok(self.idling) Sep 08 13:40:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:55 volumio sudo[2591]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:40:55 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 08 13:40:55 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:40:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:55 volumio sudo[2594]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:55 volumio sudo[2598]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:55 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:55 volumio volumio[2414]: info: MPD running with PID2564 Sep 08 13:40:55 volumio volumio[2414]: ,establishing connection Sep 08 13:40:55 volumio volumio[2414]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:40:55 volumio volumio[2414]: info: Output device has changed, restarting MPD Sep 08 13:40:55 volumio sudo[2604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:55 volumio sudo[2604]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:55 volumio volumio[2414]: info: Output device has changed, restarting Shairport Sync Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio sudo[2604]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:55 volumio sudo[2607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:55 volumio sudo[2607]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:55 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:40:55 volumio volumio[2414]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:55 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:55 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:55 volumio volumio[2414]: info: Discovery: adding 1ea99d28-e957-42b8-80ae-afd56707d40e Sep 08 13:40:55 volumio volumio[2414]: info: Discovery: Found device Volumio Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:55 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:55 volumio volumio[2414]: info: Discovery: this is already registered, 1ea99d28-e957-42b8-80ae-afd56707d40e Sep 08 13:40:55 volumio volumio[2414]: info: Discovery: Found device Volumio Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:55 volumio volumio[2414]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:55 volumio volumio[2414]: error: updateQueue error: null Sep 08 13:40:55 volumio volumio[2414]: info: Shairport-Sync Started Sep 08 13:40:55 volumio volumio[2414]: Error adding Membership: Error: addMembership EINVAL Sep 08 13:40:55 volumio volumio[2414]: info: Shairport-Sync Started Sep 08 13:40:55 volumio volumio[2414]: info: Shairport-Sync Started Sep 08 13:40:55 volumio volumio[2414]: error: MPD error: Error: write EPIPE Sep 08 13:40:55 volumio volumio[2414]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Sep 08 13:40:55 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:55 volumio volumio[2414]: info: MPD Permissions set Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:55 volumio sudo[2612]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:55 volumio sudo[2612]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:55 volumio volumio[2414]: info: Starting Shairport Sync Sep 08 13:40:55 volumio sudo[2612]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:55 volumio sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:40:55 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:40:55 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:40:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:40:55 volumio sudo[2621]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:55 volumio volumio[2414]: info: Shairport-Sync Started Sep 08 13:40:55 volumio mpd[2619]: Sep 08 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:40:55 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:40:55 volumio sudo[2607]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:55 volumio volumio[2414]: error: updateQueue error: null Sep 08 13:40:55 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 13:40:56 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 08 13:40:56 volumio volumio[2414]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 08 13:40:56 volumio volumio[2414]: 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 Sep 08 13:40:56 volumio volumio[2414]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 08 13:40:56 volumio volumio[2414]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Sep 08 13:40:56 volumio volumio[2414]: info: camilladsp service started and running in background, instance 1 Sep 08 13:40:56 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:56 volumio volumio[2414]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:40:56 volumio volumio[2414]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 08 13:40:56 volumio volumio[2414]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:40:56 volumio volumio[2414]: info: FusionDsp loaded Sep 08 13:40:56 volumio volumio[2414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:56 volumio sudo[2639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 08 13:40:56 volumio sudo[2639]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:56 volumio volumio[2414]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 08 13:40:56 volumio volumio[2414]: info: Adding Signal Path Element [object Object] Sep 08 13:40:56 volumio volumio[2414]: info: Adding fusiondspeq DSP Signal Path Element Sep 08 13:40:56 volumio volumio[2414]: info: FusionDsp - ---- installed callbackRead Sep 08 13:40:56 volumio volumio[2414]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:40:56 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:40:56 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 6. Sep 08 13:40:56 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:40:56 volumio volumio[2414]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 08 13:40:56 volumio volumio[2414]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 08 13:40:56 volumio volumio[2414]: at onErrorNT (internal/child_process.js:465:16) Sep 08 13:40:56 volumio volumio[2414]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 08 13:40:56 volumio volumio[2414]: errno: -2, Sep 08 13:40:56 volumio volumio[2414]: code: 'ENOENT', Sep 08 13:40:56 volumio volumio[2414]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:40:56 volumio volumio[2414]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:40:56 volumio volumio[2414]: spawnargs: [ Sep 08 13:40:56 volumio volumio[2414]: '-p', Sep 08 13:40:56 volumio volumio[2414]: 9876, Sep 08 13:40:56 volumio volumio[2414]: '-o', Sep 08 13:40:56 volumio volumio[2414]: '/tmp/camilladsp.log', Sep 08 13:40:56 volumio volumio[2414]: '-l', Sep 08 13:40:56 volumio volumio[2414]: 'warn', Sep 08 13:40:56 volumio volumio[2414]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 08 13:40:56 volumio volumio[2414]: ] Sep 08 13:40:56 volumio volumio[2414]: } Sep 08 13:40:56 volumio volumio[2414]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:40:57 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:40:57 volumio sudo[2639]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:57 volumio volumio[2641]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:40:57 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:40:57 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:40:57 volumio sudo[2651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 13:39 Sep 08 13:40:57 volumio sudo[2651]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:57 volumio sudo[2651]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 13:40:57 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 13:40:57 volumio systemd[1]: Started dynamicswap service. Sep 08 13:40:57 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 13:40:57 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 13:40:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Sep 08 13:40:57 volumio systemd[1]: Stopped Volumio Backend Module. Sep 08 13:40:57 volumio systemd[1]: Started Volumio Backend Module. Sep 08 13:40:57 volumio systemd[1]: Started dynamicswap service. Sep 08 13:40:57 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 13:40:57 volumio volumio[2661]: info: ------------------------------------------- Sep 08 13:40:57 volumio volumio[2661]: info: ----- Volumio3 ---- Sep 08 13:40:57 volumio volumio[2661]: info: ------------------------------------------- Sep 08 13:40:57 volumio volumio[2661]: info: ----- System startup ---- Sep 08 13:40:57 volumio volumio[2661]: info: ------------------------------------------- Sep 08 13:40:57 volumio volumio-remote-updater[883]: [2024-09-08 13:40:57] [connect] Successful connection Sep 08 13:40:58 volumio volumio[2661]: info: MYVOLUMIO Environment detected Sep 08 13:40:58 volumio volumio[2661]: info: Plugin folders cleanup Sep 08 13:40:58 volumio volumio[2661]: info: Scanning into folder /volumio/app/plugins/ Sep 08 13:40:58 volumio volumio[2661]: info: Scanning category audio_interface Sep 08 13:40:58 volumio volumio[2661]: info: Scanning category miscellanea Sep 08 13:40:58 volumio volumio[2661]: info: Scanning category music_service Sep 08 13:40:58 volumio volumio[2661]: info: Scanning category plugins.json Sep 08 13:40:58 volumio volumio[2661]: info: Scanning category system_controller Sep 08 13:40:58 volumio volumio[2661]: info: Scanning category user_interface Sep 08 13:40:58 volumio volumio[2661]: info: Scanning into folder /data/plugins/ Sep 08 13:40:58 volumio volumio[2661]: info: Scanning category audio_interface Sep 08 13:40:58 volumio volumio[2661]: info: Plugin folders cleanup completed Sep 08 13:40:58 volumio volumio[2661]: info: ------------------------------------------- Sep 08 13:40:58 volumio volumio[2661]: info: ----- Core plugins startup ---- Sep 08 13:40:58 volumio volumio[2661]: info: ------------------------------------------- Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 13:40:58 volumio volumio[2661]: info: Adding plugin upnp to MyMusic Plugins Sep 08 13:40:58 volumio volumio[2661]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 13:40:58 volumio volumio[2661]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugins from folder /data/plugins/ Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "system"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "appearance"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "network"... Sep 08 13:40:58 volumio volumio[2661]: info: Refreshing Cached IP Addresses Sep 08 13:40:58 volumio sudo[2688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 13:40:58 volumio sudo[2688]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:58 volumio sudo[2688]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:58 volumio sudo[2690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 13:40:58 volumio sudo[2690]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:58 volumio sudo[2690]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "services"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "alsa_controller"... Sep 08 13:40:58 volumio sudo[2694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 13:40:58 volumio sudo[2694]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "wizard"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "networkfs"... Sep 08 13:40:58 volumio volumio[2661]: info: Starting Udev Watcher for removable devices Sep 08 13:40:58 volumio volumio[2661]: info: Ignoring mount for partition: boot Sep 08 13:40:58 volumio volumio[2661]: info: Ignoring mount for partition: volumio Sep 08 13:40:58 volumio volumio[2661]: info: Ignoring mount for partition: volumio_data Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "volumio_command_line_client"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "upnp"... Sep 08 13:40:58 volumio volumio[2661]: info: [1725795658337] Starting Upmpd Daemon Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "my_music"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "mpd"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "upnp_browser"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "alarm-clock"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "airplay_emulation"... Sep 08 13:40:58 volumio volumio[2661]: info: Starting Shairport Sync Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "last_100"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "webradio"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "i2s_dacs"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "volumiodiscovery"... Sep 08 13:40:58 volumio volumio[2661]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:58 volumio node[2661]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:58 volumio volumio[2661]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:58 volumio node[2661]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:58 volumio volumio[2661]: *** WARNING *** For more information see Sep 08 13:40:58 volumio node[2661]: *** WARNING *** For more information see Sep 08 13:40:58 volumio volumio[2661]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:58 volumio node[2661]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:40:58 volumio volumio[2661]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:58 volumio node[2661]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:40:58 volumio volumio[2661]: *** WARNING *** For more information see Sep 08 13:40:58 volumio node[2661]: *** WARNING *** For more information see Sep 08 13:40:58 volumio volumio[2661]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 13:40:58 volumio volumio[2661]: info: Discovery: Started advertising with name: Volumio Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "outputs"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "albumart"... Sep 08 13:40:58 volumio volumio[2661]: info: Plugin example_plugin is not enabled Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "inputs"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "updater_comm"... Sep 08 13:40:58 volumio volumio[2661]: info: Plugin mpdemulation is not enabled Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "rest_api"... Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "websocket"... Sep 08 13:40:58 volumio volumio[2661]: info: Starting Socket.io Server version 2.3.0 Sep 08 13:40:58 volumio volumio[2661]: info: Loading plugin "fusiondsp"... Sep 08 13:40:58 volumio volumio[2661]: Forking 3 albumart workers Sep 08 13:40:58 volumio volumio[2661]: info: Applying required configuration parameters for plugin fusiondsp Sep 08 13:40:58 volumio volumio[2661]: info: Loading i18n strings for locale es Sep 08 13:40:58 volumio volumio[2661]: Updating browse sources language Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::initPlayerControls Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: Express server listening on port 3000 Sep 08 13:40:58 volumio volumio[2661]: [Metrics] WebUI: 0s 999.54ms Sep 08 13:40:58 volumio volumio[2661]: info: CoreStateMachine::resetVolumioState Sep 08 13:40:58 volumio volumio[2661]: info: CoreStateMachine::getcurrentVolume Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:40:58 volumio volumio[2661]: Starting albumart workers Sep 08 13:40:58 volumio volumio[2661]: Starting albumart workers Sep 08 13:40:58 volumio volumio-remote-updater[883]: [2024-09-08 13:40:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725795657 101 Sep 08 13:40:58 volumio volumio[2661]: 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: 1 Sep 08 13:40:58 volumio volumio[2661]: verbose: New Socket.io Connection to 192.168.1.179 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Herring/90.1.1410.5 Engine version: 3 Transport: polling Total Clients: 2 Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:58 volumio volumio[2661]: info: Reloading queue from file Sep 08 13:40:58 volumio volumio[2661]: info: CoreStateMachine::setRepeat null single undefined Sep 08 13:40:58 volumio volumio[2661]: info: CoreStateMachine::pushState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:58 volumio volumio[2661]: info: CoreStateMachine::setRandom null Sep 08 13:40:58 volumio volumio[2661]: info: CoreStateMachine::pushState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:58 volumio volumio[2661]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 08 13:40:58 volumio volumio[2661]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Sep 08 13:40:58 volumio volumio[2661]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 08 13:40:58 volumio volumio[2661]: Starting albumart workers Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: VolumeController:: Volume=0 Mute =false Sep 08 13:40:58 volumio volumio[2661]: info: CoreStateMachine::pushState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:58 volumio volumio[2661]: info: CoreStateMachine::updateTrackBlock Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrackBlock Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:40:58 volumio volumio[2661]: info: Setting Device type: Raspberry PI Sep 08 13:40:58 volumio volumio[2661]: info: Received Get System Info Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:40:58 volumio volumio[2661]: info: Discovery: Getting this device information Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 13:40:58 volumio volumio[2661]: info: Received Get System Info Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:40:58 volumio volumio[2661]: info: Discovery: Getting this device information Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:40:58 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:40:58 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:58 volumio volumio[2661]: info: Listing playlists Sep 08 13:40:58 volumio volumio[2661]: info: Completed loading Core Plugins Sep 08 13:40:58 volumio volumio[2661]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:59 volumio volumio[2661]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:40:59 volumio volumio[2661]: info: Reading ALSA contributions from plugins. Sep 08 13:40:59 volumio volumio[2661]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:40:59 volumio volumio[2661]: info: Output device has changed, restarting MPD Sep 08 13:40:59 volumio sudo[2762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:59 volumio sudo[2762]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:59 volumio volumio[2661]: info: Output device has changed, restarting Shairport Sync Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio sudo[2762]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:59 volumio sudo[2764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:59 volumio sudo[2764]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:59 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:40:59 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 7. Sep 08 13:40:59 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:40:59 volumio volumio[2661]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:59 volumio volumio[2661]: info: ___________ START PLUGINS ___________ Sep 08 13:40:59 volumio volumio[2661]: info: ControllerMpd::onStart: Initializing MPD Sep 08 13:40:59 volumio volumio[2661]: info: Creating MPD Configuration file Sep 08 13:40:59 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:40:59 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:59 volumio volumio[2661]: info: [1725795659079] CoreMusicLibrary::Adding element Media Servers Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:59 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:59 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:59 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:59 volumio sudo[2772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:40:59 volumio sudo[2772]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:59 volumio sudo[2772]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:59 volumio sudo[2775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:40:59 volumio sudo[2775]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:59 volumio volumio[2770]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:40:59 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:40:59 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:40:59 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 08 13:40:59 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:40:59 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:40:59 volumio volumio[2661]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:59 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:40:59 volumio volumio[2661]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:59 volumio volumio[2661]: info: [1725795659145] CoreMusicLibrary::Adding element Last_100 Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:40:59 volumio volumio[2661]: info: [1725795659146] CoreMusicLibrary::Adding element Webradio Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:40:59 volumio sudo[2789]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:40:59 volumio sudo[2789]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:59 volumio volumio[2661]: info: Initializing BBC Radios Sep 08 13:40:59 volumio sudo[2789]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: Loading i18n strings for locale es Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: FusionDsp - mixtype--------------------- Hardware Sep 08 13:40:59 volumio volumio[2661]: info: Volumio Calling Home Sep 08 13:40:59 volumio volumio[2661]: info: Preparing to generate the ALSA configuration file Sep 08 13:40:59 volumio volumio[2661]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:40:59 volumio volumio[2661]: info: Reading ALSA contributions from plugins. Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:40:59 volumio volumio[2661]: info: MPD Permissions set Sep 08 13:40:59 volumio volumio[2661]: info: MPD Permissions set Sep 08 13:40:59 volumio volumio[2661]: info: VolumeController:: Volume=0 Mute =false Sep 08 13:40:59 volumio volumio[2661]: info: CoreStateMachine::pushState Sep 08 13:40:59 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::volumioPushState Sep 08 13:40:59 volumio volumio[2661]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:40:59 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:40:59 volumio mpd[2794]: Sep 08 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:40:59 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:40:59 volumio sudo[2775]: pam_unix(sudo:session): session closed for user root Sep 08 13:40:59 volumio sudo[2764]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:00 volumio volumio[2661]: info: Volumio called home Sep 08 13:41:00 volumio volumio[2661]: info: Completed starting Core Plugins Sep 08 13:41:00 volumio volumio[2661]: info: ------------------------------------------- Sep 08 13:41:00 volumio volumio[2661]: info: ----- MyVolumio plugins startup ---- Sep 08 13:41:00 volumio volumio[2661]: info: ------------------------------------------- Sep 08 13:41:00 volumio volumio[2661]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 08 13:41:00 volumio volumio[2661]: info: Starting Shairport Sync Sep 08 13:41:00 volumio volumio[2661]: info: Starting Shairport Sync Sep 08 13:41:00 volumio sudo[2821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:41:00 volumio sudo[2821]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:00 volumio volumio[2661]: info: Starting Shairport Sync Sep 08 13:41:00 volumio sudo[2824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:41:00 volumio sudo[2824]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:41:00 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:41:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:00 volumio sudo[2828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:41:00 volumio sudo[2828]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:00 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:00 volumio sudo[2821]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:41:00 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 08 13:41:00 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:41:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:00 volumio sudo[2824]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:00 volumio sudo[2828]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:00 volumio volumio[2661]: error: MPD error: The expression evaluated to a falsy value: Sep 08 13:41:00 volumio volumio[2661]: assert.ok(self.idling) Sep 08 13:41:00 volumio volumio[2661]: error: The expression evaluated to a falsy value: Sep 08 13:41:00 volumio volumio[2661]: assert.ok(self.idling) Sep 08 13:41:00 volumio volumio[2661]: info: MPD running with PID2794 Sep 08 13:41:00 volumio volumio[2661]: ,establishing connection Sep 08 13:41:00 volumio volumio[2661]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:41:00 volumio volumio[2661]: info: Output device has changed, restarting MPD Sep 08 13:41:00 volumio sudo[2834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:41:00 volumio sudo[2834]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:00 volumio volumio[2661]: info: Output device has changed, restarting Shairport Sync Sep 08 13:41:00 volumio sudo[2834]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:00 volumio sudo[2837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:41:00 volumio sudo[2837]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:00 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:41:00 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:41:00 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:41:00 volumio volumio[2661]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:41:00 volumio volumio[2661]: info: Discovery: adding 1ea99d28-e957-42b8-80ae-afd56707d40e Sep 08 13:41:00 volumio volumio[2661]: info: Discovery: Found device Volumio Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:00 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:00 volumio volumio[2661]: info: Discovery: this is already registered, 1ea99d28-e957-42b8-80ae-afd56707d40e Sep 08 13:41:00 volumio volumio[2661]: info: Discovery: Found device Volumio Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:00 volumio volumio[2661]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:00 volumio volumio[2661]: error: updateQueue error: null Sep 08 13:41:00 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:41:00 volumio sudo[2842]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:41:00 volumio sudo[2842]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:00 volumio volumio[2661]: info: Shairport-Sync Started Sep 08 13:41:00 volumio volumio[2661]: Error adding Membership: Error: addMembership EINVAL Sep 08 13:41:00 volumio volumio[2661]: info: Shairport-Sync Started Sep 08 13:41:00 volumio volumio[2661]: info: Shairport-Sync Started Sep 08 13:41:00 volumio sudo[2842]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:00 volumio volumio[2661]: error: MPD error: Error: write EPIPE Sep 08 13:41:00 volumio volumio[2661]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Sep 08 13:41:00 volumio volumio[2661]: info: MPD Permissions set Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:00 volumio volumio[2661]: info: Starting Shairport Sync Sep 08 13:41:00 volumio sudo[2850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:41:00 volumio sudo[2850]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 13:41:00 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 08 13:41:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:00 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 08 13:41:00 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 08 13:41:00 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:00 volumio sudo[2850]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:00 volumio volumio[2661]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 08 13:41:00 volumio volumio[2661]: Job for shairport-sync.service failed. Sep 08 13:41:00 volumio volumio[2661]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 08 13:41:00 volumio mpd[2844]: Sep 08 13:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:41:00 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:41:00 volumio sudo[2837]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:00 volumio volumio[2661]: error: updateQueue error: null Sep 08 13:41:00 volumio volumio[2661]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:41:00 volumio volumio[2661]: TypeError: Cannot read property 'off' of undefined Sep 08 13:41:00 volumio volumio[2661]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Sep 08 13:41:00 volumio volumio[2661]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 08 13:41:00 volumio volumio[2661]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 08 13:41:00 volumio volumio[2661]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Sep 08 13:41:00 volumio volumio[2661]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1387:47) Sep 08 13:41:00 volumio volumio[2661]: at Socket.emit (events.js:315:20) Sep 08 13:41:00 volumio volumio[2661]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 08 13:41:00 volumio volumio[2661]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 08 13:41:00 volumio volumio[2661]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:41:00 volumio sudo[2865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 13:40 Sep 08 13:41:00 volumio sudo[2865]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:00 volumio sudo[2865]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:01 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:41:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 8. Sep 08 13:41:01 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:41:01 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:41:01 volumio volumio[2872]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:41:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:41:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:41:01 volumio sudo[2694]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:01 volumio volumio-remote-updater[883]: [2024-09-08 13:41:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 08 13:41:01 volumio volumio-remote-updater[883]: [2024-09-08 13:41:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 08 13:41:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 13:41:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 13:41:01 volumio systemd[1]: Started dynamicswap service. Sep 08 13:41:01 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 13:41:01 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 13:41:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Sep 08 13:41:01 volumio systemd[1]: Stopped Volumio Backend Module. Sep 08 13:41:01 volumio systemd[1]: Started Volumio Backend Module. Sep 08 13:41:01 volumio systemd[1]: Started dynamicswap service. Sep 08 13:41:01 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 08 13:41:02 volumio volumio[2879]: info: ------------------------------------------- Sep 08 13:41:02 volumio volumio[2879]: info: ----- Volumio3 ---- Sep 08 13:41:02 volumio volumio[2879]: info: ------------------------------------------- Sep 08 13:41:02 volumio volumio[2879]: info: ----- System startup ---- Sep 08 13:41:02 volumio volumio[2879]: info: ------------------------------------------- Sep 08 13:41:02 volumio volumio[2879]: info: MYVOLUMIO Environment detected Sep 08 13:41:02 volumio volumio[2879]: info: Plugin folders cleanup Sep 08 13:41:02 volumio volumio[2879]: info: Scanning into folder /volumio/app/plugins/ Sep 08 13:41:02 volumio volumio[2879]: info: Scanning category audio_interface Sep 08 13:41:02 volumio volumio[2879]: info: Scanning category miscellanea Sep 08 13:41:02 volumio volumio[2879]: info: Scanning category music_service Sep 08 13:41:02 volumio volumio[2879]: info: Scanning category plugins.json Sep 08 13:41:02 volumio volumio[2879]: info: Scanning category system_controller Sep 08 13:41:02 volumio volumio[2879]: info: Scanning category user_interface Sep 08 13:41:02 volumio volumio[2879]: info: Scanning into folder /data/plugins/ Sep 08 13:41:02 volumio volumio[2879]: info: Scanning category audio_interface Sep 08 13:41:02 volumio volumio[2879]: info: Plugin folders cleanup completed Sep 08 13:41:02 volumio volumio[2879]: info: ------------------------------------------- Sep 08 13:41:02 volumio volumio[2879]: info: ----- Core plugins startup ---- Sep 08 13:41:02 volumio volumio[2879]: info: ------------------------------------------- Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 13:41:02 volumio volumio[2879]: info: Adding plugin upnp to MyMusic Plugins Sep 08 13:41:02 volumio volumio[2879]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 13:41:02 volumio volumio[2879]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugins from folder /data/plugins/ Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "system"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "appearance"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "network"... Sep 08 13:41:02 volumio volumio[2879]: info: Refreshing Cached IP Addresses Sep 08 13:41:02 volumio sudo[2907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 13:41:02 volumio sudo[2907]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "services"... Sep 08 13:41:02 volumio sudo[2907]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:02 volumio sudo[2909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "alsa_controller"... Sep 08 13:41:02 volumio sudo[2909]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:02 volumio sudo[2909]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:02 volumio sudo[2912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 13:41:02 volumio sudo[2912]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:02 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "wizard"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "networkfs"... Sep 08 13:41:02 volumio volumio[2879]: info: Starting Udev Watcher for removable devices Sep 08 13:41:02 volumio volumio[2879]: info: Ignoring mount for partition: boot Sep 08 13:41:02 volumio volumio[2879]: info: Ignoring mount for partition: volumio Sep 08 13:41:02 volumio volumio[2879]: info: Ignoring mount for partition: volumio_data Sep 08 13:41:02 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "volumio_command_line_client"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "upnp"... Sep 08 13:41:02 volumio volumio[2879]: info: [1725795662597] Starting Upmpd Daemon Sep 08 13:41:02 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "my_music"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "mpd"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "upnp_browser"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "alarm-clock"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "airplay_emulation"... Sep 08 13:41:02 volumio volumio[2879]: info: Starting Shairport Sync Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "last_100"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "webradio"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "i2s_dacs"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "volumiodiscovery"... Sep 08 13:41:02 volumio volumio[2879]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:41:02 volumio node[2879]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 13:41:02 volumio node[2879]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:41:02 volumio volumio[2879]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:41:02 volumio volumio[2879]: *** WARNING *** For more information see Sep 08 13:41:02 volumio volumio[2879]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:41:02 volumio volumio[2879]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:41:02 volumio volumio[2879]: *** WARNING *** For more information see Sep 08 13:41:02 volumio node[2879]: *** WARNING *** For more information see Sep 08 13:41:02 volumio node[2879]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 13:41:02 volumio node[2879]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 13:41:02 volumio node[2879]: *** WARNING *** For more information see Sep 08 13:41:02 volumio volumio[2879]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 13:41:02 volumio volumio[2879]: info: Discovery: Started advertising with name: Volumio Sep 08 13:41:02 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "outputs"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "albumart"... Sep 08 13:41:02 volumio volumio[2879]: info: Plugin example_plugin is not enabled Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "inputs"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "updater_comm"... Sep 08 13:41:02 volumio volumio[2879]: info: Plugin mpdemulation is not enabled Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "rest_api"... Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "websocket"... Sep 08 13:41:02 volumio volumio[2879]: info: Starting Socket.io Server version 2.3.0 Sep 08 13:41:02 volumio volumio[2879]: info: Loading plugin "fusiondsp"... Sep 08 13:41:02 volumio volumio[2879]: Forking 3 albumart workers Sep 08 13:41:02 volumio volumio[2879]: info: Applying required configuration parameters for plugin fusiondsp Sep 08 13:41:02 volumio volumio[2879]: info: Loading i18n strings for locale es Sep 08 13:41:02 volumio volumio[2879]: Updating browse sources language Sep 08 13:41:02 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::initPlayerControls Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: Express server listening on port 3000 Sep 08 13:41:03 volumio volumio[2879]: [Metrics] WebUI: 0s 994.11ms Sep 08 13:41:03 volumio volumio[2879]: info: CoreStateMachine::resetVolumioState Sep 08 13:41:03 volumio volumio[2879]: info: CoreStateMachine::getcurrentVolume Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:41:03 volumio volumio[2879]: Starting albumart workers Sep 08 13:41:03 volumio volumio[2879]: verbose: New Socket.io Connection to 192.168.1.179 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Herring/90.1.1410.5 Engine version: 3 Transport: polling Total Clients: 1 Sep 08 13:41:03 volumio volumio[2879]: verbose: New Socket.io Connection to 192.168.1.157 from 192.168.1.107 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Edg/128.0.0.0 Herring/90.1.1410.5 Engine version: 3 Transport: polling Total Clients: 2 Sep 08 13:41:03 volumio volumio[2879]: Starting albumart workers Sep 08 13:41:03 volumio volumio[2879]: Starting albumart workers Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: Reloading queue from file Sep 08 13:41:03 volumio volumio[2879]: info: CoreStateMachine::setRepeat null single undefined Sep 08 13:41:03 volumio volumio[2879]: info: CoreStateMachine::pushState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioPushState Sep 08 13:41:03 volumio volumio[2879]: info: CoreStateMachine::setRandom null Sep 08 13:41:03 volumio volumio[2879]: info: CoreStateMachine::pushState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioPushState Sep 08 13:41:03 volumio volumio[2879]: info: Setting Device type: Raspberry PI Sep 08 13:41:03 volumio volumio[2879]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 08 13:41:03 volumio volumio[2879]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Sep 08 13:41:03 volumio volumio[2879]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 08 13:41:03 volumio volumio[2879]: info: VolumeController:: Volume=0 Mute =false Sep 08 13:41:03 volumio volumio[2879]: info: CoreStateMachine::pushState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioPushState Sep 08 13:41:03 volumio volumio[2879]: info: CoreStateMachine::updateTrackBlock Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrackBlock Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 13:41:03 volumio volumio[2879]: info: Completed loading Core Plugins Sep 08 13:41:03 volumio volumio[2879]: info: Preparing to generate the ALSA configuration file Sep 08 13:41:03 volumio volumio[2879]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:41:03 volumio volumio[2879]: info: Reading ALSA contributions from plugins. Sep 08 13:41:03 volumio volumio[2879]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:41:03 volumio volumio[2879]: info: Output device has changed, restarting MPD Sep 08 13:41:03 volumio sudo[2965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:41:03 volumio sudo[2965]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio volumio[2879]: info: Output device has changed, restarting Shairport Sync Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:03 volumio sudo[2969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:41:03 volumio sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio sudo[2965]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio systemd[1]: Stopping Music Player Daemon... Sep 08 13:41:03 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:41:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 9. Sep 08 13:41:03 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:41:03 volumio volumio[2879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:41:03 volumio volumio[2879]: info: ___________ START PLUGINS ___________ Sep 08 13:41:03 volumio volumio[2879]: info: ControllerMpd::onStart: Initializing MPD Sep 08 13:41:03 volumio volumio[2879]: info: Creating MPD Configuration file Sep 08 13:41:03 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:41:03 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:41:03 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:41:03 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:41:03 volumio sudo[2983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:41:03 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio volumio[2977]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 13:41:03 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:41:03 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:41:03 volumio volumio[2879]: info: [1725795663337] CoreMusicLibrary::Adding element Media Servers Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:41:03 volumio sudo[2995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:41:03 volumio sudo[2995]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio sudo[2985]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:41:03 volumio sudo[2985]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:03 volumio sudo[2985]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 08 13:41:03 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:41:03 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:41:03 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:41:03 volumio volumio[2879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:03 volumio sudo[3000]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:41:03 volumio sudo[3000]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio volumio[2879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:41:03 volumio sudo[3000]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:41:03 volumio volumio[2879]: info: [1725795663389] CoreMusicLibrary::Adding element Last_100 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 13:41:03 volumio volumio[2879]: info: [1725795663390] CoreMusicLibrary::Adding element Webradio Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:41:03 volumio volumio[2879]: info: Initializing BBC Radios Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: Loading i18n strings for locale es Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: FusionDsp - mixtype--------------------- Hardware Sep 08 13:41:03 volumio volumio[2879]: info: Volumio Calling Home Sep 08 13:41:03 volumio volumio[2879]: info: Preparing to generate the ALSA configuration file Sep 08 13:41:03 volumio volumio[2879]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 13:41:03 volumio volumio[2879]: info: Reading ALSA contributions from plugins. Sep 08 13:41:03 volumio volumio[2879]: info: MPD Permissions set Sep 08 13:41:03 volumio volumio[2879]: info: MPD Permissions set Sep 08 13:41:03 volumio volumio[2879]: info: VolumeController:: Volume=0 Mute =false Sep 08 13:41:03 volumio volumio[2879]: info: CoreStateMachine::pushState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioPushState Sep 08 13:41:03 volumio volumio[2879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: Starting Shairport Sync Sep 08 13:41:03 volumio volumio[2879]: info: Starting Shairport Sync Sep 08 13:41:03 volumio sudo[3038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:41:03 volumio volumio[2879]: info: Starting Shairport Sync Sep 08 13:41:03 volumio sudo[3038]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio sudo[3040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:41:03 volumio sudo[3040]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 08 13:41:03 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 08 13:41:03 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 08 13:41:03 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 08 13:41:03 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:03 volumio sudo[3040]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio sudo[3038]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio volumio[2879]: info: Asound.conf file unchanged, so no further update is needed Sep 08 13:41:03 volumio volumio[2879]: info: Output device has changed, restarting MPD Sep 08 13:41:03 volumio sudo[3044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:41:03 volumio sudo[3044]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 08 13:41:03 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 08 13:41:03 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:03 volumio volumio[2879]: info: Output device has changed, restarting Shairport Sync Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:03 volumio sudo[3047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 13:41:03 volumio sudo[3044]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio sudo[3047]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio sudo[3047]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio sudo[3051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 13:41:03 volumio sudo[3051]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 08 13:41:03 volumio systemd[1]: mpd.service: Succeeded. Sep 08 13:41:03 volumio systemd[1]: Stopped Music Player Daemon. Sep 08 13:41:03 volumio volumio[2879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 13:41:03 volumio volumio[2879]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 08 13:41:03 volumio volumio[2879]: Job for shairport-sync.service failed. Sep 08 13:41:03 volumio volumio[2879]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 08 13:41:03 volumio volumio[2879]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 08 13:41:03 volumio volumio[2879]: Job for shairport-sync.service failed. Sep 08 13:41:03 volumio volumio[2879]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 08 13:41:03 volumio volumio[2879]: info: MPD Permissions set Sep 08 13:41:03 volumio volumio[2879]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 08 13:41:03 volumio volumio[2879]: Job for shairport-sync.service failed. Sep 08 13:41:03 volumio volumio[2879]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio systemd[1]: Starting Music Player Daemon... Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:03 volumio volumio[2879]: info: Starting Shairport Sync Sep 08 13:41:03 volumio sudo[3061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 13:41:03 volumio sudo[3061]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio sudo[3055]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 13:41:03 volumio sudo[3055]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:03 volumio sudo[3055]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 08 13:41:03 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 08 13:41:03 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 08 13:41:03 volumio sudo[3061]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio volumio[2879]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 08 13:41:03 volumio volumio[2879]: Job for shairport-sync.service failed. Sep 08 13:41:03 volumio volumio[2879]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: Received Get System Info Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:41:03 volumio volumio[2879]: info: Discovery: Getting this device information Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 13:41:03 volumio volumio[2879]: info: Received Get System Info Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 13:41:03 volumio volumio[2879]: info: Discovery: Getting this device information Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: Listing playlists Sep 08 13:41:03 volumio volumio[2879]: info: Discovery: adding 1ea99d28-e957-42b8-80ae-afd56707d40e Sep 08 13:41:03 volumio volumio[2879]: info: Discovery: Found device Volumio Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: Discovery: this is already registered, 1ea99d28-e957-42b8-80ae-afd56707d40e Sep 08 13:41:03 volumio volumio[2879]: info: Discovery: Found device Volumio Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::volumioGetState Sep 08 13:41:03 volumio volumio[2879]: info: CorePlayQueue::getTrack 0 Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:41:03 volumio volumio[2879]: info: Volumio called home Sep 08 13:41:03 volumio mpd[3064]: Sep 08 13:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 13:41:03 volumio systemd[1]: Started Music Player Daemon. Sep 08 13:41:03 volumio sudo[3051]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio sudo[2969]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio sudo[2995]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:03 volumio volumio[2879]: info: Completed starting Core Plugins Sep 08 13:41:03 volumio volumio[2879]: info: ------------------------------------------- Sep 08 13:41:03 volumio volumio[2879]: info: ----- MyVolumio plugins startup ---- Sep 08 13:41:03 volumio volumio[2879]: info: ------------------------------------------- Sep 08 13:41:03 volumio volumio[2879]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 08 13:41:03 volumio volumio[2879]: error: MPD error: The expression evaluated to a falsy value: Sep 08 13:41:03 volumio volumio[2879]: assert.ok(self.idling) Sep 08 13:41:03 volumio volumio[2879]: error: The expression evaluated to a falsy value: Sep 08 13:41:03 volumio volumio[2879]: assert.ok(self.idling) Sep 08 13:41:03 volumio volumio[2879]: error: MPD error: The expression evaluated to a falsy value: Sep 08 13:41:03 volumio volumio[2879]: assert.ok(self.idling) Sep 08 13:41:03 volumio volumio[2879]: error: The expression evaluated to a falsy value: Sep 08 13:41:03 volumio volumio[2879]: assert.ok(self.idling) Sep 08 13:41:03 volumio volumio[2879]: error: updateQueue error: null Sep 08 13:41:03 volumio volumio[2879]: info: MPD running with PID3064 Sep 08 13:41:03 volumio volumio[2879]: ,establishing connection Sep 08 13:41:03 volumio volumio[2879]: error: updateQueue error: null Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:41:03 volumio volumio[2879]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 13:41:05 volumio volumio[2879]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 08 13:41:05 volumio volumio[2879]: 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 Sep 08 13:41:05 volumio volumio[2879]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 08 13:41:05 volumio volumio[2879]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Sep 08 13:41:05 volumio volumio[2879]: info: camilladsp service started and running in background, instance 1 Sep 08 13:41:05 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 13:41:05 volumio volumio[2879]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:41:05 volumio volumio[2879]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 08 13:41:05 volumio volumio[2879]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 08 13:41:05 volumio volumio[2879]: info: FusionDsp loaded Sep 08 13:41:05 volumio volumio[2879]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 13:41:05 volumio sudo[3080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 08 13:41:05 volumio sudo[3080]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 13:41:05 volumio volumio[2879]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 08 13:41:05 volumio volumio[2879]: info: Adding Signal Path Element [object Object] Sep 08 13:41:05 volumio volumio[2879]: info: Adding fusiondspeq DSP Signal Path Element Sep 08 13:41:05 volumio volumio[2879]: info: FusionDsp - ---- installed callbackRead Sep 08 13:41:05 volumio volumio[2879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:41:05 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 08 13:41:05 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 10. Sep 08 13:41:05 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 08 13:41:05 volumio volumio[2879]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 08 13:41:05 volumio volumio[2879]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 08 13:41:05 volumio volumio[2879]: at onErrorNT (internal/child_process.js:465:16) Sep 08 13:41:05 volumio volumio[2879]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 08 13:41:05 volumio volumio[2879]: errno: -2, Sep 08 13:41:05 volumio volumio[2879]: code: 'ENOENT', Sep 08 13:41:05 volumio volumio[2879]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:41:05 volumio volumio[2879]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 08 13:41:05 volumio volumio[2879]: spawnargs: [ Sep 08 13:41:05 volumio volumio[2879]: '-p', Sep 08 13:41:05 volumio volumio[2879]: 9876, Sep 08 13:41:05 volumio volumio[2879]: '-o', Sep 08 13:41:05 volumio volumio[2879]: '/tmp/camilladsp.log', Sep 08 13:41:05 volumio volumio[2879]: '-l', Sep 08 13:41:05 volumio volumio[2879]: 'warn', Sep 08 13:41:05 volumio volumio[2879]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 08 13:41:05 volumio volumio[2879]: ] Sep 08 13:41:05 volumio volumio[2879]: } Sep 08 13:41:05 volumio volumio[2879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 13:41:05 volumio systemd[1]: Started FusionDsp Daemon. Sep 08 13:41:05 volumio sudo[3080]: pam_unix(sudo:session): session closed for user root Sep 08 13:41:05 volumio volumio[3082]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 08 13:41:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 08 13:41:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 08 13:41:05 volumio sudo[3092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 13:40 Sep 08 13:41:05 volumio sudo[3092]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"