-- Logs begin at Thu 2024-02-22 07:42:47 +11, end at Sun 2024-03-31 19:11:00 +11. -- Mar 31 19:10:01 volumio-ares volumio[771]: info: Checking if install.sh is present Mar 31 19:10:01 volumio-ares volumio[771]: info: Executing install.sh Mar 31 19:10:01 volumio-ares sudo[4486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/RoonBridge/install.sh Mar 31 19:10:01 volumio-ares sudo[4486]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:01 volumio-ares volumio[771]: info: Installing Roon Bridge Dependencies Mar 31 19:10:01 volumio-ares volumio[771]: info: Installing Roon Bridge Dependencies Mar 31 19:10:01 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 31 19:10:01 volumio-ares volumio[771]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 31 19:10:01 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 31 19:10:01 volumio-ares volumio[771]: info: Received Get System Version Mar 31 19:10:01 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 31 19:10:01 volumio-ares volumio[771]: info: Received Get System Info Mar 31 19:10:01 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 19:10:01 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 19:10:01 volumio-ares volumio[771]: info: Discovery: Getting this device information Mar 31 19:10:01 volumio-ares volumio[771]: info: CoreCommandRouter::volumioGetState Mar 31 19:10:01 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 19:10:02 volumio-ares volumio[771]: info: Mar 31 19:10:02 volumio-ares volumio[771]: Selecting previously unselected package gcc. Mar 31 19:10:02 volumio-ares volumio[771]: info: Mar 31 19:10:02 volumio-ares volumio[771]: Selecting previously unselected package gcc. Mar 31 19:10:02 volumio-ares volumio[771]: info: Preparing to unpack .../15-gcc_4%3a8.3.0-1+rpi2_armhf.deb ... Mar 31 19:10:02 volumio-ares volumio[771]: info: Unpacking gcc (4:8.3.0-1+rpi2) ... Mar 31 19:10:02 volumio-ares volumio[771]: info: Unpacking gcc (4:8.3.0-1+rpi2) ... Mar 31 19:10:02 volumio-ares volumio[771]: info: Selecting previously unselected package libstdc++-8-dev:armhf. Mar 31 19:10:02 volumio-ares volumio[771]: info: Selecting previously unselected package libstdc++-8-dev:armhf. Mar 31 19:10:02 volumio-ares volumio[771]: info: Preparing to unpack .../16-libstdc++-8-dev_8.3.0-6+rpi1_armhf.deb ... Mar 31 19:10:02 volumio-ares volumio[771]: info: Preparing to unpack .../16-libstdc++-8-dev_8.3.0-6+rpi1_armhf.deb ... Mar 31 19:10:02 volumio-ares volumio[771]: info: Unpacking libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... Mar 31 19:10:02 volumio-ares volumio[771]: info: Unpacking libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... Mar 31 19:10:15 volumio-ares volumio[771]: info: Selecting previously unselected package g++-8. Mar 31 19:10:15 volumio-ares volumio[771]: info: Selecting previously unselected package g++-8. Mar 31 19:10:15 volumio-ares volumio[771]: info: Preparing to unpack .../17-g++-8_8.3.0-6+rpi1_armhf.deb ... Mar 31 19:10:15 volumio-ares volumio[771]: info: Preparing to unpack .../17-g++-8_8.3.0-6+rpi1_armhf.deb ... Mar 31 19:10:15 volumio-ares volumio[771]: info: Unpacking g++-8 (8.3.0-6+rpi1) ... Mar 31 19:10:15 volumio-ares volumio[771]: info: Unpacking g++-8 (8.3.0-6+rpi1) ... Mar 31 19:10:23 volumio-ares volumio[771]: info: Selecting previously unselected package g++. Mar 31 19:10:23 volumio-ares volumio[771]: info: Selecting previously unselected package g++. Mar 31 19:10:23 volumio-ares volumio[771]: info: Preparing to unpack .../18-g++_4%3a8.3.0-1+rpi2_armhf.deb ... Mar 31 19:10:23 volumio-ares volumio[771]: info: Preparing to unpack .../18-g++_4%3a8.3.0-1+rpi2_armhf.deb ... Mar 31 19:10:23 volumio-ares volumio[771]: info: Unpacking g++ (4:8.3.0-1+rpi2) ... Mar 31 19:10:23 volumio-ares volumio[771]: info: Unpacking g++ (4:8.3.0-1+rpi2) ... Mar 31 19:10:23 volumio-ares volumio[771]: info: Selecting previously unselected package make. Mar 31 19:10:23 volumio-ares volumio[771]: info: Selecting previously unselected package make. Mar 31 19:10:23 volumio-ares volumio[771]: info: Preparing to unpack .../19-make_4.2.1-1.2_armhf.deb ... Mar 31 19:10:23 volumio-ares volumio[771]: info: Preparing to unpack .../19-make_4.2.1-1.2_armhf.deb ... Mar 31 19:10:23 volumio-ares volumio[771]: info: Unpacking make (4.2.1-1.2) ... Mar 31 19:10:23 volumio-ares volumio[771]: info: Unpacking make (4.2.1-1.2) ... Mar 31 19:10:25 volumio-ares volumio[771]: info: Selecting previously unselected package patch. Mar 31 19:10:25 volumio-ares volumio[771]: info: Selecting previously unselected package patch. Mar 31 19:10:25 volumio-ares volumio[771]: info: Preparing to unpack .../20-patch_2.7.6-3+deb10u1_armhf.deb ... Mar 31 19:10:25 volumio-ares volumio[771]: info: Preparing to unpack .../20-patch_2.7.6-3+deb10u1_armhf.deb ... Mar 31 19:10:25 volumio-ares volumio[771]: info: Unpacking patch (2.7.6-3+deb10u1) ... Mar 31 19:10:25 volumio-ares volumio[771]: info: Unpacking patch (2.7.6-3+deb10u1) ... Mar 31 19:10:25 volumio-ares volumio[771]: info: Selecting previously unselected package dpkg-dev. Mar 31 19:10:25 volumio-ares volumio[771]: info: Selecting previously unselected package dpkg-dev. Mar 31 19:10:25 volumio-ares volumio[771]: info: Preparing to unpack .../21-dpkg-dev_1.19.8_all.deb ... Mar 31 19:10:25 volumio-ares volumio[771]: info: Preparing to unpack .../21-dpkg-dev_1.19.8_all.deb ... Mar 31 19:10:25 volumio-ares volumio[771]: info: Unpacking dpkg-dev (1.19.8) ... Mar 31 19:10:25 volumio-ares volumio[771]: info: Unpacking dpkg-dev (1.19.8) ... Mar 31 19:10:28 volumio-ares volumio[771]: info: Selecting previously unselected package build-essential. Mar 31 19:10:28 volumio-ares volumio[771]: info: Selecting previously unselected package build-essential. Mar 31 19:10:28 volumio-ares volumio[771]: info: Preparing to unpack .../22-build-essential_12.6_armhf.deb ... Mar 31 19:10:28 volumio-ares volumio[771]: info: Preparing to unpack .../22-build-essential_12.6_armhf.deb ... Mar 31 19:10:28 volumio-ares volumio[771]: info: Unpacking build-essential (12.6) ... Mar 31 19:10:28 volumio-ares volumio[771]: info: Unpacking build-essential (12.6) ... Mar 31 19:10:28 volumio-ares volumio[771]: info: Selecting previously unselected package dh-python. Mar 31 19:10:28 volumio-ares volumio[771]: info: Selecting previously unselected package dh-python. Mar 31 19:10:28 volumio-ares volumio[771]: info: Preparing to unpack .../23-dh-python_3.20190308_all.deb ... Mar 31 19:10:28 volumio-ares volumio[771]: info: Unpacking dh-python (3.20190308) ... Mar 31 19:10:28 volumio-ares volumio[771]: info: Preparing to unpack .../23-dh-python_3.20190308_all.deb ... Mar 31 19:10:28 volumio-ares volumio[771]: info: Unpacking dh-python (3.20190308) ... Mar 31 19:10:30 volumio-ares volumio[771]: info: Selecting previously unselected package libfakeroot:armhf. Mar 31 19:10:30 volumio-ares volumio[771]: info: Selecting previously unselected package libfakeroot:armhf. Mar 31 19:10:30 volumio-ares volumio[771]: info: Preparing to unpack .../24-libfakeroot_1.23-1_armhf.deb ... Mar 31 19:10:30 volumio-ares volumio[771]: info: Preparing to unpack .../24-libfakeroot_1.23-1_armhf.deb ... Mar 31 19:10:30 volumio-ares volumio[771]: info: Unpacking libfakeroot:armhf (1.23-1) ... Mar 31 19:10:30 volumio-ares volumio[771]: info: Unpacking libfakeroot:armhf (1.23-1) ... Mar 31 19:10:30 volumio-ares volumio[771]: info: Selecting previously unselected package fakeroot. Mar 31 19:10:30 volumio-ares volumio[771]: info: Selecting previously unselected package fakeroot. Mar 31 19:10:30 volumio-ares volumio[771]: info: Preparing to unpack .../25-fakeroot_1.23-1_armhf.deb ... Mar 31 19:10:30 volumio-ares volumio[771]: info: Preparing to unpack .../25-fakeroot_1.23-1_armhf.deb ... Mar 31 19:10:30 volumio-ares volumio[771]: info: Unpacking fakeroot (1.23-1) ... Mar 31 19:10:30 volumio-ares volumio[771]: info: Unpacking fakeroot (1.23-1) ... Mar 31 19:10:31 volumio-ares sudo[4486]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:31 volumio-ares volumio[771]: info: Install script completed Mar 31 19:10:31 volumio-ares volumio[771]: info: Adding reference to registry Mar 31 19:10:31 volumio-ares volumio[771]: info: Done installing plugin. Mar 31 19:10:31 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 31 19:10:31 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 31 19:10:31 volumio-ares volumio[771]: info: Folder /tmp/plugins removed Mar 31 19:10:31 volumio-ares volumio[771]: info: Folder /tmp/downloaded_plugin.zip removed Mar 31 19:10:31 volumio-ares volumio[771]: info: Folder /data/temp removed Mar 31 19:10:32 volumio-ares volumio[771]: info: Selecting previously unselected package libalgorithm-diff-perl. Mar 31 19:10:32 volumio-ares volumio[771]: info: Selecting previously unselected package libalgorithm-diff-perl. Mar 31 19:10:32 volumio-ares volumio[771]: info: Preparing to unpack .../26-libalgorithm-diff-perl_1.19.03-2_all.deb ... Mar 31 19:10:32 volumio-ares volumio[771]: info: Preparing to unpack .../26-libalgorithm-diff-perl_1.19.03-2_all.deb ... Mar 31 19:10:32 volumio-ares volumio[771]: info: Unpacking libalgorithm-diff-perl (1.19.03-2) ... Mar 31 19:10:32 volumio-ares volumio[771]: info: Unpacking libalgorithm-diff-perl (1.19.03-2) ... Mar 31 19:10:35 volumio-ares volumio[771]: info: Selecting previously unselected package libalgorithm-diff-xs-perl. Mar 31 19:10:35 volumio-ares volumio[771]: info: Selecting previously unselected package libalgorithm-diff-xs-perl. Mar 31 19:10:35 volumio-ares volumio[771]: info: Preparing to unpack .../27-libalgorithm-diff-xs-perl_0.04-5+b1_armhf.deb ... Mar 31 19:10:35 volumio-ares volumio[771]: info: Preparing to unpack .../27-libalgorithm-diff-xs-perl_0.04-5+b1_armhf.deb ... Mar 31 19:10:36 volumio-ares volumio[771]: info: Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... Mar 31 19:10:36 volumio-ares volumio[771]: info: Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... Mar 31 19:10:36 volumio-ares volumio[771]: info: Selecting previously unselected package libalgorithm-merge-perl. Mar 31 19:10:36 volumio-ares volumio[771]: info: Selecting previously unselected package libalgorithm-merge-perl. Mar 31 19:10:36 volumio-ares volumio[771]: info: Preparing to unpack .../28-libalgorithm-merge-perl_0.08-3_all.deb ... Mar 31 19:10:36 volumio-ares volumio[771]: info: Preparing to unpack .../28-libalgorithm-merge-perl_0.08-3_all.deb ... Mar 31 19:10:36 volumio-ares volumio[771]: info: Unpacking libalgorithm-merge-perl (0.08-3) ... Mar 31 19:10:36 volumio-ares volumio[771]: info: Unpacking libalgorithm-merge-perl (0.08-3) ... Mar 31 19:10:37 volumio-ares volumio[771]: info: Selecting previously unselected package libexpat1-dev:armhf. Mar 31 19:10:37 volumio-ares volumio[771]: info: Selecting previously unselected package libexpat1-dev:armhf. Mar 31 19:10:37 volumio-ares volumio[771]: info: Preparing to unpack .../29-libexpat1-dev_2.2.6-2+deb10u6_armhf.deb ... Mar 31 19:10:37 volumio-ares volumio[771]: info: Preparing to unpack .../29-libexpat1-dev_2.2.6-2+deb10u6_armhf.deb ... Mar 31 19:10:37 volumio-ares volumio[771]: info: Unpacking libexpat1-dev:armhf (2.2.6-2+deb10u6) ... Mar 31 19:10:37 volumio-ares volumio[771]: info: Unpacking libexpat1-dev:armhf (2.2.6-2+deb10u6) ... Mar 31 19:10:38 volumio-ares ntpd[676]: Soliciting pool server 2404:2a00::30 Mar 31 19:10:38 volumio-ares volumio[771]: info: Selecting previously unselected package libpython3.7:armhf. Mar 31 19:10:38 volumio-ares volumio[771]: info: Selecting previously unselected package libpython3.7:armhf. Mar 31 19:10:38 volumio-ares volumio[771]: info: Preparing to unpack .../30-libpython3.7_3.7.3-2+deb10u7_armhf.deb ... Mar 31 19:10:38 volumio-ares volumio[771]: info: Preparing to unpack .../30-libpython3.7_3.7.3-2+deb10u7_armhf.deb ... Mar 31 19:10:38 volumio-ares volumio[771]: info: Unpacking libpython3.7:armhf (3.7.3-2+deb10u7) ... Mar 31 19:10:38 volumio-ares volumio[771]: info: Unpacking libpython3.7:armhf (3.7.3-2+deb10u7) ... Mar 31 19:10:39 volumio-ares volumio[771]: info: Selecting previously unselected package libpython3.7-dev:armhf. Mar 31 19:10:39 volumio-ares volumio[771]: info: Selecting previously unselected package libpython3.7-dev:armhf. Mar 31 19:10:39 volumio-ares volumio[771]: info: Preparing to unpack .../31-libpython3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Mar 31 19:10:39 volumio-ares volumio[771]: info: Preparing to unpack .../31-libpython3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Mar 31 19:10:39 volumio-ares volumio[771]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... Mar 31 19:10:39 volumio-ares volumio[771]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... Mar 31 19:10:40 volumio-ares volumio[771]: info: Enabling plugin RoonBridge Mar 31 19:10:40 volumio-ares volumio[771]: info: Loading plugin "RoonBridge"... Mar 31 19:10:40 volumio-ares volumio[771]: info: PLUGIN START: RoonBridge Mar 31 19:10:40 volumio-ares sudo[4922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Mar 31 19:10:40 volumio-ares sudo[4922]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:40 volumio-ares systemd[1]: Started RoonBridge. Mar 31 19:10:40 volumio-ares sudo[4922]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:40 volumio-ares volumio[771]: info: Done. Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.008 Warn: get lock file path: /tmp/.rnbgem0- Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.313 Trace: [childprocess] using unix child process Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.367 Debug: PathForResource, filename: ../.update Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.380 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.386 Debug: PathForResource, filename: RAATServer Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.387 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.387 Debug: PathForResource, filename: RoonBridgeHelper Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.388 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Mar 31 19:10:41 volumio-ares RoonBridge[4924]: Initializing Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.421 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Mar 31 19:10:41 volumio-ares RoonBridge[4924]: 00:00:00.445 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Mar 31 19:10:41 volumio-ares RoonBridge[4924]: Not Running (.o) Mar 31 19:10:41 volumio-ares RoonBridge[4924]: Running Mar 31 19:10:42 volumio-ares RoonBridge[4924]: 00:00:00.025 Warn: get lock file path: /tmp/.rnbhgem0- Mar 31 19:10:49 volumio-ares volumio[771]: info: Enabling plugin fusiondsp Mar 31 19:10:49 volumio-ares volumio[771]: info: Loading plugin "fusiondsp"... Mar 31 19:10:50 volumio-ares volumio[771]: info: Preparing to generate the ALSA configuration file Mar 31 19:10:50 volumio-ares volumio[771]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 31 19:10:50 volumio-ares volumio[771]: info: Reading ALSA contributions from plugins. Mar 31 19:10:50 volumio-ares volumio[771]: info: Asound.conf file written Mar 31 19:10:50 volumio-ares sudo[4988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 31 19:10:50 volumio-ares sudo[4988]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:50 volumio-ares sudo[4988]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:50 volumio-ares volumio[771]: xcb_connection_has_error() returned true Mar 31 19:10:50 volumio-ares volumio[771]: info: Output device has changed, restarting MPD Mar 31 19:10:50 volumio-ares volumio[771]: info: Output device has changed, restarting Shairport Sync Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 19:10:50 volumio-ares sudo[4997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 31 19:10:50 volumio-ares sudo[4997]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:50 volumio-ares sudo[4995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 31 19:10:50 volumio-ares sudo[4995]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:50 volumio-ares sudo[4995]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:50 volumio-ares systemd[1]: Stopping Music Player Daemon... Mar 31 19:10:50 volumio-ares volumio[771]: xcb_connection_has_error() returned true Mar 31 19:10:50 volumio-ares volumio[771]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 31 19:10:50 volumio-ares volumio[771]: xcb_connection_has_error() returned true Mar 31 19:10:50 volumio-ares volumio[771]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares systemd[1]: mpd.service: Succeeded. Mar 31 19:10:50 volumio-ares systemd[1]: Stopped Music Player Daemon. Mar 31 19:10:50 volumio-ares vtcs[2618]: [2024-03-31 19:10:50.646] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 31 19:10:50 volumio-ares vtcs[2618]: [2024-03-31 19:10:50.647] [tisoc] [error] [SpkconServer.cpp:382] recv error. client fd=5 errorno=104 error=Connection reset by peer Mar 31 19:10:50 volumio-ares vtcs[2618]: [2024-03-31 19:10:50.647] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected Mar 31 19:10:50 volumio-ares systemd[1]: Starting Music Player Daemon... Mar 31 19:10:50 volumio-ares sudo[5007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 31 19:10:50 volumio-ares sudo[5007]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:50 volumio-ares volumio[771]: info: PLUGIN START: fusiondsp Mar 31 19:10:50 volumio-ares systemd[1]: Stopping Volumio Tidal Connect Service... Mar 31 19:10:50 volumio-ares systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 31 19:10:50 volumio-ares systemd[1]: vtcs.service: Succeeded. Mar 31 19:10:50 volumio-ares systemd[1]: Stopped Volumio Tidal Connect Service. Mar 31 19:10:50 volumio-ares sudo[5007]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:50 volumio-ares volumio[771]: info: Loading i18n strings for locale fr Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: FusionDsp - mixtype--------------------- None Mar 31 19:10:50 volumio-ares volumio[771]: info: Preparing to generate the ALSA configuration file Mar 31 19:10:50 volumio-ares volumio[771]: info: Done. Mar 31 19:10:50 volumio-ares sudo[5011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 31 19:10:50 volumio-ares sudo[5011]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:50 volumio-ares volumio[771]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 31 19:10:50 volumio-ares volumio[771]: info: Reading ALSA contributions from plugins. Mar 31 19:10:50 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 19:10:50 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 19:10:50 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 19:10:50 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 19:10:50 volumio-ares sudo[5011]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:50 volumio-ares volumio[771]: info: MPD Permissions set Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:50 volumio-ares volumio[771]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 19:10:50 volumio-ares volumio[771]: info: Starting Shairport Sync Mar 31 19:10:50 volumio-ares sudo[5020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 31 19:10:50 volumio-ares sudo[5020]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:51 volumio-ares systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 31 19:10:51 volumio-ares systemd[1]: shairport-sync.service: Succeeded. Mar 31 19:10:51 volumio-ares systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 31 19:10:51 volumio-ares systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 31 19:10:51 volumio-ares sudo[5020]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:51 volumio-ares volumio[771]: info: Asound.conf file unchanged, so no further update is needed Mar 31 19:10:51 volumio-ares volumio[771]: info: Output device has changed, restarting MPD Mar 31 19:10:51 volumio-ares volumio[771]: info: Output device has changed, restarting Shairport Sync Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 19:10:51 volumio-ares sudo[5030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 31 19:10:51 volumio-ares sudo[5030]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:51 volumio-ares sudo[5030]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:51 volumio-ares sudo[5033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 31 19:10:51 volumio-ares sudo[5033]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:51 volumio-ares systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 31 19:10:51 volumio-ares systemd[1]: mpd.service: Succeeded. Mar 31 19:10:51 volumio-ares systemd[1]: Stopped Music Player Daemon. Mar 31 19:10:51 volumio-ares volumio[771]: xcb_connection_has_error() returned true Mar 31 19:10:51 volumio-ares volumio[771]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 31 19:10:51 volumio-ares volumio[771]: xcb_connection_has_error() returned true Mar 31 19:10:51 volumio-ares volumio[771]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares systemd[1]: Starting Music Player Daemon... Mar 31 19:10:51 volumio-ares sudo[5043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 31 19:10:51 volumio-ares sudo[5043]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:51 volumio-ares sudo[5043]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:51 volumio-ares sudo[5044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 31 19:10:51 volumio-ares sudo[5044]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:51 volumio-ares sudo[5044]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:51 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 19:10:51 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 19:10:51 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 19:10:51 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 19:10:51 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 19:10:51 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 19:10:51 volumio-ares volumio[771]: info: MPD Permissions set Mar 31 19:10:51 volumio-ares volumio[771]: info: Shairport-Sync Started Mar 31 19:10:51 volumio-ares volumio[771]: (node:771) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 31 19:10:51 volumio-ares volumio[771]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 31 19:10:51 volumio-ares volumio[771]: (node:771) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 31 19:10:51 volumio-ares volumio[771]: (node:771) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 31 19:10:51 volumio-ares volumio[771]: (node:771) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 31 19:10:51 volumio-ares volumio[771]: (node:771) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:51 volumio-ares volumio[771]: info: Starting Shairport Sync Mar 31 19:10:51 volumio-ares sudo[5054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 31 19:10:51 volumio-ares sudo[5054]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:51 volumio-ares systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 31 19:10:51 volumio-ares systemd[1]: shairport-sync.service: Succeeded. Mar 31 19:10:51 volumio-ares systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 31 19:10:51 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 19:10:51 volumio-ares volumio[771]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 19:10:51 volumio-ares systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 31 19:10:51 volumio-ares sudo[5054]: pam_unix(sudo:session): session closed for user root Mar 31 19:10:51 volumio-ares volumio[771]: info: Shairport-Sync Started Mar 31 19:10:56 volumio-ares volumio[771]: info: TidalConnect service stoped! Mar 31 19:10:56 volumio-ares volumio[771]: info: TidalConnect service stoped! Mar 31 19:10:56 volumio-ares volumio[771]: info: TidalConnect service stoped! Mar 31 19:10:56 volumio-ares volumio[771]: info: TidalConnect service stoped! Mar 31 19:10:56 volumio-ares volumio[771]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Mar 31 19:10:56 volumio-ares volumio[771]: info: camilladsp service started and running in background, instance 1 Mar 31 19:10:56 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 19:10:56 volumio-ares volumio[771]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Mar 31 19:10:56 volumio-ares volumio[771]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Mar 31 19:10:56 volumio-ares volumio[771]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Mar 31 19:10:56 volumio-ares volumio[771]: info: FusionDsp loaded Mar 31 19:10:56 volumio-ares volumio[771]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 19:10:56 volumio-ares sudo[5079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 31 19:10:56 volumio-ares sudo[5079]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 19:10:56 volumio-ares volumio[771]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 31 19:10:56 volumio-ares volumio[771]: info: Adding Signal Path Element [object Object] Mar 31 19:10:56 volumio-ares volumio[771]: info: Adding fusiondspeq DSP Signal Path Element Mar 31 19:10:56 volumio-ares volumio[771]: info: FusionDsp - ---- installed callbackRead Mar 31 19:10:56 volumio-ares volumio[771]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 19:10:56 volumio-ares volumio[771]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Mar 31 19:10:56 volumio-ares volumio[771]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Mar 31 19:10:56 volumio-ares volumio[771]: at onErrorNT (internal/child_process.js:465:16) Mar 31 19:10:56 volumio-ares volumio[771]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 31 19:10:56 volumio-ares volumio[771]: at runNextTicks (internal/process/task_queues.js:62:3) Mar 31 19:10:56 volumio-ares volumio[771]: at listOnTimeout (internal/timers.js:523:9) Mar 31 19:10:56 volumio-ares volumio[771]: at processTimers (internal/timers.js:497:7) { Mar 31 19:10:56 volumio-ares volumio[771]: errno: -2, Mar 31 19:10:56 volumio-ares volumio[771]: code: 'ENOENT', Mar 31 19:10:56 volumio-ares volumio[771]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Mar 31 19:10:56 volumio-ares volumio[771]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Mar 31 19:10:56 volumio-ares volumio[771]: spawnargs: [ Mar 31 19:10:56 volumio-ares volumio[771]: '-p', Mar 31 19:10:56 volumio-ares volumio[771]: 9876, Mar 31 19:10:56 volumio-ares volumio[771]: '-o', Mar 31 19:10:56 volumio-ares volumio[771]: '/tmp/camilladsp.log', Mar 31 19:10:56 volumio-ares volumio[771]: '-l', Mar 31 19:10:56 volumio-ares volumio[771]: 'warn', Mar 31 19:10:56 volumio-ares volumio[771]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Mar 31 19:10:56 volumio-ares volumio[771]: ] Mar 31 19:10:56 volumio-ares volumio[771]: } Mar 31 19:10:56 volumio-ares volumio[771]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 19:11:00 volumio-ares mpd[5038]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 31 19:11:00 volumio-ares mpd[5038]: output: No 'audio_output' defined in config file Mar 31 19:11:00 volumio-ares sudo[5079]: pam_unix(sudo:session): session closed for user root Mar 31 19:11:00 volumio-ares mpd[5038]: output: Successfully detected a alsa audio device Mar 31 19:11:00 volumio-ares mpd[5038]: zeroconf: No global port, disabling zeroconf Mar 31 19:11:00 volumio-ares systemd[1]: Started Music Player Daemon. Mar 31 19:11:00 volumio-ares sudo[4997]: pam_unix(sudo:session): session closed for user root Mar 31 19:11:00 volumio-ares sudo[5033]: pam_unix(sudo:session): session closed for user root Mar 31 19:11:00 volumio-ares sudo[5093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 19:10 Mar 31 19:11:00 volumio-ares sudo[5093]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"