-- Logs begin at Thu 2024-02-22 07:42:47 +11, end at Sun 2024-03-31 17:42:18 +11. -- Mar 31 17:41:25 volumio volumio[758]: info: Selecting previously unselected package libpython3-dev:armhf. Mar 31 17:41:25 volumio volumio[758]: info: Selecting previously unselected package libpython3-dev:armhf. Mar 31 17:41:25 volumio volumio[758]: info: Preparing to unpack .../32-libpython3-dev_3.7.3-1_armhf.deb ... Mar 31 17:41:25 volumio volumio[758]: info: Preparing to unpack .../32-libpython3-dev_3.7.3-1_armhf.deb ... Mar 31 17:41:25 volumio volumio[758]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... Mar 31 17:41:25 volumio volumio[758]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... Mar 31 17:41:26 volumio volumio[758]: info: Selecting previously unselected package python-pip-whl. Mar 31 17:41:26 volumio volumio[758]: info: Selecting previously unselected package python-pip-whl. Mar 31 17:41:26 volumio volumio[758]: info: Preparing to unpack .../33-python-pip-whl_18.1-5+rpt1_all.deb ... Mar 31 17:41:26 volumio volumio[758]: info: Unpacking python-pip-whl (18.1-5+rpt1) ... Mar 31 17:41:26 volumio volumio[758]: info: Preparing to unpack .../33-python-pip-whl_18.1-5+rpt1_all.deb ... Mar 31 17:41:26 volumio volumio[758]: info: Unpacking python-pip-whl (18.1-5+rpt1) ... Mar 31 17:41:28 volumio volumio[758]: info: Selecting previously unselected package python3-multidict. Mar 31 17:41:28 volumio volumio[758]: info: Selecting previously unselected package python3-multidict. Mar 31 17:41:28 volumio volumio[758]: info: Preparing to unpack .../34-python3-multidict_4.5.2-1_armhf.deb ... Mar 31 17:41:28 volumio volumio[758]: info: Preparing to unpack .../34-python3-multidict_4.5.2-1_armhf.deb ... Mar 31 17:41:28 volumio volumio[758]: info: Unpacking python3-multidict (4.5.2-1) ... Mar 31 17:41:28 volumio volumio[758]: info: Unpacking python3-multidict (4.5.2-1) ... Mar 31 17:41:29 volumio volumio[758]: info: Selecting previously unselected package python3-yarl. Mar 31 17:41:29 volumio volumio[758]: info: Selecting previously unselected package python3-yarl. Mar 31 17:41:29 volumio volumio[758]: info: Preparing to unpack .../35-python3-yarl_1.3.0-1_armhf.deb ... Mar 31 17:41:29 volumio volumio[758]: info: Preparing to unpack .../35-python3-yarl_1.3.0-1_armhf.deb ... Mar 31 17:41:29 volumio volumio[758]: info: Unpacking python3-yarl (1.3.0-1) ... Mar 31 17:41:29 volumio volumio[758]: info: Unpacking python3-yarl (1.3.0-1) ... Mar 31 17:41:30 volumio volumio[758]: info: Selecting previously unselected package python3-async-timeout. Mar 31 17:41:30 volumio volumio[758]: info: Selecting previously unselected package python3-async-timeout. Mar 31 17:41:30 volumio volumio[758]: info: Preparing to unpack .../36-python3-async-timeout_3.0.1-1_all.deb ... Mar 31 17:41:30 volumio volumio[758]: info: Preparing to unpack .../36-python3-async-timeout_3.0.1-1_all.deb ... Mar 31 17:41:30 volumio volumio[758]: info: Unpacking python3-async-timeout (3.0.1-1) ... Mar 31 17:41:30 volumio volumio[758]: info: Unpacking python3-async-timeout (3.0.1-1) ... Mar 31 17:41:30 volumio volumio[758]: info: Selecting previously unselected package python3-attr. Mar 31 17:41:30 volumio volumio[758]: info: Selecting previously unselected package python3-attr. Mar 31 17:41:30 volumio volumio[758]: info: Preparing to unpack .../37-python3-attr_18.2.0-1_all.deb ... Mar 31 17:41:30 volumio volumio[758]: info: Preparing to unpack .../37-python3-attr_18.2.0-1_all.deb ... Mar 31 17:41:30 volumio volumio[758]: info: Unpacking python3-attr (18.2.0-1) ... Mar 31 17:41:30 volumio volumio[758]: info: Unpacking python3-attr (18.2.0-1) ... Mar 31 17:41:31 volumio volumio[758]: info: Selecting previously unselected package python3-aiohttp. Mar 31 17:41:31 volumio volumio[758]: info: Selecting previously unselected package python3-aiohttp. Mar 31 17:41:31 volumio volumio[758]: info: Preparing to unpack .../38-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... Mar 31 17:41:31 volumio volumio[758]: info: Preparing to unpack .../38-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... Mar 31 17:41:32 volumio volumio[758]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... Mar 31 17:41:32 volumio volumio[758]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... Mar 31 17:41:36 volumio volumio[758]: info: Selecting previously unselected package python3-asn1crypto. Mar 31 17:41:36 volumio volumio[758]: info: Selecting previously unselected package python3-asn1crypto. Mar 31 17:41:36 volumio volumio[758]: info: Preparing to unpack .../39-python3-asn1crypto_0.24.0-1_all.deb ... Mar 31 17:41:36 volumio volumio[758]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Mar 31 17:41:36 volumio volumio[758]: info: Preparing to unpack .../39-python3-asn1crypto_0.24.0-1_all.deb ... Mar 31 17:41:36 volumio volumio[758]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Mar 31 17:41:37 volumio volumio[758]: info: Selecting previously unselected package python3-cffi-backend. Mar 31 17:41:37 volumio volumio[758]: info: Selecting previously unselected package python3-cffi-backend. Mar 31 17:41:37 volumio volumio[758]: info: Preparing to unpack .../40-python3-cffi-backend_1.12.2-1_armhf.deb ... Mar 31 17:41:37 volumio volumio[758]: info: Preparing to unpack .../40-python3-cffi-backend_1.12.2-1_armhf.deb ... Mar 31 17:41:37 volumio volumio[758]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Mar 31 17:41:37 volumio volumio[758]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Mar 31 17:41:37 volumio volumio[758]: info: Selecting previously unselected package python3-crypto. Mar 31 17:41:37 volumio volumio[758]: info: Selecting previously unselected package python3-crypto. Mar 31 17:41:37 volumio volumio[758]: info: Preparing to unpack .../41-python3-crypto_2.6.1-9+b1_armhf.deb ... Mar 31 17:41:37 volumio volumio[758]: info: Preparing to unpack .../41-python3-crypto_2.6.1-9+b1_armhf.deb ... Mar 31 17:41:37 volumio volumio[758]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Mar 31 17:41:37 volumio volumio[758]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Mar 31 17:41:44 volumio ntpd[657]: Soliciting pool server 2404:2a00::30 Mar 31 17:41:45 volumio systemd[1]: Starting Cleanup of Temporary Directories... Mar 31 17:41:45 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 17:41:45 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Mar 31 17:41:45 volumio systemd[1]: Started Cleanup of Temporary Directories. Mar 31 17:41:51 volumio volumio[758]: info: Selecting previously unselected package python3-cryptography. Mar 31 17:41:51 volumio volumio[758]: info: Selecting previously unselected package python3-cryptography. Mar 31 17:41:51 volumio volumio[758]: info: Preparing to unpack .../42-python3-cryptography_2.6.1-3+deb10u4_armhf.deb ... Mar 31 17:41:51 volumio volumio[758]: info: Preparing to unpack .../42-python3-cryptography_2.6.1-3+deb10u4_armhf.deb ... Mar 31 17:41:51 volumio volumio[758]: info: Unpacking python3-cryptography (2.6.1-3+deb10u4) ... Mar 31 17:41:51 volumio volumio[758]: info: Unpacking python3-cryptography (2.6.1-3+deb10u4) ... Mar 31 17:41:58 volumio volumio[758]: info: Selecting previously unselected package python3-dbus. Mar 31 17:41:58 volumio volumio[758]: info: Selecting previously unselected package python3-dbus. Mar 31 17:41:58 volumio volumio[758]: info: Preparing to unpack .../43-python3-dbus_1.2.8-3_armhf.deb ... Mar 31 17:41:58 volumio volumio[758]: info: Unpacking python3-dbus (1.2.8-3) ... Mar 31 17:41:58 volumio volumio[758]: info: Preparing to unpack .../43-python3-dbus_1.2.8-3_armhf.deb ... Mar 31 17:41:58 volumio volumio[758]: info: Unpacking python3-dbus (1.2.8-3) ... Mar 31 17:41:59 volumio volumio[758]: info: Selecting previously unselected package python3.7-dev. Mar 31 17:41:59 volumio volumio[758]: info: Selecting previously unselected package python3.7-dev. Mar 31 17:41:59 volumio volumio[758]: info: Preparing to unpack .../44-python3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Mar 31 17:41:59 volumio volumio[758]: info: Unpacking python3.7-dev (3.7.3-2+deb10u7) ... Mar 31 17:41:59 volumio volumio[758]: info: Preparing to unpack .../44-python3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Mar 31 17:41:59 volumio volumio[758]: info: Unpacking python3.7-dev (3.7.3-2+deb10u7) ... Mar 31 17:41:59 volumio volumio[758]: info: Selecting previously unselected package python3-dev. Mar 31 17:41:59 volumio volumio[758]: info: Selecting previously unselected package python3-dev. Mar 31 17:41:59 volumio volumio[758]: info: Preparing to unpack .../45-python3-dev_3.7.3-1_armhf.deb ... Mar 31 17:41:59 volumio volumio[758]: info: Preparing to unpack .../45-python3-dev_3.7.3-1_armhf.deb ... Mar 31 17:41:59 volumio volumio[758]: info: Unpacking python3-dev (3.7.3-1) ... Mar 31 17:41:59 volumio volumio[758]: info: Unpacking python3-dev (3.7.3-1) ... Mar 31 17:41:59 volumio volumio[758]: info: Selecting previously unselected package python3-entrypoints. Mar 31 17:41:59 volumio volumio[758]: info: Selecting previously unselected package python3-entrypoints. Mar 31 17:41:59 volumio volumio[758]: info: Preparing to unpack .../46-python3-entrypoints_0.3-1_all.deb ... Mar 31 17:41:59 volumio volumio[758]: info: Unpacking python3-entrypoints (0.3-1) ... Mar 31 17:41:59 volumio volumio[758]: info: Preparing to unpack .../46-python3-entrypoints_0.3-1_all.deb ... Mar 31 17:41:59 volumio volumio[758]: info: Unpacking python3-entrypoints (0.3-1) ... Mar 31 17:41:59 volumio volumio[758]: info: Selecting previously unselected package python3-gi. Mar 31 17:41:59 volumio volumio[758]: info: Selecting previously unselected package python3-gi. Mar 31 17:41:59 volumio volumio[758]: info: Preparing to unpack .../47-python3-gi_3.30.4-1_armhf.deb ... Mar 31 17:41:59 volumio volumio[758]: info: Preparing to unpack .../47-python3-gi_3.30.4-1_armhf.deb ... Mar 31 17:42:00 volumio volumio[758]: info: Unpacking python3-gi (3.30.4-1) ... Mar 31 17:42:00 volumio volumio[758]: info: Unpacking python3-gi (3.30.4-1) ... Mar 31 17:42:01 volumio volumio[758]: info: Selecting previously unselected package python3-secretstorage. Mar 31 17:42:01 volumio volumio[758]: info: Selecting previously unselected package python3-secretstorage. Mar 31 17:42:01 volumio volumio[758]: info: Preparing to unpack .../48-python3-secretstorage_2.3.1-2_all.deb ... Mar 31 17:42:01 volumio volumio[758]: info: Preparing to unpack .../48-python3-secretstorage_2.3.1-2_all.deb ... Mar 31 17:42:01 volumio volumio[758]: info: Unpacking python3-secretstorage (2.3.1-2) ... Mar 31 17:42:01 volumio volumio[758]: info: Unpacking python3-secretstorage (2.3.1-2) ... Mar 31 17:42:02 volumio volumio[758]: info: Selecting previously unselected package python3-keyring. Mar 31 17:42:02 volumio volumio[758]: info: Selecting previously unselected package python3-keyring. Mar 31 17:42:02 volumio volumio[758]: info: Preparing to unpack .../49-python3-keyring_17.1.1-1_all.deb ... Mar 31 17:42:02 volumio volumio[758]: info: Preparing to unpack .../49-python3-keyring_17.1.1-1_all.deb ... Mar 31 17:42:02 volumio volumio[758]: info: Unpacking python3-keyring (17.1.1-1) ... Mar 31 17:42:02 volumio volumio[758]: info: Unpacking python3-keyring (17.1.1-1) ... Mar 31 17:42:03 volumio volumio[758]: info: Selecting previously unselected package python3-keyrings.alt. Mar 31 17:42:03 volumio volumio[758]: info: Selecting previously unselected package python3-keyrings.alt. Mar 31 17:42:03 volumio volumio[758]: info: Preparing to unpack .../50-python3-keyrings.alt_3.1.1-1_all.deb ... Mar 31 17:42:03 volumio volumio[758]: info: Preparing to unpack .../50-python3-keyrings.alt_3.1.1-1_all.deb ... Mar 31 17:42:03 volumio volumio[758]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... Mar 31 17:42:03 volumio volumio[758]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... Mar 31 17:42:03 volumio volumio[758]: info: Selecting previously unselected package python3-pip. Mar 31 17:42:03 volumio volumio[758]: info: Selecting previously unselected package python3-pip. Mar 31 17:42:03 volumio volumio[758]: info: Preparing to unpack .../51-python3-pip_18.1-5+rpt1_all.deb ... Mar 31 17:42:03 volumio volumio[758]: info: Preparing to unpack .../51-python3-pip_18.1-5+rpt1_all.deb ... Mar 31 17:42:03 volumio volumio[758]: info: Unpacking python3-pip (18.1-5+rpt1) ... Mar 31 17:42:03 volumio volumio[758]: info: Unpacking python3-pip (18.1-5+rpt1) ... Mar 31 17:42:06 volumio volumio[758]: info: Selecting previously unselected package python3-setuptools. Mar 31 17:42:06 volumio volumio[758]: info: Selecting previously unselected package python3-setuptools. Mar 31 17:42:06 volumio volumio[758]: info: Preparing to unpack .../52-python3-setuptools_40.8.0-1_all.deb ... Mar 31 17:42:06 volumio volumio[758]: info: Preparing to unpack .../52-python3-setuptools_40.8.0-1_all.deb ... Mar 31 17:42:07 volumio volumio[758]: info: Unpacking python3-setuptools (40.8.0-1) ... Mar 31 17:42:07 volumio volumio[758]: info: Unpacking python3-setuptools (40.8.0-1) ... Mar 31 17:42:09 volumio volumio[758]: info: Selecting previously unselected package python3-wheel. Mar 31 17:42:10 volumio volumio[758]: info: Selecting previously unselected package python3-wheel. Mar 31 17:42:10 volumio volumio[758]: info: Preparing to unpack .../53-python3-wheel_0.32.3-2_all.deb ... Mar 31 17:42:10 volumio volumio[758]: info: Preparing to unpack .../53-python3-wheel_0.32.3-2_all.deb ... Mar 31 17:42:10 volumio volumio[758]: info: Unpacking python3-wheel (0.32.3-2) ... Mar 31 17:42:10 volumio volumio[758]: info: Unpacking python3-wheel (0.32.3-2) ... Mar 31 17:42:11 volumio volumio[758]: info: Selecting previously unselected package python3-xdg. Mar 31 17:42:11 volumio volumio[758]: info: Selecting previously unselected package python3-xdg. Mar 31 17:42:11 volumio volumio[758]: info: Preparing to unpack .../54-python3-xdg_0.25-5_all.deb ... Mar 31 17:42:11 volumio volumio[758]: info: Preparing to unpack .../54-python3-xdg_0.25-5_all.deb ... Mar 31 17:42:11 volumio volumio[758]: info: Unpacking python3-xdg (0.25-5) ... Mar 31 17:42:11 volumio volumio[758]: info: Unpacking python3-xdg (0.25-5) ... Mar 31 17:42:13 volumio volumio[758]: info: Setting up python3-entrypoints (0.3-1) ... Mar 31 17:42:13 volumio volumio[758]: info: Setting up python3-entrypoints (0.3-1) ... Mar 31 17:42:15 volumio volumio[758]: info: Setting up dh-python (3.20190308) ... Mar 31 17:42:15 volumio volumio[758]: info: Setting up dh-python (3.20190308) ... Mar 31 17:42:15 volumio volumio[758]: info: Enabling plugin fusiondsp Mar 31 17:42:15 volumio volumio[758]: info: Loading plugin "fusiondsp"... Mar 31 17:42:15 volumio volumio[758]: info: Preparing to generate the ALSA configuration file Mar 31 17:42:15 volumio volumio[758]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 31 17:42:15 volumio volumio[758]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 31 17:42:15 volumio volumio[758]: info: Reading ALSA contributions from plugins. Mar 31 17:42:15 volumio volumio[758]: info: Asound.conf file written Mar 31 17:42:15 volumio sudo[4276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 31 17:42:15 volumio sudo[4276]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:15 volumio sudo[4276]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:15 volumio volumio[758]: xcb_connection_has_error() returned true Mar 31 17:42:15 volumio volumio[758]: No state is present for card b1 Mar 31 17:42:15 volumio volumio[758]: /usr/share/alsa/init/bcm2835:14: control element not found Mar 31 17:42:15 volumio volumio[758]: No state is present for card b1 Mar 31 17:42:15 volumio volumio[758]: No state is present for card Headphones Mar 31 17:42:15 volumio volumio[758]: /usr/share/alsa/init/bcm2835:20: control element not found Mar 31 17:42:15 volumio volumio[758]: No state is present for card Headphones Mar 31 17:42:15 volumio volumio[758]: No state is present for card sndallodigione Mar 31 17:42:15 volumio volumio[758]: Found hardware: "RPi-WM8804" "" "" "" "" Mar 31 17:42:15 volumio volumio[758]: Hardware is initialized using a generic method Mar 31 17:42:15 volumio volumio[758]: No state is present for card sndallodigione Mar 31 17:42:15 volumio volumio[758]: info: Output device has changed, restarting MPD Mar 31 17:42:15 volumio sudo[4284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 31 17:42:15 volumio sudo[4284]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:15 volumio volumio[758]: info: Output device has changed, restarting Shairport Sync Mar 31 17:42:15 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:15 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 17:42:15 volumio sudo[4284]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:15 volumio sudo[4287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 31 17:42:15 volumio sudo[4287]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:15 volumio systemd[1]: Stopping Music Player Daemon... Mar 31 17:42:15 volumio volumio[758]: xcb_connection_has_error() returned true Mar 31 17:42:15 volumio volumio[758]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 31 17:42:15 volumio volumio[758]: xcb_connection_has_error() returned true Mar 31 17:42:15 volumio volumio[758]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 31 17:42:15 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:15 volumio systemd[1]: mpd.service: Succeeded. Mar 31 17:42:15 volumio systemd[1]: Stopped Music Player Daemon. Mar 31 17:42:15 volumio vtcs[2778]: [2024-03-31 17:42:15.811] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 31 17:42:15 volumio vtcs[2778]: [2024-03-31 17:42:15.815] [tisoc] [error] [SpkconServer.cpp:382] recv error. client fd=8 errorno=104 error=Connection reset by peer Mar 31 17:42:15 volumio vtcs[2778]: [2024-03-31 17:42:15.815] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected Mar 31 17:42:15 volumio systemd[1]: Starting Music Player Daemon... Mar 31 17:42:15 volumio sudo[4296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 31 17:42:15 volumio volumio[758]: info: PLUGIN START: fusiondsp Mar 31 17:42:15 volumio volumio[758]: info: Loading i18n strings for locale fr Mar 31 17:42:15 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 31 17:42:15 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:15 volumio volumio[758]: info: FusionDsp - mixtype--------------------- Software Mar 31 17:42:15 volumio volumio[758]: info: Preparing to generate the ALSA configuration file Mar 31 17:42:15 volumio volumio[758]: info: Done. Mar 31 17:42:15 volumio sudo[4296]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:15 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Mar 31 17:42:15 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 31 17:42:15 volumio volumio[758]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 31 17:42:15 volumio volumio[758]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 31 17:42:15 volumio volumio[758]: info: Reading ALSA contributions from plugins. Mar 31 17:42:15 volumio systemd[1]: vtcs.service: Succeeded. Mar 31 17:42:15 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Mar 31 17:42:15 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 17:42:15 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 17:42:15 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 17:42:15 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 17:42:15 volumio sudo[4296]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:15 volumio volumio[758]: info: MPD Permissions set Mar 31 17:42:15 volumio sudo[4310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 31 17:42:15 volumio sudo[4310]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:15 volumio volumio[758]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 17:42:15 volumio volumio[758]: info: Setting up python3-attr (18.2.0-1) ... Mar 31 17:42:15 volumio sudo[4310]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:16 volumio volumio[758]: info: Setting up python3-attr (18.2.0-1) ... Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: Starting Shairport Sync Mar 31 17:42:16 volumio sudo[4322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 31 17:42:16 volumio sudo[4322]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 31 17:42:16 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 31 17:42:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 31 17:42:16 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 17:42:16 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 17:42:16 volumio volumio[758]: info: Asound.conf file unchanged, so no further update is needed Mar 31 17:42:16 volumio volumio[758]: info: Output device has changed, restarting MPD Mar 31 17:42:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 31 17:42:16 volumio sudo[4322]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:16 volumio volumio[758]: info: Output device has changed, restarting Shairport Sync Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 17:42:16 volumio sudo[4327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 31 17:42:16 volumio sudo[4327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:16 volumio sudo[4330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 31 17:42:16 volumio sudo[4330]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:16 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 31 17:42:16 volumio systemd[1]: mpd.service: Succeeded. Mar 31 17:42:16 volumio systemd[1]: Stopped Music Player Daemon. Mar 31 17:42:16 volumio systemd[1]: Starting Music Player Daemon... Mar 31 17:42:16 volumio volumio[758]: xcb_connection_has_error() returned true Mar 31 17:42:16 volumio volumio[758]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 31 17:42:16 volumio volumio[758]: xcb_connection_has_error() returned true Mar 31 17:42:16 volumio volumio[758]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio sudo[4342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 31 17:42:16 volumio sudo[4342]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:16 volumio sudo[4344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 31 17:42:16 volumio sudo[4344]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:16 volumio sudo[4327]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:16 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 17:42:16 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 17:42:16 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 17:42:16 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 17:42:16 volumio volumio[758]: info: Shairport-Sync Started Mar 31 17:42:16 volumio sudo[4344]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:16 volumio sudo[4342]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:16 volumio volumio[758]: info: MPD Permissions set Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:16 volumio volumio[758]: info: Setting up python3-dbus (1.2.8-3) ... Mar 31 17:42:16 volumio volumio[758]: info: Setting up python3-dbus (1.2.8-3) ... Mar 31 17:42:16 volumio volumio[758]: info: Starting Shairport Sync Mar 31 17:42:16 volumio sudo[4379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 31 17:42:16 volumio sudo[4379]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 31 17:42:16 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 31 17:42:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 31 17:42:16 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 31 17:42:16 volumio volumio[758]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 31 17:42:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 31 17:42:16 volumio sudo[4379]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:16 volumio volumio[758]: info: Shairport-Sync Started Mar 31 17:42:17 volumio mpd[4338]: Mar 31 17:42 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 31 17:42:17 volumio systemd[1]: Started Music Player Daemon. Mar 31 17:42:17 volumio sudo[4287]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:17 volumio sudo[4330]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:17 volumio volumio[758]: error: MPD error: The expression evaluated to a falsy value: Mar 31 17:42:17 volumio volumio[758]: assert.ok(self.idling) Mar 31 17:42:17 volumio volumio[758]: error: The expression evaluated to a falsy value: Mar 31 17:42:17 volumio volumio[758]: assert.ok(self.idling) Mar 31 17:42:17 volumio volumio[758]: error: updateQueue error: null Mar 31 17:42:17 volumio volumio[758]: info: Setting up python3-setuptools (40.8.0-1) ... Mar 31 17:42:17 volumio volumio[758]: info: Setting up python3-setuptools (40.8.0-1) ... Mar 31 17:42:17 volumio volumio[758]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Mar 31 17:42:17 volumio volumio[758]: info: camilladsp service started and running in background, instance 1 Mar 31 17:42:17 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 17:42:17 volumio volumio[758]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Mar 31 17:42:17 volumio volumio[758]: 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 17:42:17 volumio volumio[758]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Mar 31 17:42:17 volumio volumio[758]: info: FusionDsp loaded Mar 31 17:42:17 volumio volumio[758]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 17:42:17 volumio sudo[4444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 31 17:42:17 volumio sudo[4444]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 17:42:17 volumio volumio[758]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 31 17:42:17 volumio volumio[758]: info: Adding Signal Path Element [object Object] Mar 31 17:42:17 volumio volumio[758]: info: Adding fusiondspeq DSP Signal Path Element Mar 31 17:42:17 volumio volumio[758]: info: FusionDsp - ---- installed callbackRead Mar 31 17:42:17 volumio volumio[758]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 17:42:17 volumio volumio[758]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Mar 31 17:42:17 volumio volumio[758]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Mar 31 17:42:17 volumio volumio[758]: at onErrorNT (internal/child_process.js:465:16) Mar 31 17:42:17 volumio volumio[758]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Mar 31 17:42:17 volumio volumio[758]: errno: -2, Mar 31 17:42:17 volumio volumio[758]: code: 'ENOENT', Mar 31 17:42:17 volumio volumio[758]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Mar 31 17:42:17 volumio volumio[758]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Mar 31 17:42:17 volumio volumio[758]: spawnargs: [ Mar 31 17:42:17 volumio volumio[758]: '-p', Mar 31 17:42:17 volumio volumio[758]: 9876, Mar 31 17:42:17 volumio volumio[758]: '-o', Mar 31 17:42:17 volumio volumio[758]: '/tmp/camilladsp.log', Mar 31 17:42:17 volumio volumio[758]: '-l', Mar 31 17:42:17 volumio volumio[758]: 'warn', Mar 31 17:42:17 volumio volumio[758]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Mar 31 17:42:17 volumio volumio[758]: ] Mar 31 17:42:17 volumio volumio[758]: } Mar 31 17:42:17 volumio volumio[758]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 17:42:17 volumio sudo[4444]: pam_unix(sudo:session): session closed for user root Mar 31 17:42:18 volumio sudo[4456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 17:41 Mar 31 17:42:18 volumio sudo[4456]: 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"