-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2025-07-04 11:46:44 CEST. -- Jul 04 11:45:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:45:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:45:01 volumio volumio[1064]: info: Discovery: Getting this device information Jul 04 11:45:01 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:45:03 volumio volumio[1064]: info: Selecting previously unselected package libpython3-dev:armhf. Jul 04 11:45:03 volumio volumio[1064]: info: Preparing to unpack .../28-libpython3-dev_3.7.3-1_armhf.deb ... Jul 04 11:45:03 volumio volumio[1064]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... Jul 04 11:45:03 volumio volumio[1064]: info: Selecting previously unselected package python-pip-whl. Jul 04 11:45:03 volumio volumio[1064]: info: Preparing to unpack .../29-python-pip-whl_18.1-5+rpt1_all.deb ... Jul 04 11:45:03 volumio volumio[1064]: info: Unpacking python-pip-whl (18.1-5+rpt1) ... Jul 04 11:45:04 volumio volumio[1064]: info: Selecting previously unselected package python3-multidict. Jul 04 11:45:04 volumio volumio[1064]: info: Preparing to unpack .../30-python3-multidict_4.5.2-1_armhf.deb ... Jul 04 11:45:04 volumio volumio[1064]: info: Unpacking python3-multidict (4.5.2-1) ... Jul 04 11:45:04 volumio volumio[1064]: info: Selecting previously unselected package python3-yarl. Jul 04 11:45:04 volumio volumio[1064]: info: Preparing to unpack .../31-python3-yarl_1.3.0-1_armhf.deb ... Jul 04 11:45:04 volumio volumio[1064]: info: Unpacking python3-yarl (1.3.0-1) ... Jul 04 11:45:04 volumio volumio[1064]: info: Selecting previously unselected package python3-async-timeout. Jul 04 11:45:04 volumio volumio[1064]: info: Preparing to unpack .../32-python3-async-timeout_3.0.1-1_all.deb ... Jul 04 11:45:04 volumio volumio[1064]: info: Unpacking python3-async-timeout (3.0.1-1) ... Jul 04 11:45:04 volumio volumio[1064]: info: Selecting previously unselected package python3-attr. Jul 04 11:45:04 volumio volumio[1064]: info: Preparing to unpack .../33-python3-attr_18.2.0-1_all.deb ... Jul 04 11:45:04 volumio volumio[1064]: info: Unpacking python3-attr (18.2.0-1) ... Jul 04 11:45:05 volumio volumio[1064]: info: Selecting previously unselected package python3-aiohttp. Jul 04 11:45:05 volumio volumio[1064]: info: Preparing to unpack .../34-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... Jul 04 11:45:05 volumio volumio[1064]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... Jul 04 11:45:05 volumio qobuz-connect[1518]: 20250704 11:45:05.576 [1518.1518] INFO ProtocolHandler: [0xd35a40]: Need to send state update Jul 04 11:45:05 volumio qobuz-connect[1518]: 20250704 11:45:05.576 [1518.1518] INFO QConnectMessageSender: [0xd754c8]: Sending StateUpdated message Jul 04 11:45:05 volumio qobuz-connect[1518]: 20250704 11:45:05.576 [1518.1518] INFO QwspMessageSender: [0xe3fa30]: Sending Payload message Jul 04 11:45:05 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:05 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 04 11:45:05 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 04 11:45:05 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:45:05 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 04 11:45:05 volumio volumio[1064]: info: MRS: Pushing multiroomSync output update for this device Jul 04 11:45:05 volumio volumio[1064]: info: MRS: Pushing multiroomSync output Jul 04 11:45:05 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:05 volumio volumio[1064]: info: Selecting previously unselected package python3-asn1crypto. Jul 04 11:45:05 volumio volumio[1064]: info: Preparing to unpack .../35-python3-asn1crypto_0.24.0-1_all.deb ... Jul 04 11:45:05 volumio volumio[1064]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Jul 04 11:45:05 volumio volumio[1064]: info: Selecting previously unselected package python3-cffi-backend. Jul 04 11:45:05 volumio volumio[1064]: info: Preparing to unpack .../36-python3-cffi-backend_1.12.2-1_armhf.deb ... Jul 04 11:45:05 volumio volumio[1064]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Jul 04 11:45:06 volumio volumio[1064]: info: Selecting previously unselected package python3-crypto. Jul 04 11:45:06 volumio volumio[1064]: info: Preparing to unpack .../37-python3-crypto_2.6.1-9+b1_armhf.deb ... Jul 04 11:45:06 volumio volumio[1064]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Jul 04 11:45:07 volumio volumio[1064]: info: Selecting previously unselected package python3-cryptography. Jul 04 11:45:07 volumio volumio[1064]: info: Preparing to unpack .../38-python3-cryptography_2.6.1-3+deb10u4_armhf.deb ... Jul 04 11:45:07 volumio volumio[1064]: info: Unpacking python3-cryptography (2.6.1-3+deb10u4) ... Jul 04 11:45:07 volumio volumio[1064]: info: Selecting previously unselected package python3-dbus. Jul 04 11:45:07 volumio volumio[1064]: info: Preparing to unpack .../39-python3-dbus_1.2.8-3_armhf.deb ... Jul 04 11:45:07 volumio volumio[1064]: info: Unpacking python3-dbus (1.2.8-3) ... Jul 04 11:45:08 volumio volumio[1064]: info: Selecting previously unselected package python3.7-dev. Jul 04 11:45:08 volumio volumio[1064]: info: Preparing to unpack .../40-python3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Jul 04 11:45:08 volumio volumio[1064]: info: Unpacking python3.7-dev (3.7.3-2+deb10u7) ... Jul 04 11:45:08 volumio volumio[1064]: info: Selecting previously unselected package python3-dev. Jul 04 11:45:08 volumio volumio[1064]: info: Preparing to unpack .../41-python3-dev_3.7.3-1_armhf.deb ... Jul 04 11:45:08 volumio volumio[1064]: info: Unpacking python3-dev (3.7.3-1) ... Jul 04 11:45:08 volumio volumio[1064]: info: Selecting previously unselected package python3-entrypoints. Jul 04 11:45:08 volumio volumio[1064]: info: Preparing to unpack .../42-python3-entrypoints_0.3-1_all.deb ... Jul 04 11:45:08 volumio volumio[1064]: info: Unpacking python3-entrypoints (0.3-1) ... Jul 04 11:45:08 volumio volumio[1064]: info: Selecting previously unselected package python3-gi. Jul 04 11:45:08 volumio volumio[1064]: info: Preparing to unpack .../43-python3-gi_3.30.4-1_armhf.deb ... Jul 04 11:45:08 volumio volumio[1064]: info: Unpacking python3-gi (3.30.4-1) ... Jul 04 11:45:08 volumio volumio[1064]: info: Selecting previously unselected package python3-secretstorage. Jul 04 11:45:08 volumio volumio[1064]: info: Preparing to unpack .../44-python3-secretstorage_2.3.1-2_all.deb ... Jul 04 11:45:08 volumio volumio[1064]: info: Unpacking python3-secretstorage (2.3.1-2) ... Jul 04 11:45:08 volumio volumio[1064]: info: Selecting previously unselected package python3-keyring. Jul 04 11:45:08 volumio volumio[1064]: info: Preparing to unpack .../45-python3-keyring_17.1.1-1_all.deb ... Jul 04 11:45:08 volumio volumio[1064]: info: Unpacking python3-keyring (17.1.1-1) ... Jul 04 11:45:09 volumio volumio[1064]: info: Selecting previously unselected package python3-keyrings.alt. Jul 04 11:45:09 volumio volumio[1064]: info: Preparing to unpack .../46-python3-keyrings.alt_3.1.1-1_all.deb ... Jul 04 11:45:09 volumio volumio[1064]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... Jul 04 11:45:09 volumio volumio[1064]: info: Selecting previously unselected package python3-pip. Jul 04 11:45:09 volumio volumio[1064]: info: Preparing to unpack .../47-python3-pip_18.1-5+rpt1_all.deb ... Jul 04 11:45:09 volumio volumio[1064]: info: Unpacking python3-pip (18.1-5+rpt1) ... Jul 04 11:45:10 volumio volumio[1064]: info: Selecting previously unselected package python3-setuptools. Jul 04 11:45:10 volumio volumio[1064]: info: Preparing to unpack .../48-python3-setuptools_40.8.0-1_all.deb ... Jul 04 11:45:10 volumio volumio[1064]: info: Unpacking python3-setuptools (40.8.0-1) ... Jul 04 11:45:10 volumio volumio[1064]: info: Selecting previously unselected package python3-wheel. Jul 04 11:45:10 volumio volumio[1064]: info: Preparing to unpack .../49-python3-wheel_0.32.3-2_all.deb ... Jul 04 11:45:10 volumio volumio[1064]: info: Unpacking python3-wheel (0.32.3-2) ... Jul 04 11:45:10 volumio volumio[1064]: info: Selecting previously unselected package python3-xdg. Jul 04 11:45:10 volumio volumio[1064]: info: Preparing to unpack .../50-python3-xdg_0.25-5_all.deb ... Jul 04 11:45:11 volumio volumio[1064]: info: Unpacking python3-xdg (0.25-5) ... Jul 04 11:45:11 volumio volumio[1064]: info: Setting up python3-entrypoints (0.3-1) ... Jul 04 11:45:11 volumio volumio[1064]: info: Setting up dh-python (3.20190308) ... Jul 04 11:45:12 volumio volumio[1064]: info: Setting up python3-attr (18.2.0-1) ... Jul 04 11:45:12 volumio volumio[1064]: info: Setting up python3-dbus (1.2.8-3) ... Jul 04 11:45:13 volumio volumio[1064]: info: Setting up python3-setuptools (40.8.0-1) ... Jul 04 11:45:13 volumio volumio[1064]: info: Tunnel connection is inactive, restarting it Jul 04 11:45:13 volumio volumio[1064]: info: Starting Tunnel 1 Jul 04 11:45:13 volumio volumio[1064]: info: Starting Tunnel Connection Checker Jul 04 11:45:13 volumio sudo[2676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jul 04 11:45:13 volumio sudo[2676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:45:13 volumio autossh[1370]: received signal to exit (15) Jul 04 11:45:13 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Jul 04 11:45:13 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Jul 04 11:45:13 volumio systemd[1]: sshtunnel.service: Succeeded. Jul 04 11:45:13 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jul 04 11:45:13 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:45:13 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 11:45:13 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jul 04 11:45:13 volumio sudo[2676]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:13 volumio volumio[1064]: info: Remote SSH Started Jul 04 11:45:13 volumio autossh[2683]: port set to 0, monitoring disabled Jul 04 11:45:13 volumio autossh[2683]: starting ssh (count 1) Jul 04 11:45:13 volumio autossh[2683]: ssh child pid is 2686 Jul 04 11:45:13 volumio volumio[1064]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 04 11:45:13 volumio volumiossh-tunnel[2680]: ssh: connect to host eu4.myvolumio.org port 2222: No route to host Jul 04 11:45:13 volumio autossh[2683]: ssh exited prematurely with status 255; autossh exiting Jul 04 11:45:13 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:45:13 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jul 04 11:45:13 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:13 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jul 04 11:45:13 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jul 04 11:45:13 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jul 04 11:45:13 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jul 04 11:45:14 volumio autossh[2688]: port set to 0, monitoring disabled Jul 04 11:45:14 volumio autossh[2688]: starting ssh (count 1) Jul 04 11:45:14 volumio autossh[2688]: ssh child pid is 2691 Jul 04 11:45:14 volumio volumiossh-tunnel[2687]: ssh: connect to host eu4.myvolumio.org port 2222: No route to host Jul 04 11:45:14 volumio autossh[2688]: ssh exited prematurely with status 255; autossh exiting Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jul 04 11:45:14 volumio volumio[1064]: info: Setting up libpython3.7:armhf (3.7.3-2+deb10u7) ... Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jul 04 11:45:14 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jul 04 11:45:14 volumio volumio[1064]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ... Jul 04 11:45:14 volumio volumio[1064]: info: Setting up python3-xdg (0.25-5) ... Jul 04 11:45:14 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jul 04 11:45:14 volumio autossh[2708]: port set to 0, monitoring disabled Jul 04 11:45:14 volumio autossh[2708]: starting ssh (count 1) Jul 04 11:45:14 volumio autossh[2708]: ssh child pid is 2712 Jul 04 11:45:14 volumio volumiossh-tunnel[2693]: ssh: connect to host eu4.myvolumio.org port 2222: No route to host Jul 04 11:45:14 volumio autossh[2708]: ssh exited prematurely with status 255; autossh exiting Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jul 04 11:45:14 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jul 04 11:45:14 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jul 04 11:45:14 volumio autossh[2722]: port set to 0, monitoring disabled Jul 04 11:45:14 volumio autossh[2722]: starting ssh (count 1) Jul 04 11:45:14 volumio autossh[2722]: ssh child pid is 2725 Jul 04 11:45:14 volumio volumiossh-tunnel[2719]: ssh: connect to host eu4.myvolumio.org port 2222: No route to host Jul 04 11:45:14 volumio autossh[2722]: ssh exited prematurely with status 255; autossh exiting Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Jul 04 11:45:14 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jul 04 11:45:14 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jul 04 11:45:14 volumio autossh[2730]: port set to 0, monitoring disabled Jul 04 11:45:14 volumio autossh[2730]: starting ssh (count 1) Jul 04 11:45:14 volumio autossh[2730]: ssh child pid is 2733 Jul 04 11:45:14 volumio volumio[1064]: info: Setting up bzip2 (1.0.6-9.2~deb10u2) ... Jul 04 11:45:14 volumio volumiossh-tunnel[2728]: ssh: connect to host eu4.myvolumio.org port 2222: No route to host Jul 04 11:45:14 volumio autossh[2730]: ssh exited prematurely with status 255; autossh exiting Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jul 04 11:45:14 volumio volumio[1064]: info: Setting up python3-wheel (0.32.3-2) ... Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jul 04 11:45:14 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Start request repeated too quickly. Jul 04 11:45:14 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jul 04 11:45:14 volumio systemd[1]: Failed to start MyVolumio SSH Tunnel. Jul 04 11:45:15 volumio volumio[1064]: info: Setting up python3-multidict (4.5.2-1) ... Jul 04 11:45:15 volumio qobuz-connect[1518]: 20250704 11:45:15.585 [1518.1518] INFO ProtocolHandler: [0xd35a40]: Need to send state update Jul 04 11:45:15 volumio qobuz-connect[1518]: 20250704 11:45:15.585 [1518.1518] INFO QConnectMessageSender: [0xd754c8]: Sending StateUpdated message Jul 04 11:45:15 volumio qobuz-connect[1518]: 20250704 11:45:15.585 [1518.1518] INFO QwspMessageSender: [0xe3fa30]: Sending Payload message Jul 04 11:45:15 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:15 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 04 11:45:15 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 04 11:45:15 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:45:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 04 11:45:15 volumio volumio[1064]: info: MRS: Pushing multiroomSync output update for this device Jul 04 11:45:15 volumio volumio[1064]: info: MRS: Pushing multiroomSync output Jul 04 11:45:15 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:15 volumio volumio[1064]: info: Setting up libfakeroot:armhf (1.23-1) ... Jul 04 11:45:15 volumio volumio[1064]: info: Setting up python3-async-timeout (3.0.1-1) ... Jul 04 11:45:16 volumio volumio[1064]: info: Setting up fakeroot (1.23-1) ... Jul 04 11:45:16 volumio volumio[1064]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode Jul 04 11:45:16 volumio volumio[1064]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/fakeroot.1.gz because associated file /usr/share/man/es/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Jul 04 11:45:16 volumio volumio[1064]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/faked.1.gz because associated file /usr/share/man/es/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Jul 04 11:45:16 volumio volumio[1064]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/fakeroot.1.gz because associated file /usr/share/man/fr/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Jul 04 11:45:16 volumio volumio[1064]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/faked.1.gz because associated file /usr/share/man/fr/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Jul 04 11:45:16 volumio volumio[1064]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/fakeroot.1.gz because associated file /usr/share/man/sv/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Jul 04 11:45:16 volumio volumio[1064]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/faked.1.gz because associated file /usr/share/man/sv/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Jul 04 11:45:16 volumio volumio[1064]: info: Setting up libasan5:armhf (8.3.0-6+rpi1) ... Jul 04 11:45:16 volumio volumio[1064]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u7) ... Jul 04 11:45:16 volumio volumio[1064]: info: Setting up make (4.2.1-1.2) ... Jul 04 11:45:16 volumio volumio[1064]: info: Setting up libmpfr6:armhf (4.0.2-1) ... Jul 04 11:45:16 volumio volumio[1064]: info: Setting up python3-gi (3.30.4-1) ... Jul 04 11:45:16 volumio volumio[1064]: info: Setting up libmpc3:armhf (1.1.0-1) ... Jul 04 11:45:16 volumio volumio[1064]: info: Setting up patch (2.7.6-3+deb10u1) ... Jul 04 11:45:16 volumio volumio[1064]: info: Setting up libubsan1:armhf (8.3.0-6+rpi1) ... Jul 04 11:45:17 volumio volumio[1064]: info: Setting up libisl19:armhf (0.20-2) ... Jul 04 11:45:17 volumio volumio[1064]: info: Setting up python3-crypto (2.6.1-9+b1) ... Jul 04 11:45:17 volumio volumio[1064]: info: Setting up python-pip-whl (18.1-5+rpt1) ... Jul 04 11:45:17 volumio volumio[1064]: info: Setting up cpp-8 (8.3.0-6+rpi1) ... Jul 04 11:45:17 volumio volumio[1064]: info: Setting up python3-yarl (1.3.0-1) ... Jul 04 11:45:18 volumio volumio[1064]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... Jul 04 11:45:18 volumio volumio[1064]: info: Setting up python3-asn1crypto (0.24.0-1) ... Jul 04 11:45:18 volumio volumio[1064]: info: Setting up libcc1-0:armhf (8.3.0-6+rpi1) ... Jul 04 11:45:18 volumio volumio[1064]: info: Setting up python3-cffi-backend (1.12.2-1) ... Jul 04 11:45:19 volumio volumio[1064]: info: Setting up libalgorithm-merge-perl (0.08-3) ... Jul 04 11:45:19 volumio volumio[1064]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... Jul 04 11:45:19 volumio volumio[1064]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ... Jul 04 11:45:19 volumio volumio[1064]: info: Setting up python3.7-dev (3.7.3-2+deb10u7) ... Jul 04 11:45:19 volumio volumio[1064]: info: Setting up dpkg-dev (1.19.8) ... Jul 04 11:45:19 volumio volumio[1064]: info: Setting up python3-cryptography (2.6.1-3+deb10u4) ... Jul 04 11:45:20 volumio volumio[1064]: info: Setting up python3-pip (18.1-5+rpt1) ... Jul 04 11:45:21 volumio volumio[1064]: info: Setting up libgcc-8-dev:armhf (8.3.0-6+rpi1) ... Jul 04 11:45:21 volumio volumio[1064]: info: Setting up cpp (4:8.3.0-1+rpi2) ... Jul 04 11:45:21 volumio volumio[1064]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Jul 04 11:45:21 volumio volumio[1064]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... Jul 04 11:45:21 volumio volumio[1064]: info: Setting up gcc-8 (8.3.0-6+rpi1) ... Jul 04 11:45:21 volumio volumio[1064]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Jul 04 11:45:21 volumio volumio[1064]: info: Setting up gcc (4:8.3.0-1+rpi2) ... Jul 04 11:45:22 volumio volumio[1064]: info: Setting up python3-secretstorage (2.3.1-2) ... Jul 04 11:45:22 volumio volumio[1064]: info: Setting up python3-dev (3.7.3-1) ... Jul 04 11:45:22 volumio volumio[1064]: info: Setting up g++-8 (8.3.0-6+rpi1) ... Jul 04 11:45:22 volumio volumio[1064]: info: Setting up python3-keyring (17.1.1-1) ... Jul 04 11:45:22 volumio volumio[1064]: info: Setting up g++ (4:8.3.0-1+rpi2) ... Jul 04 11:45:23 volumio volumio[1064]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode Jul 04 11:45:23 volumio volumio[1064]: info: Setting up build-essential (12.6) ... Jul 04 11:45:23 volumio volumio[1064]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Jul 04 11:45:24 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:24 volumio sudo[2983]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jul 04 11:45:24 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:45:24 volumio volumio[1064]: info: lib/systemd/system/fusiondsp.service Jul 04 11:45:24 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:24 volumio volumio[1064]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jul 04 11:45:24 volumio volumio[1064]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jul 04 11:45:24 volumio volumio[1064]: info: Jul 04 11:45:24 volumio volumio[1064]: info: Cannot open cgui-1.0.0.zip or cgui-1.0.0.zip.zip Jul 04 11:45:24 volumio sudo[2987]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jul 04 11:45:24 volumio sudo[2987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:45:24 volumio sudo[2987]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:24 volumio sudo[2989]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jul 04 11:45:24 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:45:24 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:24 volumio sudo[2991]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jul 04 11:45:24 volumio sudo[2991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:45:24 volumio sudo[2991]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:25 volumio qobuz-connect[1518]: 20250704 11:45:25.585 [1518.1518] INFO ProtocolHandler: [0xd35a40]: Need to send state update Jul 04 11:45:25 volumio qobuz-connect[1518]: 20250704 11:45:25.586 [1518.1518] INFO QConnectMessageSender: [0xd754c8]: Sending StateUpdated message Jul 04 11:45:25 volumio qobuz-connect[1518]: 20250704 11:45:25.586 [1518.1518] INFO QwspMessageSender: [0xe3fa30]: Sending Payload message Jul 04 11:45:25 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:25 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 04 11:45:25 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 04 11:45:25 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:45:25 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 04 11:45:25 volumio volumio[1064]: info: MRS: Pushing multiroomSync output update for this device Jul 04 11:45:25 volumio volumio[1064]: info: MRS: Pushing multiroomSync output Jul 04 11:45:25 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:28 volumio volumio[1064]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Jul 04 11:45:28 volumio volumio[1064]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Jul 04 11:45:28 volumio volumio[1064]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-8yzwhsgp Jul 04 11:45:30 volumio volumio[1064]: info: Collecting PyYAML (from camilladsp==1.0.0) Jul 04 11:45:31 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:45:31 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:45:31 volumio volumio[1064]: info: Discovery: Getting this device information Jul 04 11:45:31 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:31 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:45:35 volumio qobuz-connect[1518]: 20250704 11:45:35.585 [1518.1518] INFO ProtocolHandler: [0xd35a40]: Need to send state update Jul 04 11:45:35 volumio qobuz-connect[1518]: 20250704 11:45:35.585 [1518.1518] INFO QConnectMessageSender: [0xd754c8]: Sending StateUpdated message Jul 04 11:45:35 volumio qobuz-connect[1518]: 20250704 11:45:35.585 [1518.1518] INFO QwspMessageSender: [0xe3fa30]: Sending Payload message Jul 04 11:45:35 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:35 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 04 11:45:35 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 04 11:45:35 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:45:35 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 04 11:45:35 volumio volumio[1064]: info: MRS: Pushing multiroomSync output update for this device Jul 04 11:45:35 volumio volumio[1064]: info: MRS: Pushing multiroomSync output Jul 04 11:45:35 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:37 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:37 volumio sudo[3033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 11:45:37 volumio sudo[3033]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:45:37 volumio sudo[3033]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:37 volumio sudo[3036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 11:45:37 volumio sudo[3036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:45:37 volumio sudo[3036]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:37 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 04 11:45:38 volumio sudo[3039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 11:45:38 volumio sudo[3039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:45:38 volumio sudo[3039]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:38 volumio sudo[3042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 11:45:38 volumio sudo[3042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:45:38 volumio sudo[3042]: pam_unix(sudo:session): session closed for user root Jul 04 11:45:38 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::volumioGetVisibleSources Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 04 11:45:38 volumio volumio[1064]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 04 11:45:38 volumio volumio[1064]: info: Received Get System Info Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:45:38 volumio volumio[1064]: info: Discovery: Getting this device information Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:38 volumio volumio[1064]: info: Listing playlists Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 11:45:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 04 11:45:39 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 04 11:45:40 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 11:45:40 volumio volumio[1064]: info: Received Get System Info Jul 04 11:45:40 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:45:40 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:45:40 volumio volumio[1064]: info: Discovery: Getting this device information Jul 04 11:45:40 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:40 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:45:40 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 11:45:40 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 04 11:45:41 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 11:45:41 volumio volumio[1064]: info: Received Get System Info Jul 04 11:45:41 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:45:41 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:45:41 volumio volumio[1064]: info: Discovery: Getting this device information Jul 04 11:45:41 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:41 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:45:42 volumio volumio[1064]: info: CoreCommandRouter::Close All Modals sent Jul 04 11:45:45 volumio qobuz-connect[1518]: 20250704 11:45:45.586 [1518.1518] INFO ProtocolHandler: [0xd35a40]: Need to send state update Jul 04 11:45:45 volumio qobuz-connect[1518]: 20250704 11:45:45.586 [1518.1518] INFO QConnectMessageSender: [0xd754c8]: Sending StateUpdated message Jul 04 11:45:45 volumio qobuz-connect[1518]: 20250704 11:45:45.586 [1518.1518] INFO QwspMessageSender: [0xe3fa30]: Sending Payload message Jul 04 11:45:45 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:45 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 04 11:45:45 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 04 11:45:45 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:45:45 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 04 11:45:45 volumio volumio[1064]: info: MRS: Pushing multiroomSync output update for this device Jul 04 11:45:45 volumio volumio[1064]: info: MRS: Pushing multiroomSync output Jul 04 11:45:45 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:46 volumio volumio[1064]: info: Downloading https://files.pythonhosted.org/packages/cd/e5/af35f7ea75cf72f2cd079c95ee16797de7cd71f29ea7c68ae5ce7be1eda0/PyYAML-6.0.1.tar.gz (125kB) Jul 04 11:45:47 volumio volumio[1064]: info: Installing build dependencies: started Jul 04 11:45:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 11:45:47 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 04 11:45:48 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 04 11:45:48 volumio volumio[1064]: info: CoreCommandRouter::Close All Modals sent Jul 04 11:45:55 volumio qobuz-connect[1518]: 20250704 11:45:55.587 [1518.1518] INFO ProtocolHandler: [0xd35a40]: Need to send state update Jul 04 11:45:55 volumio qobuz-connect[1518]: 20250704 11:45:55.587 [1518.1518] INFO QConnectMessageSender: [0xd754c8]: Sending StateUpdated message Jul 04 11:45:55 volumio qobuz-connect[1518]: 20250704 11:45:55.587 [1518.1518] INFO QwspMessageSender: [0xe3fa30]: Sending Payload message Jul 04 11:45:55 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:55 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 04 11:45:55 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 04 11:45:55 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:45:55 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 04 11:45:55 volumio volumio[1064]: info: MRS: Pushing multiroomSync output update for this device Jul 04 11:45:55 volumio volumio[1064]: info: MRS: Pushing multiroomSync output Jul 04 11:45:55 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:56 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 11:45:56 volumio volumio[1064]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 04 11:45:56 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 04 11:45:56 volumio volumio[1064]: info: Received Get System Version Jul 04 11:45:56 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:45:56 volumio volumio[1064]: info: Received Get System Info Jul 04 11:45:56 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:45:56 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:45:56 volumio volumio[1064]: info: Discovery: Getting this device information Jul 04 11:45:56 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:45:56 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:45:56 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 11:45:56 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 04 11:46:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:46:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:46:01 volumio volumio[1064]: info: Discovery: Getting this device information Jul 04 11:46:01 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:05 volumio qobuz-connect[1518]: 20250704 11:46:05.588 [1518.1518] INFO ProtocolHandler: [0xd35a40]: Need to send state update Jul 04 11:46:05 volumio qobuz-connect[1518]: 20250704 11:46:05.588 [1518.1518] INFO QConnectMessageSender: [0xd754c8]: Sending StateUpdated message Jul 04 11:46:05 volumio qobuz-connect[1518]: 20250704 11:46:05.588 [1518.1518] INFO QwspMessageSender: [0xe3fa30]: Sending Payload message Jul 04 11:46:05 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:05 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Jul 04 11:46:05 volumio volumio[1064]: info: CoreStateMachine::pushState Jul 04 11:46:05 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:46:05 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:05 volumio volumio[1064]: info: MRS: Pushing multiroomSync output update for this device Jul 04 11:46:05 volumio volumio[1064]: info: MRS: Pushing multiroomSync output Jul 04 11:46:05 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:06 volumio volumio[1064]: info: Enabling plugin fusiondsp Jul 04 11:46:06 volumio volumio[1064]: info: Loading plugin "fusiondsp"... Jul 04 11:46:06 volumio volumio[1064]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:06 volumio volumio[1064]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 04 11:46:06 volumio volumio[1064]: info: Reading ALSA contributions from plugins. Jul 04 11:46:06 volumio volumio[1064]: info: Asound.conf file written Jul 04 11:46:06 volumio sudo[3099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 04 11:46:06 volumio sudo[3099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:06 volumio sudo[3099]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:06 volumio volumio[1064]: xcb_connection_has_error() returned true Jul 04 11:46:06 volumio volumio[1064]: info: Output device has changed, restarting MPD Jul 04 11:46:06 volumio sudo[3104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:06 volumio sudo[3104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:06 volumio volumio[1064]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:06 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:06 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:06 volumio sudo[3104]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:06 volumio sudo[3107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:06 volumio sudo[3107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:06 volumio systemd[1]: Stopping Music Player Daemon... Jul 04 11:46:06 volumio volumio[1064]: xcb_connection_has_error() returned true Jul 04 11:46:06 volumio volumio[1064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:06 volumio volumio[1064]: xcb_connection_has_error() returned true Jul 04 11:46:06 volumio volumio[1064]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 04 11:46:06 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:06 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:06 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:06 volumio volumio[1064]: info: QobuzConnect: setDeactiveState invoked Jul 04 11:46:06 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:06 volumio volumio[1064]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Jul 04 11:46:06 volumio volumio[1064]: verbose: UNSET VOLATILE: Service: qobuzconnect Jul 04 11:46:06 volumio volumio[1064]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Jul 04 11:46:06 volumio volumio[1064]: info: QobuzConnect: Received stop Jul 04 11:46:06 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.957 [1518.1518] INFO MediaEngine: [0xd35300]: Stopping playback, keeping tracks Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.957 [1518.1518] INFO AudioRenderer: [0xd35520]: Stopping audio renderer Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.957 [1518.1518] INFO SampleApp: Stopping playback Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.957 [1518.1518] INFO AudioStreamManager: [0xd353e8]: Clearing all streams Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.958 [1518.1518] INFO AudioStreamManager: [0xd353e8]: [Stream 1]: Disposing of audio stream Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.958 [1518.1518] INFO ContentFetcher: [0xd70dd0]: Canceling download Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.962 [1518.1518] INFO AudioStreamManager: [0xd353e8]: New stream: 3 Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.962 [1518.1518] INFO HttpDownloader: [0xe108a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9e8b229dfddc6f27208d76a19227c92e&request_ts=1751622366&track_id=270789538 Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.962 [1518.1518] INFO HttpDownloader: [0xd3d600]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=270789538 Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.962 [1518.1518] INFO HttpDownloader: [0xee63e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=c85a4480367cdc063fe08a28bac4e271&request_ts=1751622366&track_id=270789538 Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.962 [1518.1518] INFO AudioStreamManager: [0xd353e8]: [Stream 3]: Running audio stream Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.962 [1518.1518] INFO AudioStreamManager: [0xd353e8]: New stream: 4 Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.962 [1518.1518] INFO HttpDownloader: [0xe15448]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4f03af22f8097d79d9ba489b84d10a43&request_ts=1751622366&track_id=270789535 Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.962 [1518.1518] INFO HttpDownloader: [0xeeb8b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=270789535 Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.963 [1518.1518] INFO HttpDownloader: [0xd715a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=43b3449dbb74e4e9fc4c76ee9e34ec01&request_ts=1751622366&track_id=270789535 Jul 04 11:46:06 volumio sudo[3123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 04 11:46:06 volumio vtcs[1604]: [2025-07-04 11:46:06.959] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jul 04 11:46:06 volumio vtcs[1604]: [2025-07-04 11:46:06.959] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jul 04 11:46:06 volumio volumio[1064]: info: PLUGIN START: fusiondsp Jul 04 11:46:06 volumio volumio[1064]: info: Loading i18n strings for locale de Jul 04 11:46:06 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 04 11:46:06 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:06 volumio volumio[1064]: info: FusionDsp - mixtype--------------------- Hardware Jul 04 11:46:06 volumio volumio[1064]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.968 [1518.1518] INFO SampleApp: Playback state changed: Stopped Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.968 [1518.1518] INFO StreamingEventReporter: [0xd35e58]: Reporting streaming ended event Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.969 [1518.1518] INFO HttpDownloader: [0xd35eb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.969 [1518.1518] INFO ProtocolHandler: [0xd35a40]: Need to send state update Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.969 [1518.1518] INFO QConnectMessageSender: [0xd754c8]: Sending StateUpdated message Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.969 [1518.1518] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Jul 04 11:46:06 volumio qobuz-connect[1518]: 20250704 11:46:06.969 [1518.1518] INFO QwspMessageSender: [0xe3fa30]: Sending Payload message Jul 04 11:46:06 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:06 volumio sudo[3123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio volumio[1064]: info: Done. Jul 04 11:46:07 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jul 04 11:46:07 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 04 11:46:07 volumio systemd[1]: vtcs.service: Succeeded. Jul 04 11:46:07 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jul 04 11:46:07 volumio sudo[3123]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.045 [1518.1518] ERROR HttpClient: [0xe15c90]: Client finished: Couldn't connect to server (7) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.045 [1518.1518] INFO HttpClient: [0xe15c90]: Retrying connection (1 out of 1) Jul 04 11:46:07 volumio sudo[3127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 04 11:46:07 volumio sudo[3127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.051 [1518.1518] ERROR HttpClient: [0xeadc38]: Client finished: Couldn't connect to server (7) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.051 [1518.1518] INFO HttpClient: [0xeadc38]: Retrying connection (1 out of 1) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.051 [1518.1518] ERROR HttpClient: [0xf83320]: Client finished: Couldn't connect to server (7) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.052 [1518.1518] INFO HttpClient: [0xf83320]: Retrying connection (1 out of 1) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.052 [1518.1518] ERROR HttpClient: [0xe16288]: Client finished: Couldn't connect to server (7) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.052 [1518.1518] INFO HttpClient: [0xe16288]: Retrying connection (1 out of 1) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.053 [1518.1518] ERROR HttpClient: [0xee14b8]: Client finished: Couldn't connect to server (7) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.053 [1518.1518] INFO HttpClient: [0xee14b8]: Retrying connection (1 out of 1) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.053 [1518.1518] ERROR HttpClient: [0xee1b20]: Client finished: Couldn't connect to server (7) Jul 04 11:46:07 volumio qobuz-connect[1518]: 20250704 11:46:07.053 [1518.1518] INFO HttpClient: [0xee1b20]: Retrying connection (1 out of 1) Jul 04 11:46:07 volumio sudo[3113]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:07 volumio sudo[3113]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio sudo[3113]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio sudo[3127]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio volumio[1064]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 04 11:46:07 volumio volumio[1064]: info: Reading ALSA contributions from plugins. Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:46:07 volumio sudo[3135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 04 11:46:07 volumio sudo[3135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:46:07 volumio volumio[1064]: info: QobuzConnect: playback_stop received, deferred solved Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:07 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:07 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:07 volumio volumio[1064]: info: MPD Permissions set Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: Starting Shairport Sync Jul 04 11:46:07 volumio volumio[1064]: info: Asound.conf file unchanged, so no further update is needed Jul 04 11:46:07 volumio volumio[1064]: info: Output device has changed, restarting MPD Jul 04 11:46:07 volumio sudo[3135]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio sudo[3143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:07 volumio sudo[3143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio sudo[3146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:07 volumio sudo[3146]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio sudo[3145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 04 11:46:07 volumio sudo[3146]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio sudo[3145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio volumio[1064]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:07 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:07 volumio sudo[3143]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio sudo[3151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:07 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 04 11:46:07 volumio sudo[3151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 04 11:46:07 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:07 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:07 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:07 volumio volumio[1064]: xcb_connection_has_error() returned true Jul 04 11:46:07 volumio volumio[1064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:07 volumio volumio[1064]: xcb_connection_has_error() returned true Jul 04 11:46:07 volumio volumio[1064]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: QobuzConnect: setDeactiveState invoked Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:07 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:07 volumio sudo[3163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 04 11:46:07 volumio sudo[3163]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio sudo[3159]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:07 volumio sudo[3159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio sudo[3166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 04 11:46:07 volumio sudo[3166]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio sudo[3159]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio sudo[3163]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio sudo[3166]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 11:46:07 volumio sudo[3176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 04 11:46:07 volumio sudo[3176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio sudo[3176]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:46:07 volumio volumio[1064]: info: MPD Permissions set Jul 04 11:46:07 volumio volumio[1064]: info: Shairport-Sync Started Jul 04 11:46:07 volumio sudo[3178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 04 11:46:07 volumio sudo[3178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:46:07 volumio volumio[1064]: info: Starting Shairport Sync Jul 04 11:46:07 volumio sudo[3185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:07 volumio sudo[3185]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:07 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 04 11:46:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:07 volumio volumio[1064]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 04 11:46:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:07 volumio sudo[3185]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:07 volumio volumio[1064]: info: Shairport-Sync Started Jul 04 11:46:07 volumio mpd[3170]: Jul 04 11:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 04 11:46:08 volumio systemd[1]: Started Music Player Daemon. Jul 04 11:46:08 volumio sudo[3107]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:08 volumio sudo[3151]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:08 volumio volumio[1064]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:08 volumio volumio[1064]: assert.ok(self.idling) Jul 04 11:46:08 volumio volumio[1064]: error: The expression evaluated to a falsy value: Jul 04 11:46:08 volumio volumio[1064]: assert.ok(self.idling) Jul 04 11:46:08 volumio volumio[1064]: error: updateQueue error: null Jul 04 11:46:09 volumio volumio[1064]: info: camilladsp service started and running in background, instance 1 Jul 04 11:46:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:09 volumio volumio[1064]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:09 volumio volumio[1064]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 04 11:46:09 volumio volumio[1064]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:09 volumio volumio[1064]: info: FusionDsp loaded Jul 04 11:46:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:09 volumio sudo[3202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 04 11:46:09 volumio sudo[3202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:09 volumio volumio[1064]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 04 11:46:09 volumio volumio[1064]: info: Adding Signal Path Element [object Object] Jul 04 11:46:09 volumio volumio[1064]: info: Adding fusiondspeq DSP Signal Path Element Jul 04 11:46:09 volumio volumio[1064]: info: FusionDsp - ---- installed callbackRead Jul 04 11:46:09 volumio volumio[1064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:09 volumio volumio[1064]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 04 11:46:09 volumio volumio[1064]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 04 11:46:09 volumio volumio[1064]: at onErrorNT (internal/child_process.js:465:16) Jul 04 11:46:09 volumio volumio[1064]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 04 11:46:09 volumio volumio[1064]: errno: -2, Jul 04 11:46:09 volumio volumio[1064]: code: 'ENOENT', Jul 04 11:46:09 volumio volumio[1064]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:09 volumio volumio[1064]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:09 volumio volumio[1064]: spawnargs: [ Jul 04 11:46:09 volumio volumio[1064]: '-p', Jul 04 11:46:09 volumio volumio[1064]: 9876, Jul 04 11:46:09 volumio volumio[1064]: '-o', Jul 04 11:46:09 volumio volumio[1064]: '/tmp/camilladsp.log', Jul 04 11:46:09 volumio volumio[1064]: '-l', Jul 04 11:46:09 volumio volumio[1064]: 'warn', Jul 04 11:46:09 volumio volumio[1064]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 04 11:46:09 volumio volumio[1064]: ] Jul 04 11:46:09 volumio volumio[1064]: } Jul 04 11:46:09 volumio volumio[1064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:09 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:09 volumio sudo[3202]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:09 volumio volumio[3204]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:09 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:09 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:09 volumio sudo[3214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 11:45 Jul 04 11:46:09 volumio sudo[3214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:09 volumio sudo[3214]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:09 volumio volumio-remote-updater[635]: [2025-07-04 11:46:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 04 11:46:09 volumio volumio-remote-updater[635]: [2025-07-04 11:46:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 04 11:46:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:46:09 volumio sudo[3178]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:09 volumio sudo[3145]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:09 volumio sudo[1793]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 04 11:46:09 volumio systemd[1]: Started dynamicswap service. Jul 04 11:46:09 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 04 11:46:09 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 04 11:46:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jul 04 11:46:09 volumio systemd[1]: Started dynamicswap service. Jul 04 11:46:09 volumio systemd[1]: Stopped Volumio Backend Module. Jul 04 11:46:09 volumio systemd[1]: Started Volumio Backend Module. Jul 04 11:46:10 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 04 11:46:10 volumio volumio[3228]: info: ------------------------------------------- Jul 04 11:46:10 volumio volumio[3228]: info: ----- Volumio3 ---- Jul 04 11:46:10 volumio volumio[3228]: info: ------------------------------------------- Jul 04 11:46:10 volumio volumio[3228]: info: ----- System startup ---- Jul 04 11:46:10 volumio volumio[3228]: info: ------------------------------------------- Jul 04 11:46:11 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:11 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Jul 04 11:46:11 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:11 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:11 volumio volumio[3248]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:11 volumio volumio[3228]: info: MYVOLUMIO Environment detected Jul 04 11:46:11 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:11 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:11 volumio volumio[3228]: info: Plugin folders cleanup Jul 04 11:46:11 volumio volumio[3228]: info: Scanning into folder /volumio/app/plugins/ Jul 04 11:46:11 volumio volumio[3228]: info: Scanning category audio_interface Jul 04 11:46:11 volumio volumio[3228]: info: Scanning category miscellanea Jul 04 11:46:11 volumio volumio[3228]: info: Scanning category music_service Jul 04 11:46:11 volumio volumio[3228]: info: Scanning category plugins.json Jul 04 11:46:11 volumio volumio[3228]: info: Scanning category system_controller Jul 04 11:46:11 volumio volumio[3228]: info: Scanning category user_interface Jul 04 11:46:11 volumio volumio[3228]: info: Scanning into folder /data/plugins/ Jul 04 11:46:11 volumio volumio[3228]: info: Scanning category audio_interface Jul 04 11:46:11 volumio volumio[3228]: info: Plugin folders cleanup completed Jul 04 11:46:11 volumio volumio[3228]: info: ------------------------------------------- Jul 04 11:46:11 volumio volumio[3228]: info: ----- Core plugins startup ---- Jul 04 11:46:11 volumio volumio[3228]: info: ------------------------------------------- Jul 04 11:46:11 volumio volumio[3228]: info: Loading plugins from folder /volumio/app/plugins/ Jul 04 11:46:11 volumio volumio[3228]: info: Adding plugin upnp to MyMusic Plugins Jul 04 11:46:11 volumio volumio[3228]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 04 11:46:11 volumio volumio[3228]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 04 11:46:11 volumio volumio[3228]: info: Loading plugins from folder /data/plugins/ Jul 04 11:46:11 volumio volumio[3228]: info: Loading plugin "system"... Jul 04 11:46:11 volumio volumio[3228]: info: Loading plugin "appearance"... Jul 04 11:46:11 volumio volumio[3228]: info: Loading plugin "network"... Jul 04 11:46:11 volumio volumio[3228]: info: Refreshing Cached IP Addresses Jul 04 11:46:11 volumio sudo[3256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 11:46:11 volumio sudo[3256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:11 volumio sudo[3256]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:11 volumio sudo[3258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 11:46:11 volumio sudo[3258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:11 volumio sudo[3258]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "services"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "alsa_controller"... Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.009 [1518.1518] ERROR HttpClient: [0xe0cde0]: Client finished: Couldn't connect to server (7) Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.009 [1518.1518] INFO HttpClient: [0xe0cde0]: Retrying connection (1 out of 1) Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.010 [1518.1518] ERROR HttpClient: [0xe15c90]: Client finished: Couldn't connect to server (7) Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.010 [1518.1518] ERROR HttpClient: [0xe15c90]: Failed to connect after 1 retries Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.010 [1518.1518] ERROR HttpClient: [0xeadc38]: Client finished: Couldn't connect to server (7) Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.010 [1518.1518] ERROR HttpClient: [0xeadc38]: Failed to connect after 1 retries Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.010 [1518.1518] ERROR AudioStreamInfoFetcher: [0xe10670]: Unable to fetch audio stream information for track: 270789535 Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.010 [1518.1518] ERROR MetadataFetcher: [0xd38a90]: Unable to fetch metadata for track: 270789535 Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.010 [1518.1518] INFO HttpDownloader: [0xee63e8]: Canceling download Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.010 [1518.1518] INFO HttpDownloader: [0xd3d600]: Canceling download Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.011 [1518.1518] INFO HttpDownloader: [0xe108a0]: Canceling download Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.011 [1518.1518] INFO HttpDownloader: [0xe15448]: Canceling download Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.011 [1518.1518] INFO CloudClient: [0xd358d8]: Disconnecting from the cloud Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.011 [1518.1518] INFO CloudClient: [0xd358d8]: Connection closed Jul 04 11:46:12 volumio sudo[3270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 04 11:46:12 volumio sudo[3270]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:12 volumio qobuz-connect[1518]: 20250704 11:46:12.021 [1518.1518] INFO HttpDownloader: [0xd35eb0]: Canceling download Jul 04 11:46:12 volumio qobuz-connect[1518]: qobuz-connect-daemon: /home/builder/qobuz-connect-sdk/libqobuz_connect/details/network/network_manager.c:302: qbz_network_manager_on_network_client_curl_handle_changed: Assertion `error == CURLM_OK' failed. Jul 04 11:46:12 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Jul 04 11:46:12 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Jul 04 11:46:12 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 04 11:46:12 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "wizard"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "networkfs"... Jul 04 11:46:12 volumio volumio[3228]: info: Starting Udev Watcher for removable devices Jul 04 11:46:12 volumio volumio[3228]: info: Ignoring mount for partition: boot Jul 04 11:46:12 volumio volumio[3228]: info: Ignoring mount for partition: volumio Jul 04 11:46:12 volumio volumio[3228]: info: Ignoring mount for partition: volumio_data Jul 04 11:46:12 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "volumio_command_line_client"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "upnp"... Jul 04 11:46:12 volumio volumio[3228]: info: [1751622372130] Starting Upmpd Daemon Jul 04 11:46:12 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "my_music"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "mpd"... Jul 04 11:46:12 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "upnp_browser"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "alarm-clock"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "airplay_emulation"... Jul 04 11:46:12 volumio volumio[3228]: info: Starting Shairport Sync Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "last_100"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "webradio"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "i2s_dacs"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "volumiodiscovery"... Jul 04 11:46:12 volumio volumio[3228]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:12 volumio volumio[3228]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:12 volumio node[3228]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:12 volumio volumio[3228]: *** WARNING *** For more information see Jul 04 11:46:12 volumio volumio[3228]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:12 volumio volumio[3228]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:12 volumio volumio[3228]: *** WARNING *** For more information see Jul 04 11:46:12 volumio node[3228]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:12 volumio node[3228]: *** WARNING *** For more information see Jul 04 11:46:12 volumio node[3228]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:12 volumio node[3228]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:12 volumio node[3228]: *** WARNING *** For more information see Jul 04 11:46:12 volumio volumio[3228]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 04 11:46:12 volumio volumio[3228]: info: Discovery: Started advertising with name: Volumio Jul 04 11:46:12 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "outputs"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "albumart"... Jul 04 11:46:12 volumio volumio[3228]: info: Plugin example_plugin is not enabled Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "inputs"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "updater_comm"... Jul 04 11:46:12 volumio volumio[3228]: info: Plugin mpdemulation is not enabled Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "rest_api"... Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "websocket"... Jul 04 11:46:12 volumio volumio[3228]: info: Starting Socket.io Server version 2.3.0 Jul 04 11:46:12 volumio volumio[3228]: info: Loading plugin "fusiondsp"... Jul 04 11:46:12 volumio volumio[3228]: Forking 3 albumart workers Jul 04 11:46:13 volumio volumio[3228]: info: Applying required configuration parameters for plugin fusiondsp Jul 04 11:46:13 volumio volumio[3228]: info: Loading i18n strings for locale de Jul 04 11:46:13 volumio volumio[3228]: Updating browse sources language Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::initPlayerControls Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: Express server listening on port 3000 Jul 04 11:46:13 volumio volumio[3228]: [Metrics] WebUI: 2s 797.60ms Jul 04 11:46:13 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. Jul 04 11:46:13 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:13 volumio volumio[3228]: info: CoreStateMachine::resetVolumioState Jul 04 11:46:13 volumio volumio[3228]: info: CoreStateMachine::getcurrentVolume Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 11:46:13 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:13 volumio volumio[3228]: info: Volumio Network Manager: Network status updated: 1 Jul 04 11:46:13 volumio volumio[3317]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:13 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:13 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:13 volumio volumio[3228]: Starting albumart workers Jul 04 11:46:13 volumio volumio[3228]: Starting albumart workers Jul 04 11:46:13 volumio volumio[3228]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 04 11:46:13 volumio volumio[3228]: Starting albumart workers Jul 04 11:46:13 volumio volumio[3228]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.122 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jul 04 11:46:13 volumio volumio[3228]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 04 11:46:13 volumio volumio[3228]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:13 volumio volumio[3228]: info: Reloading queue from file Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:46:13 volumio volumio[3228]: info: Discovery: Getting this device information Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:13 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:13 volumio volumio[3228]: info: CoreStateMachine::setRepeat null single undefined Jul 04 11:46:13 volumio volumio[3228]: info: CoreStateMachine::pushState Jul 04 11:46:13 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:13 volumio volumio[3228]: info: CoreStateMachine::setRandom null Jul 04 11:46:13 volumio volumio[3228]: info: CoreStateMachine::pushState Jul 04 11:46:13 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:13 volumio volumio[3228]: info: Setting Device type: Raspberry PI Jul 04 11:46:13 volumio volumio[3228]: info: Completed loading Core Plugins Jul 04 11:46:13 volumio volumio[3228]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:13 volumio volumio[3228]: info: VolumeController:: Volume=20 Mute =true Jul 04 11:46:13 volumio volumio[3228]: info: CoreStateMachine::pushState Jul 04 11:46:13 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:13 volumio volumio[3228]: info: CoreStateMachine::updateTrackBlock Jul 04 11:46:13 volumio volumio[3228]: info: CorePlayQueue::getTrackBlock Jul 04 11:46:13 volumio volumio[3228]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 11:46:13 volumio volumio[3228]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 04 11:46:13 volumio volumio[3228]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jul 04 11:46:13 volumio volumio[3228]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 04 11:46:13 volumio volumio[3228]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 11:46:13 volumio volumio[3228]: info: Reading ALSA contributions from plugins. Jul 04 11:46:13 volumio volumio[3228]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.122 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jul 04 11:46:14 volumio volumio[3228]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:14 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:14 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 04 11:46:14 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 04 11:46:14 volumio volumio[3228]: info: VolumeController:: Volume=20 Mute =true Jul 04 11:46:14 volumio volumio[3228]: info: CoreStateMachine::pushState Jul 04 11:46:14 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:14 volumio volumio[3228]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:14 volumio volumio[3228]: info: Discovery: adding e1e7d381-ad59-41c9-877d-51ebac659692 Jul 04 11:46:14 volumio volumio[3228]: info: Discovery: Found device Volumio Jul 04 11:46:14 volumio volumio[3228]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:14 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:14 volumio volumio[3228]: info: Asound.conf file written Jul 04 11:46:15 volumio qobuz-connect[3280]: Error: Application ID was not specified! Jul 04 11:46:15 volumio qobuz-connect[3280]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Jul 04 11:46:15 volumio qobuz-connect[3280]: -m|--manufacturer: Manufacturer of the device Jul 04 11:46:15 volumio qobuz-connect[3280]: -n|--deviceName: Device name Jul 04 11:46:15 volumio qobuz-connect[3280]: -l|--model: Device's model name Jul 04 11:46:15 volumio qobuz-connect[3280]: -s|--serialNumber: Device's serial number Jul 04 11:46:15 volumio qobuz-connect[3280]: -o|--outputDevice: ALSA PCM device to play audio to Jul 04 11:46:15 volumio qobuz-connect[3280]: -p|--port: Port to run the local configuration HTTP server on Jul 04 11:46:15 volumio qobuz-connect[3280]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Jul 04 11:46:15 volumio qobuz-connect[3280]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Jul 04 11:46:15 volumio qobuz-connect[3280]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Jul 04 11:46:15 volumio qobuz-connect[3280]: -h|--help: Print this help Jul 04 11:46:15 volumio wpa_supplicant[898]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Sunrise_3749481" Jul 04 11:46:15 volumio volumio-remote-updater[635]: [2025-07-04 11:46:15] [connect] Successful connection Jul 04 11:46:15 volumio wpa_supplicant[898]: wlan0: Trying to associate with SSID 'Sunrise_3749481' Jul 04 11:46:15 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:46:15 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Jul 04 11:46:15 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:15 volumio sudo[3347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 04 11:46:15 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. Jul 04 11:46:15 volumio sudo[3347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:15 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:15 volumio sudo[3347]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:15 volumio volumio[3228]: info: Output device has changed, restarting MPD Jul 04 11:46:15 volumio volumio[3228]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:15 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:15 volumio sudo[3359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:15 volumio sudo[3359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:15 volumio sudo[3359]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:15 volumio wpa_supplicant[898]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jul 04 11:46:15 volumio wpa_supplicant[898]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Sunrise_3749481" auth_failures=5 duration=75 reason=CONN_FAILED Jul 04 11:46:15 volumio sudo[3362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:15 volumio sudo[3362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:15 volumio systemd[1]: Stopping Music Player Daemon... Jul 04 11:46:15 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:15 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:15 volumio volumio[3355]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:15 volumio volumio[3228]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:15 volumio volumio[3228]: info: ___________ START PLUGINS ___________ Jul 04 11:46:15 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:15 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:15 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:15 volumio volumio[3228]: info: ControllerMpd::onStart: Initializing MPD Jul 04 11:46:15 volumio volumio[3228]: info: Creating MPD Configuration file Jul 04 11:46:15 volumio sudo[3369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:15 volumio sudo[3369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:15 volumio volumio[3228]: info: [1751622375769] CoreMusicLibrary::Adding element Medienserver Jul 04 11:46:15 volumio sudo[3369]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:15 volumio sudo[3371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:15 volumio sudo[3371]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:15 volumio sudo[3367]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:15 volumio sudo[3367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:15 volumio sudo[3367]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:15 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 04 11:46:15 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:15 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:15 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:15 volumio volumio[3228]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:15 volumio sudo[3270]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:15 volumio volumio[3228]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:15 volumio volumio[3228]: info: [1751622375863] CoreMusicLibrary::Adding element Last_100 Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:15 volumio volumio[3228]: info: [1751622375866] CoreMusicLibrary::Adding element Webradio Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:15 volumio sudo[3375]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:15 volumio volumio[3228]: info: Initializing BBC Radios Jul 04 11:46:15 volumio sudo[3375]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:15 volumio sudo[3375]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:15 volumio volumio[3228]: info: Loading i18n strings for locale de Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 04 11:46:15 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:15 volumio volumio[3228]: info: FusionDsp - mixtype--------------------- Hardware Jul 04 11:46:15 volumio volumio[3228]: info: Volumio Calling Home Jul 04 11:46:16 volumio volumio[3228]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:16 volumio volumio[3228]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 11:46:16 volumio volumio[3228]: info: Reading ALSA contributions from plugins. Jul 04 11:46:16 volumio volumio[3228]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 04 11:46:16 volumio volumio[3228]: info: MPD Permissions set Jul 04 11:46:16 volumio volumio[3228]: info: MPD Permissions set Jul 04 11:46:16 volumio volumio-remote-updater[635]: [2025-07-04 11:46:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751622374 101 Jul 04 11:46:16 volumio volumio[3228]: 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: 7 Jul 04 11:46:16 volumio volumio[3228]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: Starting Shairport Sync Jul 04 11:46:16 volumio volumio[3228]: info: Starting Shairport Sync Jul 04 11:46:16 volumio sudo[3405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:16 volumio volumio[3228]: info: Starting Shairport Sync Jul 04 11:46:16 volumio sudo[3405]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:16 volumio sudo[3407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:16 volumio sudo[3407]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:16 volumio volumio[3228]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:16 volumio sudo[3410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:16 volumio sudo[3410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:16 volumio volumio[3228]: info: Asound.conf file unchanged, so no further update is needed Jul 04 11:46:16 volumio volumio[3228]: info: Output device has changed, restarting MPD Jul 04 11:46:16 volumio volumio[3228]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:16 volumio sudo[3415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:16 volumio sudo[3415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:16 volumio sudo[3415]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:16 volumio sudo[3405]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:16 volumio sudo[3417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:16 volumio sudo[3417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:16 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 04 11:46:16 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:16 volumio volumio[3228]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:16 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:16 volumio sudo[3407]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:16 volumio sudo[3410]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:16 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:16 volumio volumio[3228]: info: MPD Permissions set Jul 04 11:46:16 volumio volumio[3228]: info: Shairport-Sync Started Jul 04 11:46:16 volumio volumio[3228]: Error adding Membership: Error: addMembership EINVAL Jul 04 11:46:16 volumio volumio[3228]: info: Shairport-Sync Started Jul 04 11:46:16 volumio volumio[3228]: info: Shairport-Sync Started Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:16 volumio volumio[3228]: info: Starting Shairport Sync Jul 04 11:46:16 volumio sudo[3434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:16 volumio sudo[3434]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:16 volumio sudo[3426]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:16 volumio sudo[3426]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:16 volumio sudo[3426]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:16 volumio sudo[3434]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:16 volumio volumio[3228]: info: Shairport-Sync Started Jul 04 11:46:17 volumio mpd[3437]: Jul 04 11:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 04 11:46:17 volumio systemd[1]: Started Music Player Daemon. Jul 04 11:46:17 volumio sudo[3362]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:17 volumio sudo[3417]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:17 volumio sudo[3371]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:17 volumio volumio[3228]: info: Completed starting Core Plugins Jul 04 11:46:17 volumio volumio[3228]: info: ------------------------------------------- Jul 04 11:46:17 volumio volumio[3228]: info: ----- MyVolumio plugins startup ---- Jul 04 11:46:17 volumio volumio[3228]: info: ------------------------------------------- Jul 04 11:46:17 volumio volumio[3228]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 04 11:46:17 volumio volumio[3228]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:17 volumio volumio[3228]: assert.ok(self.idling) Jul 04 11:46:17 volumio volumio[3228]: error: The expression evaluated to a falsy value: Jul 04 11:46:17 volumio volumio[3228]: assert.ok(self.idling) Jul 04 11:46:17 volumio volumio[3228]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:17 volumio volumio[3228]: assert.ok(self.idling) Jul 04 11:46:17 volumio volumio[3228]: error: The expression evaluated to a falsy value: Jul 04 11:46:17 volumio volumio[3228]: assert.ok(self.idling) Jul 04 11:46:17 volumio volumio[3228]: info: MPD running with PID3437 Jul 04 11:46:17 volumio volumio[3228]: ,establishing connection Jul 04 11:46:17 volumio volumio[3228]: error: updateQueue error: null Jul 04 11:46:17 volumio volumio[3228]: error: updateQueue error: null Jul 04 11:46:17 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:17 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 4. Jul 04 11:46:17 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:17 volumio volumio[3228]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 04 11:46:17 volumio volumio[3228]: 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 Jul 04 11:46:17 volumio volumio[3228]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 04 11:46:17 volumio volumio[3228]: info: camilladsp service started and running in background, instance 1 Jul 04 11:46:17 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:17 volumio volumio[3228]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:17 volumio volumio[3228]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 04 11:46:17 volumio volumio[3228]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:18 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:18 volumio volumio[3228]: info: FusionDsp loaded Jul 04 11:46:18 volumio volumio[3228]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:18 volumio sudo[3456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 04 11:46:18 volumio sudo[3456]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:18 volumio volumio[3228]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 04 11:46:18 volumio volumio[3228]: info: Adding Signal Path Element [object Object] Jul 04 11:46:18 volumio volumio[3228]: info: Adding fusiondspeq DSP Signal Path Element Jul 04 11:46:18 volumio volumio[3228]: info: FusionDsp - ---- installed callbackRead Jul 04 11:46:18 volumio volumio[3228]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:18 volumio sudo[3456]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:18 volumio volumio[3228]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 04 11:46:18 volumio volumio[3228]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 04 11:46:18 volumio volumio[3228]: at onErrorNT (internal/child_process.js:465:16) Jul 04 11:46:18 volumio volumio[3228]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 04 11:46:18 volumio volumio[3228]: errno: -2, Jul 04 11:46:18 volumio volumio[3228]: code: 'ENOENT', Jul 04 11:46:18 volumio volumio[3228]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:18 volumio volumio[3228]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:18 volumio volumio[3228]: spawnargs: [ Jul 04 11:46:18 volumio volumio[3228]: '-p', Jul 04 11:46:18 volumio volumio[3228]: 9876, Jul 04 11:46:18 volumio volumio[3228]: '-o', Jul 04 11:46:18 volumio volumio[3228]: '/tmp/camilladsp.log', Jul 04 11:46:18 volumio volumio[3228]: '-l', Jul 04 11:46:18 volumio volumio[3228]: 'warn', Jul 04 11:46:18 volumio volumio[3228]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 04 11:46:18 volumio volumio[3228]: ] Jul 04 11:46:18 volumio volumio[3228]: } Jul 04 11:46:18 volumio volumio[3228]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:18 volumio volumio[3445]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:18 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:18 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:18 volumio sudo[3467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 11:45 Jul 04 11:46:18 volumio sudo[3467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:18 volumio sudo[3467]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:18 volumio volumio-remote-updater[635]: [2025-07-04 11:46:18] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 04 11:46:18 volumio volumio-remote-updater[635]: [2025-07-04 11:46:18] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 04 11:46:18 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:46:18 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 04 11:46:18 volumio systemd[1]: Started dynamicswap service. Jul 04 11:46:18 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 04 11:46:18 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 04 11:46:18 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jul 04 11:46:18 volumio systemd[1]: Stopped Volumio Backend Module. Jul 04 11:46:18 volumio systemd[1]: Started Volumio Backend Module. Jul 04 11:46:18 volumio systemd[1]: Started dynamicswap service. Jul 04 11:46:18 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 04 11:46:19 volumio volumio[3480]: info: ------------------------------------------- Jul 04 11:46:19 volumio volumio[3480]: info: ----- Volumio3 ---- Jul 04 11:46:19 volumio volumio[3480]: info: ------------------------------------------- Jul 04 11:46:19 volumio volumio[3480]: info: ----- System startup ---- Jul 04 11:46:19 volumio volumio[3480]: info: ------------------------------------------- Jul 04 11:46:20 volumio volumio[3480]: info: MYVOLUMIO Environment detected Jul 04 11:46:20 volumio volumio[3480]: info: Plugin folders cleanup Jul 04 11:46:20 volumio volumio[3480]: info: Scanning into folder /volumio/app/plugins/ Jul 04 11:46:20 volumio volumio[3480]: info: Scanning category audio_interface Jul 04 11:46:20 volumio volumio[3480]: info: Scanning category miscellanea Jul 04 11:46:20 volumio volumio[3480]: info: Scanning category music_service Jul 04 11:46:20 volumio volumio[3480]: info: Scanning category plugins.json Jul 04 11:46:20 volumio volumio[3480]: info: Scanning category system_controller Jul 04 11:46:20 volumio volumio[3480]: info: Scanning category user_interface Jul 04 11:46:20 volumio volumio[3480]: info: Scanning into folder /data/plugins/ Jul 04 11:46:20 volumio volumio[3480]: info: Scanning category audio_interface Jul 04 11:46:20 volumio volumio[3480]: info: Plugin folders cleanup completed Jul 04 11:46:20 volumio volumio[3480]: info: ------------------------------------------- Jul 04 11:46:20 volumio volumio[3480]: info: ----- Core plugins startup ---- Jul 04 11:46:20 volumio volumio[3480]: info: ------------------------------------------- Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugins from folder /volumio/app/plugins/ Jul 04 11:46:20 volumio volumio[3480]: info: Adding plugin upnp to MyMusic Plugins Jul 04 11:46:20 volumio volumio[3480]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 04 11:46:20 volumio volumio[3480]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugins from folder /data/plugins/ Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "system"... Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "appearance"... Jul 04 11:46:20 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:20 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 5. Jul 04 11:46:20 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:20 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:20 volumio volumio[3506]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:20 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:20 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "network"... Jul 04 11:46:20 volumio volumio[3480]: info: Refreshing Cached IP Addresses Jul 04 11:46:20 volumio sudo[3508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 11:46:20 volumio sudo[3508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:20 volumio sudo[3508]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:20 volumio sudo[3510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 11:46:20 volumio sudo[3510]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:20 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Jul 04 11:46:20 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. Jul 04 11:46:20 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "services"... Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "alsa_controller"... Jul 04 11:46:20 volumio sudo[3510]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:20 volumio sudo[3520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 04 11:46:20 volumio sudo[3520]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:20 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "wizard"... Jul 04 11:46:20 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "networkfs"... Jul 04 11:46:20 volumio volumio[3480]: info: Starting Udev Watcher for removable devices Jul 04 11:46:20 volumio volumio[3480]: info: Ignoring mount for partition: boot Jul 04 11:46:20 volumio volumio[3480]: info: Ignoring mount for partition: volumio Jul 04 11:46:20 volumio volumio[3480]: info: Ignoring mount for partition: volumio_data Jul 04 11:46:20 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "volumio_command_line_client"... Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "upnp"... Jul 04 11:46:20 volumio volumio[3480]: info: [1751622380830] Starting Upmpd Daemon Jul 04 11:46:20 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "my_music"... Jul 04 11:46:20 volumio volumio[3480]: info: Loading plugin "mpd"... Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "upnp_browser"... Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "alarm-clock"... Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "airplay_emulation"... Jul 04 11:46:21 volumio volumio[3480]: info: Starting Shairport Sync Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "last_100"... Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "webradio"... Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "i2s_dacs"... Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "volumiodiscovery"... Jul 04 11:46:21 volumio volumio[3480]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:21 volumio volumio[3480]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:21 volumio volumio[3480]: *** WARNING *** For more information see Jul 04 11:46:21 volumio node[3480]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:21 volumio volumio[3480]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:21 volumio volumio[3480]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:21 volumio volumio[3480]: *** WARNING *** For more information see Jul 04 11:46:21 volumio node[3480]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:21 volumio node[3480]: *** WARNING *** For more information see Jul 04 11:46:21 volumio node[3480]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:21 volumio node[3480]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:21 volumio node[3480]: *** WARNING *** For more information see Jul 04 11:46:21 volumio volumio[3480]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 04 11:46:21 volumio volumio[3480]: info: Discovery: Started advertising with name: Volumio Jul 04 11:46:21 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "outputs"... Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "albumart"... Jul 04 11:46:21 volumio volumio[3480]: info: Plugin example_plugin is not enabled Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "inputs"... Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "updater_comm"... Jul 04 11:46:21 volumio volumio[3480]: info: Plugin mpdemulation is not enabled Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "rest_api"... Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "websocket"... Jul 04 11:46:21 volumio volumio[3480]: info: Starting Socket.io Server version 2.3.0 Jul 04 11:46:21 volumio volumio[3480]: info: Loading plugin "fusiondsp"... Jul 04 11:46:21 volumio volumio[3480]: Forking 3 albumart workers Jul 04 11:46:21 volumio volumio[3480]: info: Applying required configuration parameters for plugin fusiondsp Jul 04 11:46:21 volumio volumio[3480]: info: Loading i18n strings for locale de Jul 04 11:46:21 volumio volumio[3480]: Updating browse sources language Jul 04 11:46:21 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::initPlayerControls Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: Express server listening on port 3000 Jul 04 11:46:22 volumio volumio[3480]: [Metrics] WebUI: 2s 756.04ms Jul 04 11:46:22 volumio volumio[3480]: info: CoreStateMachine::resetVolumioState Jul 04 11:46:22 volumio volumio[3480]: info: CoreStateMachine::getcurrentVolume Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 11:46:22 volumio volumio[3480]: info: Volumio Network Manager: Network status updated: 1 Jul 04 11:46:22 volumio volumio[3480]: Starting albumart workers Jul 04 11:46:22 volumio volumio[3480]: Starting albumart workers Jul 04 11:46:22 volumio volumio[3480]: Starting albumart workers Jul 04 11:46:22 volumio volumio[3480]: info: No valid Plugin REST Endpoint Jul 04 11:46:22 volumio qobuz-connect[3519]: 20250704 11:46:22.363 [3519.3519] ERROR SampleApp: api request was not successful Jul 04 11:46:22 volumio qobuz-connect[3519]: Error: Application ID was not specified! Jul 04 11:46:22 volumio qobuz-connect[3519]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Jul 04 11:46:22 volumio qobuz-connect[3519]: -m|--manufacturer: Manufacturer of the device Jul 04 11:46:22 volumio qobuz-connect[3519]: -n|--deviceName: Device name Jul 04 11:46:22 volumio qobuz-connect[3519]: -l|--model: Device's model name Jul 04 11:46:22 volumio qobuz-connect[3519]: -s|--serialNumber: Device's serial number Jul 04 11:46:22 volumio qobuz-connect[3519]: -o|--outputDevice: ALSA PCM device to play audio to Jul 04 11:46:22 volumio qobuz-connect[3519]: -p|--port: Port to run the local configuration HTTP server on Jul 04 11:46:22 volumio qobuz-connect[3519]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Jul 04 11:46:22 volumio qobuz-connect[3519]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Jul 04 11:46:22 volumio qobuz-connect[3519]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Jul 04 11:46:22 volumio qobuz-connect[3519]: -h|--help: Print this help Jul 04 11:46:22 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:46:22 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Jul 04 11:46:22 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:22 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 6. Jul 04 11:46:22 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:22 volumio volumio[3480]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.122 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jul 04 11:46:22 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: Reloading queue from file Jul 04 11:46:22 volumio volumio[3571]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:22 volumio volumio[3480]: info: CoreStateMachine::setRepeat null single undefined Jul 04 11:46:22 volumio volumio[3480]: info: CoreStateMachine::pushState Jul 04 11:46:22 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:22 volumio volumio[3480]: info: CoreStateMachine::setRandom null Jul 04 11:46:22 volumio volumio[3480]: info: CoreStateMachine::pushState Jul 04 11:46:22 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:22 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:22 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:22 volumio volumio[3480]: info: Setting Device type: Raspberry PI Jul 04 11:46:22 volumio volumio[3480]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 04 11:46:22 volumio volumio[3480]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jul 04 11:46:22 volumio volumio[3480]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:46:22 volumio volumio[3480]: info: Discovery: Getting this device information Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:22 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:22 volumio volumio[3480]: info: VolumeController:: Volume=20 Mute =true Jul 04 11:46:22 volumio volumio[3480]: info: CoreStateMachine::pushState Jul 04 11:46:22 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:22 volumio volumio[3480]: info: CoreStateMachine::updateTrackBlock Jul 04 11:46:22 volumio volumio[3480]: info: CorePlayQueue::getTrackBlock Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 11:46:22 volumio volumio[3480]: info: Completed loading Core Plugins Jul 04 11:46:22 volumio volumio[3480]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:22 volumio volumio[3480]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 11:46:22 volumio volumio[3480]: info: Reading ALSA contributions from plugins. Jul 04 11:46:22 volumio volumio[3480]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.122 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:22 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 04 11:46:22 volumio volumio[3480]: info: Asound.conf file unchanged, so no further update is needed Jul 04 11:46:22 volumio volumio[3480]: info: Output device has changed, restarting MPD Jul 04 11:46:22 volumio sudo[3593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:22 volumio volumio[3480]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:22 volumio sudo[3593]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:22 volumio sudo[3603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:22 volumio sudo[3603]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:22 volumio sudo[3593]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:22 volumio systemd[1]: Stopping Music Player Daemon... Jul 04 11:46:22 volumio volumio[3480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:22 volumio volumio[3480]: info: ___________ START PLUGINS ___________ Jul 04 11:46:22 volumio volumio[3480]: info: ControllerMpd::onStart: Initializing MPD Jul 04 11:46:22 volumio volumio[3480]: info: Creating MPD Configuration file Jul 04 11:46:22 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:22 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:22 volumio sudo[3615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:22 volumio sudo[3615]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:22 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:22 volumio volumio[3480]: info: [1751622382866] CoreMusicLibrary::Adding element Medienserver Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:22 volumio sudo[3615]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:22 volumio sudo[3618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:22 volumio sudo[3618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:22 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 04 11:46:22 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:22 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:22 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:22 volumio volumio[3480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:22 volumio volumio[3480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:22 volumio volumio[3480]: info: [1751622382959] CoreMusicLibrary::Adding element Last_100 Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:22 volumio volumio[3480]: info: [1751622382961] CoreMusicLibrary::Adding element Webradio Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:22 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:22 volumio volumio[3480]: info: Initializing BBC Radios Jul 04 11:46:22 volumio sudo[3623]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:22 volumio sudo[3623]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:22 volumio sudo[3623]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: Loading i18n strings for locale de Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: FusionDsp - mixtype--------------------- Hardware Jul 04 11:46:23 volumio volumio[3480]: info: Volumio Calling Home Jul 04 11:46:23 volumio sudo[3520]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:23 volumio volumio[3480]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:23 volumio volumio[3480]: info: Discovery: adding e1e7d381-ad59-41c9-877d-51ebac659692 Jul 04 11:46:23 volumio volumio[3480]: info: Discovery: Found device Volumio Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:23 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:23 volumio volumio[3480]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 11:46:23 volumio volumio[3480]: info: Reading ALSA contributions from plugins. Jul 04 11:46:23 volumio volumio[3480]: info: MPD Permissions set Jul 04 11:46:23 volumio volumio[3480]: info: MPD Permissions set Jul 04 11:46:23 volumio volumio[3480]: info: VolumeController:: Volume=20 Mute =true Jul 04 11:46:23 volumio volumio[3480]: info: CoreStateMachine::pushState Jul 04 11:46:23 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:23 volumio volumio[3480]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: Starting Shairport Sync Jul 04 11:46:23 volumio volumio[3480]: info: Starting Shairport Sync Jul 04 11:46:23 volumio volumio[3480]: info: Starting Shairport Sync Jul 04 11:46:23 volumio sudo[3651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:23 volumio sudo[3651]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:23 volumio sudo[3653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:23 volumio sudo[3653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:23 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:23 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:23 volumio sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:23 volumio sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:23 volumio sudo[3661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:23 volumio volumio[3480]: info: Asound.conf file unchanged, so no further update is needed Jul 04 11:46:23 volumio volumio[3480]: info: Output device has changed, restarting MPD Jul 04 11:46:23 volumio volumio[3480]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:23 volumio sudo[3661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:23 volumio sudo[3661]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:23 volumio sudo[3663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:23 volumio sudo[3663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:23 volumio sudo[3651]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:23 volumio volumio[3480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:23 volumio volumio[3480]: info: MPD Permissions set Jul 04 11:46:23 volumio volumio[3480]: info: Shairport-Sync Started Jul 04 11:46:23 volumio volumio[3480]: Error adding Membership: Error: addMembership EINVAL Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio volumio-remote-updater[635]: [2025-07-04 11:46:23] [connect] Successful connection Jul 04 11:46:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:23 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 04 11:46:23 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:23 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:23 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:23 volumio volumio-remote-updater[635]: [2025-07-04 11:46:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751622383 101 Jul 04 11:46:23 volumio volumio[3480]: 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: 4 Jul 04 11:46:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:23 volumio volumio[3480]: info: Starting Shairport Sync Jul 04 11:46:23 volumio sudo[3653]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:23 volumio sudo[3656]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:23 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:23 volumio volumio[3480]: info: Shairport-Sync Started Jul 04 11:46:23 volumio volumio[3480]: info: Shairport-Sync Started Jul 04 11:46:23 volumio volumio[3480]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 04 11:46:23 volumio sudo[3677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:23 volumio sudo[3677]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:23 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:23 volumio sudo[3677]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 11:46:23 volumio volumio[3480]: info: Shairport-Sync Started Jul 04 11:46:23 volumio sudo[3675]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:23 volumio sudo[3675]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:23 volumio sudo[3675]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::volumioGetVisibleSources Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:23 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 04 11:46:23 volumio volumio[3480]: info: Received Get System Info Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:46:23 volumio volumio[3480]: info: Discovery: Getting this device information Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:23 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:23 volumio volumio[3480]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:23 volumio volumio[3480]: info: Listing playlists Jul 04 11:46:23 volumio volumio[3480]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 11:46:23 volumio volumio[3480]: info: FusionDsp - Jul 04 11:46:23 volumio volumio[3480]: info: FusionDsp - undefined Jul 04 11:46:24 volumio mpd[3684]: Jul 04 11:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 04 11:46:24 volumio systemd[1]: Started Music Player Daemon. Jul 04 11:46:24 volumio sudo[3603]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:24 volumio sudo[3618]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:24 volumio sudo[3663]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:24 volumio volumio[3480]: info: Completed starting Core Plugins Jul 04 11:46:24 volumio volumio[3480]: info: ------------------------------------------- Jul 04 11:46:24 volumio volumio[3480]: info: ----- MyVolumio plugins startup ---- Jul 04 11:46:24 volumio volumio[3480]: info: ------------------------------------------- Jul 04 11:46:24 volumio volumio[3480]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 04 11:46:24 volumio volumio[3480]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:24 volumio volumio[3480]: assert.ok(self.idling) Jul 04 11:46:24 volumio volumio[3480]: error: The expression evaluated to a falsy value: Jul 04 11:46:24 volumio volumio[3480]: assert.ok(self.idling) Jul 04 11:46:24 volumio volumio[3480]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:24 volumio volumio[3480]: assert.ok(self.idling) Jul 04 11:46:24 volumio volumio[3480]: error: The expression evaluated to a falsy value: Jul 04 11:46:24 volumio volumio[3480]: assert.ok(self.idling) Jul 04 11:46:24 volumio volumio[3480]: error: updateQueue error: null Jul 04 11:46:24 volumio volumio[3480]: info: MPD running with PID3684 Jul 04 11:46:24 volumio volumio[3480]: ,establishing connection Jul 04 11:46:24 volumio volumio[3480]: error: updateQueue error: null Jul 04 11:46:24 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:24 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 7. Jul 04 11:46:24 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:24 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:24 volumio volumio[3743]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:24 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:24 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:25 volumio volumio[3480]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 04 11:46:25 volumio volumio[3480]: 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 Jul 04 11:46:25 volumio volumio[3480]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 04 11:46:25 volumio volumio[3480]: info: camilladsp service started and running in background, instance 1 Jul 04 11:46:25 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:25 volumio volumio[3480]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:25 volumio volumio[3480]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 04 11:46:25 volumio volumio[3480]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:25 volumio volumio[3480]: info: FusionDsp loaded Jul 04 11:46:25 volumio volumio[3480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:25 volumio sudo[3754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 04 11:46:25 volumio sudo[3754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:25 volumio volumio[3480]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 04 11:46:25 volumio volumio[3480]: info: Adding Signal Path Element [object Object] Jul 04 11:46:25 volumio volumio[3480]: info: Adding fusiondspeq DSP Signal Path Element Jul 04 11:46:25 volumio volumio[3480]: info: FusionDsp - ---- installed callbackRead Jul 04 11:46:25 volumio volumio[3480]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:25 volumio volumio[3480]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 04 11:46:25 volumio volumio[3480]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 04 11:46:25 volumio volumio[3480]: at onErrorNT (internal/child_process.js:465:16) Jul 04 11:46:25 volumio volumio[3480]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 04 11:46:25 volumio volumio[3480]: errno: -2, Jul 04 11:46:25 volumio volumio[3480]: code: 'ENOENT', Jul 04 11:46:25 volumio volumio[3480]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:25 volumio volumio[3480]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:25 volumio volumio[3480]: spawnargs: [ Jul 04 11:46:25 volumio volumio[3480]: '-p', Jul 04 11:46:25 volumio volumio[3480]: 9876, Jul 04 11:46:25 volumio volumio[3480]: '-o', Jul 04 11:46:25 volumio volumio[3480]: '/tmp/camilladsp.log', Jul 04 11:46:25 volumio volumio[3480]: '-l', Jul 04 11:46:25 volumio volumio[3480]: 'warn', Jul 04 11:46:25 volumio volumio[3480]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 04 11:46:25 volumio volumio[3480]: ] Jul 04 11:46:25 volumio volumio[3480]: } Jul 04 11:46:25 volumio volumio[3480]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:25 volumio sudo[3765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 11:45 Jul 04 11:46:25 volumio sudo[3765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:25 volumio sudo[3765]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:25 volumio volumio-remote-updater[635]: [2025-07-04 11:46:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 04 11:46:25 volumio volumio-remote-updater[635]: [2025-07-04 11:46:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 04 11:46:25 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:46:25 volumio sudo[3754]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:25 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 04 11:46:25 volumio systemd[1]: Started dynamicswap service. Jul 04 11:46:25 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 04 11:46:25 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 04 11:46:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jul 04 11:46:25 volumio systemd[1]: Stopped Volumio Backend Module. Jul 04 11:46:25 volumio systemd[1]: Started Volumio Backend Module. Jul 04 11:46:25 volumio systemd[1]: Started dynamicswap service. Jul 04 11:46:26 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 04 11:46:26 volumio volumio[3778]: info: ------------------------------------------- Jul 04 11:46:26 volumio volumio[3778]: info: ----- Volumio3 ---- Jul 04 11:46:26 volumio volumio[3778]: info: ------------------------------------------- Jul 04 11:46:26 volumio volumio[3778]: info: ----- System startup ---- Jul 04 11:46:26 volumio volumio[3778]: info: ------------------------------------------- Jul 04 11:46:26 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:26 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 8. Jul 04 11:46:26 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:26 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:27 volumio volumio[3802]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:27 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:27 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:27 volumio volumio[3778]: info: MYVOLUMIO Environment detected Jul 04 11:46:27 volumio volumio[3778]: info: Plugin folders cleanup Jul 04 11:46:27 volumio volumio[3778]: info: Scanning into folder /volumio/app/plugins/ Jul 04 11:46:27 volumio volumio[3778]: info: Scanning category audio_interface Jul 04 11:46:27 volumio volumio[3778]: info: Scanning category miscellanea Jul 04 11:46:27 volumio volumio[3778]: info: Scanning category music_service Jul 04 11:46:27 volumio volumio[3778]: info: Scanning category plugins.json Jul 04 11:46:27 volumio volumio[3778]: info: Scanning category system_controller Jul 04 11:46:27 volumio volumio[3778]: info: Scanning category user_interface Jul 04 11:46:27 volumio volumio[3778]: info: Scanning into folder /data/plugins/ Jul 04 11:46:27 volumio volumio[3778]: info: Scanning category audio_interface Jul 04 11:46:27 volumio volumio[3778]: info: Plugin folders cleanup completed Jul 04 11:46:27 volumio volumio[3778]: info: ------------------------------------------- Jul 04 11:46:27 volumio volumio[3778]: info: ----- Core plugins startup ---- Jul 04 11:46:27 volumio volumio[3778]: info: ------------------------------------------- Jul 04 11:46:27 volumio volumio[3778]: info: Loading plugins from folder /volumio/app/plugins/ Jul 04 11:46:27 volumio volumio[3778]: info: Adding plugin upnp to MyMusic Plugins Jul 04 11:46:27 volumio volumio[3778]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 04 11:46:27 volumio volumio[3778]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 04 11:46:27 volumio volumio[3778]: info: Loading plugins from folder /data/plugins/ Jul 04 11:46:27 volumio volumio[3778]: info: Loading plugin "system"... Jul 04 11:46:27 volumio volumio[3778]: info: Loading plugin "appearance"... Jul 04 11:46:27 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Jul 04 11:46:27 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 2. Jul 04 11:46:27 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 04 11:46:27 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jul 04 11:46:27 volumio volumio[3778]: info: Loading plugin "network"... Jul 04 11:46:27 volumio volumio[3778]: info: Refreshing Cached IP Addresses Jul 04 11:46:27 volumio sudo[3811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 11:46:27 volumio sudo[3811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:27 volumio sudo[3811]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:27 volumio sudo[3813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 11:46:27 volumio sudo[3813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:27 volumio sudo[3813]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "services"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "alsa_controller"... Jul 04 11:46:28 volumio sudo[3826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 04 11:46:28 volumio sudo[3826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:28 volumio sudo[3826]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:28 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "wizard"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "networkfs"... Jul 04 11:46:28 volumio volumio[3778]: info: Starting Udev Watcher for removable devices Jul 04 11:46:28 volumio volumio[3778]: info: Ignoring mount for partition: boot Jul 04 11:46:28 volumio volumio[3778]: info: Ignoring mount for partition: volumio Jul 04 11:46:28 volumio volumio[3778]: info: Ignoring mount for partition: volumio_data Jul 04 11:46:28 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "volumio_command_line_client"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "upnp"... Jul 04 11:46:28 volumio volumio[3778]: info: [1751622388132] Starting Upmpd Daemon Jul 04 11:46:28 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "my_music"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "mpd"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "upnp_browser"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "alarm-clock"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "airplay_emulation"... Jul 04 11:46:28 volumio volumio[3778]: info: Starting Shairport Sync Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "last_100"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "webradio"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "i2s_dacs"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "volumiodiscovery"... Jul 04 11:46:28 volumio volumio[3778]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:28 volumio volumio[3778]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:28 volumio node[3778]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:28 volumio volumio[3778]: *** WARNING *** For more information see Jul 04 11:46:28 volumio volumio[3778]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:28 volumio volumio[3778]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:28 volumio volumio[3778]: *** WARNING *** For more information see Jul 04 11:46:28 volumio node[3778]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:28 volumio node[3778]: *** WARNING *** For more information see Jul 04 11:46:28 volumio node[3778]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:28 volumio node[3778]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:28 volumio node[3778]: *** WARNING *** For more information see Jul 04 11:46:28 volumio volumio[3778]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 04 11:46:28 volumio volumio[3778]: info: Discovery: Started advertising with name: Volumio Jul 04 11:46:28 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "outputs"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "albumart"... Jul 04 11:46:28 volumio volumio[3778]: info: Plugin example_plugin is not enabled Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "inputs"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "updater_comm"... Jul 04 11:46:28 volumio volumio[3778]: info: Plugin mpdemulation is not enabled Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "rest_api"... Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "websocket"... Jul 04 11:46:28 volumio volumio[3778]: info: Starting Socket.io Server version 2.3.0 Jul 04 11:46:28 volumio volumio[3778]: info: Loading plugin "fusiondsp"... Jul 04 11:46:28 volumio volumio[3778]: Forking 3 albumart workers Jul 04 11:46:29 volumio volumio[3778]: info: Applying required configuration parameters for plugin fusiondsp Jul 04 11:46:29 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:29 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 9. Jul 04 11:46:29 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:29 volumio volumio[3778]: info: Loading i18n strings for locale de Jul 04 11:46:29 volumio volumio[3778]: Updating browse sources language Jul 04 11:46:29 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:29 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:29 volumio volumio[3868]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:29 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:29 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:29 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:29 volumio volumio[3778]: info: CoreCommandRouter::initPlayerControls Jul 04 11:46:29 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:29 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:29 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:29 volumio volumio[3778]: Express server listening on port 3000 Jul 04 11:46:29 volumio volumio[3778]: [Metrics] WebUI: 2s 792.54ms Jul 04 11:46:29 volumio volumio[3778]: info: CoreStateMachine::resetVolumioState Jul 04 11:46:29 volumio volumio[3778]: info: CoreStateMachine::getcurrentVolume Jul 04 11:46:29 volumio volumio[3778]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 11:46:29 volumio volumio[3778]: info: Volumio Network Manager: Network status updated: 1 Jul 04 11:46:29 volumio volumio[3778]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 04 11:46:29 volumio volumio[3778]: wlan0 Interface doesn't support scanning : Device or resource busy Jul 04 11:46:29 volumio volumio[3778]: info: Cannot use regular scanning, forcing with ap-force Jul 04 11:46:29 volumio sudo[3874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 04 11:46:29 volumio sudo[3874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:29 volumio volumio[3778]: Starting albumart workers Jul 04 11:46:29 volumio volumio[3778]: Starting albumart workers Jul 04 11:46:29 volumio volumio[3778]: Starting albumart workers Jul 04 11:46:31 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:31 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 10. Jul 04 11:46:31 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:31 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:31 volumio volumio[3901]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:31 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:31 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:32 volumio volumio-remote-updater[635]: [2025-07-04 11:46:32] [connect] Successful connection Jul 04 11:46:33 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:33 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 11. Jul 04 11:46:33 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:33 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:33 volumio volumio[3902]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:33 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:33 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:34 volumio sudo[3874]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:34 volumio volumio[3778]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 04 11:46:34 volumio volumio[3778]: info: No valid Plugin REST Endpoint Jul 04 11:46:34 volumio qobuz-connect[3808]: 20250704 11:46:34.686 [3808.3808] ERROR SampleApp: api request was not successful Jul 04 11:46:34 volumio qobuz-connect[3808]: Error: Application ID was not specified! Jul 04 11:46:34 volumio qobuz-connect[3808]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Jul 04 11:46:34 volumio qobuz-connect[3808]: -m|--manufacturer: Manufacturer of the device Jul 04 11:46:34 volumio qobuz-connect[3808]: -n|--deviceName: Device name Jul 04 11:46:34 volumio qobuz-connect[3808]: -l|--model: Device's model name Jul 04 11:46:34 volumio qobuz-connect[3808]: -s|--serialNumber: Device's serial number Jul 04 11:46:34 volumio qobuz-connect[3808]: -o|--outputDevice: ALSA PCM device to play audio to Jul 04 11:46:34 volumio qobuz-connect[3808]: -p|--port: Port to run the local configuration HTTP server on Jul 04 11:46:34 volumio qobuz-connect[3808]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Jul 04 11:46:34 volumio qobuz-connect[3808]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Jul 04 11:46:34 volumio qobuz-connect[3808]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Jul 04 11:46:34 volumio qobuz-connect[3808]: -h|--help: Print this help Jul 04 11:46:34 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:46:34 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Jul 04 11:46:34 volumio volumio[3778]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.122 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jul 04 11:46:34 volumio volumio[3778]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 04 11:46:34 volumio volumio[3778]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 04 11:46:34 volumio volumio-remote-updater[635]: [2025-07-04 11:46:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751622390 101 Jul 04 11:46:34 volumio volumio[3778]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jul 04 11:46:34 volumio volumio[3778]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:34 volumio volumio[3778]: info: Reloading queue from file Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:46:34 volumio volumio[3778]: info: Discovery: Getting this device information Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:34 volumio volumio[3778]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:34 volumio volumio[3778]: info: VolumeController:: Volume=20 Mute =true Jul 04 11:46:34 volumio volumio[3778]: info: CoreStateMachine::pushState Jul 04 11:46:34 volumio volumio[3778]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:34 volumio volumio[3778]: info: CoreStateMachine::updateTrackBlock Jul 04 11:46:34 volumio volumio[3778]: info: CorePlayQueue::getTrackBlock Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 11:46:34 volumio volumio[3778]: info: CoreStateMachine::setRepeat null single undefined Jul 04 11:46:34 volumio volumio[3778]: info: CoreStateMachine::pushState Jul 04 11:46:34 volumio volumio[3778]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:34 volumio volumio[3778]: info: CoreStateMachine::setRandom null Jul 04 11:46:34 volumio volumio[3778]: info: CoreStateMachine::pushState Jul 04 11:46:34 volumio volumio[3778]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:34 volumio volumio[3778]: info: Setting Device type: Raspberry PI Jul 04 11:46:34 volumio volumio[3778]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 04 11:46:34 volumio volumio[3778]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jul 04 11:46:34 volumio volumio[3778]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 04 11:46:34 volumio volumio[3778]: info: Completed loading Core Plugins Jul 04 11:46:34 volumio volumio[3778]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:34 volumio volumio[3778]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 11:46:34 volumio volumio[3778]: info: Reading ALSA contributions from plugins. Jul 04 11:46:34 volumio volumio[3778]: info: Asound.conf file unchanged, so no further update is needed Jul 04 11:46:34 volumio volumio[3778]: info: Output device has changed, restarting MPD Jul 04 11:46:34 volumio sudo[3912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:34 volumio sudo[3912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:34 volumio volumio[3778]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:34 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:34 volumio sudo[3912]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:34 volumio sudo[3914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:34 volumio sudo[3914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:34 volumio systemd[1]: Stopping Music Player Daemon... Jul 04 11:46:34 volumio volumio[3778]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:34 volumio volumio[3778]: info: ___________ START PLUGINS ___________ Jul 04 11:46:34 volumio volumio[3778]: info: ControllerMpd::onStart: Initializing MPD Jul 04 11:46:34 volumio volumio[3778]: info: Creating MPD Configuration file Jul 04 11:46:34 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:34 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:34 volumio sudo[3922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:34 volumio sudo[3922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:35 volumio volumio[3778]: info: [1751622394971] CoreMusicLibrary::Adding element Medienserver Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:34 volumio sudo[3922]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:35 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:34 volumio sudo[3925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:34 volumio sudo[3925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 04 11:46:35 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:35 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:35 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:35 volumio volumio[3778]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:35 volumio volumio[3778]: info: [1751622395062] CoreMusicLibrary::Adding element Last_100 Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:35 volumio volumio[3778]: info: [1751622395064] CoreMusicLibrary::Adding element Webradio Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:35 volumio volumio[3778]: info: Initializing BBC Radios Jul 04 11:46:35 volumio sudo[3933]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:35 volumio sudo[3933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio sudo[3933]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: Loading i18n strings for locale de Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: FusionDsp - mixtype--------------------- Hardware Jul 04 11:46:35 volumio volumio[3778]: info: Volumio Calling Home Jul 04 11:46:35 volumio volumio[3778]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:35 volumio volumio[3778]: info: Discovery: adding e1e7d381-ad59-41c9-877d-51ebac659692 Jul 04 11:46:35 volumio volumio[3778]: info: Discovery: Found device Volumio Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:35 volumio volumio[3778]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:35 volumio volumio[3778]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 11:46:35 volumio volumio[3778]: info: Reading ALSA contributions from plugins. Jul 04 11:46:35 volumio volumio[3778]: info: MPD Permissions set Jul 04 11:46:35 volumio volumio[3778]: info: MPD Permissions set Jul 04 11:46:35 volumio volumio[3778]: info: VolumeController:: Volume=20 Mute =true Jul 04 11:46:35 volumio volumio[3778]: info: CoreStateMachine::pushState Jul 04 11:46:35 volumio volumio[3778]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:35 volumio volumio[3778]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: Starting Shairport Sync Jul 04 11:46:35 volumio volumio[3778]: info: Starting Shairport Sync Jul 04 11:46:35 volumio sudo[3958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:35 volumio volumio[3778]: info: Starting Shairport Sync Jul 04 11:46:35 volumio sudo[3958]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:35 volumio sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:35 volumio sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:35 volumio volumio[3778]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:35 volumio sudo[3963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:35 volumio sudo[3963]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:35 volumio volumio[3778]: info: Asound.conf file unchanged, so no further update is needed Jul 04 11:46:35 volumio volumio[3778]: info: Output device has changed, restarting MPD Jul 04 11:46:35 volumio sudo[3968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:35 volumio sudo[3968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:35 volumio volumio[3778]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:35 volumio sudo[3968]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:35 volumio sudo[3958]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:35 volumio sudo[3971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:35 volumio sudo[3971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio volumio[3778]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:35 volumio volumio[3778]: info: MPD Permissions set Jul 04 11:46:35 volumio volumio[3778]: info: Shairport-Sync Started Jul 04 11:46:35 volumio volumio[3778]: Error adding Membership: Error: addMembership EINVAL Jul 04 11:46:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:35 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 04 11:46:35 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:35 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:35 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:35 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:35 volumio sudo[3960]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:35 volumio sudo[3963]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:35 volumio volumio[3778]: info: Starting Shairport Sync Jul 04 11:46:35 volumio volumio[3778]: info: Shairport-Sync Started Jul 04 11:46:35 volumio volumio[3778]: info: Shairport-Sync Started Jul 04 11:46:35 volumio sudo[3985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:35 volumio sudo[3985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:35 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:35 volumio sudo[3978]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:35 volumio sudo[3978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:35 volumio sudo[3978]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:35 volumio sudo[3985]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:35 volumio volumio[3778]: info: Shairport-Sync Started Jul 04 11:46:35 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:35 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 12. Jul 04 11:46:35 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:35 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:36 volumio volumio[3993]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:36 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:36 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:36 volumio mpd[3990]: Jul 04 11:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 04 11:46:36 volumio systemd[1]: Started Music Player Daemon. Jul 04 11:46:36 volumio sudo[3925]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:36 volumio sudo[3914]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:36 volumio sudo[3971]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:36 volumio volumio[3778]: info: Completed starting Core Plugins Jul 04 11:46:36 volumio volumio[3778]: info: ------------------------------------------- Jul 04 11:46:36 volumio volumio[3778]: info: ----- MyVolumio plugins startup ---- Jul 04 11:46:36 volumio volumio[3778]: info: ------------------------------------------- Jul 04 11:46:36 volumio volumio[3778]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 04 11:46:36 volumio volumio[3778]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:36 volumio volumio[3778]: assert.ok(self.idling) Jul 04 11:46:36 volumio volumio[3778]: error: The expression evaluated to a falsy value: Jul 04 11:46:36 volumio volumio[3778]: assert.ok(self.idling) Jul 04 11:46:36 volumio volumio[3778]: info: MPD running with PID3990 Jul 04 11:46:36 volumio volumio[3778]: ,establishing connection Jul 04 11:46:36 volumio volumio[3778]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:36 volumio volumio[3778]: assert.ok(self.idling) Jul 04 11:46:36 volumio volumio[3778]: error: The expression evaluated to a falsy value: Jul 04 11:46:36 volumio volumio[3778]: assert.ok(self.idling) Jul 04 11:46:36 volumio volumio[3778]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:36 volumio volumio[3778]: assert.ok(self.idling) Jul 04 11:46:36 volumio volumio[3778]: error: The expression evaluated to a falsy value: Jul 04 11:46:36 volumio volumio[3778]: assert.ok(self.idling) Jul 04 11:46:36 volumio volumio[3778]: error: updateQueue error: null Jul 04 11:46:37 volumio volumio[3778]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 04 11:46:37 volumio volumio[3778]: 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 Jul 04 11:46:37 volumio volumio[3778]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 04 11:46:37 volumio volumio[3778]: info: camilladsp service started and running in background, instance 1 Jul 04 11:46:37 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:37 volumio volumio[3778]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:37 volumio volumio[3778]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 04 11:46:37 volumio volumio[3778]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:37 volumio volumio[3778]: info: FusionDsp loaded Jul 04 11:46:37 volumio volumio[3778]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:37 volumio sudo[4008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 04 11:46:37 volumio sudo[4008]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:37 volumio volumio[3778]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 04 11:46:37 volumio volumio[3778]: info: Adding Signal Path Element [object Object] Jul 04 11:46:37 volumio volumio[3778]: info: Adding fusiondspeq DSP Signal Path Element Jul 04 11:46:37 volumio volumio[3778]: info: FusionDsp - ---- installed callbackRead Jul 04 11:46:37 volumio volumio[3778]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:37 volumio volumio[3778]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 04 11:46:37 volumio volumio[3778]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 04 11:46:37 volumio volumio[3778]: at onErrorNT (internal/child_process.js:465:16) Jul 04 11:46:37 volumio volumio[3778]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 04 11:46:37 volumio volumio[3778]: errno: -2, Jul 04 11:46:37 volumio volumio[3778]: code: 'ENOENT', Jul 04 11:46:37 volumio volumio[3778]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:37 volumio volumio[3778]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:37 volumio volumio[3778]: spawnargs: [ Jul 04 11:46:37 volumio volumio[3778]: '-p', Jul 04 11:46:37 volumio volumio[3778]: 9876, Jul 04 11:46:37 volumio volumio[3778]: '-o', Jul 04 11:46:37 volumio volumio[3778]: '/tmp/camilladsp.log', Jul 04 11:46:37 volumio volumio[3778]: '-l', Jul 04 11:46:37 volumio volumio[3778]: 'warn', Jul 04 11:46:37 volumio volumio[3778]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 04 11:46:37 volumio volumio[3778]: ] Jul 04 11:46:37 volumio volumio[3778]: } Jul 04 11:46:37 volumio volumio[3778]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:37 volumio sudo[4019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 11:45 Jul 04 11:46:37 volumio sudo[4019]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:37 volumio sudo[4019]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:37 volumio volumio-remote-updater[635]: [2025-07-04 11:46:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 04 11:46:37 volumio volumio-remote-updater[635]: [2025-07-04 11:46:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 04 11:46:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:46:37 volumio sudo[4008]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 04 11:46:37 volumio systemd[1]: Started dynamicswap service. Jul 04 11:46:37 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 04 11:46:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 04 11:46:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jul 04 11:46:37 volumio systemd[1]: Started dynamicswap service. Jul 04 11:46:37 volumio systemd[1]: Stopped Volumio Backend Module. Jul 04 11:46:37 volumio systemd[1]: Started Volumio Backend Module. Jul 04 11:46:38 volumio systemd[1]: dynamicswap.service: Succeeded. Jul 04 11:46:38 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:38 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 13. Jul 04 11:46:38 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:38 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:38 volumio volumio[4045]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:38 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:38 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:38 volumio volumio[4033]: info: ------------------------------------------- Jul 04 11:46:38 volumio volumio[4033]: info: ----- Volumio3 ---- Jul 04 11:46:38 volumio volumio[4033]: info: ------------------------------------------- Jul 04 11:46:38 volumio volumio[4033]: info: ----- System startup ---- Jul 04 11:46:38 volumio volumio[4033]: info: ------------------------------------------- Jul 04 11:46:39 volumio volumio[4033]: info: MYVOLUMIO Environment detected Jul 04 11:46:39 volumio volumio[4033]: info: Plugin folders cleanup Jul 04 11:46:39 volumio volumio[4033]: info: Scanning into folder /volumio/app/plugins/ Jul 04 11:46:39 volumio volumio[4033]: info: Scanning category audio_interface Jul 04 11:46:39 volumio volumio[4033]: info: Scanning category miscellanea Jul 04 11:46:39 volumio volumio[4033]: info: Scanning category music_service Jul 04 11:46:39 volumio volumio[4033]: info: Scanning category plugins.json Jul 04 11:46:39 volumio volumio[4033]: info: Scanning category system_controller Jul 04 11:46:39 volumio volumio[4033]: info: Scanning category user_interface Jul 04 11:46:39 volumio volumio[4033]: info: Scanning into folder /data/plugins/ Jul 04 11:46:39 volumio volumio[4033]: info: Scanning category audio_interface Jul 04 11:46:39 volumio volumio[4033]: info: Plugin folders cleanup completed Jul 04 11:46:39 volumio volumio[4033]: info: ------------------------------------------- Jul 04 11:46:39 volumio volumio[4033]: info: ----- Core plugins startup ---- Jul 04 11:46:39 volumio volumio[4033]: info: ------------------------------------------- Jul 04 11:46:39 volumio volumio[4033]: info: Loading plugins from folder /volumio/app/plugins/ Jul 04 11:46:39 volumio volumio[4033]: info: Adding plugin upnp to MyMusic Plugins Jul 04 11:46:39 volumio volumio[4033]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 04 11:46:39 volumio volumio[4033]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 04 11:46:39 volumio volumio[4033]: info: Loading plugins from folder /data/plugins/ Jul 04 11:46:39 volumio volumio[4033]: info: Loading plugin "system"... Jul 04 11:46:39 volumio volumio[4033]: info: Loading plugin "appearance"... Jul 04 11:46:39 volumio systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Jul 04 11:46:39 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3. Jul 04 11:46:39 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 04 11:46:39 volumio volumio[4033]: info: Loading plugin "network"... Jul 04 11:46:39 volumio volumio[4033]: info: Refreshing Cached IP Addresses Jul 04 11:46:39 volumio sudo[4061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 11:46:39 volumio sudo[4061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:39 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jul 04 11:46:39 volumio sudo[4063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 11:46:39 volumio sudo[4063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:39 volumio sudo[4061]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:39 volumio sudo[4063]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:39 volumio volumio[4033]: info: Loading plugin "services"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "alsa_controller"... Jul 04 11:46:40 volumio sudo[4072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 04 11:46:40 volumio sudo[4072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:40 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "wizard"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "networkfs"... Jul 04 11:46:40 volumio volumio[4033]: info: Starting Udev Watcher for removable devices Jul 04 11:46:40 volumio volumio[4033]: info: Ignoring mount for partition: boot Jul 04 11:46:40 volumio volumio[4033]: info: Ignoring mount for partition: volumio Jul 04 11:46:40 volumio volumio[4033]: info: Ignoring mount for partition: volumio_data Jul 04 11:46:40 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "volumio_command_line_client"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "upnp"... Jul 04 11:46:40 volumio volumio[4033]: info: [1751622400123] Starting Upmpd Daemon Jul 04 11:46:40 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "my_music"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "mpd"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "upnp_browser"... Jul 04 11:46:40 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:40 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 14. Jul 04 11:46:40 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "alarm-clock"... Jul 04 11:46:40 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:40 volumio volumio[4089]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:40 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:40 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "airplay_emulation"... Jul 04 11:46:40 volumio volumio[4033]: info: Starting Shairport Sync Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "last_100"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "webradio"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "i2s_dacs"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "volumiodiscovery"... Jul 04 11:46:40 volumio volumio[4033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:40 volumio volumio[4033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:40 volumio node[4033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:40 volumio volumio[4033]: *** WARNING *** For more information see Jul 04 11:46:40 volumio volumio[4033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:40 volumio volumio[4033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:40 volumio volumio[4033]: *** WARNING *** For more information see Jul 04 11:46:40 volumio node[4033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:40 volumio node[4033]: *** WARNING *** For more information see Jul 04 11:46:40 volumio node[4033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 11:46:40 volumio node[4033]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 11:46:40 volumio node[4033]: *** WARNING *** For more information see Jul 04 11:46:40 volumio volumio[4033]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 04 11:46:40 volumio volumio[4033]: info: Discovery: Started advertising with name: Volumio Jul 04 11:46:40 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "outputs"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "albumart"... Jul 04 11:46:40 volumio volumio[4033]: info: Plugin example_plugin is not enabled Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "inputs"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "updater_comm"... Jul 04 11:46:40 volumio volumio[4033]: info: Plugin mpdemulation is not enabled Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "rest_api"... Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "websocket"... Jul 04 11:46:40 volumio volumio[4033]: info: Starting Socket.io Server version 2.3.0 Jul 04 11:46:40 volumio volumio[4033]: info: Loading plugin "fusiondsp"... Jul 04 11:46:40 volumio volumio[4033]: Forking 3 albumart workers Jul 04 11:46:41 volumio volumio[4033]: info: Applying required configuration parameters for plugin fusiondsp Jul 04 11:46:41 volumio volumio[4033]: info: Loading i18n strings for locale de Jul 04 11:46:41 volumio volumio[4033]: Updating browse sources language Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::initPlayerControls Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: Express server listening on port 3000 Jul 04 11:46:41 volumio volumio[4033]: [Metrics] WebUI: 2s 722.59ms Jul 04 11:46:41 volumio volumio[4033]: info: CoreStateMachine::resetVolumioState Jul 04 11:46:41 volumio volumio[4033]: info: CoreStateMachine::getcurrentVolume Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 11:46:41 volumio volumio[4033]: info: Volumio Network Manager: Network status updated: 1 Jul 04 11:46:41 volumio volumio[4033]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.122 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jul 04 11:46:41 volumio volumio[4033]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jul 04 11:46:41 volumio volumio[4033]: Starting albumart workers Jul 04 11:46:41 volumio volumio[4033]: Starting albumart workers Jul 04 11:46:41 volumio volumio[4033]: info: No valid Plugin REST Endpoint Jul 04 11:46:41 volumio qobuz-connect[4059]: 20250704 11:46:41.575 [4059.4059] ERROR SampleApp: api request was not successful Jul 04 11:46:41 volumio qobuz-connect[4059]: Error: Application ID was not specified! Jul 04 11:46:41 volumio qobuz-connect[4059]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Jul 04 11:46:41 volumio qobuz-connect[4059]: -m|--manufacturer: Manufacturer of the device Jul 04 11:46:41 volumio qobuz-connect[4059]: -n|--deviceName: Device name Jul 04 11:46:41 volumio qobuz-connect[4059]: -l|--model: Device's model name Jul 04 11:46:41 volumio qobuz-connect[4059]: -s|--serialNumber: Device's serial number Jul 04 11:46:41 volumio qobuz-connect[4059]: -o|--outputDevice: ALSA PCM device to play audio to Jul 04 11:46:41 volumio qobuz-connect[4059]: -p|--port: Port to run the local configuration HTTP server on Jul 04 11:46:41 volumio qobuz-connect[4059]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Jul 04 11:46:41 volumio qobuz-connect[4059]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Jul 04 11:46:41 volumio qobuz-connect[4059]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Jul 04 11:46:41 volumio qobuz-connect[4059]: -h|--help: Print this help Jul 04 11:46:41 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Jul 04 11:46:41 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Jul 04 11:46:41 volumio volumio[4033]: Starting albumart workers Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:41 volumio volumio[4033]: info: Reloading queue from file Jul 04 11:46:41 volumio volumio[4033]: info: CoreStateMachine::setRepeat null single undefined Jul 04 11:46:41 volumio volumio[4033]: info: CoreStateMachine::pushState Jul 04 11:46:41 volumio volumio[4033]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:41 volumio volumio[4033]: info: CoreStateMachine::setRandom null Jul 04 11:46:41 volumio volumio[4033]: info: CoreStateMachine::pushState Jul 04 11:46:41 volumio volumio[4033]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:41 volumio volumio[4033]: info: Setting Device type: Raspberry PI Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 11:46:41 volumio volumio[4033]: info: Discovery: Getting this device information Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:41 volumio volumio[4033]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:41 volumio volumio[4033]: info: Completed loading Core Plugins Jul 04 11:46:41 volumio volumio[4033]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:41 volumio volumio[4033]: info: VolumeController:: Volume=20 Mute =true Jul 04 11:46:41 volumio volumio[4033]: info: CoreStateMachine::pushState Jul 04 11:46:41 volumio volumio[4033]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:41 volumio volumio[4033]: info: CoreStateMachine::updateTrackBlock Jul 04 11:46:41 volumio volumio[4033]: info: CorePlayQueue::getTrackBlock Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 11:46:41 volumio volumio[4033]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 04 11:46:41 volumio volumio[4033]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jul 04 11:46:41 volumio volumio[4033]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 04 11:46:41 volumio volumio[4033]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 11:46:41 volumio volumio[4033]: info: Reading ALSA contributions from plugins. Jul 04 11:46:41 volumio volumio[4033]: verbose: New Socket.io Connection to 192.168.1.14:3000 from 192.168.1.122 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:41 volumio volumio[4033]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 04 11:46:41 volumio volumio[4033]: info: Discovery: adding e1e7d381-ad59-41c9-877d-51ebac659692 Jul 04 11:46:41 volumio volumio[4033]: info: Discovery: Found device Volumio Jul 04 11:46:41 volumio volumio[4033]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:41 volumio volumio[4033]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:41 volumio volumio[4033]: info: Asound.conf file unchanged, so no further update is needed Jul 04 11:46:41 volumio volumio[4033]: info: Output device has changed, restarting MPD Jul 04 11:46:42 volumio sudo[4148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:42 volumio sudo[4148]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio volumio[4033]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:42 volumio sudo[4148]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio sudo[4152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:42 volumio sudo[4152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio systemd[1]: Stopping Music Player Daemon... Jul 04 11:46:42 volumio volumio[4033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:42 volumio volumio[4033]: info: ___________ START PLUGINS ___________ Jul 04 11:46:42 volumio volumio[4033]: info: ControllerMpd::onStart: Initializing MPD Jul 04 11:46:42 volumio volumio[4033]: info: Creating MPD Configuration file Jul 04 11:46:42 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:42 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:42 volumio sudo[4159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:42 volumio sudo[4159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:42 volumio volumio[4033]: info: [1751622402120] CoreMusicLibrary::Adding element Medienserver Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:42 volumio sudo[4159]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:42 volumio sudo[4166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:42 volumio sudo[4166]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 04 11:46:42 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:42 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:42 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:42 volumio volumio[4033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:42 volumio volumio[4033]: info: [1751622402219] CoreMusicLibrary::Adding element Last_100 Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 11:46:42 volumio volumio[4033]: info: [1751622402222] CoreMusicLibrary::Adding element Webradio Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:42 volumio volumio[4033]: info: Initializing BBC Radios Jul 04 11:46:42 volumio sudo[4175]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:42 volumio sudo[4175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio sudo[4175]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: Loading i18n strings for locale de Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: FusionDsp - mixtype--------------------- Hardware Jul 04 11:46:42 volumio volumio[4033]: info: Volumio Calling Home Jul 04 11:46:42 volumio sudo[4072]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio volumio[4033]: info: Preparing to generate the ALSA configuration file Jul 04 11:46:42 volumio volumio[4033]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 11:46:42 volumio volumio[4033]: info: Reading ALSA contributions from plugins. Jul 04 11:46:42 volumio volumio[4033]: info: MPD Permissions set Jul 04 11:46:42 volumio volumio[4033]: info: VolumeController:: Volume=20 Mute =true Jul 04 11:46:42 volumio volumio[4033]: info: CoreStateMachine::pushState Jul 04 11:46:42 volumio volumio[4033]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::volumioPushState Jul 04 11:46:42 volumio volumio[4033]: info: Volumio called home Jul 04 11:46:42 volumio volumio[4033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: Starting Shairport Sync Jul 04 11:46:42 volumio volumio[4033]: info: Starting Shairport Sync Jul 04 11:46:42 volumio sudo[4203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:42 volumio volumio[4033]: info: Starting Shairport Sync Jul 04 11:46:42 volumio sudo[4203]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio sudo[4205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:42 volumio sudo[4205]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 04 11:46:42 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 15. Jul 04 11:46:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:42 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 04 11:46:42 volumio sudo[4209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:42 volumio sudo[4209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::volumioGetState Jul 04 11:46:42 volumio volumio[4033]: info: CorePlayQueue::getTrack 0 Jul 04 11:46:42 volumio volumio[4033]: info: Asound.conf file unchanged, so no further update is needed Jul 04 11:46:42 volumio volumio[4033]: info: Output device has changed, restarting MPD Jul 04 11:46:42 volumio volumio[4033]: info: Output device has changed, restarting Shairport Sync Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:42 volumio sudo[4213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 11:46:42 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:42 volumio sudo[4213]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:42 volumio sudo[4213]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio sudo[4215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 11:46:42 volumio sudo[4215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:42 volumio sudo[4203]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:42 volumio sudo[4205]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 04 11:46:42 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:42 volumio volumio-remote-updater[635]: [2025-07-04 11:46:42] [connect] Successful connection Jul 04 11:46:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:42 volumio sudo[4209]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio volumio[4210]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 04 11:46:42 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 04 11:46:42 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 04 11:46:42 volumio volumio[4033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 11:46:42 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 04 11:46:42 volumio systemd[1]: mpd.service: Succeeded. Jul 04 11:46:42 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 11:46:42 volumio systemd[1]: Starting Music Player Daemon... Jul 04 11:46:42 volumio volumio[4033]: info: MPD Permissions set Jul 04 11:46:42 volumio volumio[4033]: info: Shairport-Sync Started Jul 04 11:46:42 volumio volumio[4033]: Error adding Membership: Error: addMembership EINVAL Jul 04 11:46:42 volumio volumio[4033]: info: Shairport-Sync Started Jul 04 11:46:42 volumio volumio[4033]: info: Shairport-Sync Started Jul 04 11:46:42 volumio volumio-remote-updater[635]: [2025-07-04 11:46:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751622402 101 Jul 04 11:46:42 volumio volumio[4033]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:42 volumio volumio[4033]: info: Starting Shairport Sync Jul 04 11:46:42 volumio sudo[4223]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 11:46:42 volumio sudo[4223]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio sudo[4231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 11:46:42 volumio sudo[4223]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio sudo[4231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 11:46:42 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 11:46:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:42 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Jul 04 11:46:42 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Jul 04 11:46:42 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Jul 04 11:46:42 volumio sudo[4231]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:42 volumio volumio[4033]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Jul 04 11:46:42 volumio volumio[4033]: Job for shairport-sync.service failed. Jul 04 11:46:42 volumio volumio[4033]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Jul 04 11:46:43 volumio mpd[4234]: Jul 04 11:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 04 11:46:43 volumio systemd[1]: Started Music Player Daemon. Jul 04 11:46:43 volumio sudo[4166]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:43 volumio sudo[4152]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:43 volumio sudo[4215]: pam_unix(sudo:session): session closed for user root Jul 04 11:46:43 volumio volumio[4033]: info: Completed starting Core Plugins Jul 04 11:46:43 volumio volumio[4033]: info: ------------------------------------------- Jul 04 11:46:43 volumio volumio[4033]: info: ----- MyVolumio plugins startup ---- Jul 04 11:46:43 volumio volumio[4033]: info: ------------------------------------------- Jul 04 11:46:43 volumio volumio[4033]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 04 11:46:43 volumio volumio[4033]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:43 volumio volumio[4033]: assert.ok(self.idling) Jul 04 11:46:43 volumio volumio[4033]: error: The expression evaluated to a falsy value: Jul 04 11:46:43 volumio volumio[4033]: assert.ok(self.idling) Jul 04 11:46:43 volumio volumio[4033]: error: MPD error: The expression evaluated to a falsy value: Jul 04 11:46:43 volumio volumio[4033]: assert.ok(self.idling) Jul 04 11:46:43 volumio volumio[4033]: error: The expression evaluated to a falsy value: Jul 04 11:46:43 volumio volumio[4033]: assert.ok(self.idling) Jul 04 11:46:43 volumio volumio[4033]: info: MPD running with PID4234 Jul 04 11:46:43 volumio volumio[4033]: ,establishing connection Jul 04 11:46:43 volumio volumio[4033]: error: updateQueue error: null Jul 04 11:46:43 volumio volumio[4033]: error: updateQueue error: null Jul 04 11:46:44 volumio volumio[4033]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.122 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 04 11:46:44 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 11:46:44 volumio volumio[4033]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 04 11:46:44 volumio volumio[4033]: 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 Jul 04 11:46:44 volumio volumio[4033]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 04 11:46:44 volumio volumio[4033]: info: camilladsp service started and running in background, instance 1 Jul 04 11:46:44 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 11:46:44 volumio volumio[4033]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:44 volumio volumio[4033]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 04 11:46:44 volumio volumio[4033]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 04 11:46:44 volumio volumio[4033]: info: FusionDsp loaded Jul 04 11:46:44 volumio volumio[4033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 11:46:44 volumio sudo[4249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 04 11:46:44 volumio sudo[4249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 11:46:44 volumio volumio[4033]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 04 11:46:44 volumio volumio[4033]: info: Adding Signal Path Element [object Object] Jul 04 11:46:44 volumio volumio[4033]: info: Adding fusiondspeq DSP Signal Path Element Jul 04 11:46:44 volumio volumio[4033]: info: FusionDsp - ---- installed callbackRead Jul 04 11:46:44 volumio volumio[4033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:44 volumio volumio[4033]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 04 11:46:44 volumio volumio[4033]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 04 11:46:44 volumio volumio[4033]: at onErrorNT (internal/child_process.js:465:16) Jul 04 11:46:44 volumio volumio[4033]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 04 11:46:44 volumio volumio[4033]: errno: -2, Jul 04 11:46:44 volumio volumio[4033]: code: 'ENOENT', Jul 04 11:46:44 volumio volumio[4033]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:44 volumio volumio[4033]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 04 11:46:44 volumio volumio[4033]: spawnargs: [ Jul 04 11:46:44 volumio volumio[4033]: '-p', Jul 04 11:46:44 volumio volumio[4033]: 9876, Jul 04 11:46:44 volumio volumio[4033]: '-o', Jul 04 11:46:44 volumio volumio[4033]: '/tmp/camilladsp.log', Jul 04 11:46:44 volumio volumio[4033]: '-l', Jul 04 11:46:44 volumio volumio[4033]: 'warn', Jul 04 11:46:44 volumio volumio[4033]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 04 11:46:44 volumio volumio[4033]: ] Jul 04 11:46:44 volumio volumio[4033]: } Jul 04 11:46:44 volumio volumio[4033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 11:46:44 volumio sudo[4261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 11:45 Jul 04 11:46:44 volumio sudo[4261]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"